See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/1927/changes>
Changes: [vinod] Added a test for CFS cpu limits in the cpu isolator. ------------------------------------------ [...truncated 10865 lines...] I0228 08:07:35.680649 23256 exec.cpp:217] Executor::registered took 8926ns I0228 08:07:35.680712 23256 exec.cpp:292] Executor asked to run task '0' I0228 08:07:35.680748 23256 exec.cpp:301] Executor::launchTask took 20781ns I0228 08:07:35.681522 23256 exec.cpp:524] Executor sending status update TASK_RUNNING (UUID: be3f602f-e9b9-41df-96c6-6a944178b212) for task 0 of framework 2014-02-28-08:07:35-160088899-34779-23227-0000 I0228 08:07:35.681599 23256 slave.cpp:1650] Handling status update TASK_RUNNING (UUID: be3f602f-e9b9-41df-96c6-6a944178b212) for task 0 of framework 2014-02-28-08:07:35-160088899-34779-23227-0000 from executor(23)@67.195.138.9:34779 I0228 08:07:35.681711 23254 status_update_manager.cpp:320] Received status update TASK_RUNNING (UUID: be3f602f-e9b9-41df-96c6-6a944178b212) for task 0 of framework 2014-02-28-08:07:35-160088899-34779-23227-0000 I0228 08:07:35.681740 23254 status_update_manager.cpp:499] Creating StatusUpdate stream for task 0 of framework 2014-02-28-08:07:35-160088899-34779-23227-0000 I0228 08:07:35.681807 23254 status_update_manager.cpp:373] Forwarding status update TASK_RUNNING (UUID: be3f602f-e9b9-41df-96c6-6a944178b212) for task 0 of framework 2014-02-28-08:07:35-160088899-34779-23227-0000 to [email protected]:34779 I0228 08:07:35.681918 23249 slave.cpp:1770] Status update manager successfully handled status update TASK_RUNNING (UUID: be3f602f-e9b9-41df-96c6-6a944178b212) for task 0 of framework 2014-02-28-08:07:35-160088899-34779-23227-0000 I0228 08:07:35.681934 23256 master.cpp:2040] Status update TASK_RUNNING (UUID: be3f602f-e9b9-41df-96c6-6a944178b212) for task 0 of framework 2014-02-28-08:07:35-160088899-34779-23227-0000 from slave(52)@67.195.138.9:34779 I0228 08:07:35.681952 23249 slave.cpp:1776] Sending acknowledgement for status update TASK_RUNNING (UUID: be3f602f-e9b9-41df-96c6-6a944178b212) for task 0 of framework 2014-02-28-08:07:35-160088899-34779-23227-0000 to executor(23)@67.195.138.9:34779 I0228 08:07:35.682004 23254 sched.cpp:619] Scheduler::statusUpdate took 21725ns I0228 08:07:35.682083 23253 exec.cpp:338] Executor received status update acknowledgement be3f602f-e9b9-41df-96c6-6a944178b212 for task 0 of framework 2014-02-28-08:07:35-160088899-34779-23227-0000 I0228 08:07:35.682293 23253 status_update_manager.cpp:398] Received status update acknowledgement (UUID: be3f602f-e9b9-41df-96c6-6a944178b212) for task 0 of framework 2014-02-28-08:07:35-160088899-34779-23227-0000 I0228 08:07:35.682374 23251 slave.cpp:2054] Executor 'default' of framework 2014-02-28-08:07:35-160088899-34779-23227-0000 has exited with status 0 I0228 08:07:35.683218 23251 slave.cpp:1650] Handling status update TASK_LOST (UUID: f23188d5-4f79-4642-81b0-4a98fce977fd) for task 0 of framework 2014-02-28-08:07:35-160088899-34779-23227-0000 from @0.0.0.0:0 I0228 08:07:35.683239 23251 slave.cpp:3149] Terminating task 0 I0228 08:07:35.683418 23255 status_update_manager.cpp:320] Received status update TASK_LOST (UUID: f23188d5-4f79-4642-81b0-4a98fce977fd) for task 0 of framework 2014-02-28-08:07:35-160088899-34779-23227-0000 I0228 08:07:35.683434 23254 master.cpp:2113] Executor default of framework 2014-02-28-08:07:35-160088899-34779-23227-0000 on slave 2014-02-28-08:07:35-160088899-34779-23227-0 (vesta.apache.org) has exited with status 0 I0228 08:07:35.683449 23251 slave.cpp:1263] Status update manager successfully handled status update acknowledgement (UUID: be3f602f-e9b9-41df-96c6-6a944178b212) for task 0 of framework 2014-02-28-08:07:35-160088899-34779-23227-0000 I0228 08:07:35.683456 23255 status_update_manager.cpp:373] Forwarding status update TASK_LOST (UUID: f23188d5-4f79-4642-81b0-4a98fce977fd) for task 0 of framework 2014-02-28-08:07:35-160088899-34779-23227-0000 to [email protected]:34779 I0228 08:07:35.683596 23252 slave.cpp:1770] Status update manager successfully handled status update TASK_LOST (UUID: f23188d5-4f79-4642-81b0-4a98fce977fd) for task 0 of framework 2014-02-28-08:07:35-160088899-34779-23227-0000 I0228 08:07:35.683604 23254 master.cpp:2040] Status update TASK_LOST (UUID: f23188d5-4f79-4642-81b0-4a98fce977fd) for task 0 of framework 2014-02-28-08:07:35-160088899-34779-23227-0000 from slave(52)@67.195.138.9:34779 I0228 08:07:35.683642 23254 master.hpp:441] Removing task 0 with resources cpus(*):2; mem(*):1024 on slave 2014-02-28-08:07:35-160088899-34779-23227-0 (vesta.apache.org) I0228 08:07:35.683660 23255 sched.cpp:619] Scheduler::statusUpdate took 7816ns I0228 08:07:35.683838 23251 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-08:07:35-160088899-34779-23227-0 from framework 2014-02-28-08:07:35-160088899-34779-23227-0000 I0228 08:07:35.683867 23255 status_update_manager.cpp:398] Received status update acknowledgement (UUID: f23188d5-4f79-4642-81b0-4a98fce977fd) for task 0 of framework 2014-02-28-08:07:35-160088899-34779-23227-0000 I0228 08:07:35.683902 23255 status_update_manager.cpp:530] Cleaning up status update stream for task 0 of framework 2014-02-28-08:07:35-160088899-34779-23227-0000 I0228 08:07:35.683987 23255 slave.cpp:1263] Status update manager successfully handled status update acknowledgement (UUID: f23188d5-4f79-4642-81b0-4a98fce977fd) for task 0 of framework 2014-02-28-08:07:35-160088899-34779-23227-0000 I0228 08:07:35.684005 23255 slave.cpp:3172] Completing task 0 I0228 08:07:35.684015 23255 slave.cpp:2193] Cleaning up executor 'default' of framework 2014-02-28-08:07:35-160088899-34779-23227-0000 I0228 08:07:35.684118 23255 slave.cpp:2268] Cleaning up framework 2014-02-28-08:07:35-160088899-34779-23227-0000 I0228 08:07:35.684119 23249 gc.cpp:56] Scheduling '/tmp/GarbageCollectorIntegrationTest_DiskUsage_fdGB0w/slaves/2014-02-28-08:07:35-160088899-34779-23227-0/frameworks/2014-02-28-08:07:35-160088899-34779-23227-0000/executors/default/runs/4cb7cc01-76d1-4c84-8013-f985d188b2c0' for gc 6.99999210438815days in the future I0228 08:07:35.684187 23249 gc.cpp:56] Scheduling '/tmp/GarbageCollectorIntegrationTest_DiskUsage_fdGB0w/slaves/2014-02-28-08:07:35-160088899-34779-23227-0/frameworks/2014-02-28-08:07:35-160088899-34779-23227-0000/executors/default' for gc 6.99999210438815days in the future I0228 08:07:35.684202 23254 status_update_manager.cpp:282] Closing status update streams for framework 2014-02-28-08:07:35-160088899-34779-23227-0000 I0228 08:07:35.684244 23249 gc.cpp:56] Scheduling '/tmp/GarbageCollectorIntegrationTest_DiskUsage_fdGB0w/slaves/2014-02-28-08:07:35-160088899-34779-23227-0/frameworks/2014-02-28-08:07:35-160088899-34779-23227-0000' for gc 6.99999210438815days in the future I0228 08:07:35.803177 23256 slave.cpp:2518] Current usage 90.00%. Max allowed age: 0ns I0228 08:07:35.803352 23249 gc.cpp:167] Pruning directories with remaining removal time 6.99999210438815days I0228 08:07:35.803411 23249 gc.cpp:134] Deleting /tmp/GarbageCollectorIntegrationTest_DiskUsage_fdGB0w/slaves/2014-02-28-08:07:35-160088899-34779-23227-0/frameworks/2014-02-28-08:07:35-160088899-34779-23227-0000/executors/default/runs/4cb7cc01-76d1-4c84-8013-f985d188b2c0 I0228 08:07:35.803469 23249 gc.cpp:143] Deleted '/tmp/GarbageCollectorIntegrationTest_DiskUsage_fdGB0w/slaves/2014-02-28-08:07:35-160088899-34779-23227-0/frameworks/2014-02-28-08:07:35-160088899-34779-23227-0000/executors/default/runs/4cb7cc01-76d1-4c84-8013-f985d188b2c0' I0228 08:07:35.803540 23249 gc.cpp:134] Deleting /tmp/GarbageCollectorIntegrationTest_DiskUsage_fdGB0w/slaves/2014-02-28-08:07:35-160088899-34779-23227-0/frameworks/2014-02-28-08:07:35-160088899-34779-23227-0000/executors/default I0228 08:07:35.803696 23249 gc.cpp:143] Deleted '/tmp/GarbageCollectorIntegrationTest_DiskUsage_fdGB0w/slaves/2014-02-28-08:07:35-160088899-34779-23227-0/frameworks/2014-02-28-08:07:35-160088899-34779-23227-0000/executors/default' I0228 08:07:35.803709 23249 gc.cpp:134] Deleting /tmp/GarbageCollectorIntegrationTest_DiskUsage_fdGB0w/slaves/2014-02-28-08:07:35-160088899-34779-23227-0/frameworks/2014-02-28-08:07:35-160088899-34779-23227-0000 I0228 08:07:35.803778 23249 gc.cpp:143] Deleted '/tmp/GarbageCollectorIntegrationTest_DiskUsage_fdGB0w/slaves/2014-02-28-08:07:35-160088899-34779-23227-0/frameworks/2014-02-28-08:07:35-160088899-34779-23227-0000' I0228 08:07:35.823490 23253 process.cpp:3189] Handling HTTP event for process 'files' with path: '/files/browse.json' I0228 08:07:35.823956 23264 process.cpp:1008] Socket closed while receiving I0228 08:07:35.833596 23252 sched.cpp:730] Stopping framework '2014-02-28-08:07:35-160088899-34779-23227-0000' I0228 08:07:35.833598 23227 master.cpp:527] Master terminating I0228 08:07:35.833855 23256 slave.cpp:1851] [email protected]:34779 exited W0228 08:07:35.833871 23256 slave.cpp:1854] Master disconnected! Waiting for a new master to be elected I0228 08:07:35.834291 23256 slave.cpp:294] Slave terminating [ OK ] GarbageCollectorIntegrationTest.DiskUsage (164 ms) [ RUN ] GarbageCollectorIntegrationTest.Unschedule I0228 08:07:35.835439 23227 master.cpp:252] Master ID: 2014-02-28-08:07:35-160088899-34779-23227 Hostname: vesta.apache.org I0228 08:07:35.835536 23250 master.cpp:262] Master started on 67.195.138.9:34779 I0228 08:07:35.835551 23250 master.cpp:265] Master only allowing authenticated frameworks to register! I0228 08:07:35.836045 23253 hierarchical_allocator_process.hpp:302] Initializing hierarchical allocator process with master : [email protected]:34779 I0228 08:07:35.836223 23250 master.cpp:96] No whitelist given. Advertising offers for all slaves I0228 08:07:35.836416 23250 master.cpp:770] The newly elected leader is [email protected]:34779 with id 2014-02-28-08:07:35-160088899-34779-23227 I0228 08:07:35.836426 23250 master.cpp:780] Elected as the leading master! I0228 08:07:35.837926 23250 slave.cpp:119] Slave started on 53)@67.195.138.9:34779 I0228 08:07:35.838050 23250 slave.cpp:129] Slave resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I0228 08:07:35.838161 23250 slave.cpp:157] Slave hostname: vesta.apache.org I0228 08:07:35.838171 23250 slave.cpp:158] Slave checkpoint: false I0228 08:07:35.838510 23256 state.cpp:33] Recovering state from '/tmp/GarbageCollectorIntegrationTest_Unschedule_3HsvhJ/meta' I0228 08:07:35.838685 23249 status_update_manager.cpp:193] Recovering status update manager I0228 08:07:35.838830 23250 slave.cpp:2677] Finished recovery I0228 08:07:35.839090 23250 slave.cpp:404] New master detected at [email protected]:34779 I0228 08:07:35.839141 23249 status_update_manager.cpp:167] New master detected at [email protected]:34779 I0228 08:07:35.839164 23250 slave.cpp:429] Detecting new master I0228 08:07:35.839197 23249 master.cpp:1857] Attempting to register slave on vesta.apache.org at slave(53)@67.195.138.9:34779 I0228 08:07:35.839210 23249 master.cpp:2840] Adding slave 2014-02-28-08:07:35-160088899-34779-23227-0 at vesta.apache.org with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I0228 08:07:35.839310 23253 slave.cpp:447] Registered with master [email protected]:34779; given slave ID 2014-02-28-08:07:35-160088899-34779-23227-0 I0228 08:07:35.839380 23254 hierarchical_allocator_process.hpp:445] Added slave 2014-02-28-08:07:35-160088899-34779-23227-0 (vesta.apache.org) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (and cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] available) I0228 08:07:35.839416 23254 hierarchical_allocator_process.hpp:708] Performed allocation for slave 2014-02-28-08:07:35-160088899-34779-23227-0 in 5335ns I0228 08:07:35.840620 23227 sched.cpp:121] Version: 0.19.0 I0228 08:07:35.840790 23255 sched.cpp:217] New master detected at [email protected]:34779 I0228 08:07:35.840807 23255 sched.cpp:267] Authenticating with master [email protected]:34779 I0228 08:07:35.840919 23249 authenticatee.hpp:128] Creating new client SASL connection I0228 08:07:35.840996 23250 master.cpp:2353] Authenticating framework at scheduler(70)@67.195.138.9:34779 I0228 08:07:35.841058 23249 authenticator.hpp:148] Creating new server SASL connection I0228 08:07:35.841138 23249 authenticatee.hpp:219] Received SASL authentication mechanisms: CRAM-MD5 I0228 08:07:35.841159 23249 authenticatee.hpp:245] Attempting to authenticate with mechanism 'CRAM-MD5' I0228 08:07:35.841187 23249 authenticator.hpp:254] Received SASL authentication start I0228 08:07:35.841238 23249 authenticator.hpp:342] Authentication requires more steps I0228 08:07:35.841280 23249 authenticatee.hpp:265] Received SASL authentication step I0228 08:07:35.841326 23249 authenticator.hpp:282] Received SASL authentication step I0228 08:07:35.841356 23249 auxprop.cpp:81] Request to lookup properties for user: 'test-principal' realm: 'vesta.apache.org' server FQDN: 'vesta.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I0228 08:07:35.841363 23249 auxprop.cpp:153] Looking up auxiliary property '*userPassword' I0228 08:07:35.841378 23249 auxprop.cpp:153] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I0228 08:07:35.841388 23249 auxprop.cpp:81] Request to lookup properties for user: 'test-principal' realm: 'vesta.apache.org' server FQDN: 'vesta.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I0228 08:07:35.841394 23249 auxprop.cpp:103] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I0228 08:07:35.841398 23249 auxprop.cpp:103] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I0228 08:07:35.841410 23249 authenticator.hpp:334] Authentication success I0228 08:07:35.841444 23249 authenticatee.hpp:305] Authentication success I0228 08:07:35.841464 23253 master.cpp:2393] Successfully authenticated framework at scheduler(70)@67.195.138.9:34779 I0228 08:07:35.841539 23249 sched.cpp:341] Successfully authenticated with master [email protected]:34779 I0228 08:07:35.841559 23249 sched.cpp:460] Sending registration request to [email protected]:34779 I0228 08:07:35.841646 23253 master.cpp:828] Received registration request from scheduler(70)@67.195.138.9:34779 I0228 08:07:35.841683 23253 master.cpp:846] Registering framework 2014-02-28-08:07:35-160088899-34779-23227-0000 at scheduler(70)@67.195.138.9:34779 I0228 08:07:35.841788 23250 sched.cpp:391] Framework registered with 2014-02-28-08:07:35-160088899-34779-23227-0000 I0228 08:07:35.841809 23255 hierarchical_allocator_process.hpp:332] Added framework 2014-02-28-08:07:35-160088899-34779-23227-0000 I0228 08:07:35.841826 23250 sched.cpp:405] Scheduler::registered took 21911ns I0228 08:07:35.841846 23255 hierarchical_allocator_process.hpp:752] Offering cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 2014-02-28-08:07:35-160088899-34779-23227-0 to framework 2014-02-28-08:07:35-160088899-34779-23227-0000 I0228 08:07:35.841989 23255 hierarchical_allocator_process.hpp:688] Performed allocation for 1 slaves in 166033ns I0228 08:07:35.842056 23250 master.hpp:451] Adding offer 2014-02-28-08:07:35-160088899-34779-23227-0 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 2014-02-28-08:07:35-160088899-34779-23227-0 (vesta.apache.org) I0228 08:07:35.842121 23250 master.cpp:2308] Sending 1 offers to framework 2014-02-28-08:07:35-160088899-34779-23227-0000 I0228 08:07:35.842385 23249 sched.cpp:528] Scheduler::resourceOffers took 149836ns I0228 08:07:35.842535 23250 master.hpp:461] Removing offer 2014-02-28-08:07:35-160088899-34779-23227-0 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 2014-02-28-08:07:35-160088899-34779-23227-0 (vesta.apache.org) I0228 08:07:35.842586 23250 master.cpp:1584] Processing reply for offers: [ 2014-02-28-08:07:35-160088899-34779-23227-0 ] on slave 2014-02-28-08:07:35-160088899-34779-23227-0 (vesta.apache.org) for framework 2014-02-28-08:07:35-160088899-34779-23227-0000 I0228 08:07:35.842658 23250 master.hpp:423] Adding task 0 with resources cpus(*):2; mem(*):1024 on slave 2014-02-28-08:07:35-160088899-34779-23227-0 (vesta.apache.org) I0228 08:07:35.842689 23250 master.cpp:2477] Launching task 0 of framework 2014-02-28-08:07:35-160088899-34779-23227-0000 with resources cpus(*):2; mem(*):1024 on slave 2014-02-28-08:07:35-160088899-34779-23227-0 (vesta.apache.org) I0228 08:07:35.842757 23249 slave.cpp:632] Got assigned task 0 for framework 2014-02-28-08:07:35-160088899-34779-23227-0000 I0228 08:07:35.842808 23250 hierarchical_allocator_process.hpp:547] Framework 2014-02-28-08:07:35-160088899-34779-23227-0000 left disk(*):1024; ports(*):[31000-32000] unused on slave 2014-02-28-08:07:35-160088899-34779-23227-0 I0228 08:07:35.842882 23250 hierarchical_allocator_process.hpp:590] Framework 2014-02-28-08:07:35-160088899-34779-23227-0000 filtered slave 2014-02-28-08:07:35-160088899-34779-23227-0 for 5secs I0228 08:07:35.842926 23249 slave.cpp:741] Launching task 0 for framework 2014-02-28-08:07:35-160088899-34779-23227-0000 I0228 08:07:35.844737 23249 exec.cpp:131] Version: 0.19.0 I0228 08:07:35.844804 23255 exec.cpp:181] Executor started at: executor(24)@67.195.138.9:34779 with pid 23227 I0228 08:07:35.844841 23249 slave.cpp:851] Queuing task '0' for executor executor-1 of framework '2014-02-28-08:07:35-160088899-34779-23227-0000 I0228 08:07:35.844880 23249 slave.cpp:365] Successfully attached file '/tmp/GarbageCollectorIntegrationTest_Unschedule_3HsvhJ/slaves/2014-02-28-08:07:35-160088899-34779-23227-0/frameworks/2014-02-28-08:07:35-160088899-34779-23227-0000/executors/executor-1/runs/875aee85-3cc6-4b77-801f-e99b933e6d4e' I0228 08:07:35.844902 23249 slave.cpp:1996] Monitoring executor 'executor-1' of framework '2014-02-28-08:07:35-160088899-34779-23227-0000' in container '875aee85-3cc6-4b77-801f-e99b933e6d4e' I0228 08:07:35.844960 23249 slave.cpp:1323] Got registration for executor 'executor-1' of framework 2014-02-28-08:07:35-160088899-34779-23227-0000 I0228 08:07:35.845119 23249 slave.cpp:1441] Flushing queued task 0 for executor 'executor-1' of framework 2014-02-28-08:07:35-160088899-34779-23227-0000 I0228 08:07:35.845146 23254 exec.cpp:205] Executor registered on slave 2014-02-28-08:07:35-160088899-34779-23227-0 I0228 08:07:35.845962 23254 exec.cpp:217] Executor::registered took 19347ns I0228 08:07:35.846014 23254 exec.cpp:292] Executor asked to run task '0' I0228 08:07:35.846051 23254 exec.cpp:301] Executor::launchTask took 21186ns I0228 08:07:35.846833 23254 exec.cpp:524] Executor sending status update TASK_RUNNING (UUID: 265f8e9d-92af-4b5c-b604-ee1b17c655a7) for task 0 of framework 2014-02-28-08:07:35-160088899-34779-23227-0000 I0228 08:07:35.846963 23251 slave.cpp:1650] Handling status update TASK_RUNNING (UUID: 265f8e9d-92af-4b5c-b604-ee1b17c655a7) for task 0 of framework 2014-02-28-08:07:35-160088899-34779-23227-0000 from executor(24)@67.195.138.9:34779 I0228 08:07:35.847041 23253 status_update_manager.cpp:320] Received status update TASK_RUNNING (UUID: 265f8e9d-92af-4b5c-b604-ee1b17c655a7) for task 0 of framework 2014-02-28-08:07:35-160088899-34779-23227-0000 I0228 08:07:35.847059 23253 status_update_manager.cpp:499] Creating StatusUpdate stream for task 0 of framework 2014-02-28-08:07:35-160088899-34779-23227-0000 I0228 08:07:35.847107 23253 status_update_manager.cpp:373] Forwarding status update TASK_RUNNING (UUID: 265f8e9d-92af-4b5c-b604-ee1b17c655a7) for task 0 of framework 2014-02-28-08:07:35-160088899-34779-23227-0000 to [email protected]:34779 I0228 08:07:35.847210 23250 slave.cpp:1770] Status update manager successfully handled status update TASK_RUNNING (UUID: 265f8e9d-92af-4b5c-b604-ee1b17c655a7) for task 0 of framework 2014-02-28-08:07:35-160088899-34779-23227-0000 I0228 08:07:35.847232 23251 master.cpp:2040] Status update TASK_RUNNING (UUID: 265f8e9d-92af-4b5c-b604-ee1b17c655a7) for task 0 of framework 2014-02-28-08:07:35-160088899-34779-23227-0000 from slave(53)@67.195.138.9:34779 I0228 08:07:35.847259 23250 slave.cpp:1776] Sending acknowledgement for status update TASK_RUNNING (UUID: 265f8e9d-92af-4b5c-b604-ee1b17c655a7) for task 0 of framework 2014-02-28-08:07:35-160088899-34779-23227-0000 to executor(24)@67.195.138.9:34779 I0228 08:07:35.847296 23253 sched.cpp:619] Scheduler::statusUpdate took 23047ns I0228 08:07:35.847420 23249 exec.cpp:338] Executor received status update acknowledgement 265f8e9d-92af-4b5c-b604-ee1b17c655a7 for task 0 of framework 2014-02-28-08:07:35-160088899-34779-23227-0000 I0228 08:07:35.847481 23253 status_update_manager.cpp:398] Received status update acknowledgement (UUID: 265f8e9d-92af-4b5c-b604-ee1b17c655a7) for task 0 of framework 2014-02-28-08:07:35-160088899-34779-23227-0000 I0228 08:07:35.847554 23253 slave.cpp:1263] Status update manager successfully handled status update acknowledgement (UUID: 265f8e9d-92af-4b5c-b604-ee1b17c655a7) for task 0 of framework 2014-02-28-08:07:35-160088899-34779-23227-0000 I0228 08:07:35.847858 23251 slave.cpp:2054] Executor 'executor-1' of framework 2014-02-28-08:07:35-160088899-34779-23227-0000 has exited with status 0 I0228 08:07:35.848661 23251 slave.cpp:1650] Handling status update TASK_LOST (UUID: 87d6616b-53ee-4548-b4e7-508074074152) for task 0 of framework 2014-02-28-08:07:35-160088899-34779-23227-0000 from @0.0.0.0:0 I0228 08:07:35.848678 23251 slave.cpp:3149] Terminating task 0 I0228 08:07:35.848796 23250 status_update_manager.cpp:320] Received status update TASK_LOST (UUID: 87d6616b-53ee-4548-b4e7-508074074152) for task 0 of framework 2014-02-28-08:07:35-160088899-34779-23227-0000 I0228 08:07:35.848839 23250 status_update_manager.cpp:373] Forwarding status update TASK_LOST (UUID: 87d6616b-53ee-4548-b4e7-508074074152) for task 0 of framework 2014-02-28-08:07:35-160088899-34779-23227-0000 to [email protected]:34779 I0228 08:07:35.848848 23256 master.cpp:2113] Executor executor-1 of framework 2014-02-28-08:07:35-160088899-34779-23227-0000 on slave 2014-02-28-08:07:35-160088899-34779-23227-0 (vesta.apache.org) has exited with status 0 I0228 08:07:35.848989 23253 slave.cpp:1770] Status update manager successfully handled status update TASK_LOST (UUID: 87d6616b-53ee-4548-b4e7-508074074152) for task 0 of framework 2014-02-28-08:07:35-160088899-34779-23227-0000 I0228 08:07:35.849020 23256 master.cpp:2040] Status update TASK_LOST (UUID: 87d6616b-53ee-4548-b4e7-508074074152) for task 0 of framework 2014-02-28-08:07:35-160088899-34779-23227-0000 from slave(53)@67.195.138.9:34779 I0228 08:07:35.849056 23253 sched.cpp:619] Scheduler::statusUpdate took 6857ns I0228 08:07:35.849066 23256 master.hpp:441] Removing task 0 with resources cpus(*):2; mem(*):1024 on slave 2014-02-28-08:07:35-160088899-34779-23227-0 (vesta.apache.org) I0228 08:07:35.849198 23251 status_update_manager.cpp:398] Received status update acknowledgement (UUID: 87d6616b-53ee-4548-b4e7-508074074152) for task 0 of framework 2014-02-28-08:07:35-160088899-34779-23227-0000 I0228 08:07:35.849207 23250 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-08:07:35-160088899-34779-23227-0 from framework 2014-02-28-08:07:35-160088899-34779-23227-0000 I0228 08:07:35.849231 23251 status_update_manager.cpp:530] Cleaning up status update stream for task 0 of framework 2014-02-28-08:07:35-160088899-34779-23227-0000 I0228 08:07:35.849300 23251 slave.cpp:1263] Status update manager successfully handled status update acknowledgement (UUID: 87d6616b-53ee-4548-b4e7-508074074152) for task 0 of framework 2014-02-28-08:07:35-160088899-34779-23227-0000 I0228 08:07:35.849319 23251 slave.cpp:3172] Completing task 0 I0228 08:07:35.849328 23251 slave.cpp:2193] Cleaning up executor 'executor-1' of framework 2014-02-28-08:07:35-160088899-34779-23227-0000 I0228 08:07:35.849449 23251 slave.cpp:2268] Cleaning up framework 2014-02-28-08:07:35-160088899-34779-23227-0000 I0228 08:07:35.849452 23249 gc.cpp:56] Scheduling '/tmp/GarbageCollectorIntegrationTest_Unschedule_3HsvhJ/slaves/2014-02-28-08:07:35-160088899-34779-23227-0/frameworks/2014-02-28-08:07:35-160088899-34779-23227-0000/executors/executor-1/runs/875aee85-3cc6-4b77-801f-e99b933e6d4e' for gc 6.99999018802074days in the future I0228 08:07:35.849503 23253 status_update_manager.cpp:282] Closing status update streams for framework 2014-02-28-08:07:35-160088899-34779-23227-0000 I0228 08:07:35.849526 23249 gc.cpp:56] Scheduling '/tmp/GarbageCollectorIntegrationTest_Unschedule_3HsvhJ/slaves/2014-02-28-08:07:35-160088899-34779-23227-0/frameworks/2014-02-28-08:07:35-160088899-34779-23227-0000/executors/executor-1' for gc 6.99999018802074days in the future I0228 08:07:35.849571 23249 gc.cpp:56] Scheduling '/tmp/GarbageCollectorIntegrationTest_Unschedule_3HsvhJ/slaves/2014-02-28-08:07:35-160088899-34779-23227-0/frameworks/2014-02-28-08:07:35-160088899-34779-23227-0000' for gc 6.99999018802074days in the future I0228 08:07:35.978775 23253 hierarchical_allocator_process.hpp:752] Offering disk(*):1024; ports(*):[31000-32000]; cpus(*):2; mem(*):1024 on slave 2014-02-28-08:07:35-160088899-34779-23227-0 to framework 2014-02-28-08:07:35-160088899-34779-23227-0000 I0228 08:07:35.978905 23253 hierarchical_allocator_process.hpp:688] Performed allocation for 1 slaves in 168537ns I0228 08:07:35.978968 23255 master.hpp:451] Adding offer 2014-02-28-08:07:35-160088899-34779-23227-1 with resources disk(*):1024; ports(*):[31000-32000]; cpus(*):2; mem(*):1024 on slave 2014-02-28-08:07:35-160088899-34779-23227-0 (vesta.apache.org) I0228 08:07:35.979027 23255 master.cpp:2308] Sending 1 offers to framework 2014-02-28-08:07:35-160088899-34779-23227-0000 I0228 08:07:35.979270 23255 sched.cpp:528] Scheduler::resourceOffers took 150422ns I0228 08:07:35.979418 23255 master.hpp:461] Removing offer 2014-02-28-08:07:35-160088899-34779-23227-1 with resources disk(*):1024; ports(*):[31000-32000]; cpus(*):2; mem(*):1024 on slave 2014-02-28-08:07:35-160088899-34779-23227-0 (vesta.apache.org) I0228 08:07:35.979464 23255 master.cpp:1584] Processing reply for offers: [ 2014-02-28-08:07:35-160088899-34779-23227-1 ] on slave 2014-02-28-08:07:35-160088899-34779-23227-0 (vesta.apache.org) for framework 2014-02-28-08:07:35-160088899-34779-23227-0000 I0228 08:07:35.979529 23255 master.hpp:423] Adding task 0 with resources cpus(*):2; mem(*):1024 on slave 2014-02-28-08:07:35-160088899-34779-23227-0 (vesta.apache.org) I0228 08:07:35.979562 23255 master.cpp:2477] Launching task 0 of framework 2014-02-28-08:07:35-160088899-34779-23227-0000 with resources cpus(*):2; mem(*):1024 on slave 2014-02-28-08:07:35-160088899-34779-23227-0 (vesta.apache.org) I0228 08:07:35.979701 23250 slave.cpp:632] Got assigned task 0 for framework 2014-02-28-08:07:35-160088899-34779-23227-0000 I0228 08:07:35.979704 23254 hierarchical_allocator_process.hpp:547] Framework 2014-02-28-08:07:35-160088899-34779-23227-0000 left disk(*):1024; ports(*):[31000-32000] unused on slave 2014-02-28-08:07:35-160088899-34779-23227-0 I0228 08:07:35.979797 23254 hierarchical_allocator_process.hpp:590] Framework 2014-02-28-08:07:35-160088899-34779-23227-0000 filtered slave 2014-02-28-08:07:35-160088899-34779-23227-0 for 5secs I0228 08:07:35.980029 23250 gc.cpp:84] Unscheduling '/tmp/GarbageCollectorIntegrationTest_Unschedule_3HsvhJ/slaves/2014-02-28-08:07:35-160088899-34779-23227-0/frameworks/2014-02-28-08:07:35-160088899-34779-23227-0000' from gc I0228 08:07:35.980131 23250 slave.cpp:741] Launching task 0 for framework 2014-02-28-08:07:35-160088899-34779-23227-0000 I0228 08:07:35.981947 23250 exec.cpp:131] Version: 0.19.0 I0228 08:07:35.982002 23249 exec.cpp:181] Executor started at: executor(25)@67.195.138.9:34779 with pid 23227 I0228 08:07:35.982082 23250 slave.cpp:851] Queuing task '0' for executor executor-2 of framework '2014-02-28-08:07:35-160088899-34779-23227-0000 I0228 08:07:35.982143 23250 slave.cpp:365] Successfully attached file '/tmp/GarbageCollectorIntegrationTest_Unschedule_3HsvhJ/slaves/2014-02-28-08:07:35-160088899-34779-23227-0/frameworks/2014-02-28-08:07:35-160088899-34779-23227-0000/executors/executor-2/runs/f157fcf2-4b68-4ce2-ace4-d34fc7d5e994' I0228 08:07:35.982168 23250 slave.cpp:1996] Monitoring executor 'executor-2' of framework '2014-02-28-08:07:35-160088899-34779-23227-0000' in container 'f157fcf2-4b68-4ce2-ace4-d34fc7d5e994' I0228 08:07:35.982221 23250 slave.cpp:1323] Got registration for executor 'executor-2' of framework 2014-02-28-08:07:35-160088899-34779-23227-0000 I0228 08:07:35.982404 23250 slave.cpp:1441] Flushing queued task 0 for executor 'executor-2' of framework 2014-02-28-08:07:35-160088899-34779-23227-0000 I0228 08:07:35.982414 23255 exec.cpp:205] Executor registered on slave 2014-02-28-08:07:35-160088899-34779-23227-0 I0228 08:07:35.983217 23255 exec.cpp:217] Executor::registered took 10273ns I0228 08:07:35.983261 23255 exec.cpp:292] Executor asked to run task '0' I0228 08:07:35.983294 23255 exec.cpp:301] Executor::launchTask took 15310ns I0228 08:07:35.984050 23255 exec.cpp:524] Executor sending status update TASK_RUNNING (UUID: e868a15a-9db7-4647-9a86-7f88d0764cfa) for task 0 of framework 2014-02-28-08:07:35-160088899-34779-23227-0000 I0228 08:07:35.984112 23255 slave.cpp:1650] Handling status update TASK_RUNNING (UUID: e868a15a-9db7-4647-9a86-7f88d0764cfa) for task 0 of framework 2014-02-28-08:07:35-160088899-34779-23227-0000 from executor(25)@67.195.138.9:34779 I0228 08:07:35.984230 23252 status_update_manager.cpp:320] Received status update TASK_RUNNING (UUID: e868a15a-9db7-4647-9a86-7f88d0764cfa) for task 0 of framework 2014-02-28-08:07:35-160088899-34779-23227-0000 I0228 08:07:35.984259 23252 status_update_manager.cpp:499] Creating StatusUpdate stream for task 0 of framework 2014-02-28-08:07:35-160088899-34779-23227-0000 I0228 08:07:35.984340 23252 status_update_manager.cpp:373] Forwarding status update TASK_RUNNING (UUID: e868a15a-9db7-4647-9a86-7f88d0764cfa) for task 0 of framework 2014-02-28-08:07:35-160088899-34779-23227-0000 to [email protected]:34779 I0228 08:07:35.984442 23256 slave.cpp:1770] Status update manager successfully handled status update TASK_RUNNING (UUID: e868a15a-9db7-4647-9a86-7f88d0764cfa) for task 0 of framework 2014-02-28-08:07:35-160088899-34779-23227-0000 I0228 08:07:35.984462 23256 slave.cpp:1776] Sending acknowledgement for status update TASK_RUNNING (UUID: e868a15a-9db7-4647-9a86-7f88d0764cfa) for task 0 of framework 2014-02-28-08:07:35-160088899-34779-23227-0000 to executor(25)@67.195.138.9:34779 I0228 08:07:35.984469 23255 master.cpp:2040] Status update TASK_RUNNING (UUID: e868a15a-9db7-4647-9a86-7f88d0764cfa) for task 0 of framework 2014-02-28-08:07:35-160088899-34779-23227-0000 from slave(53)@67.195.138.9:34779 I0228 08:07:35.984524 23255 exec.cpp:338] Executor received status update acknowledgement e868a15a-9db7-4647-9a86-7f88d0764cfa for task 0 of framework 2014-02-28-08:07:35-160088899-34779-23227-0000 I0228 08:07:35.984534 23254 sched.cpp:619] Scheduler::statusUpdate took 34067ns I0228 08:07:35.984655 23254 status_update_manager.cpp:398] Received status update acknowledgement (UUID: e868a15a-9db7-4647-9a86-7f88d0764cfa) for task 0 of framework 2014-02-28-08:07:35-160088899-34779-23227-0000 I0228 08:07:35.984719 23254 slave.cpp:1263] Status update manager successfully handled status update acknowledgement (UUID: e868a15a-9db7-4647-9a86-7f88d0764cfa) for task 0 of framework 2014-02-28-08:07:35-160088899-34779-23227-0000 I0228 08:07:35.998736 23227 master.cpp:527] Master terminating I0228 08:07:35.998795 23250 sched.cpp:730] Stopping framework '2014-02-28-08:07:35-160088899-34779-23227-0000' I0228 08:07:35.998841 23227 master.hpp:441] Removing task 0 with resources cpus(*):2; mem(*):1024 on slave 2014-02-28-08:07:35-160088899-34779-23227-0 (vesta.apache.org) I0228 08:07:35.998972 23249 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-08:07:35-160088899-34779-23227-0 from framework 2014-02-28-08:07:35-160088899-34779-23227-0000 I0228 08:07:35.999173 23251 slave.cpp:1851] [email protected]:34779 exited W0228 08:07:35.999186 23251 slave.cpp:1854] Master disconnected! Waiting for a new master to be elected I0228 08:07:35.999644 23227 slave.cpp:294] Slave terminating I0228 08:07:35.999671 23227 slave.cpp:1034] Asked to shut down framework 2014-02-28-08:07:35-160088899-34779-23227-0000 by @0.0.0.0:0 I0228 08:07:35.999680 23227 slave.cpp:1059] Shutting down framework 2014-02-28-08:07:35-160088899-34779-23227-0000 I0228 08:07:35.999697 23227 slave.cpp:2339] Shutting down executor 'executor-2' of framework 2014-02-28-08:07:35-160088899-34779-23227-0000 I0228 08:07:35.999850 23250 exec.cpp:378] Executor asked to shutdown I0228 08:07:35.999881 23250 exec.cpp:393] Executor::shutdown took 10778ns [ OK ] GarbageCollectorIntegrationTest.Unschedule (166 ms) [----------] 5 tests from GarbageCollectorIntegrationTest (868 ms total) [----------] 2 tests from CpuIsolatorTest/0, where TypeParam = mesos::internal::slave::PosixCpuIsolatorProcess [ RUN ] CpuIsolatorTest/0.UserCpuUsage I0228 08:07:36.001857 23227 launcher.cpp:120] Forked child with pid '24199' for container 'user_cpu_usage' [ OK ] CpuIsolatorTest/0.UserCpuUsage (1006 ms) [ RUN ] CpuIsolatorTest/0.SystemCpuUsage I0228 08:07:37.007830 23227 launcher.cpp:120] Forked child with pid '24203' for container 'system_cpu_usage' [ OK ] CpuIsolatorTest/0.SystemCpuUsage (998 ms) [----------] 2 tests from CpuIsolatorTest/0 (2004 ms total) [----------] 1 test from LimitedCpuIsolatorTest [ RUN ] LimitedCpuIsolatorTest.CgroupsCfs F0228 08:07:38.004500 23227 isolator_tests.cpp:315] CHECK_SOME(isolator): Failed to create isolator: Using cgroups requires root permissions *** Check failure stack trace: *** @ 0x2b107001f2fd google::LogMessage::Fail() @ 0x2b10700213ff google::LogMessage::SendToLog() @ 0x2b107001eeec google::LogMessage::Flush() @ 0x2b1070021c9d 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 @ 0x2b10713e476d (unknown) @ 0x4b27b1 (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
