You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@mesos.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2013/06/28 07:52:45 UTC
Build failed in Jenkins:
Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui
#1283
See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/1283/>
------------------------------------------
[...truncated 53668 lines...]
I0628 05:52:42.398442 4440 master.hpp:313] Adding offer 201306280552-453759884-56904-4314-2 with resources cpus=4; mem=2048; ports=[31000-32000]; disk=55861 on slave 201306280552-453759884-56904-4314-1
I0628 05:52:42.400142 4440 master.cpp:1239] Sending 1 offers to framework 201306280552-453759884-56904-4314-0000
I0628 05:52:42.400923 4446 master.cpp:1472] Processing reply for offer 201306280552-453759884-56904-4314-2 on slave 201306280552-453759884-56904-4314-1 (hemera.apache.org) for framework 201306280552-453759884-56904-4314-0000
I0628 05:52:42.401890 4446 master.hpp:323] Removing offer 201306280552-453759884-56904-4314-2 with resources cpus=4; mem=2048; ports=[31000-32000]; disk=55861 on slave 201306280552-453759884-56904-4314-1
I0628 05:52:42.401981 4450 hierarchical_allocator_process.hpp:526] Framework 201306280552-453759884-56904-4314-0000 left cpus=4; mem=2048; ports=[31000-32000]; disk=55861 unused on slave 201306280552-453759884-56904-4314-1
I0628 05:52:42.449682 4440 hierarchical_allocator_process.hpp:797] Filtered cpus=1; mem=512; ports=[31000-32000]; disk=55861 on slave 201306280552-453759884-56904-4314-0 for framework 201306280552-453759884-56904-4314-0000
I0628 05:52:42.451323 4440 hierarchical_allocator_process.hpp:727] Offering cpus=4; mem=2048; ports=[31000-32000]; disk=55861 on slave 201306280552-453759884-56904-4314-1 to framework 201306280552-453759884-56904-4314-0000
I0628 05:52:42.452591 4440 hierarchical_allocator_process.hpp:667] Performed allocation for 2 slaves in 2.939593ms
I0628 05:52:42.452713 4439 master.hpp:313] Adding offer 201306280552-453759884-56904-4314-3 with resources cpus=4; mem=2048; ports=[31000-32000]; disk=55861 on slave 201306280552-453759884-56904-4314-1
I0628 05:52:42.454818 4439 master.cpp:1239] Sending 1 offers to framework 201306280552-453759884-56904-4314-0000
I0628 05:52:42.455713 4448 master.cpp:1472] Processing reply for offer 201306280552-453759884-56904-4314-3 on slave 201306280552-453759884-56904-4314-1 (hemera.apache.org) for framework 201306280552-453759884-56904-4314-0000
I0628 05:52:42.456562 4448 master.hpp:323] Removing offer 201306280552-453759884-56904-4314-3 with resources cpus=4; mem=2048; ports=[31000-32000]; disk=55861 on slave 201306280552-453759884-56904-4314-1
I0628 05:52:42.456637 4446 hierarchical_allocator_process.hpp:526] Framework 201306280552-453759884-56904-4314-0000 left cpus=4; mem=2048; ports=[31000-32000]; disk=55861 unused on slave 201306280552-453759884-56904-4314-1
I0628 05:52:42.503993 4439 hierarchical_allocator_process.hpp:727] Offering cpus=1; mem=512; ports=[31000-32000]; disk=55861 on slave 201306280552-453759884-56904-4314-0 to framework 201306280552-453759884-56904-4314-0000
I0628 05:52:42.505126 4439 hierarchical_allocator_process.hpp:727] Offering cpus=4; mem=2048; ports=[31000-32000]; disk=55861 on slave 201306280552-453759884-56904-4314-1 to framework 201306280552-453759884-56904-4314-0000
I0628 05:52:42.506307 4439 hierarchical_allocator_process.hpp:667] Performed allocation for 2 slaves in 2.338154ms
I0628 05:52:42.506415 4443 master.hpp:313] Adding offer 201306280552-453759884-56904-4314-4 with resources cpus=1; mem=512; ports=[31000-32000]; disk=55861 on slave 201306280552-453759884-56904-4314-0
I0628 05:52:42.508946 4443 master.hpp:313] Adding offer 201306280552-453759884-56904-4314-5 with resources cpus=4; mem=2048; ports=[31000-32000]; disk=55861 on slave 201306280552-453759884-56904-4314-1
I0628 05:52:42.509860 4443 master.cpp:1239] Sending 2 offers to framework 201306280552-453759884-56904-4314-0000
I0628 05:52:42.510926 4433 sched.cpp:427] Stopping framework '201306280552-453759884-56904-4314-0000'
I0628 05:52:42.510946 4314 master.cpp:385] Master terminating
I0628 05:52:42.512903 4314 master.cpp:207] Shutting down master
I0628 05:52:42.512953 4437 slave.cpp:484] Slave asked to shut down by master@140.211.11.27:56904
I0628 05:52:42.514961 4437 slave.cpp:439] Slave terminating
I0628 05:52:42.513991 4314 master.hpp:303] Removing task 0 with resources cpus=2; mem=512 on slave 201306280552-453759884-56904-4314-0
I0628 05:52:42.514076 4431 hierarchical_allocator_process.hpp:412] Deactivated framework 201306280552-453759884-56904-4314-0000
I0628 05:52:42.513051 4445 slave.cpp:484] Slave asked to shut down by master@140.211.11.27:56904
I0628 05:52:42.516532 4314 master.hpp:323] Removing offer 201306280552-453759884-56904-4314-5 with resources cpus=4; mem=2048; ports=[31000-32000]; disk=55861 on slave 201306280552-453759884-56904-4314-1
I0628 05:52:42.517346 4431 hierarchical_allocator_process.hpp:616] Recovered cpus=2; mem=512 (total allocatable: cpus=2; mem=512; ports=[]; disk=0) on slave 201306280552-453759884-56904-4314-0 from framework 201306280552-453759884-56904-4314-0000
I0628 05:52:42.517894 4445 slave.cpp:1111] Asked to shut down framework 201306280552-453759884-56904-4314-0000 by master@140.211.11.27:56904
I0628 05:52:42.518484 4314 master.hpp:323] Removing offer 201306280552-453759884-56904-4314-4 with resources cpus=1; mem=512; ports=[31000-32000]; disk=55861 on slave 201306280552-453759884-56904-4314-0
I0628 05:52:42.519191 4431 hierarchical_allocator_process.hpp:616] Recovered cpus=4; mem=2048; ports=[31000-32000]; disk=55861 (total allocatable: cpus=4; mem=2048; ports=[31000-32000]; disk=55861) on slave 201306280552-453759884-56904-4314-1 from framework 201306280552-453759884-56904-4314-0000
I0628 05:52:42.519511 4445 slave.cpp:1136] Shutting down framework 201306280552-453759884-56904-4314-0000
I0628 05:52:42.520926 4431 hierarchical_allocator_process.hpp:616] Recovered cpus=1; mem=512; ports=[31000-32000]; disk=55861 (total allocatable: cpus=3; mem=1024; ports=[31000-32000]; disk=55861) on slave 201306280552-453759884-56904-4314-0 from framework 201306280552-453759884-56904-4314-0000
I0628 05:52:42.521272 4445 slave.cpp:2327] Shutting down executor 'default' of framework 201306280552-453759884-56904-4314-0000
I0628 05:52:42.521700 4431 hierarchical_allocator_process.hpp:367] Removed framework 201306280552-453759884-56904-4314-0000
I0628 05:52:42.522079 4429 exec.cpp:323] Executor asked to shutdown
I0628 05:52:42.522079 4445 slave.cpp:439] Slave terminating
I0628 05:52:42.522404 4431 hierarchical_allocator_process.hpp:477] Removed slave 201306280552-453759884-56904-4314-1
I0628 05:52:42.523087 4445 slave.cpp:1111] Asked to shut down framework 201306280552-453759884-56904-4314-0000 by @0.0.0.0:0
I0628 05:52:42.523418 4431 hierarchical_allocator_process.hpp:477] Removed slave 201306280552-453759884-56904-4314-0
W0628 05:52:42.523736 4445 slave.cpp:1132] Ignoring shutdown framework 201306280552-453759884-56904-4314-0000 because it is terminating
[ OK ] AllocatorTest/0.SlaveAdded (182 ms)
[ RUN ] AllocatorTest/0.TaskFinished
I0628 05:52:42.525843 4450 master.cpp:228] Master started on 140.211.11.27:56904
I0628 05:52:42.526249 4450 master.cpp:243] Master ID: 201306280552-453759884-56904-4314
W0628 05:52:42.526801 4445 master.cpp:83] No whitelist given. Advertising offers for all slaves
I0628 05:52:42.527009 4450 master.cpp:526] Elected as master!
I0628 05:52:42.527076 4432 hierarchical_allocator_process.hpp:295] Initializing hierarchical allocator process with master : master@140.211.11.27:56904
I0628 05:52:42.527164 4430 slave.cpp:112] Slave started on 489)@140.211.11.27:56904
I0628 05:52:42.527753 4435 sched.cpp:177] New master at master@140.211.11.27:56904
I0628 05:52:42.528537 4430 slave.cpp:204] Slave resources: cpus=3; mem=1024; ports=[31000-32000]; disk=55861
I0628 05:52:42.528818 4435 master.cpp:569] Registering framework 201306280552-453759884-56904-4314-0000 at scheduler(444)@140.211.11.27:56904
I0628 05:52:42.529340 4445 sched.cpp:222] Framework registered with 201306280552-453759884-56904-4314-0000
I0628 05:52:42.529384 4438 hierarchical_allocator_process.hpp:327] Added framework 201306280552-453759884-56904-4314-0000
I0628 05:52:42.529647 4430 slave.cpp:528] New master detected at master@140.211.11.27:56904
I0628 05:52:42.529921 4438 hierarchical_allocator_process.hpp:705] No resources available to allocate!
I0628 05:52:42.530074 4430 slave.cpp:543] Postponing registration until recovery is complete
I0628 05:52:42.530087 4447 status_update_manager.cpp:155] New master detected at master@140.211.11.27:56904
I0628 05:52:42.530354 4438 hierarchical_allocator_process.hpp:667] Performed allocation for 0 slaves in 416.212us
I0628 05:52:42.530519 4430 slave.cpp:389] Finished recovery
I0628 05:52:42.531028 4442 master.cpp:891] Attempting to register slave on hemera.apache.org at slave(489)@140.211.11.27:56904
I0628 05:52:42.531160 4442 master.cpp:1851] Adding slave 201306280552-453759884-56904-4314-0 at hemera.apache.org with cpus=3; mem=1024; ports=[31000-32000]; disk=55861
I0628 05:52:42.531350 4433 slave.cpp:588] Registered with master master@140.211.11.27:56904; given slave ID 201306280552-453759884-56904-4314-0
I0628 05:52:42.531523 4436 hierarchical_allocator_process.hpp:449] Added slave 201306280552-453759884-56904-4314-0 (hemera.apache.org) with cpus=3; mem=1024; ports=[31000-32000]; disk=55861 (and cpus=3; mem=1024; ports=[31000-32000]; disk=55861 available)
I0628 05:52:42.531783 4436 hierarchical_allocator_process.hpp:727] Offering cpus=3; mem=1024; ports=[31000-32000]; disk=55861 on slave 201306280552-453759884-56904-4314-0 to framework 201306280552-453759884-56904-4314-0000
I0628 05:52:42.532112 4436 hierarchical_allocator_process.hpp:687] Performed allocation for slave 201306280552-453759884-56904-4314-0 in 338.903us
I0628 05:52:42.532145 4433 master.hpp:313] Adding offer 201306280552-453759884-56904-4314-0 with resources cpus=3; mem=1024; ports=[31000-32000]; disk=55861 on slave 201306280552-453759884-56904-4314-0
I0628 05:52:42.532480 4433 master.cpp:1239] Sending 1 offers to framework 201306280552-453759884-56904-4314-0000
I0628 05:52:42.532933 4437 master.cpp:1472] Processing reply for offer 201306280552-453759884-56904-4314-0 on slave 201306280552-453759884-56904-4314-0 (hemera.apache.org) for framework 201306280552-453759884-56904-4314-0000
I0628 05:52:42.533151 4437 master.hpp:291] Adding task 0 with resources cpus=1; mem=256 on slave 201306280552-453759884-56904-4314-0
I0628 05:52:42.533476 4437 master.cpp:1591] Launching task 0 of framework 201306280552-453759884-56904-4314-0000 with resources cpus=1; mem=256 on slave 201306280552-453759884-56904-4314-0 (hemera.apache.org)
I0628 05:52:42.533833 4446 slave.cpp:738] Got assigned task 0 for framework 201306280552-453759884-56904-4314-0000
I0628 05:52:42.533828 4437 master.hpp:291] Adding task 1 with resources cpus=1; mem=256 on slave 201306280552-453759884-56904-4314-0
I0628 05:52:42.534293 4446 slave.cpp:836] Launching task 0 for framework 201306280552-453759884-56904-4314-0000
I0628 05:52:42.534368 4437 master.cpp:1591] Launching task 1 of framework 201306280552-453759884-56904-4314-0000 with resources cpus=1; mem=256 on slave 201306280552-453759884-56904-4314-0 (hemera.apache.org)
I0628 05:52:42.534868 4437 master.hpp:323] Removing offer 201306280552-453759884-56904-4314-0 with resources cpus=3; mem=1024; ports=[31000-32000]; disk=55861 on slave 201306280552-453759884-56904-4314-0
I0628 05:52:42.534937 4444 hierarchical_allocator_process.hpp:526] Framework 201306280552-453759884-56904-4314-0000 left cpus=1; mem=512; ports=[31000-32000]; disk=55861 unused on slave 201306280552-453759884-56904-4314-0
I0628 05:52:42.535433 4444 hierarchical_allocator_process.hpp:569] Framework 201306280552-453759884-56904-4314-0000 filtered slave 201306280552-453759884-56904-4314-0 for 5secs
I0628 05:52:42.536211 4446 paths.hpp:303] Created executor directory '/tmp/AllocatorTest_0_TaskFinished_vGFxvW/slaves/201306280552-453759884-56904-4314-0/frameworks/201306280552-453759884-56904-4314-0000/executors/default/runs/e886094d-4cee-4016-9e7c-bf2032980337'
I0628 05:52:42.536659 4446 slave.cpp:947] Queuing task '0' for executor default of framework '201306280552-453759884-56904-4314-0000
I0628 05:52:42.536924 4434 exec.cpp:170] Executor started at: executor(180)@140.211.11.27:56904 with pid 4314
I0628 05:52:42.536964 4446 slave.cpp:738] Got assigned task 1 for framework 201306280552-453759884-56904-4314-0000
I0628 05:52:42.537569 4446 slave.cpp:510] Successfully attached file '/tmp/AllocatorTest_0_TaskFinished_vGFxvW/slaves/201306280552-453759884-56904-4314-0/frameworks/201306280552-453759884-56904-4314-0000/executors/default/runs/e886094d-4cee-4016-9e7c-bf2032980337'
I0628 05:52:42.537880 4446 slave.cpp:1394] Got registration for executor 'default' of framework 201306280552-453759884-56904-4314-0000
I0628 05:52:42.538264 4446 slave.cpp:1509] Flushing queued task 0 for executor 'default' of framework 201306280552-453759884-56904-4314-0000
I0628 05:52:42.538295 4435 exec.cpp:194] Executor registered on slave 201306280552-453759884-56904-4314-0
I0628 05:52:42.538521 4446 slave.cpp:836] Launching task 1 for framework 201306280552-453759884-56904-4314-0000
I0628 05:52:42.538717 4435 exec.cpp:258] Executor asked to run task '0'
I0628 05:52:42.538933 4446 slave.cpp:972] Sending task '1' to executor 'default' of framework 201306280552-453759884-56904-4314-0000
I0628 05:52:42.540534 4435 exec.cpp:404] Executor sending status update TASK_RUNNING (UUID: 47e36ec1-93ca-4b9c-9c6d-b57865eac7b2) for task 0 of framework 201306280552-453759884-56904-4314-0000
I0628 05:52:42.541013 4435 exec.cpp:258] Executor asked to run task '1'
I0628 05:52:42.541051 4431 slave.cpp:1691] Handling status update TASK_RUNNING (UUID: 47e36ec1-93ca-4b9c-9c6d-b57865eac7b2) for task 0 of framework 201306280552-453759884-56904-4314-0000 from executor(180)@140.211.11.27:56904
I0628 05:52:42.542392 4431 status_update_manager.cpp:290] Received status update TASK_RUNNING (UUID: 47e36ec1-93ca-4b9c-9c6d-b57865eac7b2) for task 0 of framework 201306280552-453759884-56904-4314-0000 with checkpoint=false
I0628 05:52:42.542956 4431 status_update_manager.cpp:450] Creating StatusUpdate stream for task 0 of framework 201306280552-453759884-56904-4314-0000
I0628 05:52:42.543453 4431 status_update_manager.cpp:336] Forwarding status update TASK_RUNNING (UUID: 47e36ec1-93ca-4b9c-9c6d-b57865eac7b2) for task 0 of framework 201306280552-453759884-56904-4314-0000 to master@140.211.11.27:56904
I0628 05:52:42.543648 4435 exec.cpp:404] Executor sending status update TASK_FINISHED (UUID: 1cc89043-eaa7-4959-bb4e-532e02840056) for task 0 of framework 201306280552-453759884-56904-4314-0000
I0628 05:52:42.543903 4439 master.cpp:1022] Status update from slave(489)@140.211.11.27:56904: task 0 of framework 201306280552-453759884-56904-4314-0000 is now in state TASK_RUNNING
I0628 05:52:42.544093 4437 slave.cpp:1802] Status update manager successfully handled status update TASK_RUNNING (UUID: 47e36ec1-93ca-4b9c-9c6d-b57865eac7b2) for task 0 of framework 201306280552-453759884-56904-4314-0000
I0628 05:52:42.544960 4437 slave.cpp:1808] Sending acknowledgement for status update TASK_RUNNING (UUID: 47e36ec1-93ca-4b9c-9c6d-b57865eac7b2) for task 0 of framework 201306280552-453759884-56904-4314-0000 to executor(180)@140.211.11.27:56904
I0628 05:52:42.545371 4437 slave.cpp:1691] Handling status update TASK_FINISHED (UUID: 1cc89043-eaa7-4959-bb4e-532e02840056) for task 0 of framework 201306280552-453759884-56904-4314-0000 from executor(180)@140.211.11.27:56904
I0628 05:52:42.545831 4444 status_update_manager.cpp:290] Received status update TASK_FINISHED (UUID: 1cc89043-eaa7-4959-bb4e-532e02840056) for task 0 of framework 201306280552-453759884-56904-4314-0000 with checkpoint=false
I0628 05:52:42.546172 4435 exec.cpp:404] Executor sending status update TASK_RUNNING (UUID: 8e0a72cc-005d-429e-883c-07cf98428c16) for task 1 of framework 201306280552-453759884-56904-4314-0000
I0628 05:52:42.546406 4444 status_update_manager.cpp:360] Received status update acknowledgement 47e36ec1-93ca-4b9c-9c6d-b57865eac7b2 for task 0 of framework 201306280552-453759884-56904-4314-0000
I0628 05:52:42.546458 4433 slave.cpp:1802] Status update manager successfully handled status update TASK_FINISHED (UUID: 1cc89043-eaa7-4959-bb4e-532e02840056) for task 0 of framework 201306280552-453759884-56904-4314-0000
I0628 05:52:42.546576 4435 exec.cpp:290] Executor received status update acknowledgement 47e36ec1-93ca-4b9c-9c6d-b57865eac7b2 for task 0 of framework 201306280552-453759884-56904-4314-0000
I0628 05:52:42.546871 4444 status_update_manager.cpp:336] Forwarding status update TASK_FINISHED (UUID: 1cc89043-eaa7-4959-bb4e-532e02840056) for task 0 of framework 201306280552-453759884-56904-4314-0000 to master@140.211.11.27:56904
I0628 05:52:42.547209 4433 slave.cpp:1808] Sending acknowledgement for status update TASK_FINISHED (UUID: 1cc89043-eaa7-4959-bb4e-532e02840056) for task 0 of framework 201306280552-453759884-56904-4314-0000 to executor(180)@140.211.11.27:56904
I0628 05:52:42.547924 4436 master.cpp:1022] Status update from slave(489)@140.211.11.27:56904: task 0 of framework 201306280552-453759884-56904-4314-0000 is now in state TASK_FINISHED
I0628 05:52:42.548326 4444 exec.cpp:290] Executor received status update acknowledgement 1cc89043-eaa7-4959-bb4e-532e02840056 for task 0 of framework 201306280552-453759884-56904-4314-0000
I0628 05:52:42.548329 4433 slave.cpp:1691] Handling status update TASK_RUNNING (UUID: 8e0a72cc-005d-429e-883c-07cf98428c16) for task 1 of framework 201306280552-453759884-56904-4314-0000 from executor(180)@140.211.11.27:56904
I0628 05:52:42.548702 4436 master.hpp:303] Removing task 0 with resources cpus=1; mem=256 on slave 201306280552-453759884-56904-4314-0
I0628 05:52:42.549530 4433 slave.cpp:1342] Status update manager successfully handled status update acknowledgement 47e36ec1-93ca-4b9c-9c6d-b57865eac7b2 for task 0 of framework 201306280552-453759884-56904-4314-0000
I0628 05:52:42.549567 4438 status_update_manager.cpp:290] Received status update TASK_RUNNING (UUID: 8e0a72cc-005d-429e-883c-07cf98428c16) for task 1 of framework 201306280552-453759884-56904-4314-0000 with checkpoint=false
I0628 05:52:42.550365 4444 hierarchical_allocator_process.hpp:616] Recovered cpus=1; mem=256 (total allocatable: cpus=2; mem=768; ports=[31000-32000]; disk=55861) on slave 201306280552-453759884-56904-4314-0 from framework 201306280552-453759884-56904-4314-0000
I0628 05:52:42.550639 4438 status_update_manager.cpp:450] Creating StatusUpdate stream for task 1 of framework 201306280552-453759884-56904-4314-0000
I0628 05:52:42.551269 4438 status_update_manager.cpp:336] Forwarding status update TASK_RUNNING (UUID: 8e0a72cc-005d-429e-883c-07cf98428c16) for task 1 of framework 201306280552-453759884-56904-4314-0000 to master@140.211.11.27:56904
I0628 05:52:42.551647 4438 status_update_manager.cpp:360] Received status update acknowledgement 1cc89043-eaa7-4959-bb4e-532e02840056 for task 0 of framework 201306280552-453759884-56904-4314-0000
I0628 05:52:42.551683 4433 master.cpp:1022] Status update from slave(489)@140.211.11.27:56904: task 1 of framework 201306280552-453759884-56904-4314-0000 is now in state TASK_RUNNING
I0628 05:52:42.551702 4443 slave.cpp:1802] Status update manager successfully handled status update TASK_RUNNING (UUID: 8e0a72cc-005d-429e-883c-07cf98428c16) for task 1 of framework 201306280552-453759884-56904-4314-0000
I0628 05:52:42.551852 4438 status_update_manager.cpp:481] Cleaning up status update stream for task 0 of framework 201306280552-453759884-56904-4314-0000
I0628 05:52:42.552580 4443 slave.cpp:1808] Sending acknowledgement for status update TASK_RUNNING (UUID: 8e0a72cc-005d-429e-883c-07cf98428c16) for task 1 of framework 201306280552-453759884-56904-4314-0000 to executor(180)@140.211.11.27:56904
I0628 05:52:42.553500 4438 exec.cpp:290] Executor received status update acknowledgement 8e0a72cc-005d-429e-883c-07cf98428c16 for task 1 of framework 201306280552-453759884-56904-4314-0000
I0628 05:52:42.553524 4443 slave.cpp:1342] Status update manager successfully handled status update acknowledgement 1cc89043-eaa7-4959-bb4e-532e02840056 for task 0 of framework 201306280552-453759884-56904-4314-0000
I0628 05:52:42.553526 4450 status_update_manager.cpp:360] Received status update acknowledgement 8e0a72cc-005d-429e-883c-07cf98428c16 for task 1 of framework 201306280552-453759884-56904-4314-0000
E0628 05:52:42.554319 4443 slave.cpp:1366] Status update acknowledgement 1cc89043-eaa7-4959-bb4e-532e02840056 for task 0 of unknown executor
I0628 05:52:42.556164 4443 slave.cpp:1342] Status update manager successfully handled status update acknowledgement 8e0a72cc-005d-429e-883c-07cf98428c16 for task 1 of framework 201306280552-453759884-56904-4314-0000
I0628 05:52:42.578306 4445 hierarchical_allocator_process.hpp:727] Offering cpus=2; mem=768; ports=[31000-32000]; disk=55861 on slave 201306280552-453759884-56904-4314-0 to framework 201306280552-453759884-56904-4314-0000
I0628 05:52:42.579953 4445 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 1.71038ms
I0628 05:52:42.580098 4438 master.hpp:313] Adding offer 201306280552-453759884-56904-4314-1 with resources cpus=2; mem=768; ports=[31000-32000]; disk=55861 on slave 201306280552-453759884-56904-4314-0
I0628 05:52:42.582033 4438 master.cpp:1239] Sending 1 offers to framework 201306280552-453759884-56904-4314-0000
I0628 05:52:42.583438 4449 sched.cpp:427] Stopping framework '201306280552-453759884-56904-4314-0000'
I0628 05:52:42.584180 4429 master.cpp:385] Master terminating
I0628 05:52:42.585289 4314 master.cpp:207] Shutting down master
I0628 05:52:42.585248 4433 slave.cpp:484] Slave asked to shut down by master@140.211.11.27:56904
I0628 05:52:42.586233 4314 master.hpp:303] Removing task 1 with resources cpus=1; mem=256 on slave 201306280552-453759884-56904-4314-0
I0628 05:52:42.586288 4429 hierarchical_allocator_process.hpp:412] Deactivated framework 201306280552-453759884-56904-4314-0000
I0628 05:52:42.587049 4433 slave.cpp:1111] Asked to shut down framework 201306280552-453759884-56904-4314-0000 by master@140.211.11.27:56904
I0628 05:52:42.587661 4314 master.hpp:323] Removing offer 201306280552-453759884-56904-4314-1 with resources cpus=2; mem=768; ports=[31000-32000]; disk=55861 on slave 201306280552-453759884-56904-4314-0
I0628 05:52:42.588259 4429 hierarchical_allocator_process.hpp:616] Recovered cpus=1; mem=256 (total allocatable: cpus=1; mem=256; ports=[]; disk=0) on slave 201306280552-453759884-56904-4314-0 from framework 201306280552-453759884-56904-4314-0000
I0628 05:52:42.588919 4433 slave.cpp:1136] Shutting down framework 201306280552-453759884-56904-4314-0000
I0628 05:52:42.590335 4429 hierarchical_allocator_process.hpp:616] Recovered cpus=2; mem=768; ports=[31000-32000]; disk=55861 (total allocatable: cpus=3; mem=1024; ports=[31000-32000]; disk=55861) on slave 201306280552-453759884-56904-4314-0 from framework 201306280552-453759884-56904-4314-0000
I0628 05:52:42.590951 4433 slave.cpp:2327] Shutting down executor 'default' of framework 201306280552-453759884-56904-4314-0000
I0628 05:52:42.591647 4429 hierarchical_allocator_process.hpp:367] Removed framework 201306280552-453759884-56904-4314-0000
I0628 05:52:42.592386 4433 slave.cpp:439] Slave terminating
I0628 05:52:42.592494 4440 exec.cpp:323] Executor asked to shutdown
I0628 05:52:42.593169 4429 hierarchical_allocator_process.hpp:477] Removed slave 201306280552-453759884-56904-4314-0
I0628 05:52:42.593752 4433 slave.cpp:1111] Asked to shut down framework 201306280552-453759884-56904-4314-0000 by @0.0.0.0:0
W0628 05:52:42.595379 4433 slave.cpp:1132] Ignoring shutdown framework 201306280552-453759884-56904-4314-0000 because it is terminating
[ OK ] AllocatorTest/0.TaskFinished (72 ms)
[ RUN ] AllocatorTest/0.WhitelistSlave
I0628 05:52:42.598083 4435 master.cpp:228] Master started on 140.211.11.27:56904
I0628 05:52:42.598711 4435 master.cpp:243] Master ID: 201306280552-453759884-56904-4314
I0628 05:52:42.598953 4442 slave.cpp:112] Slave started on 490)@140.211.11.27:56904
I0628 05:52:42.599411 4435 master.cpp:526] Elected as master!
I0628 05:52:42.599715 4439 hierarchical_allocator_process.hpp:295] Initializing hierarchical allocator process with master : master@140.211.11.27:56904
I0628 05:52:42.599776 4442 slave.cpp:204] Slave resources: cpus=2; mem=1024; ports=[31000-32000]; disk=55861
I0628 05:52:42.600050 4451 sched.cpp:177] New master at master@140.211.11.27:56904
I0628 05:52:42.601346 4442 slave.cpp:528] New master detected at master@140.211.11.27:56904
I0628 05:52:42.600482 4439 hierarchical_allocator_process.hpp:491] Updated slave white list: { dummy-slave }
I0628 05:52:42.601997 4439 hierarchical_allocator_process.hpp:700] No users to allocate resources!
I0628 05:52:42.601701 4434 status_update_manager.cpp:155] New master detected at master@140.211.11.27:56904
I0628 05:52:42.601711 4431 master.cpp:569] Registering framework 201306280552-453759884-56904-4314-0000 at scheduler(445)@140.211.11.27:56904
I0628 05:52:42.601687 4442 slave.cpp:543] Postponing registration until recovery is complete
I0628 05:52:42.603212 4442 slave.cpp:389] Finished recovery
I0628 05:52:42.602959 4452 sched.cpp:222] Framework registered with 201306280552-453759884-56904-4314-0000
I0628 05:52:42.602957 4431 master.cpp:556] Framework 201306280552-453759884-56904-4314-0000 (scheduler(445)@140.211.11.27:56904) already registered, resending acknowledgement
I0628 05:52:42.602335 4439 hierarchical_allocator_process.hpp:667] Performed allocation for 0 slaves in 339.758us
I0628 05:52:42.604017 4433 sched.cpp:217] Ignoring framework registered message because the driver is already connected!
I0628 05:52:42.604022 4431 master.cpp:891] Attempting to register slave on hemera.apache.org at slave(490)@140.211.11.27:56904
I0628 05:52:42.604274 4439 hierarchical_allocator_process.hpp:327] Added framework 201306280552-453759884-56904-4314-0000
I0628 05:52:42.604686 4431 master.cpp:1851] Adding slave 201306280552-453759884-56904-4314-0 at hemera.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=55861
I0628 05:52:42.604892 4439 hierarchical_allocator_process.hpp:705] No resources available to allocate!
I0628 05:52:42.605147 4446 slave.cpp:588] Registered with master master@140.211.11.27:56904; given slave ID 201306280552-453759884-56904-4314-0
I0628 05:52:42.605219 4431 master.cpp:880] Slave 201306280552-453759884-56904-4314-0 (hemera.apache.org) already registered, resending acknowledgement
I0628 05:52:42.605247 4439 hierarchical_allocator_process.hpp:667] Performed allocation for 0 slaves in 355.934us
W0628 05:52:42.605603 4443 slave.cpp:612] Already registered with master master@140.211.11.27:56904
I0628 05:52:42.605782 4439 hierarchical_allocator_process.hpp:449] Added slave 201306280552-453759884-56904-4314-0 (hemera.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=55861 (and cpus=2; mem=1024; ports=[31000-32000]; disk=55861 available)
I0628 05:52:42.610342 4431 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 16.097us
I0628 05:52:42.620462 4446 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 14.43us
I0628 05:52:42.630614 4433 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 64.541us
I0628 05:52:42.640864 4451 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 18.292us
I0628 05:52:42.642068 4451 hierarchical_allocator_process.hpp:491] Updated slave white list: { dummy-slave, hemera.apache.org }
I0628 05:52:42.650827 4441 hierarchical_allocator_process.hpp:727] Offering cpus=2; mem=1024; ports=[31000-32000]; disk=55861 on slave 201306280552-453759884-56904-4314-0 to framework 201306280552-453759884-56904-4314-0000
I0628 05:52:42.652202 4441 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 1.404095ms
I0628 05:52:42.652241 4443 master.hpp:313] Adding offer 201306280552-453759884-56904-4314-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=55861 on slave 201306280552-453759884-56904-4314-0
I0628 05:52:42.654523 4443 master.cpp:1239] Sending 1 offers to framework 201306280552-453759884-56904-4314-0000
I0628 05:52:42.660895 4314 master.cpp:385] Master terminating
I0628 05:52:42.660904 4443 sched.cpp:427] Stopping framework '201306280552-453759884-56904-4314-0000'
I0628 05:52:42.662155 4314 master.cpp:207] Shutting down master
I0628 05:52:42.662180 4445 slave.cpp:484] Slave asked to shut down by master@140.211.11.27:56904
I0628 05:52:42.664342 4314 master.hpp:323] Removing offer 201306280552-453759884-56904-4314-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=55861 on slave 201306280552-453759884-56904-4314-0
I0628 05:52:42.664460 4450 hierarchical_allocator_process.hpp:412] Deactivated framework 201306280552-453759884-56904-4314-0000
I0628 05:52:42.665169 4445 slave.cpp:439] Slave terminating
I0628 05:52:42.666685 4450 hierarchical_allocator_process.hpp:616] Recovered cpus=2; mem=1024; ports=[31000-32000]; disk=55861 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=55861) on slave 201306280552-453759884-56904-4314-0 from framework 201306280552-453759884-56904-4314-0000
[ OK ] AllocatorTest/0.WhitelistSlave (71 ms)
[----------] 9 tests from AllocatorTest/0 (529 ms total)
[----------] 1 test from LoggingTest
[ RUN ] LoggingTest.Toggle
I0628 05:52:42.668669 4439 process.cpp:2942] Handling HTTP event for process 'logging' with path: '/logging/toggle'
I0628 05:52:42.670743 4453 process.cpp:878] Socket closed while receiving
I0628 05:52:42.671967 4446 process.cpp:2942] Handling HTTP event for process 'logging' with path: '/logging/toggle'
I0628 05:52:42.674150 4453 process.cpp:878] Socket closed while receiving
I0628 05:52:42.675279 4440 process.cpp:2942] Handling HTTP event for process 'logging' with path: '/logging/toggle'
I0628 05:52:42.676405 4453 process.cpp:878] Socket closed while receiving
I0628 05:52:42.677888 4435 process.cpp:2942] Handling HTTP event for process 'logging' with path: '/logging/toggle'
I0628 05:52:42.679604 4453 process.cpp:878] Socket closed while receiving
I0628 05:52:42.680681 4446 process.cpp:2942] Handling HTTP event for process 'logging' with path: '/logging/toggle'
I0628 05:52:42.681702 4453 process.cpp:878] Socket closed while receiving
I0628 05:52:42.682587 4438 process.cpp:2942] Handling HTTP event for process 'logging' with path: '/logging/toggle'
I0628 05:52:42.683797 4453 process.cpp:878] Socket closed while receiving
[ OK ] LoggingTest.Toggle (16 ms)
[----------] 1 test from LoggingTest (17 ms total)
[----------] 5 tests from CgroupsCpusetTest
[ RUN ] CgroupsCpusetTest.OneCPUOneCpuset
[ OK ] CgroupsCpusetTest.OneCPUOneCpuset (0 ms)
[ RUN ] CgroupsCpusetTest.OneCPUManyCpusets
[ OK ] CgroupsCpusetTest.OneCPUManyCpusets (0 ms)
[ RUN ] CgroupsCpusetTest.ManyCPUOneCpuset
[ OK ] CgroupsCpusetTest.ManyCPUOneCpuset (0 ms)
[ RUN ] CgroupsCpusetTest.ManyCPUManyCpusets
[ OK ] CgroupsCpusetTest.ManyCPUManyCpusets (0 ms)
[ RUN ] CgroupsCpusetTest.IntegerAllocations
[ OK ] CgroupsCpusetTest.IntegerAllocations (0 ms)
[----------] 5 tests from CgroupsCpusetTest (0 ms total)
[----------] 3 tests from FsTest
[ RUN ] FsTest.MountTableRead
[ OK ] FsTest.MountTableRead (0 ms)
[ RUN ] FsTest.MountTableHasOption
[ OK ] FsTest.MountTableHasOption (0 ms)
[ RUN ] FsTest.FileSystemTableRead
[ OK ] FsTest.FileSystemTableRead (0 ms)
[----------] 3 tests from FsTest (0 ms total)
[----------] Global test environment tear-down
[==========] 168 tests from 32 test cases ran. (17567 ms total)
[ PASSED ] 168 tests.
make[3]: *** [check-local] Error 1
make[3]: Leaving directory `/x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/build/src'
make[2]: *** [check-am] Error 2
make[2]: Leaving directory `/x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/build/src'
make[1]: *** [check] Error 2
make[1]: Leaving directory `/x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/build/src'
make: *** [check-recursive] Error 1
I0628 05:52:45.478795 10801 exec.cpp:83] Committing suicide by killing the process group
Build step 'Execute shell' marked build as failure
Jenkins build is back to normal :
Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui
#1288
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/1288/changes>
Build failed in Jenkins:
Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui
#1287
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/1287/>
------------------------------------------
[...truncated 51931 lines...]
I0628 16:01:52.088273 13292 master.cpp:1239] Sending 2 offers to framework 201306281601-160088899-38080-13271-0000
I0628 16:01:52.096427 13297 sched.cpp:427] Stopping framework '201306281601-160088899-38080-13271-0000'
I0628 16:01:52.096460 13271 master.cpp:385] Master terminating
I0628 16:01:52.112134 13271 master.cpp:207] Shutting down master
I0628 16:01:52.120134 13271 master.hpp:303] Removing task 0 with resources cpus=2; mem=512 on slave 201306281601-160088899-38080-13271-0
I0628 16:01:52.112167 13295 slave.cpp:484] Slave asked to shut down by master@67.195.138.9:38080
I0628 16:01:52.120146 13294 hierarchical_allocator_process.hpp:412] Deactivated framework 201306281601-160088899-38080-13271-0000
I0628 16:01:52.112164 13292 slave.cpp:484] Slave asked to shut down by master@67.195.138.9:38080
I0628 16:01:52.128114 13271 master.hpp:323] Removing offer 201306281601-160088899-38080-13271-5 with resources cpus=4; mem=2048; ports=[31000-32000]; disk=821985 on slave 201306281601-160088899-38080-13271-1
I0628 16:01:52.135997 13295 slave.cpp:1111] Asked to shut down framework 201306281601-160088899-38080-13271-0000 by master@67.195.138.9:38080
I0628 16:01:52.167850 13295 slave.cpp:1136] Shutting down framework 201306281601-160088899-38080-13271-0000
I0628 16:01:52.151907 13292 slave.cpp:439] Slave terminating
I0628 16:01:52.159945 13271 master.hpp:323] Removing offer 201306281601-160088899-38080-13271-4 with resources cpus=1; mem=512; ports=[31000-32000]; disk=821986 on slave 201306281601-160088899-38080-13271-0
I0628 16:01:52.143966 13294 hierarchical_allocator_process.hpp:667] Performed allocation for 2 slaves in 7.563us
I0628 16:01:52.175822 13295 slave.cpp:2327] Shutting down executor 'default' of framework 201306281601-160088899-38080-13271-0000
I0628 16:01:52.207747 13295 slave.cpp:439] Slave terminating
I0628 16:01:52.215692 13295 slave.cpp:1111] Asked to shut down framework 201306281601-160088899-38080-13271-0000 by @0.0.0.0:0
I0628 16:01:52.199800 13294 hierarchical_allocator_process.hpp:616] Recovered cpus=2; mem=512 (total allocatable: cpus=2; mem=512; ports=[]; disk=0) on slave 201306281601-160088899-38080-13271-0 from framework 201306281601-160088899-38080-13271-0000
I0628 16:01:52.207787 13291 exec.cpp:323] Executor asked to shutdown
W0628 16:01:52.223671 13295 slave.cpp:1132] Ignoring shutdown framework 201306281601-160088899-38080-13271-0000 because it is terminating
I0628 16:01:52.231729 13294 hierarchical_allocator_process.hpp:616] Recovered cpus=4; mem=2048; ports=[31000-32000]; disk=821985 (total allocatable: cpus=4; mem=2048; ports=[31000-32000]; disk=821985) on slave 201306281601-160088899-38080-13271-1 from framework 201306281601-160088899-38080-13271-0000
[ OK ] AllocatorTest/0.SlaveAdded (1151 ms)
[ RUN ] AllocatorTest/0.TaskFinished
I0628 16:01:52.255997 13296 master.cpp:228] Master started on 67.195.138.9:38080
I0628 16:01:52.256446 13292 slave.cpp:112] Slave started on 489)@67.195.138.9:38080
I0628 16:01:52.256798 13290 sched.cpp:177] New master at master@67.195.138.9:38080
I0628 16:01:52.263566 13296 master.cpp:243] Master ID: 201306281601-160088899-38080-13271
I0628 16:01:52.271595 13292 slave.cpp:204] Slave resources: cpus=3; mem=1024; ports=[31000-32000]; disk=821985
I0628 16:01:52.287617 13294 hierarchical_allocator_process.hpp:295] Initializing hierarchical allocator process with master : master@67.195.138.9:38080
W0628 16:01:52.287643 13297 master.cpp:83] No whitelist given. Advertising offers for all slaves
I0628 16:01:52.287713 13296 master.cpp:526] Elected as master!
I0628 16:01:52.295817 13292 slave.cpp:528] New master detected at master@67.195.138.9:38080
I0628 16:01:52.327394 13292 slave.cpp:543] Postponing registration until recovery is complete
I0628 16:01:52.319473 13296 master.cpp:569] Registering framework 201306281601-160088899-38080-13271-0000 at scheduler(444)@67.195.138.9:38080
I0628 16:01:52.327424 13295 status_update_manager.cpp:155] New master detected at master@67.195.138.9:38080
I0628 16:01:52.335360 13292 slave.cpp:389] Finished recovery
I0628 16:01:52.343364 13296 sched.cpp:222] Framework registered with 201306281601-160088899-38080-13271-0000
I0628 16:01:52.343417 13291 hierarchical_allocator_process.hpp:327] Added framework 201306281601-160088899-38080-13271-0000
I0628 16:01:52.359364 13297 master.cpp:891] Attempting to register slave on vesta.apache.org at slave(489)@67.195.138.9:38080
I0628 16:01:52.375229 13291 hierarchical_allocator_process.hpp:705] No resources available to allocate!
I0628 16:01:52.383201 13297 master.cpp:1851] Adding slave 201306281601-160088899-38080-13271-0 at vesta.apache.org with cpus=3; mem=1024; ports=[31000-32000]; disk=821985
I0628 16:01:52.391177 13291 hierarchical_allocator_process.hpp:667] Performed allocation for 0 slaves in 15.948997ms
I0628 16:01:52.399211 13292 slave.cpp:588] Registered with master master@67.195.138.9:38080; given slave ID 201306281601-160088899-38080-13271-0
I0628 16:01:52.407135 13291 hierarchical_allocator_process.hpp:705] No resources available to allocate!
I0628 16:01:52.423077 13291 hierarchical_allocator_process.hpp:667] Performed allocation for 0 slaves in 15.944835ms
I0628 16:01:52.431109 13291 hierarchical_allocator_process.hpp:449] Added slave 201306281601-160088899-38080-13271-0 (vesta.apache.org) with cpus=3; mem=1024; ports=[31000-32000]; disk=821985 (and cpus=3; mem=1024; ports=[31000-32000]; disk=821985 available)
I0628 16:01:52.439048 13291 hierarchical_allocator_process.hpp:727] Offering cpus=3; mem=1024; ports=[31000-32000]; disk=821985 on slave 201306281601-160088899-38080-13271-0 to framework 201306281601-160088899-38080-13271-0000
I0628 16:01:52.447093 13291 hierarchical_allocator_process.hpp:687] Performed allocation for slave 201306281601-160088899-38080-13271-0 in 8.050601ms
I0628 16:01:52.447116 13292 master.hpp:313] Adding offer 201306281601-160088899-38080-13271-0 with resources cpus=3; mem=1024; ports=[31000-32000]; disk=821985 on slave 201306281601-160088899-38080-13271-0
I0628 16:01:52.462977 13292 master.cpp:1239] Sending 1 offers to framework 201306281601-160088899-38080-13271-0000
I0628 16:01:52.471125 13297 master.cpp:1472] Processing reply for offer 201306281601-160088899-38080-13271-0 on slave 201306281601-160088899-38080-13271-0 (vesta.apache.org) for framework 201306281601-160088899-38080-13271-0000
I0628 16:01:52.478976 13297 master.hpp:291] Adding task 0 with resources cpus=1; mem=256 on slave 201306281601-160088899-38080-13271-0
I0628 16:01:52.486894 13297 master.cpp:1591] Launching task 0 of framework 201306281601-160088899-38080-13271-0000 with resources cpus=1; mem=256 on slave 201306281601-160088899-38080-13271-0 (vesta.apache.org)
I0628 16:01:52.494910 13294 slave.cpp:738] Got assigned task 0 for framework 201306281601-160088899-38080-13271-0000
I0628 16:01:52.481225 13292 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 13.142us
I0628 16:01:52.494927 13297 master.hpp:291] Adding task 1 with resources cpus=1; mem=256 on slave 201306281601-160088899-38080-13271-0
I0628 16:01:52.502974 13294 slave.cpp:836] Launching task 0 for framework 201306281601-160088899-38080-13271-0000
I0628 16:01:52.518791 13297 master.cpp:1591] Launching task 1 of framework 201306281601-160088899-38080-13271-0000 with resources cpus=1; mem=256 on slave 201306281601-160088899-38080-13271-0 (vesta.apache.org)
I0628 16:01:52.527683 13294 paths.hpp:303] Created executor directory '/tmp/AllocatorTest_0_TaskFinished_sdvkns/slaves/201306281601-160088899-38080-13271-0/frameworks/201306281601-160088899-38080-13271-0000/executors/default/runs/19bb22fb-6ec8-4281-9f88-789adafaa46b'
I0628 16:01:52.534842 13297 master.hpp:323] Removing offer 201306281601-160088899-38080-13271-0 with resources cpus=3; mem=1024; ports=[31000-32000]; disk=821985 on slave 201306281601-160088899-38080-13271-0
I0628 16:01:52.534951 13290 hierarchical_allocator_process.hpp:526] Framework 201306281601-160088899-38080-13271-0000 left cpus=1; mem=512; ports=[31000-32000]; disk=821985 unused on slave 201306281601-160088899-38080-13271-0
I0628 16:01:52.542853 13294 slave.cpp:947] Queuing task '0' for executor default of framework '201306281601-160088899-38080-13271-0000
I0628 16:01:52.542963 13292 exec.cpp:170] Executor started at: executor(180)@67.195.138.9:38080 with pid 13271
I0628 16:01:52.558756 13290 hierarchical_allocator_process.hpp:569] Framework 201306281601-160088899-38080-13271-0000 filtered slave 201306281601-160088899-38080-13271-0 for 5secs
I0628 16:01:52.566681 13294 slave.cpp:738] Got assigned task 1 for framework 201306281601-160088899-38080-13271-0000
I0628 16:01:52.582649 13290 hierarchical_allocator_process.hpp:797] Filtered cpus=1; mem=512; ports=[31000-32000]; disk=821985 on slave 201306281601-160088899-38080-13271-0 for framework 201306281601-160088899-38080-13271-0000
I0628 16:01:52.598558 13290 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 15.934102ms
I0628 16:01:52.590668 13294 slave.cpp:510] Successfully attached file '/tmp/AllocatorTest_0_TaskFinished_sdvkns/slaves/201306281601-160088899-38080-13271-0/frameworks/201306281601-160088899-38080-13271-0000/executors/default/runs/19bb22fb-6ec8-4281-9f88-789adafaa46b'
I0628 16:01:52.614555 13294 slave.cpp:1394] Got registration for executor 'default' of framework 201306281601-160088899-38080-13271-0000
I0628 16:01:52.622520 13294 slave.cpp:1509] Flushing queued task 0 for executor 'default' of framework 201306281601-160088899-38080-13271-0000
I0628 16:01:52.622561 13291 exec.cpp:194] Executor registered on slave 201306281601-160088899-38080-13271-0
I0628 16:01:52.630482 13294 slave.cpp:836] Launching task 1 for framework 201306281601-160088899-38080-13271-0000
I0628 16:01:52.638468 13291 exec.cpp:258] Executor asked to run task '0'
I0628 16:01:52.646442 13294 slave.cpp:972] Sending task '1' to executor 'default' of framework 201306281601-160088899-38080-13271-0000
I0628 16:01:52.655177 13291 exec.cpp:404] Executor sending status update TASK_RUNNING (UUID: 30145532-82b2-44b2-85ae-1220cd7467bc) for task 0 of framework 201306281601-160088899-38080-13271-0000
I0628 16:01:52.657424 13296 hierarchical_allocator_process.hpp:797] Filtered cpus=1; mem=512; ports=[31000-32000]; disk=821985 on slave 201306281601-160088899-38080-13271-0 for framework 201306281601-160088899-38080-13271-0000
I0628 16:01:52.670419 13297 slave.cpp:1691] Handling status update TASK_RUNNING (UUID: 30145532-82b2-44b2-85ae-1220cd7467bc) for task 0 of framework 201306281601-160088899-38080-13271-0000 from executor(180)@67.195.138.9:38080
I0628 16:01:52.671094 13291 exec.cpp:404] Executor sending status update TASK_FINISHED (UUID: b34ab03a-4e07-4cb3-bb48-9aa704186334) for task 0 of framework 201306281601-160088899-38080-13271-0000
I0628 16:01:52.694280 13291 exec.cpp:258] Executor asked to run task '1'
I0628 16:01:52.686393 13295 status_update_manager.cpp:290] Received status update TASK_RUNNING (UUID: 30145532-82b2-44b2-85ae-1220cd7467bc) for task 0 of framework 201306281601-160088899-38080-13271-0000 with checkpoint=false
I0628 16:01:52.710209 13295 status_update_manager.cpp:450] Creating StatusUpdate stream for task 0 of framework 201306281601-160088899-38080-13271-0000
I0628 16:01:52.694306 13297 slave.cpp:1691] Handling status update TASK_FINISHED (UUID: b34ab03a-4e07-4cb3-bb48-9aa704186334) for task 0 of framework 201306281601-160088899-38080-13271-0000 from executor(180)@67.195.138.9:38080
I0628 16:01:52.702962 13291 exec.cpp:404] Executor sending status update TASK_RUNNING (UUID: 024950a5-062b-4737-98a4-eee6ab8d8b0d) for task 1 of framework 201306281601-160088899-38080-13271-0000
I0628 16:01:52.734170 13291 slave.cpp:1691] Handling status update TASK_RUNNING (UUID: 024950a5-062b-4737-98a4-eee6ab8d8b0d) for task 1 of framework 201306281601-160088899-38080-13271-0000 from executor(180)@67.195.138.9:38080
I0628 16:01:52.718224 13295 status_update_manager.cpp:336] Forwarding status update TASK_RUNNING (UUID: 30145532-82b2-44b2-85ae-1220cd7467bc) for task 0 of framework 201306281601-160088899-38080-13271-0000 to master@67.195.138.9:38080
I0628 16:01:52.678299 13296 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 20.918942ms
I0628 16:01:52.760825 13295 status_update_manager.cpp:290] Received status update TASK_FINISHED (UUID: b34ab03a-4e07-4cb3-bb48-9aa704186334) for task 0 of framework 201306281601-160088899-38080-13271-0000 with checkpoint=false
I0628 16:01:52.760846 13291 master.cpp:1022] Status update from slave(489)@67.195.138.9:38080: task 0 of framework 201306281601-160088899-38080-13271-0000 is now in state TASK_RUNNING
I0628 16:01:52.760846 13293 slave.cpp:1802] Status update manager successfully handled status update TASK_RUNNING (UUID: 30145532-82b2-44b2-85ae-1220cd7467bc) for task 0 of framework 201306281601-160088899-38080-13271-0000
I0628 16:01:52.792610 13293 slave.cpp:1808] Sending acknowledgement for status update TASK_RUNNING (UUID: 30145532-82b2-44b2-85ae-1220cd7467bc) for task 0 of framework 201306281601-160088899-38080-13271-0000 to executor(180)@67.195.138.9:38080
I0628 16:01:52.776705 13295 status_update_manager.cpp:290] Received status update TASK_RUNNING (UUID: 024950a5-062b-4737-98a4-eee6ab8d8b0d) for task 1 of framework 201306281601-160088899-38080-13271-0000 with checkpoint=false
I0628 16:01:52.800578 13293 slave.cpp:1802] Status update manager successfully handled status update TASK_FINISHED (UUID: b34ab03a-4e07-4cb3-bb48-9aa704186334) for task 0 of framework 201306281601-160088899-38080-13271-0000
I0628 16:01:52.800613 13297 exec.cpp:290] Executor received status update acknowledgement 30145532-82b2-44b2-85ae-1220cd7467bc for task 0 of framework 201306281601-160088899-38080-13271-0000
I0628 16:01:52.808511 13295 status_update_manager.cpp:450] Creating StatusUpdate stream for task 1 of framework 201306281601-160088899-38080-13271-0000
I0628 16:01:52.816467 13293 slave.cpp:1808] Sending acknowledgement for status update TASK_FINISHED (UUID: b34ab03a-4e07-4cb3-bb48-9aa704186334) for task 0 of framework 201306281601-160088899-38080-13271-0000 to executor(180)@67.195.138.9:38080
I0628 16:01:52.818886 13296 hierarchical_allocator_process.hpp:797] Filtered cpus=1; mem=512; ports=[31000-32000]; disk=821985 on slave 201306281601-160088899-38080-13271-0 for framework 201306281601-160088899-38080-13271-0000
I0628 16:01:52.848327 13296 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 29.514453ms
I0628 16:01:52.840415 13294 exec.cpp:290] Executor received status update acknowledgement b34ab03a-4e07-4cb3-bb48-9aa704186334 for task 0 of framework 201306281601-160088899-38080-13271-0000
I0628 16:01:52.832406 13295 status_update_manager.cpp:336] Forwarding status update TASK_RUNNING (UUID: 024950a5-062b-4737-98a4-eee6ab8d8b0d) for task 1 of framework 201306281601-160088899-38080-13271-0000 to master@67.195.138.9:38080
I0628 16:01:52.872218 13295 status_update_manager.cpp:360] Received status update acknowledgement 30145532-82b2-44b2-85ae-1220cd7467bc for task 0 of framework 201306281601-160088899-38080-13271-0000
I0628 16:01:52.872241 13293 master.cpp:1022] Status update from slave(489)@67.195.138.9:38080: task 1 of framework 201306281601-160088899-38080-13271-0000 is now in state TASK_RUNNING
I0628 16:01:52.872247 13297 slave.cpp:1802] Status update manager successfully handled status update TASK_RUNNING (UUID: 024950a5-062b-4737-98a4-eee6ab8d8b0d) for task 1 of framework 201306281601-160088899-38080-13271-0000
I0628 16:01:52.896070 13297 slave.cpp:1808] Sending acknowledgement for status update TASK_RUNNING (UUID: 024950a5-062b-4737-98a4-eee6ab8d8b0d) for task 1 of framework 201306281601-160088899-38080-13271-0000 to executor(180)@67.195.138.9:38080
I0628 16:01:52.880142 13295 status_update_manager.cpp:336] Forwarding status update TASK_FINISHED (UUID: b34ab03a-4e07-4cb3-bb48-9aa704186334) for task 0 of framework 201306281601-160088899-38080-13271-0000 to master@67.195.138.9:38080
I0628 16:01:52.875140 13291 monitor.cpp:180] Publishing resource usage for executor 'default' of framework '201306281601-160088899-38080-13271-0000'
I0628 16:01:52.904075 13297 exec.cpp:290] Executor received status update acknowledgement 024950a5-062b-4737-98a4-eee6ab8d8b0d for task 1 of framework 201306281601-160088899-38080-13271-0000
I0628 16:01:52.907238 13292 hierarchical_allocator_process.hpp:797] Filtered cpus=1; mem=512; ports=[31000-32000]; disk=821985 on slave 201306281601-160088899-38080-13271-0 for framework 201306281601-160088899-38080-13271-0000
I0628 16:01:52.912014 13295 status_update_manager.cpp:360] Received status update acknowledgement 024950a5-062b-4737-98a4-eee6ab8d8b0d for task 1 of framework 201306281601-160088899-38080-13271-0000
I0628 16:01:52.912041 13296 slave.cpp:1342] Status update manager successfully handled status update acknowledgement 30145532-82b2-44b2-85ae-1220cd7467bc for task 0 of framework 201306281601-160088899-38080-13271-0000
I0628 16:01:52.912051 13290 master.cpp:1022] Status update from slave(489)@67.195.138.9:38080: task 0 of framework 201306281601-160088899-38080-13271-0000 is now in state TASK_FINISHED
I0628 16:01:52.935856 13292 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 28.64583ms
I0628 16:01:52.951797 13296 slave.cpp:1342] Status update manager successfully handled status update acknowledgement 024950a5-062b-4737-98a4-eee6ab8d8b0d for task 1 of framework 201306281601-160088899-38080-13271-0000
I0628 16:01:52.959774 13290 master.hpp:303] Removing task 0 with resources cpus=1; mem=256 on slave 201306281601-160088899-38080-13271-0
I0628 16:01:52.975697 13293 status_update_manager.cpp:360] Received status update acknowledgement b34ab03a-4e07-4cb3-bb48-9aa704186334 for task 0 of framework 201306281601-160088899-38080-13271-0000
I0628 16:01:52.991565 13293 status_update_manager.cpp:481] Cleaning up status update stream for task 0 of framework 201306281601-160088899-38080-13271-0000
I0628 16:01:52.983717 13291 hierarchical_allocator_process.hpp:616] Recovered cpus=1; mem=256 (total allocatable: cpus=2; mem=768; ports=[31000-32000]; disk=821985) on slave 201306281601-160088899-38080-13271-0 from framework 201306281601-160088899-38080-13271-0000
I0628 16:01:52.999552 13293 slave.cpp:1342] Status update manager successfully handled status update acknowledgement b34ab03a-4e07-4cb3-bb48-9aa704186334 for task 0 of framework 201306281601-160088899-38080-13271-0000
E0628 16:01:53.015429 13293 slave.cpp:1366] Status update acknowledgement b34ab03a-4e07-4cb3-bb48-9aa704186334 for task 0 of unknown executor
I0628 16:01:53.018548 13290 hierarchical_allocator_process.hpp:727] Offering cpus=2; mem=768; ports=[31000-32000]; disk=821985 on slave 201306281601-160088899-38080-13271-0 to framework 201306281601-160088899-38080-13271-0000
I0628 16:01:53.031446 13290 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 12.911149ms
I0628 16:01:53.031483 13292 master.hpp:313] Adding offer 201306281601-160088899-38080-13271-1 with resources cpus=2; mem=768; ports=[31000-32000]; disk=821985 on slave 201306281601-160088899-38080-13271-0
I0628 16:01:53.047281 13292 master.cpp:1239] Sending 1 offers to framework 201306281601-160088899-38080-13271-0000
I0628 16:01:53.055475 13294 sched.cpp:427] Stopping framework '201306281601-160088899-38080-13271-0000'
I0628 16:01:53.055516 13271 master.cpp:385] Master terminating
I0628 16:01:53.071172 13271 master.cpp:207] Shutting down master
I0628 16:01:53.071184 13292 slave.cpp:484] Slave asked to shut down by master@67.195.138.9:38080
I0628 16:01:53.087090 13292 slave.cpp:1111] Asked to shut down framework 201306281601-160088899-38080-13271-0000 by master@67.195.138.9:38080
I0628 16:01:53.079238 13295 hierarchical_allocator_process.hpp:412] Deactivated framework 201306281601-160088899-38080-13271-0000
I0628 16:01:53.079192 13271 master.hpp:303] Removing task 1 with resources cpus=1; mem=256 on slave 201306281601-160088899-38080-13271-0
I0628 16:01:53.095031 13292 slave.cpp:1136] Shutting down framework 201306281601-160088899-38080-13271-0000
I0628 16:01:53.103025 13295 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 20.405us
I0628 16:01:53.111062 13271 master.hpp:323] Removing offer 201306281601-160088899-38080-13271-1 with resources cpus=2; mem=768; ports=[31000-32000]; disk=821985 on slave 201306281601-160088899-38080-13271-0
I0628 16:01:53.118911 13292 slave.cpp:2327] Shutting down executor 'default' of framework 201306281601-160088899-38080-13271-0000
I0628 16:01:53.126922 13295 hierarchical_allocator_process.hpp:616] Recovered cpus=1; mem=256 (total allocatable: cpus=1; mem=256; ports=[]; disk=0) on slave 201306281601-160088899-38080-13271-0 from framework 201306281601-160088899-38080-13271-0000
I0628 16:01:53.142806 13292 slave.cpp:439] Slave terminating
I0628 16:01:53.142822 13290 exec.cpp:323] Executor asked to shutdown
I0628 16:01:53.150804 13295 hierarchical_allocator_process.hpp:616] Recovered cpus=2; mem=768; ports=[31000-32000]; disk=821985 (total allocatable: cpus=3; mem=1024; ports=[31000-32000]; disk=821985) on slave 201306281601-160088899-38080-13271-0 from framework 201306281601-160088899-38080-13271-0000
I0628 16:01:53.158694 13292 slave.cpp:1111] Asked to shut down framework 201306281601-160088899-38080-13271-0000 by @0.0.0.0:0
I0628 16:01:53.174662 13295 hierarchical_allocator_process.hpp:367] Removed framework 201306281601-160088899-38080-13271-0000
W0628 16:01:53.182572 13292 slave.cpp:1132] Ignoring shutdown framework 201306281601-160088899-38080-13271-0000 because it is terminating
I0628 16:01:53.190553 13295 hierarchical_allocator_process.hpp:477] Removed slave 201306281601-160088899-38080-13271-0
[ OK ] AllocatorTest/0.TaskFinished (951 ms)
[ RUN ] AllocatorTest/0.WhitelistSlave
I0628 16:01:53.206960 13292 master.cpp:228] Master started on 67.195.138.9:38080
I0628 16:01:53.207389 13297 slave.cpp:112] Slave started on 490)@67.195.138.9:38080
I0628 16:01:53.214416 13292 master.cpp:243] Master ID: 201306281601-160088899-38080-13271
I0628 16:01:53.222430 13297 slave.cpp:204] Slave resources: cpus=2; mem=1024; ports=[31000-32000]; disk=821985
I0628 16:01:53.230515 13294 hierarchical_allocator_process.hpp:295] Initializing hierarchical allocator process with master : master@67.195.138.9:38080
I0628 16:01:53.230554 13292 master.cpp:526] Elected as master!
I0628 16:01:53.230634 13291 sched.cpp:177] New master at master@67.195.138.9:38080
I0628 16:01:53.238637 13297 slave.cpp:528] New master detected at master@67.195.138.9:38080
I0628 16:01:53.246285 13294 hierarchical_allocator_process.hpp:491] Updated slave white list: { dummy-slave }
I0628 16:01:53.268631 13291 master.cpp:569] Registering framework 201306281601-160088899-38080-13271-0000 at scheduler(445)@67.195.138.9:38080
I0628 16:01:53.297971 13297 slave.cpp:543] Postponing registration until recovery is complete
I0628 16:01:53.298025 13293 status_update_manager.cpp:155] New master detected at master@67.195.138.9:38080
I0628 16:01:53.305918 13294 hierarchical_allocator_process.hpp:700] No users to allocate resources!
I0628 16:01:53.313910 13291 master.cpp:556] Framework 201306281601-160088899-38080-13271-0000 (scheduler(445)@67.195.138.9:38080) already registered, resending acknowledgement
I0628 16:01:53.313925 13296 sched.cpp:222] Framework registered with 201306281601-160088899-38080-13271-0000
I0628 16:01:53.321835 13297 slave.cpp:389] Finished recovery
I0628 16:01:53.337751 13294 hierarchical_allocator_process.hpp:667] Performed allocation for 0 slaves in 31.832749ms
I0628 16:01:53.353693 13296 sched.cpp:217] Ignoring framework registered message because the driver is already connected!
I0628 16:01:53.361735 13297 master.cpp:891] Attempting to register slave on vesta.apache.org at slave(490)@67.195.138.9:38080
I0628 16:01:53.369608 13294 hierarchical_allocator_process.hpp:327] Added framework 201306281601-160088899-38080-13271-0000
I0628 16:01:53.385520 13297 master.cpp:1851] Adding slave 201306281601-160088899-38080-13271-0 at vesta.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=821985
I0628 16:01:53.393476 13294 hierarchical_allocator_process.hpp:705] No resources available to allocate!
I0628 16:01:53.401505 13297 master.cpp:880] Slave 201306281601-160088899-38080-13271-0 (vesta.apache.org) already registered, resending acknowledgement
I0628 16:01:53.401510 13291 slave.cpp:588] Registered with master master@67.195.138.9:38080; given slave ID 201306281601-160088899-38080-13271-0
I0628 16:01:53.409392 13294 hierarchical_allocator_process.hpp:667] Performed allocation for 0 slaves in 15.916665ms
W0628 16:01:53.425334 13291 slave.cpp:612] Already registered with master master@67.195.138.9:38080
I0628 16:01:53.433310 13294 hierarchical_allocator_process.hpp:449] Added slave 201306281601-160088899-38080-13271-0 (vesta.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=821985 (and cpus=2; mem=1024; ports=[31000-32000]; disk=821985 available)
I0628 16:01:53.452064 13290 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 10.246us
I0628 16:01:53.462129 13295 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 17.851us
I0628 16:01:53.472162 13296 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 12.204us
I0628 16:01:53.482250 13292 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 17.114us
I0628 16:01:53.482388 13292 hierarchical_allocator_process.hpp:491] Updated slave white list: { dummy-slave, vesta.apache.org }
I0628 16:01:53.492265 13296 hierarchical_allocator_process.hpp:727] Offering cpus=2; mem=1024; ports=[31000-32000]; disk=821985 on slave 201306281601-160088899-38080-13271-0 to framework 201306281601-160088899-38080-13271-0000
I0628 16:01:53.497066 13296 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 4.81017ms
I0628 16:01:53.497108 13293 master.hpp:313] Adding offer 201306281601-160088899-38080-13271-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=821985 on slave 201306281601-160088899-38080-13271-0
I0628 16:01:53.512920 13293 master.cpp:1239] Sending 1 offers to framework 201306281601-160088899-38080-13271-0000
I0628 16:01:53.522493 13271 master.cpp:385] Master terminating
I0628 16:01:53.522493 13291 sched.cpp:427] Stopping framework '201306281601-160088899-38080-13271-0000'
I0628 16:01:53.528811 13271 master.cpp:207] Shutting down master
I0628 16:01:53.528808 13294 slave.cpp:484] Slave asked to shut down by master@67.195.138.9:38080
I0628 16:01:53.544786 13290 hierarchical_allocator_process.hpp:412] Deactivated framework 201306281601-160088899-38080-13271-0000
I0628 16:01:53.544831 13271 master.hpp:323] Removing offer 201306281601-160088899-38080-13271-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=821985 on slave 201306281601-160088899-38080-13271-0
I0628 16:01:53.552645 13294 slave.cpp:439] Slave terminating
I0628 16:01:53.560679 13290 hierarchical_allocator_process.hpp:616] Recovered cpus=2; mem=1024; ports=[31000-32000]; disk=821985 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=821985) on slave 201306281601-160088899-38080-13271-0 from framework 201306281601-160088899-38080-13271-0000
[ OK ] AllocatorTest/0.WhitelistSlave (378 ms)
[----------] 9 tests from AllocatorTest/0 (6276 ms total)
[----------] 1 test from LoggingTest
[ RUN ] LoggingTest.Toggle
I0628 16:01:53.584875 13293 process.cpp:2942] Handling HTTP event for process 'logging' with path: '/logging/toggle'
I0628 16:01:53.593060 13298 process.cpp:878] Socket closed while receiving
I0628 16:01:53.600838 13290 process.cpp:2942] Handling HTTP event for process 'logging' with path: '/logging/toggle'
I0628 16:01:53.608762 13298 process.cpp:878] Socket closed while receiving
I0628 16:01:53.616682 13293 process.cpp:2942] Handling HTTP event for process 'logging' with path: '/logging/toggle'
I0628 16:01:53.624582 13298 process.cpp:878] Socket closed while receiving
I0628 16:01:53.632714 13295 process.cpp:2942] Handling HTTP event for process 'logging' with path: '/logging/toggle'
I0628 16:01:53.640709 13298 process.cpp:878] Socket closed while receiving
I0628 16:01:53.648525 13292 process.cpp:2942] Handling HTTP event for process 'logging' with path: '/logging/toggle'
I0628 16:01:53.656467 13298 process.cpp:878] Socket closed while receiving
I0628 16:01:53.664428 13291 process.cpp:2942] Handling HTTP event for process 'logging' with path: '/logging/toggle'
I0628 16:01:53.672428 13298 process.cpp:878] Socket closed while receiving
[ OK ] LoggingTest.Toggle (96 ms)
[----------] 1 test from LoggingTest (96 ms total)
[----------] 5 tests from CgroupsCpusetTest
[ RUN ] CgroupsCpusetTest.OneCPUOneCpuset
[ OK ] CgroupsCpusetTest.OneCPUOneCpuset (0 ms)
[ RUN ] CgroupsCpusetTest.OneCPUManyCpusets
[ OK ] CgroupsCpusetTest.OneCPUManyCpusets (0 ms)
[ RUN ] CgroupsCpusetTest.ManyCPUOneCpuset
[ OK ] CgroupsCpusetTest.ManyCPUOneCpuset (0 ms)
[ RUN ] CgroupsCpusetTest.ManyCPUManyCpusets
[ OK ] CgroupsCpusetTest.ManyCPUManyCpusets (0 ms)
[ RUN ] CgroupsCpusetTest.IntegerAllocations
[ OK ] CgroupsCpusetTest.IntegerAllocations (0 ms)
[----------] 5 tests from CgroupsCpusetTest (0 ms total)
[----------] 3 tests from FsTest
[ RUN ] FsTest.MountTableRead
[ OK ] FsTest.MountTableRead (0 ms)
[ RUN ] FsTest.MountTableHasOption
[ OK ] FsTest.MountTableHasOption (0 ms)
[ RUN ] FsTest.FileSystemTableRead
[ OK ] FsTest.FileSystemTableRead (0 ms)
[----------] 3 tests from FsTest (0 ms total)
[----------] Global test environment tear-down
[==========] 168 tests from 32 test cases ran. (108476 ms total)
[ PASSED ] 168 tests.
make[3]: Leaving directory `/home/hudson/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/build/src'
make[2]: Leaving directory `/home/hudson/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/build/src'
make[1]: Leaving directory `/home/hudson/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/build/src'
Making check in ec2
make[1]: Entering directory `/home/hudson/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/build/ec2'
make[1]: Nothing to be done for `check'.
make[1]: Leaving directory `/home/hudson/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/build/ec2'
Making check in hadoop
make[1]: Entering directory `/home/hudson/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/build/hadoop'
make[1]: Nothing to be done for `check'.
make[1]: Leaving directory `/home/hudson/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/build/hadoop'
Making check in jenkins
make[1]: Entering directory `/home/hudson/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/build/jenkins'
make[1]: Nothing to be done for `check'.
make[1]: Leaving directory `/home/hudson/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/build/jenkins'
make: *** No rule to make target `GTEST_BREAK_ON_FAILURE'. Stop.
Build step 'Execute shell' marked build as failure
Build failed in Jenkins:
Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui
#1286
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/1286/>
------------------------------------------
[...truncated 52223 lines...]
I0628 14:18:21.612948 7558 master.hpp:313] Adding offer 201306281418-160088899-56600-7509-3 with resources cpus=1; mem=512; ports=[31000-32000]; disk=829396 on slave 201306281418-160088899-56600-7509-0
I0628 14:18:21.628726 7558 master.hpp:313] Adding offer 201306281418-160088899-56600-7509-4 with resources cpus=4; mem=2048; ports=[31000-32000]; disk=829396 on slave 201306281418-160088899-56600-7509-1
I0628 14:18:21.636641 7558 master.cpp:1239] Sending 2 offers to framework 201306281418-160088899-56600-7509-0000
I0628 14:18:21.644794 7559 sched.cpp:427] Stopping framework '201306281418-160088899-56600-7509-0000'
I0628 14:18:21.644831 7509 master.cpp:385] Master terminating
I0628 14:18:21.660523 7509 master.cpp:207] Shutting down master
I0628 14:18:21.660560 7553 slave.cpp:484] Slave asked to shut down by master@67.195.138.9:56600
I0628 14:18:21.660569 7556 slave.cpp:484] Slave asked to shut down by master@67.195.138.9:56600
I0628 14:18:21.684422 7556 slave.cpp:439] Slave terminating
I0628 14:18:21.668527 7552 hierarchical_allocator_process.hpp:412] Deactivated framework 201306281418-160088899-56600-7509-0000
I0628 14:18:21.700364 7552 hierarchical_allocator_process.hpp:667] Performed allocation for 2 slaves in 17.121us
I0628 14:18:21.668514 7509 master.hpp:303] Removing task 0 with resources cpus=2; mem=512 on slave 201306281418-160088899-56600-7509-0
I0628 14:18:21.716383 7509 master.hpp:323] Removing offer 201306281418-160088899-56600-7509-4 with resources cpus=4; mem=2048; ports=[31000-32000]; disk=829396 on slave 201306281418-160088899-56600-7509-1
I0628 14:18:21.724320 7509 master.hpp:323] Removing offer 201306281418-160088899-56600-7509-3 with resources cpus=1; mem=512; ports=[31000-32000]; disk=829396 on slave 201306281418-160088899-56600-7509-0
I0628 14:18:21.716516 7555 hierarchical_allocator_process.hpp:616] Recovered cpus=2; mem=512 (total allocatable: cpus=2; mem=512; ports=[]; disk=0) on slave 201306281418-160088899-56600-7509-0 from framework 201306281418-160088899-56600-7509-0000
I0628 14:18:21.676442 7553 slave.cpp:1111] Asked to shut down framework 201306281418-160088899-56600-7509-0000 by master@67.195.138.9:56600
I0628 14:18:21.748133 7553 slave.cpp:1136] Shutting down framework 201306281418-160088899-56600-7509-0000
I0628 14:18:21.740247 7555 hierarchical_allocator_process.hpp:616] Recovered cpus=4; mem=2048; ports=[31000-32000]; disk=829396 (total allocatable: cpus=4; mem=2048; ports=[31000-32000]; disk=829396) on slave 201306281418-160088899-56600-7509-1 from framework 201306281418-160088899-56600-7509-0000
I0628 14:18:21.756084 7553 slave.cpp:2327] Shutting down executor 'default' of framework 201306281418-160088899-56600-7509-0000
I0628 14:18:21.764127 7555 hierarchical_allocator_process.hpp:616] Recovered cpus=1; mem=512; ports=[31000-32000]; disk=829396 (total allocatable: cpus=3; mem=1024; ports=[31000-32000]; disk=829396) on slave 201306281418-160088899-56600-7509-0 from framework 201306281418-160088899-56600-7509-0000
I0628 14:18:21.772090 7554 exec.cpp:323] Executor asked to shutdown
I0628 14:18:21.772092 7553 slave.cpp:439] Slave terminating
I0628 14:18:21.787328 7555 hierarchical_allocator_process.hpp:367] Removed framework 201306281418-160088899-56600-7509-0000
I0628 14:18:21.810175 7553 slave.cpp:1111] Asked to shut down framework 201306281418-160088899-56600-7509-0000 by @0.0.0.0:0
W0628 14:18:21.834094 7553 slave.cpp:1132] Ignoring shutdown framework 201306281418-160088899-56600-7509-0000 because it is terminating
I0628 14:18:21.826071 7555 hierarchical_allocator_process.hpp:477] Removed slave 201306281418-160088899-56600-7509-1
[ OK ] AllocatorTest/0.SlaveAdded (938 ms)
[ RUN ] AllocatorTest/0.TaskFinished
I0628 14:18:21.860321 7555 slave.cpp:112] Slave started on 489)@67.195.138.9:56600
I0628 14:18:21.860337 7556 master.cpp:228] Master started on 67.195.138.9:56600
I0628 14:18:21.881953 7556 master.cpp:243] Master ID: 201306281418-160088899-56600-7509
I0628 14:18:21.873981 7555 slave.cpp:204] Slave resources: cpus=3; mem=1024; ports=[31000-32000]; disk=829396
I0628 14:18:21.860467 7553 sched.cpp:177] New master at master@67.195.138.9:56600
W0628 14:18:21.897943 7558 master.cpp:83] No whitelist given. Advertising offers for all slaves
I0628 14:18:21.898035 7552 hierarchical_allocator_process.hpp:295] Initializing hierarchical allocator process with master : master@67.195.138.9:56600
I0628 14:18:21.898146 7556 master.cpp:526] Elected as master!
I0628 14:18:21.906363 7555 slave.cpp:528] New master detected at master@67.195.138.9:56600
I0628 14:18:21.969563 7555 slave.cpp:543] Postponing registration until recovery is complete
I0628 14:18:21.978437 7555 slave.cpp:389] Finished recovery
I0628 14:18:21.969609 7554 status_update_manager.cpp:155] New master detected at master@67.195.138.9:56600
I0628 14:18:21.953744 7556 master.cpp:569] Registering framework 201306281418-160088899-56600-7509-0000 at scheduler(444)@67.195.138.9:56600
I0628 14:18:21.995837 7559 hierarchical_allocator_process.hpp:700] No users to allocate resources!
I0628 14:18:22.026244 7559 hierarchical_allocator_process.hpp:667] Performed allocation for 0 slaves in 30.449263ms
I0628 14:18:22.017482 7557 sched.cpp:222] Framework registered with 201306281418-160088899-56600-7509-0000
I0628 14:18:22.017446 7556 master.cpp:891] Attempting to register slave on vesta.apache.org at slave(489)@67.195.138.9:56600
I0628 14:18:22.041337 7559 hierarchical_allocator_process.hpp:327] Added framework 201306281418-160088899-56600-7509-0000
I0628 14:18:22.074046 7559 hierarchical_allocator_process.hpp:705] No resources available to allocate!
I0628 14:18:22.065213 7556 master.cpp:1851] Adding slave 201306281418-160088899-56600-7509-0 at vesta.apache.org with cpus=3; mem=1024; ports=[31000-32000]; disk=829396
I0628 14:18:22.089126 7559 hierarchical_allocator_process.hpp:667] Performed allocation for 0 slaves in 15.079529ms
I0628 14:18:22.098013 7552 slave.cpp:588] Registered with master master@67.195.138.9:56600; given slave ID 201306281418-160088899-56600-7509-0
I0628 14:18:22.113057 7559 hierarchical_allocator_process.hpp:705] No resources available to allocate!
I0628 14:18:22.136970 7559 hierarchical_allocator_process.hpp:667] Performed allocation for 0 slaves in 23.913072ms
I0628 14:18:22.145809 7559 hierarchical_allocator_process.hpp:449] Added slave 201306281418-160088899-56600-7509-0 (vesta.apache.org) with cpus=3; mem=1024; ports=[31000-32000]; disk=829396 (and cpus=3; mem=1024; ports=[31000-32000]; disk=829396 available)
I0628 14:18:22.160917 7559 hierarchical_allocator_process.hpp:727] Offering cpus=3; mem=1024; ports=[31000-32000]; disk=829396 on slave 201306281418-160088899-56600-7509-0 to framework 201306281418-160088899-56600-7509-0000
I0628 14:18:22.169759 7559 hierarchical_allocator_process.hpp:687] Performed allocation for slave 201306281418-160088899-56600-7509-0 in 8.861851ms
I0628 14:18:22.169831 7552 master.hpp:313] Adding offer 201306281418-160088899-56600-7509-0 with resources cpus=3; mem=1024; ports=[31000-32000]; disk=829396 on slave 201306281418-160088899-56600-7509-0
I0628 14:18:22.196565 7553 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 29.808us
I0628 14:18:22.244750 7552 master.cpp:1239] Sending 1 offers to framework 201306281418-160088899-56600-7509-0000
I0628 14:18:22.261690 7556 master.cpp:1472] Processing reply for offer 201306281418-160088899-56600-7509-0 on slave 201306281418-160088899-56600-7509-0 (vesta.apache.org) for framework 201306281418-160088899-56600-7509-0000
I0628 14:18:22.269392 7556 master.hpp:291] Adding task 0 with resources cpus=1; mem=256 on slave 201306281418-160088899-56600-7509-0
I0628 14:18:22.277274 7556 master.cpp:1591] Launching task 0 of framework 201306281418-160088899-56600-7509-0000 with resources cpus=1; mem=256 on slave 201306281418-160088899-56600-7509-0 (vesta.apache.org)
I0628 14:18:22.285310 7555 slave.cpp:738] Got assigned task 0 for framework 201306281418-160088899-56600-7509-0000
I0628 14:18:22.293385 7555 slave.cpp:836] Launching task 0 for framework 201306281418-160088899-56600-7509-0000
I0628 14:18:22.285331 7556 master.hpp:291] Adding task 1 with resources cpus=1; mem=256 on slave 201306281418-160088899-56600-7509-0
I0628 14:18:22.309165 7556 master.cpp:1591] Launching task 1 of framework 201306281418-160088899-56600-7509-0000 with resources cpus=1; mem=256 on slave 201306281418-160088899-56600-7509-0 (vesta.apache.org)
I0628 14:18:22.317212 7556 master.hpp:323] Removing offer 201306281418-160088899-56600-7509-0 with resources cpus=3; mem=1024; ports=[31000-32000]; disk=829396 on slave 201306281418-160088899-56600-7509-0
I0628 14:18:22.302233 7555 paths.hpp:303] Created executor directory '/tmp/AllocatorTest_0_TaskFinished_H91XxI/slaves/201306281418-160088899-56600-7509-0/frameworks/201306281418-160088899-56600-7509-0000/executors/default/runs/f5350148-aefc-40bd-8b13-d80f437ba610'
I0628 14:18:22.302810 7558 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 17.079us
I0628 14:18:22.341027 7558 hierarchical_allocator_process.hpp:526] Framework 201306281418-160088899-56600-7509-0000 left cpus=1; mem=512; ports=[31000-32000]; disk=829396 unused on slave 201306281418-160088899-56600-7509-0
I0628 14:18:22.333323 7557 exec.cpp:170] Executor started at: executor(180)@67.195.138.9:56600 with pid 7509
I0628 14:18:22.333180 7555 slave.cpp:947] Queuing task '0' for executor default of framework '201306281418-160088899-56600-7509-0000
I0628 14:18:22.349057 7558 hierarchical_allocator_process.hpp:569] Framework 201306281418-160088899-56600-7509-0000 filtered slave 201306281418-160088899-56600-7509-0 for 5secs
I0628 14:18:22.364959 7555 slave.cpp:738] Got assigned task 1 for framework 201306281418-160088899-56600-7509-0000
I0628 14:18:22.380933 7555 slave.cpp:510] Successfully attached file '/tmp/AllocatorTest_0_TaskFinished_H91XxI/slaves/201306281418-160088899-56600-7509-0/frameworks/201306281418-160088899-56600-7509-0000/executors/default/runs/f5350148-aefc-40bd-8b13-d80f437ba610'
I0628 14:18:22.388862 7555 slave.cpp:1394] Got registration for executor 'default' of framework 201306281418-160088899-56600-7509-0000
I0628 14:18:22.392087 7554 hierarchical_allocator_process.hpp:797] Filtered cpus=1; mem=512; ports=[31000-32000]; disk=829396 on slave 201306281418-160088899-56600-7509-0 for framework 201306281418-160088899-56600-7509-0000
I0628 14:18:22.404749 7554 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 12.693722ms
I0628 14:18:22.396862 7557 exec.cpp:194] Executor registered on slave 201306281418-160088899-56600-7509-0
I0628 14:18:22.396834 7555 slave.cpp:1509] Flushing queued task 0 for executor 'default' of framework 201306281418-160088899-56600-7509-0000
I0628 14:18:22.428689 7555 slave.cpp:836] Launching task 1 for framework 201306281418-160088899-56600-7509-0000
I0628 14:18:22.428717 7553 exec.cpp:258] Executor asked to run task '0'
I0628 14:18:22.436681 7555 slave.cpp:972] Sending task '1' to executor 'default' of framework 201306281418-160088899-56600-7509-0000
I0628 14:18:22.445426 7553 exec.cpp:404] Executor sending status update TASK_RUNNING (UUID: f8af3fdf-ff65-4a16-900e-86388f317af0) for task 0 of framework 201306281418-160088899-56600-7509-0000
I0628 14:18:22.460592 7554 slave.cpp:1691] Handling status update TASK_RUNNING (UUID: f8af3fdf-ff65-4a16-900e-86388f317af0) for task 0 of framework 201306281418-160088899-56600-7509-0000 from executor(180)@67.195.138.9:56600
I0628 14:18:22.461299 7553 exec.cpp:404] Executor sending status update TASK_FINISHED (UUID: 577c33ba-bcdf-42ff-b08e-5af99efedae1) for task 0 of framework 201306281418-160088899-56600-7509-0000
I0628 14:18:22.463474 7557 hierarchical_allocator_process.hpp:797] Filtered cpus=1; mem=512; ports=[31000-32000]; disk=829396 on slave 201306281418-160088899-56600-7509-0 for framework 201306281418-160088899-56600-7509-0000
I0628 14:18:22.484478 7557 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 21.041479ms
I0628 14:18:22.476517 7553 exec.cpp:258] Executor asked to run task '1'
I0628 14:18:22.476544 7552 slave.cpp:1691] Handling status update TASK_FINISHED (UUID: 577c33ba-bcdf-42ff-b08e-5af99efedae1) for task 0 of framework 201306281418-160088899-56600-7509-0000 from executor(180)@67.195.138.9:56600
I0628 14:18:22.468595 7554 status_update_manager.cpp:290] Received status update TASK_RUNNING (UUID: f8af3fdf-ff65-4a16-900e-86388f317af0) for task 0 of framework 201306281418-160088899-56600-7509-0000 with checkpoint=false
I0628 14:18:22.516316 7554 status_update_manager.cpp:450] Creating StatusUpdate stream for task 0 of framework 201306281418-160088899-56600-7509-0000
I0628 14:18:22.501142 7553 exec.cpp:404] Executor sending status update TASK_RUNNING (UUID: bde42dbd-34f4-4eb5-8eff-abf1f3c0275d) for task 1 of framework 201306281418-160088899-56600-7509-0000
I0628 14:18:22.524333 7554 status_update_manager.cpp:336] Forwarding status update TASK_RUNNING (UUID: f8af3fdf-ff65-4a16-900e-86388f317af0) for task 0 of framework 201306281418-160088899-56600-7509-0000 to master@67.195.138.9:56600
I0628 14:18:22.540271 7554 status_update_manager.cpp:290] Received status update TASK_FINISHED (UUID: 577c33ba-bcdf-42ff-b08e-5af99efedae1) for task 0 of framework 201306281418-160088899-56600-7509-0000 with checkpoint=false
I0628 14:18:22.540282 7558 master.cpp:1022] Status update from slave(489)@67.195.138.9:56600: task 0 of framework 201306281418-160088899-56600-7509-0000 is now in state TASK_RUNNING
I0628 14:18:22.532284 7553 slave.cpp:1691] Handling status update TASK_RUNNING (UUID: bde42dbd-34f4-4eb5-8eff-abf1f3c0275d) for task 1 of framework 201306281418-160088899-56600-7509-0000 from executor(180)@67.195.138.9:56600
I0628 14:18:22.542620 7552 hierarchical_allocator_process.hpp:797] Filtered cpus=1; mem=512; ports=[31000-32000]; disk=829396 on slave 201306281418-160088899-56600-7509-0 for framework 201306281418-160088899-56600-7509-0000
I0628 14:18:22.572124 7552 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 29.523443ms
I0628 14:18:22.564208 7554 status_update_manager.cpp:290] Received status update TASK_RUNNING (UUID: bde42dbd-34f4-4eb5-8eff-abf1f3c0275d) for task 1 of framework 201306281418-160088899-56600-7509-0000 with checkpoint=false
I0628 14:18:22.588021 7554 status_update_manager.cpp:450] Creating StatusUpdate stream for task 1 of framework 201306281418-160088899-56600-7509-0000
I0628 14:18:22.564188 7553 slave.cpp:1802] Status update manager successfully handled status update TASK_RUNNING (UUID: f8af3fdf-ff65-4a16-900e-86388f317af0) for task 0 of framework 201306281418-160088899-56600-7509-0000
I0628 14:18:22.603956 7553 slave.cpp:1808] Sending acknowledgement for status update TASK_RUNNING (UUID: f8af3fdf-ff65-4a16-900e-86388f317af0) for task 0 of framework 201306281418-160088899-56600-7509-0000 to executor(180)@67.195.138.9:56600
I0628 14:18:22.611979 7553 slave.cpp:1802] Status update manager successfully handled status update TASK_FINISHED (UUID: 577c33ba-bcdf-42ff-b08e-5af99efedae1) for task 0 of framework 201306281418-160088899-56600-7509-0000
I0628 14:18:22.619921 7553 slave.cpp:1808] Sending acknowledgement for status update TASK_FINISHED (UUID: 577c33ba-bcdf-42ff-b08e-5af99efedae1) for task 0 of framework 201306281418-160088899-56600-7509-0000 to executor(180)@67.195.138.9:56600
I0628 14:18:22.612004 7555 exec.cpp:290] Executor received status update acknowledgement f8af3fdf-ff65-4a16-900e-86388f317af0 for task 0 of framework 201306281418-160088899-56600-7509-0000
I0628 14:18:22.635876 7555 exec.cpp:290] Executor received status update acknowledgement 577c33ba-bcdf-42ff-b08e-5af99efedae1 for task 0 of framework 201306281418-160088899-56600-7509-0000
I0628 14:18:22.630251 7553 hierarchical_allocator_process.hpp:797] Filtered cpus=1; mem=512; ports=[31000-32000]; disk=829396 on slave 201306281418-160088899-56600-7509-0 for framework 201306281418-160088899-56600-7509-0000
I0628 14:18:22.596026 7554 status_update_manager.cpp:336] Forwarding status update TASK_RUNNING (UUID: bde42dbd-34f4-4eb5-8eff-abf1f3c0275d) for task 1 of framework 201306281418-160088899-56600-7509-0000 to master@67.195.138.9:56600
I0628 14:18:22.659803 7554 status_update_manager.cpp:360] Received status update acknowledgement f8af3fdf-ff65-4a16-900e-86388f317af0 for task 0 of framework 201306281418-160088899-56600-7509-0000
I0628 14:18:22.651788 7553 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 21.562333ms
I0628 14:18:22.659832 7557 master.cpp:1022] Status update from slave(489)@67.195.138.9:56600: task 1 of framework 201306281418-160088899-56600-7509-0000 is now in state TASK_RUNNING
I0628 14:18:22.659847 7556 slave.cpp:1802] Status update manager successfully handled status update TASK_RUNNING (UUID: bde42dbd-34f4-4eb5-8eff-abf1f3c0275d) for task 1 of framework 201306281418-160088899-56600-7509-0000
I0628 14:18:22.691637 7556 slave.cpp:1808] Sending acknowledgement for status update TASK_RUNNING (UUID: bde42dbd-34f4-4eb5-8eff-abf1f3c0275d) for task 1 of framework 201306281418-160088899-56600-7509-0000 to executor(180)@67.195.138.9:56600
I0628 14:18:22.699641 7558 exec.cpp:290] Executor received status update acknowledgement bde42dbd-34f4-4eb5-8eff-abf1f3c0275d for task 1 of framework 201306281418-160088899-56600-7509-0000
I0628 14:18:22.667747 7554 status_update_manager.cpp:336] Forwarding status update TASK_FINISHED (UUID: 577c33ba-bcdf-42ff-b08e-5af99efedae1) for task 0 of framework 201306281418-160088899-56600-7509-0000 to master@67.195.138.9:56600
I0628 14:18:22.715581 7554 status_update_manager.cpp:360] Received status update acknowledgement bde42dbd-34f4-4eb5-8eff-abf1f3c0275d for task 1 of framework 201306281418-160088899-56600-7509-0000
I0628 14:18:22.715607 7556 master.cpp:1022] Status update from slave(489)@67.195.138.9:56600: task 0 of framework 201306281418-160088899-56600-7509-0000 is now in state TASK_FINISHED
I0628 14:18:22.731511 7556 master.hpp:303] Removing task 0 with resources cpus=1; mem=256 on slave 201306281418-160088899-56600-7509-0
I0628 14:18:22.725904 7554 hierarchical_allocator_process.hpp:797] Filtered cpus=1; mem=512; ports=[31000-32000]; disk=829396 on slave 201306281418-160088899-56600-7509-0 for framework 201306281418-160088899-56600-7509-0000
I0628 14:18:22.747413 7554 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 21.53148ms
I0628 14:18:22.715622 7559 slave.cpp:1342] Status update manager successfully handled status update acknowledgement f8af3fdf-ff65-4a16-900e-86388f317af0 for task 0 of framework 201306281418-160088899-56600-7509-0000
I0628 14:18:22.755421 7554 hierarchical_allocator_process.hpp:616] Recovered cpus=1; mem=256 (total allocatable: cpus=2; mem=768; ports=[31000-32000]; disk=829396) on slave 201306281418-160088899-56600-7509-0 from framework 201306281418-160088899-56600-7509-0000
I0628 14:18:22.763361 7559 slave.cpp:1342] Status update manager successfully handled status update acknowledgement bde42dbd-34f4-4eb5-8eff-abf1f3c0275d for task 1 of framework 201306281418-160088899-56600-7509-0000
I0628 14:18:22.779341 7559 status_update_manager.cpp:360] Received status update acknowledgement 577c33ba-bcdf-42ff-b08e-5af99efedae1 for task 0 of framework 201306281418-160088899-56600-7509-0000
I0628 14:18:22.787216 7556 monitor.cpp:180] Publishing resource usage for executor 'default' of framework '201306281418-160088899-56600-7509-0000'
I0628 14:18:22.787286 7559 status_update_manager.cpp:481] Cleaning up status update stream for task 0 of framework 201306281418-160088899-56600-7509-0000
I0628 14:18:22.803249 7559 slave.cpp:1342] Status update manager successfully handled status update acknowledgement 577c33ba-bcdf-42ff-b08e-5af99efedae1 for task 0 of framework 201306281418-160088899-56600-7509-0000
I0628 14:18:22.806272 7552 hierarchical_allocator_process.hpp:727] Offering cpus=2; mem=768; ports=[31000-32000]; disk=829396 on slave 201306281418-160088899-56600-7509-0 to framework 201306281418-160088899-56600-7509-0000
E0628 14:18:22.811179 7559 slave.cpp:1366] Status update acknowledgement 577c33ba-bcdf-42ff-b08e-5af99efedae1 for task 0 of unknown executor
I0628 14:18:22.819257 7552 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 13.006127ms
I0628 14:18:22.819299 7554 master.hpp:313] Adding offer 201306281418-160088899-56600-7509-1 with resources cpus=2; mem=768; ports=[31000-32000]; disk=829396 on slave 201306281418-160088899-56600-7509-0
I0628 14:18:22.843080 7554 master.cpp:1239] Sending 1 offers to framework 201306281418-160088899-56600-7509-0000
I0628 14:18:22.851229 7552 sched.cpp:427] Stopping framework '201306281418-160088899-56600-7509-0000'
I0628 14:18:22.851258 7558 master.cpp:385] Master terminating
I0628 14:18:22.866996 7556 slave.cpp:484] Slave asked to shut down by master@67.195.138.9:56600
I0628 14:18:22.867020 7509 master.cpp:207] Shutting down master
I0628 14:18:22.882967 7558 hierarchical_allocator_process.hpp:412] Deactivated framework 201306281418-160088899-56600-7509-0000
I0628 14:18:22.890869 7558 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 10.439us
I0628 14:18:22.882976 7509 master.hpp:303] Removing task 1 with resources cpus=1; mem=256 on slave 201306281418-160088899-56600-7509-0
I0628 14:18:22.874954 7556 slave.cpp:1111] Asked to shut down framework 201306281418-160088899-56600-7509-0000 by master@67.195.138.9:56600
I0628 14:18:22.906895 7509 master.hpp:323] Removing offer 201306281418-160088899-56600-7509-1 with resources cpus=2; mem=768; ports=[31000-32000]; disk=829396 on slave 201306281418-160088899-56600-7509-0
I0628 14:18:22.906970 7554 hierarchical_allocator_process.hpp:616] Recovered cpus=1; mem=256 (total allocatable: cpus=1; mem=256; ports=[]; disk=0) on slave 201306281418-160088899-56600-7509-0 from framework 201306281418-160088899-56600-7509-0000
I0628 14:18:22.914760 7556 slave.cpp:1136] Shutting down framework 201306281418-160088899-56600-7509-0000
I0628 14:18:22.938688 7556 slave.cpp:2327] Shutting down executor 'default' of framework 201306281418-160088899-56600-7509-0000
I0628 14:18:22.946662 7556 slave.cpp:439] Slave terminating
I0628 14:18:22.930804 7554 hierarchical_allocator_process.hpp:616] Recovered cpus=2; mem=768; ports=[31000-32000]; disk=829396 (total allocatable: cpus=3; mem=1024; ports=[31000-32000]; disk=829396) on slave 201306281418-160088899-56600-7509-0 from framework 201306281418-160088899-56600-7509-0000
I0628 14:18:22.946683 7553 exec.cpp:323] Executor asked to shutdown
I0628 14:18:22.954598 7556 slave.cpp:1111] Asked to shut down framework 201306281418-160088899-56600-7509-0000 by @0.0.0.0:0
I0628 14:18:22.962635 7554 hierarchical_allocator_process.hpp:367] Removed framework 201306281418-160088899-56600-7509-0000
W0628 14:18:22.978498 7556 slave.cpp:1132] Ignoring shutdown framework 201306281418-160088899-56600-7509-0000 because it is terminating
I0628 14:18:22.986506 7554 hierarchical_allocator_process.hpp:477] Removed slave 201306281418-160088899-56600-7509-0
[ OK ] AllocatorTest/0.TaskFinished (1143 ms)
[ RUN ] AllocatorTest/0.WhitelistSlave
I0628 14:18:23.003099 7552 master.cpp:228] Master started on 67.195.138.9:56600
I0628 14:18:23.010447 7552 master.cpp:243] Master ID: 201306281418-160088899-56600-7509
I0628 14:18:23.003590 7553 slave.cpp:112] Slave started on 490)@67.195.138.9:56600
I0628 14:18:23.018591 7554 hierarchical_allocator_process.hpp:295] Initializing hierarchical allocator process with master : master@67.195.138.9:56600
I0628 14:18:23.018683 7552 master.cpp:526] Elected as master!
I0628 14:18:23.018844 7557 sched.cpp:177] New master at master@67.195.138.9:56600
I0628 14:18:23.026430 7553 slave.cpp:204] Slave resources: cpus=2; mem=1024; ports=[31000-32000]; disk=829396
I0628 14:18:23.034340 7554 hierarchical_allocator_process.hpp:491] Updated slave white list: { dummy-slave }
I0628 14:18:23.050412 7559 master.cpp:569] Registering framework 201306281418-160088899-56600-7509-0000 at scheduler(445)@67.195.138.9:56600
I0628 14:18:23.074193 7552 sched.cpp:222] Framework registered with 201306281418-160088899-56600-7509-0000
I0628 14:18:23.066174 7554 hierarchical_allocator_process.hpp:700] No users to allocate resources!
I0628 14:18:23.090069 7554 hierarchical_allocator_process.hpp:667] Performed allocation for 0 slaves in 23.894959ms
I0628 14:18:23.058660 7553 slave.cpp:528] New master detected at master@67.195.138.9:56600
I0628 14:18:23.106017 7553 slave.cpp:543] Postponing registration until recovery is complete
I0628 14:18:23.098067 7554 hierarchical_allocator_process.hpp:327] Added framework 201306281418-160088899-56600-7509-0000
I0628 14:18:23.121938 7554 hierarchical_allocator_process.hpp:705] No resources available to allocate!
I0628 14:18:23.106037 7557 status_update_manager.cpp:155] New master detected at master@67.195.138.9:56600
I0628 14:18:23.113973 7553 slave.cpp:389] Finished recovery
I0628 14:18:23.074203 7559 master.cpp:556] Framework 201306281418-160088899-56600-7509-0000 (scheduler(445)@67.195.138.9:56600) already registered, resending acknowledgement
I0628 14:18:23.153862 7559 master.cpp:891] Attempting to register slave on vesta.apache.org at slave(490)@67.195.138.9:56600
I0628 14:18:23.153867 7556 sched.cpp:217] Ignoring framework registered message because the driver is already connected!
I0628 14:18:23.129900 7554 hierarchical_allocator_process.hpp:667] Performed allocation for 0 slaves in 7.96198ms
I0628 14:18:23.161779 7559 master.cpp:1851] Adding slave 201306281418-160088899-56600-7509-0 at vesta.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=829396
I0628 14:18:23.185813 7559 master.cpp:880] Slave 201306281418-160088899-56600-7509-0 (vesta.apache.org) already registered, resending acknowledgement
I0628 14:18:23.185842 7558 slave.cpp:588] Registered with master master@67.195.138.9:56600; given slave ID 201306281418-160088899-56600-7509-0
W0628 14:18:23.201648 7558 slave.cpp:612] Already registered with master master@67.195.138.9:56600
I0628 14:18:23.185937 7552 hierarchical_allocator_process.hpp:449] Added slave 201306281418-160088899-56600-7509-0 (vesta.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=829396 (and cpus=2; mem=1024; ports=[31000-32000]; disk=829396 available)
I0628 14:18:23.220197 7555 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 24.597us
I0628 14:18:23.230193 7557 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 28.364us
I0628 14:18:23.240233 7556 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 11.524us
I0628 14:18:23.250324 7553 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 16.651us
I0628 14:18:23.250452 7555 hierarchical_allocator_process.hpp:491] Updated slave white list: { dummy-slave, vesta.apache.org }
I0628 14:18:23.260370 7554 hierarchical_allocator_process.hpp:727] Offering cpus=2; mem=1024; ports=[31000-32000]; disk=829396 on slave 201306281418-160088899-56600-7509-0 to framework 201306281418-160088899-56600-7509-0000
I0628 14:18:23.265512 7554 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 5.201514ms
I0628 14:18:23.265597 7559 master.hpp:313] Adding offer 201306281418-160088899-56600-7509-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=829396 on slave 201306281418-160088899-56600-7509-0
I0628 14:18:23.281399 7559 master.cpp:1239] Sending 1 offers to framework 201306281418-160088899-56600-7509-0000
I0628 14:18:23.290536 7559 master.cpp:385] Master terminating
I0628 14:18:23.290536 7558 sched.cpp:427] Stopping framework '201306281418-160088899-56600-7509-0000'
I0628 14:18:23.297307 7559 slave.cpp:484] Slave asked to shut down by master@67.195.138.9:56600
I0628 14:18:23.297343 7509 master.cpp:207] Shutting down master
I0628 14:18:23.313205 7559 slave.cpp:439] Slave terminating
I0628 14:18:23.321286 7509 master.hpp:323] Removing offer 201306281418-160088899-56600-7509-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=829396 on slave 201306281418-160088899-56600-7509-0
I0628 14:18:23.321333 7556 hierarchical_allocator_process.hpp:412] Deactivated framework 201306281418-160088899-56600-7509-0000
[ OK ] AllocatorTest/0.WhitelistSlave (343 ms)
[----------] 9 tests from AllocatorTest/0 (6202 ms total)
[----------] 1 test from LoggingTest
[ RUN ] LoggingTest.Toggle
I0628 14:18:23.345520 7553 process.cpp:2942] Handling HTTP event for process 'logging' with path: '/logging/toggle'
I0628 14:18:23.353667 7560 process.cpp:878] Socket closed while receiving
I0628 14:18:23.361577 7556 process.cpp:2942] Handling HTTP event for process 'logging' with path: '/logging/toggle'
I0628 14:18:23.369731 7560 process.cpp:878] Socket closed while receiving
I0628 14:18:23.377387 7558 process.cpp:2942] Handling HTTP event for process 'logging' with path: '/logging/toggle'
I0628 14:18:23.385622 7560 process.cpp:878] Socket closed while receiving
I0628 14:18:23.393344 7556 process.cpp:2942] Handling HTTP event for process 'logging' with path: '/logging/toggle'
I0628 14:18:23.401363 7560 process.cpp:878] Socket closed while receiving
I0628 14:18:23.409590 7558 process.cpp:2942] Handling HTTP event for process 'logging' with path: '/logging/toggle'
I0628 14:18:23.417557 7560 process.cpp:878] Socket closed while receiving
I0628 14:18:23.425559 7556 process.cpp:2942] Handling HTTP event for process 'logging' with path: '/logging/toggle'
I0628 14:18:23.433380 7560 process.cpp:878] Socket closed while receiving
[ OK ] LoggingTest.Toggle (96 ms)
[----------] 1 test from LoggingTest (96 ms total)
[----------] 5 tests from CgroupsCpusetTest
[ RUN ] CgroupsCpusetTest.OneCPUOneCpuset
[ OK ] CgroupsCpusetTest.OneCPUOneCpuset (0 ms)
[ RUN ] CgroupsCpusetTest.OneCPUManyCpusets
[ OK ] CgroupsCpusetTest.OneCPUManyCpusets (0 ms)
[ RUN ] CgroupsCpusetTest.ManyCPUOneCpuset
[ OK ] CgroupsCpusetTest.ManyCPUOneCpuset (0 ms)
[ RUN ] CgroupsCpusetTest.ManyCPUManyCpusets
[ OK ] CgroupsCpusetTest.ManyCPUManyCpusets (0 ms)
[ RUN ] CgroupsCpusetTest.IntegerAllocations
[ OK ] CgroupsCpusetTest.IntegerAllocations (0 ms)
[----------] 5 tests from CgroupsCpusetTest (0 ms total)
[----------] 3 tests from FsTest
[ RUN ] FsTest.MountTableRead
[ OK ] FsTest.MountTableRead (0 ms)
[ RUN ] FsTest.MountTableHasOption
[ OK ] FsTest.MountTableHasOption (0 ms)
[ RUN ] FsTest.FileSystemTableRead
[ OK ] FsTest.FileSystemTableRead (0 ms)
[----------] 3 tests from FsTest (0 ms total)
[----------] Global test environment tear-down
[==========] 168 tests from 32 test cases ran. (100543 ms total)
[ PASSED ] 168 tests.
make[3]: *** [check-local] Error 1
make[3]: Leaving directory `/home/hudson/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/build/src'
make[2]: *** [check-am] Error 2
make[2]: Leaving directory `/home/hudson/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/build/src'
make[1]: *** [check] Error 2
make[1]: Leaving directory `/home/hudson/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/build/src'
make: *** [check-recursive] Error 1
Build step 'Execute shell' marked build as failure
Build failed in Jenkins:
Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui
#1285
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/1285/>
------------------------------------------
[...truncated 50941 lines...]
I0628 12:09:46.005398 23760 master.cpp:1472] Processing reply for offer 201306281209-1015726915-47508-23737-3 on slave 201306281209-1015726915-47508-23737-1 (janus.apache.org) for framework 201306281209-1015726915-47508-23737-0000
I0628 12:09:46.005810 23760 master.hpp:323] Removing offer 201306281209-1015726915-47508-23737-3 with resources cpus=4; mem=2048; ports=[31000-32000]; disk=21009 on slave 201306281209-1015726915-47508-23737-1
I0628 12:09:46.005863 23763 hierarchical_allocator_process.hpp:526] Framework 201306281209-1015726915-47508-23737-0000 left cpus=4; mem=2048; ports=[31000-32000]; disk=21009 unused on slave 201306281209-1015726915-47508-23737-1
I0628 12:09:46.054713 23756 hierarchical_allocator_process.hpp:727] Offering cpus=1; mem=512; ports=[31000-32000]; disk=21009 on slave 201306281209-1015726915-47508-23737-0 to framework 201306281209-1015726915-47508-23737-0000
I0628 12:09:46.054841 23756 hierarchical_allocator_process.hpp:727] Offering cpus=4; mem=2048; ports=[31000-32000]; disk=21009 on slave 201306281209-1015726915-47508-23737-1 to framework 201306281209-1015726915-47508-23737-0000
I0628 12:09:46.055295 23756 hierarchical_allocator_process.hpp:667] Performed allocation for 2 slaves in 630.587us
I0628 12:09:46.055348 23758 master.hpp:313] Adding offer 201306281209-1015726915-47508-23737-4 with resources cpus=4; mem=2048; ports=[31000-32000]; disk=21009 on slave 201306281209-1015726915-47508-23737-1
I0628 12:09:46.056640 23758 master.hpp:313] Adding offer 201306281209-1015726915-47508-23737-5 with resources cpus=1; mem=512; ports=[31000-32000]; disk=21009 on slave 201306281209-1015726915-47508-23737-0
I0628 12:09:46.057001 23758 master.cpp:1239] Sending 2 offers to framework 201306281209-1015726915-47508-23737-0000
I0628 12:09:46.057708 23762 sched.cpp:427] Stopping framework '201306281209-1015726915-47508-23737-0000'
I0628 12:09:46.057718 23737 master.cpp:385] Master terminating
I0628 12:09:46.058405 23737 master.cpp:207] Shutting down master
I0628 12:09:46.058447 23757 slave.cpp:484] Slave asked to shut down by master@67.195.138.60:47508
I0628 12:09:46.058449 23756 slave.cpp:484] Slave asked to shut down by master@67.195.138.60:47508
I0628 12:09:46.059823 23756 slave.cpp:1111] Asked to shut down framework 201306281209-1015726915-47508-23737-0000 by master@67.195.138.60:47508
I0628 12:09:46.060255 23756 slave.cpp:1136] Shutting down framework 201306281209-1015726915-47508-23737-0000
I0628 12:09:46.060729 23756 slave.cpp:2327] Shutting down executor 'default' of framework 201306281209-1015726915-47508-23737-0000
I0628 12:09:46.061221 23756 slave.cpp:1883] master@67.195.138.60:47508 exited
W0628 12:09:46.061667 23756 slave.cpp:1886] Master disconnected! Waiting for a new master to be elected
I0628 12:09:46.062144 23756 slave.cpp:1111] Asked to shut down framework 201306281209-1015726915-47508-23737-0000 by master@67.195.138.60:47508
I0628 12:09:46.061226 23758 exec.cpp:323] Executor asked to shutdown
I0628 12:09:46.058928 23737 master.hpp:303] Removing task 0 with resources cpus=2; mem=512 on slave 201306281209-1015726915-47508-23737-0
I0628 12:09:46.058936 23760 hierarchical_allocator_process.hpp:412] Deactivated framework 201306281209-1015726915-47508-23737-0000
I0628 12:09:46.064780 23760 hierarchical_allocator_process.hpp:616] Recovered cpus=2; mem=512 (total allocatable: cpus=2; mem=512; ports=[]; disk=0) on slave 201306281209-1015726915-47508-23737-0 from framework 201306281209-1015726915-47508-23737-0000
I0628 12:09:46.065387 23760 hierarchical_allocator_process.hpp:616] Recovered cpus=1; mem=512; ports=[31000-32000]; disk=21009 (total allocatable: cpus=3; mem=1024; ports=[31000-32000]; disk=21009) on slave 201306281209-1015726915-47508-23737-0 from framework 201306281209-1015726915-47508-23737-0000
I0628 12:09:46.063691 23737 master.hpp:323] Removing offer 201306281209-1015726915-47508-23737-5 with resources cpus=1; mem=512; ports=[31000-32000]; disk=21009 on slave 201306281209-1015726915-47508-23737-0
I0628 12:09:46.066293 23737 master.hpp:323] Removing offer 201306281209-1015726915-47508-23737-4 with resources cpus=4; mem=2048; ports=[31000-32000]; disk=21009 on slave 201306281209-1015726915-47508-23737-1
W0628 12:09:46.062613 23756 slave.cpp:1132] Ignoring shutdown framework 201306281209-1015726915-47508-23737-0000 because it is terminating
I0628 12:09:46.067193 23756 slave.cpp:439] Slave terminating
I0628 12:09:46.067914 23756 slave.cpp:1111] Asked to shut down framework 201306281209-1015726915-47508-23737-0000 by @0.0.0.0:0
W0628 12:09:46.069113 23756 slave.cpp:1132] Ignoring shutdown framework 201306281209-1015726915-47508-23737-0000 because it is terminating
I0628 12:09:46.066401 23763 hierarchical_allocator_process.hpp:616] Recovered cpus=4; mem=2048; ports=[31000-32000]; disk=21009 (total allocatable: cpus=4; mem=2048; ports=[31000-32000]; disk=21009) on slave 201306281209-1015726915-47508-23737-1 from framework 201306281209-1015726915-47508-23737-0000
I0628 12:09:46.059348 23757 slave.cpp:439] Slave terminating
I0628 12:09:46.070935 23763 hierarchical_allocator_process.hpp:367] Removed framework 201306281209-1015726915-47508-23737-0000
[ OK ] AllocatorTest/0.SlaveAdded (174 ms)
[ RUN ] AllocatorTest/0.TaskFinished
I0628 12:09:46.072330 23761 master.cpp:228] Master started on 67.195.138.60:47508
I0628 12:09:46.072387 23761 master.cpp:243] Master ID: 201306281209-1015726915-47508-23737
I0628 12:09:46.072919 23763 slave.cpp:112] Slave started on 489)@67.195.138.60:47508
W0628 12:09:46.072959 23760 master.cpp:83] No whitelist given. Advertising offers for all slaves
I0628 12:09:46.073075 23756 hierarchical_allocator_process.hpp:295] Initializing hierarchical allocator process with master : master@67.195.138.60:47508
I0628 12:09:46.073149 23761 master.cpp:526] Elected as master!
I0628 12:09:46.073421 23757 sched.cpp:177] New master at master@67.195.138.60:47508
I0628 12:09:46.073912 23763 slave.cpp:204] Slave resources: cpus=3; mem=1024; ports=[31000-32000]; disk=21009
I0628 12:09:46.075824 23757 master.cpp:569] Registering framework 201306281209-1015726915-47508-23737-0000 at scheduler(444)@67.195.138.60:47508
I0628 12:09:46.076977 23757 sched.cpp:222] Framework registered with 201306281209-1015726915-47508-23737-0000
I0628 12:09:46.076983 23763 slave.cpp:528] New master detected at master@67.195.138.60:47508
I0628 12:09:46.077076 23758 hierarchical_allocator_process.hpp:327] Added framework 201306281209-1015726915-47508-23737-0000
I0628 12:09:46.079370 23758 hierarchical_allocator_process.hpp:705] No resources available to allocate!
I0628 12:09:46.078194 23762 status_update_manager.cpp:155] New master detected at master@67.195.138.60:47508
I0628 12:09:46.078152 23763 slave.cpp:543] Postponing registration until recovery is complete
I0628 12:09:46.081202 23763 slave.cpp:389] Finished recovery
I0628 12:09:46.080281 23758 hierarchical_allocator_process.hpp:667] Performed allocation for 0 slaves in 912.02us
I0628 12:09:46.081814 23761 master.cpp:891] Attempting to register slave on janus.apache.org at slave(489)@67.195.138.60:47508
I0628 12:09:46.082732 23761 master.cpp:1851] Adding slave 201306281209-1015726915-47508-23737-0 at janus.apache.org with cpus=3; mem=1024; ports=[31000-32000]; disk=21009
I0628 12:09:46.083245 23757 slave.cpp:588] Registered with master master@67.195.138.60:47508; given slave ID 201306281209-1015726915-47508-23737-0
I0628 12:09:46.083427 23756 hierarchical_allocator_process.hpp:449] Added slave 201306281209-1015726915-47508-23737-0 (janus.apache.org) with cpus=3; mem=1024; ports=[31000-32000]; disk=21009 (and cpus=3; mem=1024; ports=[31000-32000]; disk=21009 available)
I0628 12:09:46.084450 23756 hierarchical_allocator_process.hpp:727] Offering cpus=3; mem=1024; ports=[31000-32000]; disk=21009 on slave 201306281209-1015726915-47508-23737-0 to framework 201306281209-1015726915-47508-23737-0000
I0628 12:09:46.085021 23756 hierarchical_allocator_process.hpp:687] Performed allocation for slave 201306281209-1015726915-47508-23737-0 in 584.475us
I0628 12:09:46.085063 23757 master.hpp:313] Adding offer 201306281209-1015726915-47508-23737-0 with resources cpus=3; mem=1024; ports=[31000-32000]; disk=21009 on slave 201306281209-1015726915-47508-23737-0
I0628 12:09:46.085921 23757 master.cpp:1239] Sending 1 offers to framework 201306281209-1015726915-47508-23737-0000
I0628 12:09:46.086766 23761 master.cpp:1472] Processing reply for offer 201306281209-1015726915-47508-23737-0 on slave 201306281209-1015726915-47508-23737-0 (janus.apache.org) for framework 201306281209-1015726915-47508-23737-0000
I0628 12:09:46.087074 23761 master.hpp:291] Adding task 0 with resources cpus=1; mem=256 on slave 201306281209-1015726915-47508-23737-0
I0628 12:09:46.087472 23761 master.cpp:1591] Launching task 0 of framework 201306281209-1015726915-47508-23737-0000 with resources cpus=1; mem=256 on slave 201306281209-1015726915-47508-23737-0 (janus.apache.org)
I0628 12:09:46.087991 23757 slave.cpp:738] Got assigned task 0 for framework 201306281209-1015726915-47508-23737-0000
I0628 12:09:46.088011 23761 master.hpp:291] Adding task 1 with resources cpus=1; mem=256 on slave 201306281209-1015726915-47508-23737-0
I0628 12:09:46.089640 23761 master.cpp:1591] Launching task 1 of framework 201306281209-1015726915-47508-23737-0000 with resources cpus=1; mem=256 on slave 201306281209-1015726915-47508-23737-0 (janus.apache.org)
I0628 12:09:46.089274 23757 slave.cpp:836] Launching task 0 for framework 201306281209-1015726915-47508-23737-0000
I0628 12:09:46.090827 23761 master.hpp:323] Removing offer 201306281209-1015726915-47508-23737-0 with resources cpus=3; mem=1024; ports=[31000-32000]; disk=21009 on slave 201306281209-1015726915-47508-23737-0
I0628 12:09:46.090880 23760 hierarchical_allocator_process.hpp:526] Framework 201306281209-1015726915-47508-23737-0000 left cpus=1; mem=512; ports=[31000-32000]; disk=21009 unused on slave 201306281209-1015726915-47508-23737-0
I0628 12:09:46.092247 23760 hierarchical_allocator_process.hpp:569] Framework 201306281209-1015726915-47508-23737-0000 filtered slave 201306281209-1015726915-47508-23737-0 for 5secs
I0628 12:09:46.092474 23757 paths.hpp:303] Created executor directory '/tmp/AllocatorTest_0_TaskFinished_Zwl2Xi/slaves/201306281209-1015726915-47508-23737-0/frameworks/201306281209-1015726915-47508-23737-0000/executors/default/runs/4f449ddb-02bd-4141-a97c-cf1ad4102752'
I0628 12:09:46.093617 23757 slave.cpp:947] Queuing task '0' for executor default of framework '201306281209-1015726915-47508-23737-0000
I0628 12:09:46.093942 23757 slave.cpp:738] Got assigned task 1 for framework 201306281209-1015726915-47508-23737-0000
I0628 12:09:46.093782 23758 exec.cpp:170] Executor started at: executor(180)@67.195.138.60:47508 with pid 23737
I0628 12:09:46.094519 23757 slave.cpp:510] Successfully attached file '/tmp/AllocatorTest_0_TaskFinished_Zwl2Xi/slaves/201306281209-1015726915-47508-23737-0/frameworks/201306281209-1015726915-47508-23737-0000/executors/default/runs/4f449ddb-02bd-4141-a97c-cf1ad4102752'
I0628 12:09:46.095646 23757 slave.cpp:836] Launching task 1 for framework 201306281209-1015726915-47508-23737-0000
I0628 12:09:46.096089 23757 slave.cpp:947] Queuing task '1' for executor default of framework '201306281209-1015726915-47508-23737-0000
I0628 12:09:46.097242 23757 slave.cpp:1394] Got registration for executor 'default' of framework 201306281209-1015726915-47508-23737-0000
I0628 12:09:46.097761 23757 slave.cpp:1509] Flushing queued task 1 for executor 'default' of framework 201306281209-1015726915-47508-23737-0000
I0628 12:09:46.097810 23761 exec.cpp:194] Executor registered on slave 201306281209-1015726915-47508-23737-0
I0628 12:09:46.098230 23757 slave.cpp:1509] Flushing queued task 0 for executor 'default' of framework 201306281209-1015726915-47508-23737-0000
I0628 12:09:46.099326 23761 exec.cpp:258] Executor asked to run task '1'
I0628 12:09:46.100321 23761 exec.cpp:258] Executor asked to run task '0'
I0628 12:09:46.101646 23761 exec.cpp:404] Executor sending status update TASK_RUNNING (UUID: 5fdede15-cb5b-4642-8c48-9cd3be11ce4f) for task 1 of framework 201306281209-1015726915-47508-23737-0000
I0628 12:09:46.102099 23760 slave.cpp:1691] Handling status update TASK_RUNNING (UUID: 5fdede15-cb5b-4642-8c48-9cd3be11ce4f) for task 1 of framework 201306281209-1015726915-47508-23737-0000 from executor(180)@67.195.138.60:47508
I0628 12:09:46.102570 23760 status_update_manager.cpp:290] Received status update TASK_RUNNING (UUID: 5fdede15-cb5b-4642-8c48-9cd3be11ce4f) for task 1 of framework 201306281209-1015726915-47508-23737-0000 with checkpoint=false
I0628 12:09:46.103164 23760 status_update_manager.cpp:450] Creating StatusUpdate stream for task 1 of framework 201306281209-1015726915-47508-23737-0000
I0628 12:09:46.103710 23760 status_update_manager.cpp:336] Forwarding status update TASK_RUNNING (UUID: 5fdede15-cb5b-4642-8c48-9cd3be11ce4f) for task 1 of framework 201306281209-1015726915-47508-23737-0000 to master@67.195.138.60:47508
I0628 12:09:46.103076 23761 exec.cpp:404] Executor sending status update TASK_FINISHED (UUID: 65127026-62be-475c-aded-2e7a367ca9a7) for task 1 of framework 201306281209-1015726915-47508-23737-0000
I0628 12:09:46.104202 23758 slave.cpp:1802] Status update manager successfully handled status update TASK_RUNNING (UUID: 5fdede15-cb5b-4642-8c48-9cd3be11ce4f) for task 1 of framework 201306281209-1015726915-47508-23737-0000
I0628 12:09:46.104225 23762 master.cpp:1022] Status update from slave(489)@67.195.138.60:47508: task 1 of framework 201306281209-1015726915-47508-23737-0000 is now in state TASK_RUNNING
I0628 12:09:46.105116 23758 slave.cpp:1808] Sending acknowledgement for status update TASK_RUNNING (UUID: 5fdede15-cb5b-4642-8c48-9cd3be11ce4f) for task 1 of framework 201306281209-1015726915-47508-23737-0000 to executor(180)@67.195.138.60:47508
I0628 12:09:46.106330 23758 slave.cpp:1691] Handling status update TASK_FINISHED (UUID: 65127026-62be-475c-aded-2e7a367ca9a7) for task 1 of framework 201306281209-1015726915-47508-23737-0000 from executor(180)@67.195.138.60:47508
I0628 12:09:46.105640 23761 exec.cpp:404] Executor sending status update TASK_RUNNING (UUID: 1f3bb2f0-66b9-4eef-b1d1-5e250b0b2307) for task 0 of framework 201306281209-1015726915-47508-23737-0000
I0628 12:09:46.107528 23756 status_update_manager.cpp:290] Received status update TASK_FINISHED (UUID: 65127026-62be-475c-aded-2e7a367ca9a7) for task 1 of framework 201306281209-1015726915-47508-23737-0000 with checkpoint=false
I0628 12:09:46.107852 23761 exec.cpp:290] Executor received status update acknowledgement 5fdede15-cb5b-4642-8c48-9cd3be11ce4f for task 1 of framework 201306281209-1015726915-47508-23737-0000
I0628 12:09:46.107867 23758 slave.cpp:1691] Handling status update TASK_RUNNING (UUID: 1f3bb2f0-66b9-4eef-b1d1-5e250b0b2307) for task 0 of framework 201306281209-1015726915-47508-23737-0000 from executor(180)@67.195.138.60:47508
I0628 12:09:46.108381 23756 status_update_manager.cpp:360] Received status update acknowledgement 5fdede15-cb5b-4642-8c48-9cd3be11ce4f for task 1 of framework 201306281209-1015726915-47508-23737-0000
I0628 12:09:46.109941 23756 status_update_manager.cpp:336] Forwarding status update TASK_FINISHED (UUID: 65127026-62be-475c-aded-2e7a367ca9a7) for task 1 of framework 201306281209-1015726915-47508-23737-0000 to master@67.195.138.60:47508
I0628 12:09:46.109379 23758 slave.cpp:1802] Status update manager successfully handled status update TASK_FINISHED (UUID: 65127026-62be-475c-aded-2e7a367ca9a7) for task 1 of framework 201306281209-1015726915-47508-23737-0000
I0628 12:09:46.110441 23763 master.cpp:1022] Status update from slave(489)@67.195.138.60:47508: task 1 of framework 201306281209-1015726915-47508-23737-0000 is now in state TASK_FINISHED
I0628 12:09:46.110465 23756 status_update_manager.cpp:290] Received status update TASK_RUNNING (UUID: 1f3bb2f0-66b9-4eef-b1d1-5e250b0b2307) for task 0 of framework 201306281209-1015726915-47508-23737-0000 with checkpoint=false
I0628 12:09:46.112081 23756 status_update_manager.cpp:450] Creating StatusUpdate stream for task 0 of framework 201306281209-1015726915-47508-23737-0000
I0628 12:09:46.112571 23756 status_update_manager.cpp:336] Forwarding status update TASK_RUNNING (UUID: 1f3bb2f0-66b9-4eef-b1d1-5e250b0b2307) for task 0 of framework 201306281209-1015726915-47508-23737-0000 to master@67.195.138.60:47508
I0628 12:09:46.110858 23758 slave.cpp:1808] Sending acknowledgement for status update TASK_FINISHED (UUID: 65127026-62be-475c-aded-2e7a367ca9a7) for task 1 of framework 201306281209-1015726915-47508-23737-0000 to executor(180)@67.195.138.60:47508
I0628 12:09:46.114163 23758 slave.cpp:1342] Status update manager successfully handled status update acknowledgement 5fdede15-cb5b-4642-8c48-9cd3be11ce4f for task 1 of framework 201306281209-1015726915-47508-23737-0000
I0628 12:09:46.111634 23763 master.hpp:303] Removing task 1 with resources cpus=1; mem=256 on slave 201306281209-1015726915-47508-23737-0
I0628 12:09:46.114179 23757 exec.cpp:290] Executor received status update acknowledgement 65127026-62be-475c-aded-2e7a367ca9a7 for task 1 of framework 201306281209-1015726915-47508-23737-0000
I0628 12:09:46.114670 23758 slave.cpp:1802] Status update manager successfully handled status update TASK_RUNNING (UUID: 1f3bb2f0-66b9-4eef-b1d1-5e250b0b2307) for task 0 of framework 201306281209-1015726915-47508-23737-0000
I0628 12:09:46.116683 23758 slave.cpp:1808] Sending acknowledgement for status update TASK_RUNNING (UUID: 1f3bb2f0-66b9-4eef-b1d1-5e250b0b2307) for task 0 of framework 201306281209-1015726915-47508-23737-0000 to executor(180)@67.195.138.60:47508
I0628 12:09:46.117229 23758 exec.cpp:290] Executor received status update acknowledgement 1f3bb2f0-66b9-4eef-b1d1-5e250b0b2307 for task 0 of framework 201306281209-1015726915-47508-23737-0000
I0628 12:09:46.115238 23756 hierarchical_allocator_process.hpp:616] Recovered cpus=1; mem=256 (total allocatable: cpus=2; mem=768; ports=[31000-32000]; disk=21009) on slave 201306281209-1015726915-47508-23737-0 from framework 201306281209-1015726915-47508-23737-0000
I0628 12:09:46.114681 23760 status_update_manager.cpp:360] Received status update acknowledgement 65127026-62be-475c-aded-2e7a367ca9a7 for task 1 of framework 201306281209-1015726915-47508-23737-0000
I0628 12:09:46.115157 23763 master.cpp:1022] Status update from slave(489)@67.195.138.60:47508: task 0 of framework 201306281209-1015726915-47508-23737-0000 is now in state TASK_RUNNING
I0628 12:09:46.118453 23760 status_update_manager.cpp:481] Cleaning up status update stream for task 1 of framework 201306281209-1015726915-47508-23737-0000
I0628 12:09:46.119467 23760 status_update_manager.cpp:360] Received status update acknowledgement 1f3bb2f0-66b9-4eef-b1d1-5e250b0b2307 for task 0 of framework 201306281209-1015726915-47508-23737-0000
I0628 12:09:46.119474 23762 slave.cpp:1342] Status update manager successfully handled status update acknowledgement 65127026-62be-475c-aded-2e7a367ca9a7 for task 1 of framework 201306281209-1015726915-47508-23737-0000
E0628 12:09:46.120436 23762 slave.cpp:1366] Status update acknowledgement 65127026-62be-475c-aded-2e7a367ca9a7 for task 1 of unknown executor
I0628 12:09:46.121000 23762 slave.cpp:1342] Status update manager successfully handled status update acknowledgement 1f3bb2f0-66b9-4eef-b1d1-5e250b0b2307 for task 0 of framework 201306281209-1015726915-47508-23737-0000
I0628 12:09:46.124941 23761 hierarchical_allocator_process.hpp:727] Offering cpus=2; mem=768; ports=[31000-32000]; disk=21009 on slave 201306281209-1015726915-47508-23737-0 to framework 201306281209-1015726915-47508-23737-0000
I0628 12:09:46.125120 23761 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 215.216us
I0628 12:09:46.125169 23760 master.hpp:313] Adding offer 201306281209-1015726915-47508-23737-1 with resources cpus=2; mem=768; ports=[31000-32000]; disk=21009 on slave 201306281209-1015726915-47508-23737-0
I0628 12:09:46.125852 23760 master.cpp:1239] Sending 1 offers to framework 201306281209-1015726915-47508-23737-0000
I0628 12:09:46.126494 23756 sched.cpp:427] Stopping framework '201306281209-1015726915-47508-23737-0000'
I0628 12:09:46.126516 23737 master.cpp:385] Master terminating
I0628 12:09:46.128407 23760 slave.cpp:484] Slave asked to shut down by master@67.195.138.60:47508
I0628 12:09:46.128885 23760 slave.cpp:1111] Asked to shut down framework 201306281209-1015726915-47508-23737-0000 by master@67.195.138.60:47508
I0628 12:09:46.129354 23760 slave.cpp:1136] Shutting down framework 201306281209-1015726915-47508-23737-0000
I0628 12:09:46.129835 23760 slave.cpp:2327] Shutting down executor 'default' of framework 201306281209-1015726915-47508-23737-0000
I0628 12:09:46.130352 23763 exec.cpp:323] Executor asked to shutdown
I0628 12:09:46.130352 23760 slave.cpp:1883] master@67.195.138.60:47508 exited
W0628 12:09:46.131372 23760 slave.cpp:1886] Master disconnected! Waiting for a new master to be elected
I0628 12:09:46.128414 23737 master.cpp:207] Shutting down master
I0628 12:09:46.133796 23759 slave.cpp:1111] Asked to shut down framework 201306281209-1015726915-47508-23737-0000 by master@67.195.138.60:47508
W0628 12:09:46.134182 23759 slave.cpp:1132] Ignoring shutdown framework 201306281209-1015726915-47508-23737-0000 because it is terminating
I0628 12:09:46.133824 23758 hierarchical_allocator_process.hpp:412] Deactivated framework 201306281209-1015726915-47508-23737-0000
I0628 12:09:46.133805 23737 master.hpp:303] Removing task 0 with resources cpus=1; mem=256 on slave 201306281209-1015726915-47508-23737-0
I0628 12:09:46.136201 23737 master.hpp:323] Removing offer 201306281209-1015726915-47508-23737-1 with resources cpus=2; mem=768; ports=[31000-32000]; disk=21009 on slave 201306281209-1015726915-47508-23737-0
I0628 12:09:46.136801 23737 slave.cpp:439] Slave terminating
I0628 12:09:46.136262 23756 hierarchical_allocator_process.hpp:616] Recovered cpus=1; mem=256 (total allocatable: cpus=1; mem=256; ports=[]; disk=0) on slave 201306281209-1015726915-47508-23737-0 from framework 201306281209-1015726915-47508-23737-0000
I0628 12:09:46.138067 23737 slave.cpp:1111] Asked to shut down framework 201306281209-1015726915-47508-23737-0000 by @0.0.0.0:0
W0628 12:09:46.139924 23737 slave.cpp:1132] Ignoring shutdown framework 201306281209-1015726915-47508-23737-0000 because it is terminating
I0628 12:09:46.139439 23756 hierarchical_allocator_process.hpp:616] Recovered cpus=2; mem=768; ports=[31000-32000]; disk=21009 (total allocatable: cpus=3; mem=1024; ports=[31000-32000]; disk=21009) on slave 201306281209-1015726915-47508-23737-0 from framework 201306281209-1015726915-47508-23737-0000
[ OK ] AllocatorTest/0.TaskFinished (69 ms)
[ RUN ] AllocatorTest/0.WhitelistSlave
I0628 12:09:46.141948 23757 master.cpp:228] Master started on 67.195.138.60:47508
I0628 12:09:46.142029 23757 master.cpp:243] Master ID: 201306281209-1015726915-47508-23737
I0628 12:09:46.142592 23757 master.cpp:526] Elected as master!
I0628 12:09:46.142802 23758 slave.cpp:112] Slave started on 490)@67.195.138.60:47508
I0628 12:09:46.143334 23760 sched.cpp:177] New master at master@67.195.138.60:47508
I0628 12:09:46.143363 23758 slave.cpp:204] Slave resources: cpus=2; mem=1024; ports=[31000-32000]; disk=21009
I0628 12:09:46.142897 23759 hierarchical_allocator_process.hpp:295] Initializing hierarchical allocator process with master : master@67.195.138.60:47508
I0628 12:09:46.145805 23759 hierarchical_allocator_process.hpp:491] Updated slave white list: { dummy-slave }
I0628 12:09:46.146209 23759 hierarchical_allocator_process.hpp:700] No users to allocate resources!
I0628 12:09:46.146713 23759 hierarchical_allocator_process.hpp:667] Performed allocation for 0 slaves in 505.849us
I0628 12:09:46.144863 23758 slave.cpp:528] New master detected at master@67.195.138.60:47508
I0628 12:09:46.143920 23761 master.cpp:569] Registering framework 201306281209-1015726915-47508-23737-0000 at scheduler(445)@67.195.138.60:47508
I0628 12:09:46.147727 23758 slave.cpp:543] Postponing registration until recovery is complete
I0628 12:09:46.148736 23758 slave.cpp:389] Finished recovery
I0628 12:09:46.148263 23756 sched.cpp:222] Framework registered with 201306281209-1015726915-47508-23737-0000
I0628 12:09:46.148262 23761 master.cpp:556] Framework 201306281209-1015726915-47508-23737-0000 (scheduler(445)@67.195.138.60:47508) already registered, resending acknowledgement
I0628 12:09:46.150290 23759 sched.cpp:217] Ignoring framework registered message because the driver is already connected!
I0628 12:09:46.147735 23760 status_update_manager.cpp:155] New master detected at master@67.195.138.60:47508
I0628 12:09:46.150303 23761 master.cpp:891] Attempting to register slave on janus.apache.org at slave(490)@67.195.138.60:47508
I0628 12:09:46.151723 23761 master.cpp:1851] Adding slave 201306281209-1015726915-47508-23737-0 at janus.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=21009
I0628 12:09:46.152252 23762 slave.cpp:588] Registered with master master@67.195.138.60:47508; given slave ID 201306281209-1015726915-47508-23737-0
I0628 12:09:46.152298 23761 master.cpp:880] Slave 201306281209-1015726915-47508-23737-0 (janus.apache.org) already registered, resending acknowledgement
W0628 12:09:46.153448 23761 slave.cpp:612] Already registered with master master@67.195.138.60:47508
I0628 12:09:46.148325 23763 hierarchical_allocator_process.hpp:327] Added framework 201306281209-1015726915-47508-23737-0000
I0628 12:09:46.155356 23763 hierarchical_allocator_process.hpp:705] No resources available to allocate!
I0628 12:09:46.155853 23763 hierarchical_allocator_process.hpp:667] Performed allocation for 0 slaves in 497.419us
I0628 12:09:46.156395 23763 hierarchical_allocator_process.hpp:449] Added slave 201306281209-1015726915-47508-23737-0 (janus.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=21009 (and cpus=2; mem=1024; ports=[31000-32000]; disk=21009 available)
I0628 12:09:46.163324 23762 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 14.585us
I0628 12:09:46.173369 23759 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 18.319us
I0628 12:09:46.183423 23760 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 11.745us
I0628 12:09:46.193660 23761 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 10.788us
I0628 12:09:46.193790 23756 hierarchical_allocator_process.hpp:491] Updated slave white list: { dummy-slave, janus.apache.org }
I0628 12:09:46.203547 23758 hierarchical_allocator_process.hpp:727] Offering cpus=2; mem=1024; ports=[31000-32000]; disk=21009 on slave 201306281209-1015726915-47508-23737-0 to framework 201306281209-1015726915-47508-23737-0000
I0628 12:09:46.203691 23758 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 158.714us
I0628 12:09:46.203742 23763 master.hpp:313] Adding offer 201306281209-1015726915-47508-23737-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=21009 on slave 201306281209-1015726915-47508-23737-0
I0628 12:09:46.204629 23763 master.cpp:1239] Sending 1 offers to framework 201306281209-1015726915-47508-23737-0000
I0628 12:09:46.213623 23763 sched.cpp:427] Stopping framework '201306281209-1015726915-47508-23737-0000'
I0628 12:09:46.213645 23737 master.cpp:385] Master terminating
I0628 12:09:46.214021 23737 master.cpp:207] Shutting down master
I0628 12:09:46.214491 23756 hierarchical_allocator_process.hpp:412] Deactivated framework 201306281209-1015726915-47508-23737-0000
I0628 12:09:46.215039 23756 hierarchical_allocator_process.hpp:616] Recovered cpus=2; mem=1024; ports=[31000-32000]; disk=21009 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=21009) on slave 201306281209-1015726915-47508-23737-0 from framework 201306281209-1015726915-47508-23737-0000
I0628 12:09:46.214040 23760 slave.cpp:484] Slave asked to shut down by master@67.195.138.60:47508
I0628 12:09:46.216691 23760 slave.cpp:439] Slave terminating
I0628 12:09:46.214520 23737 master.hpp:323] Removing offer 201306281209-1015726915-47508-23737-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=21009 on slave 201306281209-1015726915-47508-23737-0
I0628 12:09:46.217767 23759 hierarchical_allocator_process.hpp:367] Removed framework 201306281209-1015726915-47508-23737-0000
[ OK ] AllocatorTest/0.WhitelistSlave (77 ms)
[----------] 9 tests from AllocatorTest/0 (576 ms total)
[----------] 1 test from LoggingTest
[ RUN ] LoggingTest.Toggle
I0628 12:09:46.218479 23756 process.cpp:2942] Handling HTTP event for process 'logging' with path: '/logging/toggle'
I0628 12:09:46.218997 23764 process.cpp:878] Socket closed while receiving
I0628 12:09:46.219516 23759 process.cpp:2942] Handling HTTP event for process 'logging' with path: '/logging/toggle'
I0628 12:09:46.219959 23764 process.cpp:878] Socket closed while receiving
I0628 12:09:46.220372 23763 process.cpp:2942] Handling HTTP event for process 'logging' with path: '/logging/toggle'
I0628 12:09:46.221164 23764 process.cpp:878] Socket closed while receiving
I0628 12:09:46.222383 23762 process.cpp:2942] Handling HTTP event for process 'logging' with path: '/logging/toggle'
I0628 12:09:46.222774 23764 process.cpp:878] Socket closed while receiving
I0628 12:09:46.223268 23759 process.cpp:2942] Handling HTTP event for process 'logging' with path: '/logging/toggle'
I0628 12:09:46.223829 23764 process.cpp:878] Socket closed while receiving
I0628 12:09:46.224349 23763 process.cpp:2942] Handling HTTP event for process 'logging' with path: '/logging/toggle'
I0628 12:09:46.224750 23764 process.cpp:878] Socket closed while receiving
[ OK ] LoggingTest.Toggle (7 ms)
[----------] 1 test from LoggingTest (7 ms total)
[----------] 5 tests from CgroupsCpusetTest
[ RUN ] CgroupsCpusetTest.OneCPUOneCpuset
[ OK ] CgroupsCpusetTest.OneCPUOneCpuset (0 ms)
[ RUN ] CgroupsCpusetTest.OneCPUManyCpusets
[ OK ] CgroupsCpusetTest.OneCPUManyCpusets (0 ms)
[ RUN ] CgroupsCpusetTest.ManyCPUOneCpuset
[ OK ] CgroupsCpusetTest.ManyCPUOneCpuset (0 ms)
[ RUN ] CgroupsCpusetTest.ManyCPUManyCpusets
[ OK ] CgroupsCpusetTest.ManyCPUManyCpusets (0 ms)
[ RUN ] CgroupsCpusetTest.IntegerAllocations
[ OK ] CgroupsCpusetTest.IntegerAllocations (0 ms)
[----------] 5 tests from CgroupsCpusetTest (0 ms total)
[----------] 3 tests from FsTest
[ RUN ] FsTest.MountTableRead
[ OK ] FsTest.MountTableRead (0 ms)
[ RUN ] FsTest.MountTableHasOption
[ OK ] FsTest.MountTableHasOption (0 ms)
[ RUN ] FsTest.FileSystemTableRead
[ OK ] FsTest.FileSystemTableRead (0 ms)
[----------] 3 tests from FsTest (0 ms total)
[----------] Global test environment tear-down
[==========] 168 tests from 32 test cases ran. (77372 ms total)
[ PASSED ] 167 tests.
[ FAILED ] 1 test, listed below:
[ FAILED ] ReaperTest.TerminatedChildProcess
1 FAILED TEST
make[3]: *** [check-local] Error 1
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
I0628 12:09:48.682320 8188 exec.cpp:83] Committing suicide by killing the process group
Build step 'Execute shell' marked build as failure
Build failed in Jenkins:
Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui
#1284
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/1284/>
------------------------------------------
[...truncated 56429 lines...]
I0628 06:25:03.784023 28610 master.cpp:1239] Sending 2 offers to framework 201306280625-143311683-48171-28591-0000
I0628 06:25:03.784253 28614 sched.cpp:427] Stopping framework '201306280625-143311683-48171-28591-0000'
I0628 06:25:03.784281 28591 master.cpp:385] Master terminating
I0628 06:25:03.819341 28616 hierarchical_allocator_process.hpp:667] Performed allocation for 2 slaves in 13.891us
I0628 06:25:03.836488 28617 slave.cpp:484] Slave asked to shut down by master@67.195.138.8:48171
I0628 06:25:03.885759 28617 slave.cpp:439] Slave terminating
I0628 06:25:03.836521 28591 master.cpp:207] Shutting down master
I0628 06:25:03.836488 28610 slave.cpp:484] Slave asked to shut down by master@67.195.138.8:48171
I0628 06:25:03.886000 28591 master.hpp:303] Removing task 0 with resources cpus=2; mem=512 on slave 201306280625-143311683-48171-28591-0
I0628 06:25:03.886020 28610 slave.cpp:1111] Asked to shut down framework 201306280625-143311683-48171-28591-0000 by master@67.195.138.8:48171
I0628 06:25:03.886139 28610 slave.cpp:1136] Shutting down framework 201306280625-143311683-48171-28591-0000
I0628 06:25:03.885963 28615 hierarchical_allocator_process.hpp:412] Deactivated framework 201306280625-143311683-48171-28591-0000
I0628 06:25:03.886349 28615 hierarchical_allocator_process.hpp:616] Recovered cpus=2; mem=512 (total allocatable: cpus=2; mem=512; ports=[]; disk=0) on slave 201306280625-143311683-48171-28591-0 from framework 201306280625-143311683-48171-28591-0000
I0628 06:25:03.886253 28610 slave.cpp:2327] Shutting down executor 'default' of framework 201306280625-143311683-48171-28591-0000
I0628 06:25:03.886457 28610 slave.cpp:1883] master@67.195.138.8:48171 exited
I0628 06:25:03.886472 28615 hierarchical_allocator_process.hpp:616] Recovered cpus=1; mem=512; ports=[31000-32000]; disk=19521 (total allocatable: cpus=3; mem=1024; ports=[31000-32000]; disk=19521) on slave 201306280625-143311683-48171-28591-0 from framework 201306280625-143311683-48171-28591-0000
I0628 06:25:03.886212 28591 master.hpp:323] Removing offer 201306280625-143311683-48171-28591-5 with resources cpus=1; mem=512; ports=[31000-32000]; disk=19521 on slave 201306280625-143311683-48171-28591-0
W0628 06:25:03.886487 28610 slave.cpp:1886] Master disconnected! Waiting for a new master to be elected
I0628 06:25:03.886653 28610 slave.cpp:1111] Asked to shut down framework 201306280625-143311683-48171-28591-0000 by master@67.195.138.8:48171
W0628 06:25:03.886734 28610 slave.cpp:1132] Ignoring shutdown framework 201306280625-143311683-48171-28591-0000 because it is terminating
I0628 06:25:03.886468 28611 exec.cpp:323] Executor asked to shutdown
I0628 06:25:03.886858 28613 hierarchical_allocator_process.hpp:616] Recovered cpus=4; mem=2048; ports=[31000-32000]; disk=19521 (total allocatable: cpus=4; mem=2048; ports=[31000-32000]; disk=19521) on slave 201306280625-143311683-48171-28591-1 from framework 201306280625-143311683-48171-28591-0000
I0628 06:25:03.886687 28591 master.hpp:323] Removing offer 201306280625-143311683-48171-28591-4 with resources cpus=4; mem=2048; ports=[31000-32000]; disk=19521 on slave 201306280625-143311683-48171-28591-1
I0628 06:25:03.926564 28610 hierarchical_allocator_process.hpp:367] Removed framework 201306280625-143311683-48171-28591-0000
I0628 06:25:03.926628 28591 slave.cpp:439] Slave terminating
I0628 06:25:03.926697 28591 slave.cpp:1111] Asked to shut down framework 201306280625-143311683-48171-28591-0000 by @0.0.0.0:0
W0628 06:25:03.926764 28591 slave.cpp:1132] Ignoring shutdown framework 201306280625-143311683-48171-28591-0000 because it is terminating
I0628 06:25:03.926659 28610 hierarchical_allocator_process.hpp:477] Removed slave 201306280625-143311683-48171-28591-1
I0628 06:25:03.927240 28610 hierarchical_allocator_process.hpp:477] Removed slave 201306280625-143311683-48171-28591-0
[ OK ] AllocatorTest/0.SlaveAdded (435 ms)
[ RUN ] AllocatorTest/0.TaskFinished
I0628 06:25:03.927675 28610 master.cpp:228] Master started on 67.195.138.8:48171
I0628 06:25:03.927718 28610 master.cpp:243] Master ID: 201306280625-143311683-48171-28591
I0628 06:25:03.928187 28612 slave.cpp:112] Slave started on 489)@67.195.138.8:48171
I0628 06:25:03.928537 28614 sched.cpp:177] New master at master@67.195.138.8:48171
W0628 06:25:04.040678 28617 master.cpp:83] No whitelist given. Advertising offers for all slaves
I0628 06:25:04.040691 28612 slave.cpp:204] Slave resources: cpus=3; mem=1024; ports=[31000-32000]; disk=19521
I0628 06:25:04.040840 28613 hierarchical_allocator_process.hpp:295] Initializing hierarchical allocator process with master : master@67.195.138.8:48171
I0628 06:25:04.040858 28610 master.cpp:526] Elected as master!
I0628 06:25:04.207015 28610 master.cpp:569] Registering framework 201306280625-143311683-48171-28591-0000 at scheduler(444)@67.195.138.8:48171
I0628 06:25:04.207142 28616 sched.cpp:222] Framework registered with 201306280625-143311683-48171-28591-0000
I0628 06:25:04.207304 28616 hierarchical_allocator_process.hpp:327] Added framework 201306280625-143311683-48171-28591-0000
I0628 06:25:04.207346 28616 hierarchical_allocator_process.hpp:705] No resources available to allocate!
I0628 06:25:04.207311 28612 slave.cpp:528] New master detected at master@67.195.138.8:48171
I0628 06:25:04.207514 28616 hierarchical_allocator_process.hpp:667] Performed allocation for 0 slaves in 167.129us
I0628 06:25:04.221695 28612 slave.cpp:543] Postponing registration until recovery is complete
I0628 06:25:04.221725 28614 status_update_manager.cpp:155] New master detected at master@67.195.138.8:48171
I0628 06:25:04.221791 28612 slave.cpp:389] Finished recovery
I0628 06:25:04.222111 28615 master.cpp:891] Attempting to register slave on minerva.apache.org at slave(489)@67.195.138.8:48171
I0628 06:25:04.222161 28615 master.cpp:1851] Adding slave 201306280625-143311683-48171-28591-0 at minerva.apache.org with cpus=3; mem=1024; ports=[31000-32000]; disk=19521
I0628 06:25:04.222317 28611 slave.cpp:588] Registered with master master@67.195.138.8:48171; given slave ID 201306280625-143311683-48171-28591-0
I0628 06:25:04.222417 28615 hierarchical_allocator_process.hpp:449] Added slave 201306280625-143311683-48171-28591-0 (minerva.apache.org) with cpus=3; mem=1024; ports=[31000-32000]; disk=19521 (and cpus=3; mem=1024; ports=[31000-32000]; disk=19521 available)
I0628 06:25:04.222491 28615 hierarchical_allocator_process.hpp:727] Offering cpus=3; mem=1024; ports=[31000-32000]; disk=19521 on slave 201306280625-143311683-48171-28591-0 to framework 201306280625-143311683-48171-28591-0000
I0628 06:25:04.222643 28615 hierarchical_allocator_process.hpp:687] Performed allocation for slave 201306280625-143311683-48171-28591-0 in 162.643us
I0628 06:25:04.222703 28611 master.hpp:313] Adding offer 201306280625-143311683-48171-28591-0 with resources cpus=3; mem=1024; ports=[31000-32000]; disk=19521 on slave 201306280625-143311683-48171-28591-0
I0628 06:25:04.222800 28611 master.cpp:1239] Sending 1 offers to framework 201306280625-143311683-48171-28591-0000
I0628 06:25:04.223135 28611 master.cpp:1472] Processing reply for offer 201306280625-143311683-48171-28591-0 on slave 201306280625-143311683-48171-28591-0 (minerva.apache.org) for framework 201306280625-143311683-48171-28591-0000
I0628 06:25:04.223242 28611 master.hpp:291] Adding task 0 with resources cpus=1; mem=256 on slave 201306280625-143311683-48171-28591-0
I0628 06:25:04.223284 28611 master.cpp:1591] Launching task 0 of framework 201306280625-143311683-48171-28591-0000 with resources cpus=1; mem=256 on slave 201306280625-143311683-48171-28591-0 (minerva.apache.org)
I0628 06:25:04.223397 28614 slave.cpp:738] Got assigned task 0 for framework 201306280625-143311683-48171-28591-0000
I0628 06:25:04.223412 28611 master.hpp:291] Adding task 1 with resources cpus=1; mem=256 on slave 201306280625-143311683-48171-28591-0
I0628 06:25:04.223530 28611 master.cpp:1591] Launching task 1 of framework 201306280625-143311683-48171-28591-0000 with resources cpus=1; mem=256 on slave 201306280625-143311683-48171-28591-0 (minerva.apache.org)
I0628 06:25:04.223608 28614 slave.cpp:836] Launching task 0 for framework 201306280625-143311683-48171-28591-0000
I0628 06:25:04.223688 28611 master.hpp:323] Removing offer 201306280625-143311683-48171-28591-0 with resources cpus=3; mem=1024; ports=[31000-32000]; disk=19521 on slave 201306280625-143311683-48171-28591-0
I0628 06:25:04.223850 28611 hierarchical_allocator_process.hpp:526] Framework 201306280625-143311683-48171-28591-0000 left cpus=1; mem=512; ports=[31000-32000]; disk=19521 unused on slave 201306280625-143311683-48171-28591-0
I0628 06:25:04.223963 28611 hierarchical_allocator_process.hpp:569] Framework 201306280625-143311683-48171-28591-0000 filtered slave 201306280625-143311683-48171-28591-0 for 5secs
I0628 06:25:04.224628 28614 paths.hpp:303] Created executor directory '/tmp/AllocatorTest_0_TaskFinished_pLnROM/slaves/201306280625-143311683-48171-28591-0/frameworks/201306280625-143311683-48171-28591-0000/executors/default/runs/64ba5ae9-5110-4b96-9f28-501d39b1398b'
I0628 06:25:04.224819 28614 slave.cpp:947] Queuing task '0' for executor default of framework '201306280625-143311683-48171-28591-0000
I0628 06:25:04.224881 28614 slave.cpp:738] Got assigned task 1 for framework 201306280625-143311683-48171-28591-0000
I0628 06:25:04.224992 28614 slave.cpp:510] Successfully attached file '/tmp/AllocatorTest_0_TaskFinished_pLnROM/slaves/201306280625-143311683-48171-28591-0/frameworks/201306280625-143311683-48171-28591-0000/executors/default/runs/64ba5ae9-5110-4b96-9f28-501d39b1398b'
I0628 06:25:04.225039 28614 slave.cpp:836] Launching task 1 for framework 201306280625-143311683-48171-28591-0000
I0628 06:25:04.225065 28611 exec.cpp:170] Executor started at: executor(180)@67.195.138.8:48171 with pid 28591
I0628 06:25:04.225074 28614 slave.cpp:947] Queuing task '1' for executor default of framework '201306280625-143311683-48171-28591-0000
I0628 06:25:04.225307 28614 slave.cpp:1394] Got registration for executor 'default' of framework 201306280625-143311683-48171-28591-0000
I0628 06:25:04.225404 28614 slave.cpp:1509] Flushing queued task 1 for executor 'default' of framework 201306280625-143311683-48171-28591-0000
I0628 06:25:04.225440 28610 exec.cpp:194] Executor registered on slave 201306280625-143311683-48171-28591-0
I0628 06:25:04.225540 28614 slave.cpp:1509] Flushing queued task 0 for executor 'default' of framework 201306280625-143311683-48171-28591-0000
I0628 06:25:04.225597 28610 exec.cpp:258] Executor asked to run task '1'
I0628 06:25:04.225869 28610 exec.cpp:258] Executor asked to run task '0'
I0628 06:25:04.226760 28610 exec.cpp:404] Executor sending status update TASK_RUNNING (UUID: 578ec1a5-dae0-41ae-a1f2-20eab3076b66) for task 1 of framework 201306280625-143311683-48171-28591-0000
I0628 06:25:04.226876 28612 slave.cpp:1691] Handling status update TASK_RUNNING (UUID: 578ec1a5-dae0-41ae-a1f2-20eab3076b66) for task 1 of framework 201306280625-143311683-48171-28591-0000 from executor(180)@67.195.138.8:48171
I0628 06:25:04.227038 28612 status_update_manager.cpp:290] Received status update TASK_RUNNING (UUID: 578ec1a5-dae0-41ae-a1f2-20eab3076b66) for task 1 of framework 201306280625-143311683-48171-28591-0000 with checkpoint=false
I0628 06:25:04.227072 28612 status_update_manager.cpp:450] Creating StatusUpdate stream for task 1 of framework 201306280625-143311683-48171-28591-0000
I0628 06:25:04.227175 28612 status_update_manager.cpp:336] Forwarding status update TASK_RUNNING (UUID: 578ec1a5-dae0-41ae-a1f2-20eab3076b66) for task 1 of framework 201306280625-143311683-48171-28591-0000 to master@67.195.138.8:48171
I0628 06:25:04.227273 28611 slave.cpp:1802] Status update manager successfully handled status update TASK_RUNNING (UUID: 578ec1a5-dae0-41ae-a1f2-20eab3076b66) for task 1 of framework 201306280625-143311683-48171-28591-0000
I0628 06:25:04.227283 28612 master.cpp:1022] Status update from slave(489)@67.195.138.8:48171: task 1 of framework 201306280625-143311683-48171-28591-0000 is now in state TASK_RUNNING
I0628 06:25:04.227327 28611 slave.cpp:1808] Sending acknowledgement for status update TASK_RUNNING (UUID: 578ec1a5-dae0-41ae-a1f2-20eab3076b66) for task 1 of framework 201306280625-143311683-48171-28591-0000 to executor(180)@67.195.138.8:48171
I0628 06:25:04.227593 28617 status_update_manager.cpp:360] Received status update acknowledgement 578ec1a5-dae0-41ae-a1f2-20eab3076b66 for task 1 of framework 201306280625-143311683-48171-28591-0000
I0628 06:25:04.227658 28617 slave.cpp:1342] Status update manager successfully handled status update acknowledgement 578ec1a5-dae0-41ae-a1f2-20eab3076b66 for task 1 of framework 201306280625-143311683-48171-28591-0000
I0628 06:25:04.227754 28610 exec.cpp:404] Executor sending status update TASK_FINISHED (UUID: 830a0a8a-fd1e-4a4a-9c09-5aa561aecadb) for task 1 of framework 201306280625-143311683-48171-28591-0000
I0628 06:25:04.227813 28617 slave.cpp:1691] Handling status update TASK_FINISHED (UUID: 830a0a8a-fd1e-4a4a-9c09-5aa561aecadb) for task 1 of framework 201306280625-143311683-48171-28591-0000 from executor(180)@67.195.138.8:48171
I0628 06:25:04.227932 28617 status_update_manager.cpp:290] Received status update TASK_FINISHED (UUID: 830a0a8a-fd1e-4a4a-9c09-5aa561aecadb) for task 1 of framework 201306280625-143311683-48171-28591-0000 with checkpoint=false
I0628 06:25:04.227968 28617 status_update_manager.cpp:336] Forwarding status update TASK_FINISHED (UUID: 830a0a8a-fd1e-4a4a-9c09-5aa561aecadb) for task 1 of framework 201306280625-143311683-48171-28591-0000 to master@67.195.138.8:48171
I0628 06:25:04.228075 28617 master.cpp:1022] Status update from slave(489)@67.195.138.8:48171: task 1 of framework 201306280625-143311683-48171-28591-0000 is now in state TASK_FINISHED
I0628 06:25:04.228137 28617 master.hpp:303] Removing task 1 with resources cpus=1; mem=256 on slave 201306280625-143311683-48171-28591-0
I0628 06:25:04.228199 28617 slave.cpp:1802] Status update manager successfully handled status update TASK_FINISHED (UUID: 830a0a8a-fd1e-4a4a-9c09-5aa561aecadb) for task 1 of framework 201306280625-143311683-48171-28591-0000
I0628 06:25:04.228225 28617 slave.cpp:1808] Sending acknowledgement for status update TASK_FINISHED (UUID: 830a0a8a-fd1e-4a4a-9c09-5aa561aecadb) for task 1 of framework 201306280625-143311683-48171-28591-0000 to executor(180)@67.195.138.8:48171
I0628 06:25:04.228432 28617 status_update_manager.cpp:360] Received status update acknowledgement 830a0a8a-fd1e-4a4a-9c09-5aa561aecadb for task 1 of framework 201306280625-143311683-48171-28591-0000
I0628 06:25:04.228466 28617 status_update_manager.cpp:481] Cleaning up status update stream for task 1 of framework 201306280625-143311683-48171-28591-0000
I0628 06:25:04.228579 28617 slave.cpp:1342] Status update manager successfully handled status update acknowledgement 830a0a8a-fd1e-4a4a-9c09-5aa561aecadb for task 1 of framework 201306280625-143311683-48171-28591-0000
I0628 06:25:04.228353 28616 hierarchical_allocator_process.hpp:616] Recovered cpus=1; mem=256 (total allocatable: cpus=2; mem=768; ports=[31000-32000]; disk=19521) on slave 201306280625-143311683-48171-28591-0 from framework 201306280625-143311683-48171-28591-0000
I0628 06:25:04.228647 28610 exec.cpp:404] Executor sending status update TASK_RUNNING (UUID: fcc690dd-5e91-40cb-b9d7-1b3102d796fa) for task 0 of framework 201306280625-143311683-48171-28591-0000
I0628 06:25:04.228754 28617 slave.cpp:1691] Handling status update TASK_RUNNING (UUID: fcc690dd-5e91-40cb-b9d7-1b3102d796fa) for task 0 of framework 201306280625-143311683-48171-28591-0000 from executor(180)@67.195.138.8:48171
I0628 06:25:04.228763 28610 exec.cpp:290] Executor received status update acknowledgement 578ec1a5-dae0-41ae-a1f2-20eab3076b66 for task 1 of framework 201306280625-143311683-48171-28591-0000
I0628 06:25:04.228854 28617 status_update_manager.cpp:290] Received status update TASK_RUNNING (UUID: fcc690dd-5e91-40cb-b9d7-1b3102d796fa) for task 0 of framework 201306280625-143311683-48171-28591-0000 with checkpoint=false
I0628 06:25:04.228865 28610 exec.cpp:290] Executor received status update acknowledgement 830a0a8a-fd1e-4a4a-9c09-5aa561aecadb for task 1 of framework 201306280625-143311683-48171-28591-0000
I0628 06:25:04.228906 28617 status_update_manager.cpp:450] Creating StatusUpdate stream for task 0 of framework 201306280625-143311683-48171-28591-0000
I0628 06:25:04.229070 28617 status_update_manager.cpp:336] Forwarding status update TASK_RUNNING (UUID: fcc690dd-5e91-40cb-b9d7-1b3102d796fa) for task 0 of framework 201306280625-143311683-48171-28591-0000 to master@67.195.138.8:48171
I0628 06:25:04.229156 28617 slave.cpp:1802] Status update manager successfully handled status update TASK_RUNNING (UUID: fcc690dd-5e91-40cb-b9d7-1b3102d796fa) for task 0 of framework 201306280625-143311683-48171-28591-0000
I0628 06:25:04.229168 28613 master.cpp:1022] Status update from slave(489)@67.195.138.8:48171: task 0 of framework 201306280625-143311683-48171-28591-0000 is now in state TASK_RUNNING
I0628 06:25:04.229185 28617 slave.cpp:1808] Sending acknowledgement for status update TASK_RUNNING (UUID: fcc690dd-5e91-40cb-b9d7-1b3102d796fa) for task 0 of framework 201306280625-143311683-48171-28591-0000 to executor(180)@67.195.138.8:48171
I0628 06:25:04.229331 28617 exec.cpp:290] Executor received status update acknowledgement fcc690dd-5e91-40cb-b9d7-1b3102d796fa for task 0 of framework 201306280625-143311683-48171-28591-0000
I0628 06:25:04.229419 28613 status_update_manager.cpp:360] Received status update acknowledgement fcc690dd-5e91-40cb-b9d7-1b3102d796fa for task 0 of framework 201306280625-143311683-48171-28591-0000
I0628 06:25:04.229490 28613 slave.cpp:1342] Status update manager successfully handled status update acknowledgement fcc690dd-5e91-40cb-b9d7-1b3102d796fa for task 0 of framework 201306280625-143311683-48171-28591-0000
I0628 06:25:04.256980 28615 hierarchical_allocator_process.hpp:727] Offering cpus=2; mem=768; ports=[31000-32000]; disk=19521 on slave 201306280625-143311683-48171-28591-0 to framework 201306280625-143311683-48171-28591-0000
I0628 06:25:04.257149 28615 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 197.614us
I0628 06:25:04.257201 28616 master.hpp:313] Adding offer 201306280625-143311683-48171-28591-1 with resources cpus=2; mem=768; ports=[31000-32000]; disk=19521 on slave 201306280625-143311683-48171-28591-0
I0628 06:25:04.309739 28616 master.cpp:1239] Sending 1 offers to framework 201306280625-143311683-48171-28591-0000
I0628 06:25:04.309957 28616 sched.cpp:427] Stopping framework '201306280625-143311683-48171-28591-0000'
I0628 06:25:04.309985 28591 master.cpp:385] Master terminating
I0628 06:25:04.310052 28616 slave.cpp:484] Slave asked to shut down by master@67.195.138.8:48171
I0628 06:25:04.310056 28591 master.cpp:207] Shutting down master
I0628 06:25:04.310116 28616 slave.cpp:1111] Asked to shut down framework 201306280625-143311683-48171-28591-0000 by master@67.195.138.8:48171
I0628 06:25:04.310247 28617 hierarchical_allocator_process.hpp:412] Deactivated framework 201306280625-143311683-48171-28591-0000
I0628 06:25:04.310256 28591 master.hpp:303] Removing task 0 with resources cpus=1; mem=256 on slave 201306280625-143311683-48171-28591-0
I0628 06:25:04.310292 28616 slave.cpp:1136] Shutting down framework 201306280625-143311683-48171-28591-0000
I0628 06:25:04.310467 28616 slave.cpp:2327] Shutting down executor 'default' of framework 201306280625-143311683-48171-28591-0000
I0628 06:25:04.310597 28616 slave.cpp:1883] master@67.195.138.8:48171 exited
W0628 06:25:04.310629 28616 slave.cpp:1886] Master disconnected! Waiting for a new master to be elected
I0628 06:25:04.310521 28591 master.hpp:323] Removing offer 201306280625-143311683-48171-28591-1 with resources cpus=2; mem=768; ports=[31000-32000]; disk=19521 on slave 201306280625-143311683-48171-28591-0
I0628 06:25:04.310636 28613 exec.cpp:323] Executor asked to shutdown
I0628 06:25:04.310600 28615 hierarchical_allocator_process.hpp:616] Recovered cpus=1; mem=256 (total allocatable: cpus=1; mem=256; ports=[]; disk=0) on slave 201306280625-143311683-48171-28591-0 from framework 201306280625-143311683-48171-28591-0000
I0628 06:25:04.310685 28616 slave.cpp:1111] Asked to shut down framework 201306280625-143311683-48171-28591-0000 by master@67.195.138.8:48171
W0628 06:25:04.310917 28616 slave.cpp:1132] Ignoring shutdown framework 201306280625-143311683-48171-28591-0000 because it is terminating
I0628 06:25:04.310953 28615 hierarchical_allocator_process.hpp:616] Recovered cpus=2; mem=768; ports=[31000-32000]; disk=19521 (total allocatable: cpus=3; mem=1024; ports=[31000-32000]; disk=19521) on slave 201306280625-143311683-48171-28591-0 from framework 201306280625-143311683-48171-28591-0000
I0628 06:25:04.311079 28615 hierarchical_allocator_process.hpp:367] Removed framework 201306280625-143311683-48171-28591-0000
I0628 06:25:04.311152 28613 hierarchical_allocator_process.hpp:477] Removed slave 201306280625-143311683-48171-28591-0
I0628 06:25:04.311277 28614 slave.cpp:439] Slave terminating
I0628 06:25:04.311305 28614 slave.cpp:1111] Asked to shut down framework 201306280625-143311683-48171-28591-0000 by @0.0.0.0:0
W0628 06:25:04.311369 28614 slave.cpp:1132] Ignoring shutdown framework 201306280625-143311683-48171-28591-0000 because it is terminating
[ OK ] AllocatorTest/0.TaskFinished (384 ms)
[ RUN ] AllocatorTest/0.WhitelistSlave
I0628 06:25:04.312173 28615 master.cpp:228] Master started on 67.195.138.8:48171
I0628 06:25:04.312216 28615 master.cpp:243] Master ID: 201306280625-143311683-48171-28591
I0628 06:25:04.312504 28615 master.cpp:526] Elected as master!
I0628 06:25:04.312666 28615 slave.cpp:112] Slave started on 490)@67.195.138.8:48171
I0628 06:25:04.312789 28615 slave.cpp:204] Slave resources: cpus=2; mem=1024; ports=[31000-32000]; disk=19521
I0628 06:25:04.312810 28612 hierarchical_allocator_process.hpp:295] Initializing hierarchical allocator process with master : master@67.195.138.8:48171
I0628 06:25:04.313216 28612 hierarchical_allocator_process.hpp:491] Updated slave white list: { dummy-slave }
I0628 06:25:04.313323 28612 hierarchical_allocator_process.hpp:700] No users to allocate resources!
I0628 06:25:04.313324 28615 slave.cpp:528] New master detected at master@67.195.138.8:48171
I0628 06:25:04.313354 28616 sched.cpp:177] New master at master@67.195.138.8:48171
I0628 06:25:04.313406 28612 hierarchical_allocator_process.hpp:667] Performed allocation for 0 slaves in 86.83us
I0628 06:25:04.313483 28615 slave.cpp:543] Postponing registration until recovery is complete
I0628 06:25:04.313532 28610 status_update_manager.cpp:155] New master detected at master@67.195.138.8:48171
I0628 06:25:04.313645 28616 master.cpp:569] Registering framework 201306280625-143311683-48171-28591-0000 at scheduler(445)@67.195.138.8:48171
I0628 06:25:04.313740 28615 slave.cpp:389] Finished recovery
I0628 06:25:04.313951 28617 hierarchical_allocator_process.hpp:327] Added framework 201306280625-143311683-48171-28591-0000
I0628 06:25:04.313995 28617 hierarchical_allocator_process.hpp:705] No resources available to allocate!
I0628 06:25:04.313891 28614 sched.cpp:222] Framework registered with 201306280625-143311683-48171-28591-0000
I0628 06:25:04.313890 28616 master.cpp:556] Framework 201306280625-143311683-48171-28591-0000 (scheduler(445)@67.195.138.8:48171) already registered, resending acknowledgement
I0628 06:25:04.314074 28617 hierarchical_allocator_process.hpp:667] Performed allocation for 0 slaves in 79.04us
I0628 06:25:04.314208 28615 sched.cpp:217] Ignoring framework registered message because the driver is already connected!
I0628 06:25:04.314230 28616 master.cpp:891] Attempting to register slave on minerva.apache.org at slave(490)@67.195.138.8:48171
I0628 06:25:04.314374 28616 master.cpp:1851] Adding slave 201306280625-143311683-48171-28591-0 at minerva.apache.org with cpus=2; mem=1024; ports=[31000-32000]; disk=19521
I0628 06:25:04.314458 28611 slave.cpp:588] Registered with master master@67.195.138.8:48171; given slave ID 201306280625-143311683-48171-28591-0
I0628 06:25:04.314502 28616 master.cpp:880] Slave 201306280625-143311683-48171-28591-0 (minerva.apache.org) already registered, resending acknowledgement
W0628 06:25:04.314558 28616 slave.cpp:612] Already registered with master master@67.195.138.8:48171
I0628 06:25:04.314565 28611 hierarchical_allocator_process.hpp:449] Added slave 201306280625-143311683-48171-28591-0 (minerva.apache.org) with cpus=2; mem=1024; ports=[31000-32000]; disk=19521 (and cpus=2; mem=1024; ports=[31000-32000]; disk=19521 available)
I0628 06:25:04.323164 28613 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 19.421us
I0628 06:25:04.333192 28610 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 10.934us
I0628 06:25:04.343346 28612 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 33.51us
I0628 06:25:04.353557 28615 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 11.521us
I0628 06:25:04.367985 28615 hierarchical_allocator_process.hpp:491] Updated slave white list: { dummy-slave, minerva.apache.org }
I0628 06:25:04.373466 28613 hierarchical_allocator_process.hpp:727] Offering cpus=2; mem=1024; ports=[31000-32000]; disk=19521 on slave 201306280625-143311683-48171-28591-0 to framework 201306280625-143311683-48171-28591-0000
I0628 06:25:04.373632 28613 hierarchical_allocator_process.hpp:667] Performed allocation for 1 slaves in 199.665us
I0628 06:25:04.373677 28610 master.hpp:313] Adding offer 201306280625-143311683-48171-28591-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=19521 on slave 201306280625-143311683-48171-28591-0
I0628 06:25:04.391654 28610 master.cpp:1239] Sending 1 offers to framework 201306280625-143311683-48171-28591-0000
I0628 06:25:04.403640 28610 sched.cpp:427] Stopping framework '201306280625-143311683-48171-28591-0000'
I0628 06:25:04.403702 28591 master.cpp:385] Master terminating
I0628 06:25:04.603394 28591 master.cpp:207] Shutting down master
I0628 06:25:04.603446 28616 slave.cpp:484] Slave asked to shut down by master@67.195.138.8:48171
I0628 06:25:04.603483 28616 slave.cpp:439] Slave terminating
I0628 06:25:04.603586 28617 hierarchical_allocator_process.hpp:412] Deactivated framework 201306280625-143311683-48171-28591-0000
I0628 06:25:04.603499 28591 master.hpp:323] Removing offer 201306280625-143311683-48171-28591-0 with resources cpus=2; mem=1024; ports=[31000-32000]; disk=19521 on slave 201306280625-143311683-48171-28591-0
I0628 06:25:04.603796 28617 hierarchical_allocator_process.hpp:616] Recovered cpus=2; mem=1024; ports=[31000-32000]; disk=19521 (total allocatable: cpus=2; mem=1024; ports=[31000-32000]; disk=19521) on slave 201306280625-143311683-48171-28591-0 from framework 201306280625-143311683-48171-28591-0000
[ OK ] AllocatorTest/0.WhitelistSlave (314 ms)
[----------] 9 tests from AllocatorTest/0 (1324 ms total)
[----------] 1 test from LoggingTest
[ RUN ] LoggingTest.Toggle
I0628 06:25:04.626025 28613 process.cpp:2942] Handling HTTP event for process 'logging' with path: '/logging/toggle'
I0628 06:25:04.630975 28618 process.cpp:878] Socket closed while receiving
I0628 06:25:04.631435 28616 process.cpp:2942] Handling HTTP event for process 'logging' with path: '/logging/toggle'
I0628 06:25:04.633389 28618 process.cpp:878] Socket closed while receiving
I0628 06:25:04.637910 28610 process.cpp:2942] Handling HTTP event for process 'logging' with path: '/logging/toggle'
I0628 06:25:04.638411 28618 process.cpp:878] Socket closed while receiving
I0628 06:25:04.645186 28614 process.cpp:2942] Handling HTTP event for process 'logging' with path: '/logging/toggle'
I0628 06:25:04.646205 28618 process.cpp:878] Socket closed while receiving
I0628 06:25:04.649349 28613 process.cpp:2942] Handling HTTP event for process 'logging' with path: '/logging/toggle'
I0628 06:25:04.651093 28618 process.cpp:878] Socket closed while receiving
I0628 06:25:04.656064 28617 process.cpp:2942] Handling HTTP event for process 'logging' with path: '/logging/toggle'
I0628 06:25:04.658706 28618 process.cpp:878] Socket closed while receiving
[ OK ] LoggingTest.Toggle (38 ms)
[----------] 1 test from LoggingTest (38 ms total)
[----------] 5 tests from CgroupsCpusetTest
[ RUN ] CgroupsCpusetTest.OneCPUOneCpuset
[ OK ] CgroupsCpusetTest.OneCPUOneCpuset (0 ms)
[ RUN ] CgroupsCpusetTest.OneCPUManyCpusets
[ OK ] CgroupsCpusetTest.OneCPUManyCpusets (0 ms)
[ RUN ] CgroupsCpusetTest.ManyCPUOneCpuset
[ OK ] CgroupsCpusetTest.ManyCPUOneCpuset (0 ms)
[ RUN ] CgroupsCpusetTest.ManyCPUManyCpusets
[ OK ] CgroupsCpusetTest.ManyCPUManyCpusets (0 ms)
[ RUN ] CgroupsCpusetTest.IntegerAllocations
[ OK ] CgroupsCpusetTest.IntegerAllocations (0 ms)
[----------] 5 tests from CgroupsCpusetTest (0 ms total)
[----------] 3 tests from FsTest
[ RUN ] FsTest.MountTableRead
[ OK ] FsTest.MountTableRead (0 ms)
[ RUN ] FsTest.MountTableHasOption
[ OK ] FsTest.MountTableHasOption (0 ms)
[ RUN ] FsTest.FileSystemTableRead
[ OK ] FsTest.FileSystemTableRead (0 ms)
[----------] 3 tests from FsTest (0 ms total)
[----------] Global test environment tear-down
[==========] 168 tests from 32 test cases ran. (18139 ms total)
[ PASSED ] 168 tests.
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]: 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]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/ws/build/src'>
Making check in ec2
make[1]: Entering directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/ws/build/ec2'>
make[1]: Nothing to be done for `check'.
make[1]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/ws/build/ec2'>
Making check in hadoop
make[1]: Entering directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/ws/build/hadoop'>
make[1]: Nothing to be done for `check'.
make[1]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/ws/build/hadoop'>
Making check in jenkins
make[1]: Entering directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/ws/build/jenkins'>
make[1]: Nothing to be done for `check'.
make[1]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/ws/build/jenkins'>
make: *** No rule to make target `GTEST_BREAK_ON_FAILURE'. Stop.
I0628 06:25:06.289623 448 exec.cpp:83] Committing suicide by killing the process group
Build step 'Execute shell' marked build as failure