See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/1484/>
------------------------------------------ [...truncated 12640 lines...] [ OK ] AllocatorTest/0.SlaveAdded (175 ms) [ RUN ] AllocatorTest/0.TaskFinished I0729 22:45:25.539755 28473 master.cpp:230] Master started on 67.195.138.61:50658 I0729 22:45:25.539803 28473 master.cpp:245] Master ID: 201307292245-1032504131-50658-28293 I0729 22:45:25.540421 28475 slave.cpp:113] Slave started on 106)@67.195.138.61:50658 I0729 22:45:25.541455 28475 slave.cpp:213] Slave resources: cpus(*):3; mem(*):1024; disk(*):20812; ports(*):[31000-32000] W0729 22:45:25.541100 28471 master.cpp:83] No whitelist given. Advertising offers for all slaves I0729 22:45:25.541241 28469 hierarchical_allocator_process.hpp:287] Initializing hierarchical allocator process with master : [email protected]:50658 I0729 22:45:25.541283 28473 master.cpp:583] Elected as master! I0729 22:45:25.540956 28468 sched.cpp:178] New master at [email protected]:50658 I0729 22:45:25.543879 28468 master.cpp:633] Registering framework 201307292245-1032504131-50658-28293-0000 at scheduler(95)@67.195.138.61:50658 I0729 22:45:25.544327 28468 sched.cpp:237] Framework registered with 201307292245-1032504131-50658-28293-0000 I0729 22:45:25.544811 28468 sched.cpp:251] Scheduler::registered took 11.342us I0729 22:45:25.544389 28471 hierarchical_allocator_process.hpp:317] Added framework 201307292245-1032504131-50658-28293-0000 I0729 22:45:25.545982 28471 hierarchical_allocator_process.hpp:684] No resources available to allocate! I0729 22:45:25.546440 28471 hierarchical_allocator_process.hpp:646] Performed allocation for 0 slaves in 459.638us I0729 22:45:25.542387 28475 slave.cpp:537] New master detected at [email protected]:50658 I0729 22:45:25.547427 28475 slave.cpp:552] Postponing registration until recovery is complete I0729 22:45:25.548109 28475 slave.cpp:398] Finished recovery I0729 22:45:25.547487 28472 status_update_manager.cpp:155] New master detected at [email protected]:50658 I0729 22:45:25.548678 28474 master.cpp:962] Attempting to register slave on juno.apache.org at slave(106)@67.195.138.61:50658 I0729 22:45:25.550194 28474 master.cpp:1941] Adding slave 201307292245-1032504131-50658-28293-0 at juno.apache.org with cpus(*):3; mem(*):1024; disk(*):20812; ports(*):[31000-32000] I0729 22:45:25.550717 28470 slave.cpp:597] Registered with master [email protected]:50658; given slave ID 201307292245-1032504131-50658-28293-0 I0729 22:45:25.550847 28474 hierarchical_allocator_process.hpp:430] Added slave 201307292245-1032504131-50658-28293-0 (juno.apache.org) with cpus(*):3; mem(*):1024; disk(*):20812; ports(*):[31000-32000] (and cpus(*):3; mem(*):1024; disk(*):20812; ports(*):[31000-32000] available) I0729 22:45:25.552306 28474 hierarchical_allocator_process.hpp:709] Offering cpus(*):3; mem(*):1024; disk(*):20812; ports(*):[31000-32000] on slave 201307292245-1032504131-50658-28293-0 to framework 201307292245-1032504131-50658-28293-0000 I0729 22:45:25.552979 28474 hierarchical_allocator_process.hpp:666] Performed allocation for slave 201307292245-1032504131-50658-28293-0 in 702.134us I0729 22:45:25.553021 28470 master.hpp:328] Adding offer 201307292245-1032504131-50658-28293-0 with resources cpus(*):3; mem(*):1024; disk(*):20812; ports(*):[31000-32000] on slave 201307292245-1032504131-50658-28293-0 I0729 22:45:25.553844 28470 master.cpp:1322] Sending 1 offers to framework 201307292245-1032504131-50658-28293-0000 I0729 22:45:25.554769 28470 sched.cpp:340] Scheduler::resourceOffers took 250.207us I0729 22:45:25.555022 28471 master.cpp:1555] Processing reply for offer 201307292245-1032504131-50658-28293-0 on slave 201307292245-1032504131-50658-28293-0 (juno.apache.org) for framework 201307292245-1032504131-50658-28293-0000 I0729 22:45:25.555488 28471 master.hpp:306] Adding task 0 with resources cpus(*):1; mem(*):256 on slave 201307292245-1032504131-50658-28293-0 I0729 22:45:25.555917 28471 master.cpp:1674] Launching task 0 of framework 201307292245-1032504131-50658-28293-0000 with resources cpus(*):1; mem(*):256 on slave 201307292245-1032504131-50658-28293-0 (juno.apache.org) I0729 22:45:25.556608 28470 slave.cpp:747] Got assigned task 0 for framework 201307292245-1032504131-50658-28293-0000 I0729 22:45:25.556649 28471 master.hpp:306] Adding task 1 with resources cpus(*):1; mem(*):256 on slave 201307292245-1032504131-50658-28293-0 I0729 22:45:25.557560 28471 master.cpp:1674] Launching task 1 of framework 201307292245-1032504131-50658-28293-0000 with resources cpus(*):1; mem(*):256 on slave 201307292245-1032504131-50658-28293-0 (juno.apache.org) I0729 22:45:25.557270 28470 slave.cpp:845] Launching task 0 for framework 201307292245-1032504131-50658-28293-0000 I0729 22:45:25.560315 28470 paths.hpp:303] Created executor directory '/tmp/AllocatorTest_0_TaskFinished_qyiv5T/slaves/201307292245-1032504131-50658-28293-0/frameworks/201307292245-1032504131-50658-28293-0000/executors/default/runs/ee47bb90-e950-4722-a527-661f519a482b' I0729 22:45:25.558779 28471 master.hpp:338] Removing offer 201307292245-1032504131-50658-28293-0 with resources cpus(*):3; mem(*):1024; disk(*):20812; ports(*):[31000-32000] on slave 201307292245-1032504131-50658-28293-0 I0729 22:45:25.558847 28468 hierarchical_allocator_process.hpp:505] Framework 201307292245-1032504131-50658-28293-0000 left cpus(*):1; mem(*):512; disk(*):20812; ports(*):[31000-32000] unused on slave 201307292245-1032504131-50658-28293-0 I0729 22:45:25.560493 28470 slave.cpp:956] Queuing task '0' for executor default of framework '201307292245-1032504131-50658-28293-0000 I0729 22:45:25.560741 28475 exec.cpp:171] Executor started at: executor(37)@67.195.138.61:50658 with pid 28293 I0729 22:45:25.561833 28468 hierarchical_allocator_process.hpp:548] Framework 201307292245-1032504131-50658-28293-0000 filtered slave 201307292245-1032504131-50658-28293-0 for 5secs I0729 22:45:25.562482 28470 slave.cpp:747] Got assigned task 1 for framework 201307292245-1032504131-50658-28293-0000 I0729 22:45:25.564030 28470 slave.cpp:519] Successfully attached file '/tmp/AllocatorTest_0_TaskFinished_qyiv5T/slaves/201307292245-1032504131-50658-28293-0/frameworks/201307292245-1032504131-50658-28293-0000/executors/default/runs/ee47bb90-e950-4722-a527-661f519a482b' I0729 22:45:25.564453 28470 slave.cpp:1423] Got registration for executor 'default' of framework 201307292245-1032504131-50658-28293-0000 I0729 22:45:25.565111 28470 slave.cpp:1538] Flushing queued task 0 for executor 'default' of framework 201307292245-1032504131-50658-28293-0000 I0729 22:45:25.565248 28473 exec.cpp:195] Executor registered on slave 201307292245-1032504131-50658-28293-0 I0729 22:45:25.566050 28473 exec.cpp:206] Executor::registered took 14.759us I0729 22:45:25.565568 28470 slave.cpp:845] Launching task 1 for framework 201307292245-1032504131-50658-28293-0000 I0729 22:45:25.567651 28470 slave.cpp:981] Sending task '1' to executor 'default' of framework 201307292245-1032504131-50658-28293-0000 I0729 22:45:25.567178 28473 exec.cpp:274] Executor asked to run task '0' I0729 22:45:25.569226 28473 exec.cpp:283] Executor::launchTask took 40.517us I0729 22:45:25.569710 28473 exec.cpp:274] Executor asked to run task '1' I0729 22:45:25.570179 28473 exec.cpp:283] Executor::launchTask took 19.319us I0729 22:45:25.571802 28473 exec.cpp:464] Executor sending status update TASK_RUNNING (UUID: b76d8609-0ef3-4b8c-99e9-a22a2d751422) for task 0 of framework 201307292245-1032504131-50658-28293-0000 I0729 22:45:25.571872 28474 slave.cpp:1720] Handling status update TASK_RUNNING (UUID: b76d8609-0ef3-4b8c-99e9-a22a2d751422) for task 0 of framework 201307292245-1032504131-50658-28293-0000 from executor(37)@67.195.138.61:50658 I0729 22:45:25.572274 28474 status_update_manager.cpp:290] Received status update TASK_RUNNING (UUID: b76d8609-0ef3-4b8c-99e9-a22a2d751422) for task 0 of framework 201307292245-1032504131-50658-28293-0000 with checkpoint=false I0729 22:45:25.572909 28474 status_update_manager.cpp:450] Creating StatusUpdate stream for task 0 of framework 201307292245-1032504131-50658-28293-0000 I0729 22:45:25.572825 28473 exec.cpp:464] Executor sending status update TASK_FINISHED (UUID: a97dd917-4a54-47d3-9336-e03cf3e80d6d) for task 0 of framework 201307292245-1032504131-50658-28293-0000 I0729 22:45:25.573945 28469 slave.cpp:1720] Handling status update TASK_FINISHED (UUID: a97dd917-4a54-47d3-9336-e03cf3e80d6d) for task 0 of framework 201307292245-1032504131-50658-28293-0000 from executor(37)@67.195.138.61:50658 I0729 22:45:25.574831 28473 exec.cpp:464] Executor sending status update TASK_RUNNING (UUID: 35080ecf-fb1c-4384-930e-607431f1162f) for task 1 of framework 201307292245-1032504131-50658-28293-0000 I0729 22:45:25.575456 28473 slave.cpp:1720] Handling status update TASK_RUNNING (UUID: 35080ecf-fb1c-4384-930e-607431f1162f) for task 1 of framework 201307292245-1032504131-50658-28293-0000 from executor(37)@67.195.138.61:50658 I0729 22:45:25.573479 28474 status_update_manager.cpp:336] Forwarding status update TASK_RUNNING (UUID: b76d8609-0ef3-4b8c-99e9-a22a2d751422) for task 0 of framework 201307292245-1032504131-50658-28293-0000 to [email protected]:50658 I0729 22:45:25.576484 28474 status_update_manager.cpp:290] Received status update TASK_FINISHED (UUID: a97dd917-4a54-47d3-9336-e03cf3e80d6d) for task 0 of framework 201307292245-1032504131-50658-28293-0000 with checkpoint=false I0729 22:45:25.576488 28472 master.cpp:1093] Status update from slave(106)@67.195.138.61:50658: task 0 of framework 201307292245-1032504131-50658-28293-0000 is now in state TASK_RUNNING I0729 22:45:25.576519 28473 slave.cpp:1831] Status update manager successfully handled status update TASK_RUNNING (UUID: b76d8609-0ef3-4b8c-99e9-a22a2d751422) for task 0 of framework 201307292245-1032504131-50658-28293-0000 I0729 22:45:25.579012 28474 status_update_manager.cpp:290] Received status update TASK_RUNNING (UUID: 35080ecf-fb1c-4384-930e-607431f1162f) for task 1 of framework 201307292245-1032504131-50658-28293-0000 with checkpoint=false I0729 22:45:25.579457 28469 sched.cpp:395] Scheduler::statusUpdate took 17.196us I0729 22:45:25.579761 28473 slave.cpp:1837] Sending acknowledgement for status update TASK_RUNNING (UUID: b76d8609-0ef3-4b8c-99e9-a22a2d751422) for task 0 of framework 201307292245-1032504131-50658-28293-0000 to executor(37)@67.195.138.61:50658 I0729 22:45:25.581434 28473 slave.cpp:1831] Status update manager successfully handled status update TASK_FINISHED (UUID: a97dd917-4a54-47d3-9336-e03cf3e80d6d) for task 0 of framework 201307292245-1032504131-50658-28293-0000 I0729 22:45:25.581902 28473 slave.cpp:1837] Sending acknowledgement for status update TASK_FINISHED (UUID: a97dd917-4a54-47d3-9336-e03cf3e80d6d) for task 0 of framework 201307292245-1032504131-50658-28293-0000 to executor(37)@67.195.138.61:50658 I0729 22:45:25.581483 28471 exec.cpp:320] Executor received status update acknowledgement b76d8609-0ef3-4b8c-99e9-a22a2d751422 for task 0 of framework 201307292245-1032504131-50658-28293-0000 I0729 22:45:25.583495 28471 exec.cpp:320] Executor received status update acknowledgement a97dd917-4a54-47d3-9336-e03cf3e80d6d for task 0 of framework 201307292245-1032504131-50658-28293-0000 I0729 22:45:25.580278 28474 status_update_manager.cpp:450] Creating StatusUpdate stream for task 1 of framework 201307292245-1032504131-50658-28293-0000 I0729 22:45:25.584439 28474 status_update_manager.cpp:336] Forwarding status update TASK_RUNNING (UUID: 35080ecf-fb1c-4384-930e-607431f1162f) for task 1 of framework 201307292245-1032504131-50658-28293-0000 to [email protected]:50658 I0729 22:45:25.585593 28474 status_update_manager.cpp:360] Received status update acknowledgement b76d8609-0ef3-4b8c-99e9-a22a2d751422 for task 0 of framework 201307292245-1032504131-50658-28293-0000 I0729 22:45:25.585594 28472 slave.cpp:1831] Status update manager successfully handled status update TASK_RUNNING (UUID: 35080ecf-fb1c-4384-930e-607431f1162f) for task 1 of framework 201307292245-1032504131-50658-28293-0000 I0729 22:45:25.585597 28475 master.cpp:1093] Status update from slave(106)@67.195.138.61:50658: task 1 of framework 201307292245-1032504131-50658-28293-0000 is now in state TASK_RUNNING I0729 22:45:25.586001 28474 status_update_manager.cpp:336] Forwarding status update TASK_FINISHED (UUID: a97dd917-4a54-47d3-9336-e03cf3e80d6d) for task 0 of framework 201307292245-1032504131-50658-28293-0000 to [email protected]:50658 I0729 22:45:25.586442 28472 slave.cpp:1837] Sending acknowledgement for status update TASK_RUNNING (UUID: 35080ecf-fb1c-4384-930e-607431f1162f) for task 1 of framework 201307292245-1032504131-50658-28293-0000 to executor(37)@67.195.138.61:50658 I0729 22:45:25.588779 28472 slave.cpp:1371] Status update manager successfully handled status update acknowledgement b76d8609-0ef3-4b8c-99e9-a22a2d751422 for task 0 of framework 201307292245-1032504131-50658-28293-0000 I0729 22:45:25.587584 28475 master.cpp:1093] Status update from slave(106)@67.195.138.61:50658: task 0 of framework 201307292245-1032504131-50658-28293-0000 is now in state TASK_FINISHED I0729 22:45:25.589918 28475 master.hpp:318] Removing task 0 with resources cpus(*):1; mem(*):256 on slave 201307292245-1032504131-50658-28293-0 I0729 22:45:25.588799 28469 exec.cpp:320] Executor received status update acknowledgement 35080ecf-fb1c-4384-930e-607431f1162f for task 1 of framework 201307292245-1032504131-50658-28293-0000 I0729 22:45:25.587051 28468 sched.cpp:395] Scheduler::statusUpdate took 16.775us I0729 22:45:25.590951 28475 hierarchical_allocator_process.hpp:595] Recovered cpus(*):1; mem(*):256 (total allocatable: cpus(*):2; mem(*):768; disk(*):20812; ports(*):[31000-32000]) on slave 201307292245-1032504131-50658-28293-0 from framework 201307292245-1032504131-50658-28293-0000 I0729 22:45:25.591783 28468 sched.cpp:395] Scheduler::statusUpdate took 9.051us I0729 22:45:25.592886 28470 status_update_manager.cpp:360] Received status update acknowledgement 35080ecf-fb1c-4384-930e-607431f1162f for task 1 of framework 201307292245-1032504131-50658-28293-0000 I0729 22:45:25.593487 28471 hierarchical_allocator_process.hpp:709] Offering cpus(*):2; mem(*):768; disk(*):20812; ports(*):[31000-32000] on slave 201307292245-1032504131-50658-28293-0 to framework 201307292245-1032504131-50658-28293-0000 I0729 22:45:25.594547 28471 hierarchical_allocator_process.hpp:646] Performed allocation for 1 slaves in 1.121124ms I0729 22:45:25.593989 28468 slave.cpp:1371] Status update manager successfully handled status update acknowledgement 35080ecf-fb1c-4384-930e-607431f1162f for task 1 of framework 201307292245-1032504131-50658-28293-0000 I0729 22:45:25.593981 28470 status_update_manager.cpp:360] Received status update acknowledgement a97dd917-4a54-47d3-9336-e03cf3e80d6d for task 0 of framework 201307292245-1032504131-50658-28293-0000 I0729 22:45:25.594604 28469 master.hpp:328] Adding offer 201307292245-1032504131-50658-28293-1 with resources cpus(*):2; mem(*):768; disk(*):20812; ports(*):[31000-32000] on slave 201307292245-1032504131-50658-28293-0 I0729 22:45:25.596472 28469 master.cpp:1322] Sending 1 offers to framework 201307292245-1032504131-50658-28293-0000 I0729 22:45:25.595960 28470 status_update_manager.cpp:481] Cleaning up status update stream for task 0 of framework 201307292245-1032504131-50658-28293-0000 I0729 22:45:25.597015 28472 sched.cpp:340] Scheduler::resourceOffers took 25.823us I0729 22:45:25.597182 28293 master.cpp:436] Master terminating I0729 22:45:25.602859 28293 master.cpp:209] Shutting down master I0729 22:45:25.598194 28472 sched.cpp:498] Stopping framework '201307292245-1032504131-50658-28293-0000' I0729 22:45:25.597468 28470 slave.cpp:1371] Status update manager successfully handled status update acknowledgement a97dd917-4a54-47d3-9336-e03cf3e80d6d for task 0 of framework 201307292245-1032504131-50658-28293-0000 I0729 22:45:25.603409 28473 hierarchical_allocator_process.hpp:393] Deactivated framework 201307292245-1032504131-50658-28293-0000 I0729 22:45:25.603497 28293 master.hpp:318] Removing task 1 with resources cpus(*):1; mem(*):256 on slave 201307292245-1032504131-50658-28293-0 E0729 22:45:25.604492 28470 slave.cpp:1395] Status update acknowledgement a97dd917-4a54-47d3-9336-e03cf3e80d6d for task 0 of unknown executor I0729 22:45:25.605631 28469 hierarchical_allocator_process.hpp:595] Recovered cpus(*):1; mem(*):256 (total allocatable: cpus(*):1; mem(*):256) on slave 201307292245-1032504131-50658-28293-0 from framework 201307292245-1032504131-50658-28293-0000 I0729 22:45:25.606713 28469 hierarchical_allocator_process.hpp:595] Recovered cpus(*):2; mem(*):768; disk(*):20812; ports(*):[31000-32000] (total allocatable: cpus(*):3; mem(*):1024; disk(*):20812; ports(*):[31000-32000]) on slave 201307292245-1032504131-50658-28293-0 from framework 201307292245-1032504131-50658-28293-0000 I0729 22:45:25.605949 28470 slave.cpp:493] Slave asked to shut down by [email protected]:50658 I0729 22:45:25.607580 28470 slave.cpp:1140] Asked to shut down framework 201307292245-1032504131-50658-28293-0000 by [email protected]:50658 I0729 22:45:25.608649 28470 slave.cpp:1165] Shutting down framework 201307292245-1032504131-50658-28293-0000 I0729 22:45:25.609196 28470 slave.cpp:2356] Shutting down executor 'default' of framework 201307292245-1032504131-50658-28293-0000 I0729 22:45:25.610774 28475 exec.cpp:360] Executor asked to shutdown I0729 22:45:25.611214 28475 exec.cpp:375] Executor::shutdown took 19.136us I0729 22:45:25.605736 28293 master.hpp:338] Removing offer 201307292245-1032504131-50658-28293-1 with resources cpus(*):2; mem(*):768; disk(*):20812; ports(*):[31000-32000] on slave 201307292245-1032504131-50658-28293-0 I0729 22:45:25.610777 28470 slave.cpp:1912] [email protected]:50658 exited I0729 22:45:25.612350 28472 hierarchical_allocator_process.hpp:348] Removed framework 201307292245-1032504131-50658-28293-0000 W0729 22:45:25.612879 28470 slave.cpp:1915] Master disconnected! Waiting for a new master to be elected I0729 22:45:25.613445 28472 hierarchical_allocator_process.hpp:456] Removed slave 201307292245-1032504131-50658-28293-0 I0729 22:45:25.613818 28470 slave.cpp:448] Slave terminating I0729 22:45:25.615633 28470 slave.cpp:1140] Asked to shut down framework 201307292245-1032504131-50658-28293-0000 by @0.0.0.0:0 W0729 22:45:25.616735 28470 slave.cpp:1161] Ignoring shutdown framework 201307292245-1032504131-50658-28293-0000 because it is terminating [ OK ] AllocatorTest/0.TaskFinished (78 ms) [ RUN ] AllocatorTest/0.WhitelistSlave I0729 22:45:25.618315 28468 master.cpp:230] Master started on 67.195.138.61:50658 I0729 22:45:25.618366 28468 master.cpp:245] Master ID: 201307292245-1032504131-50658-28293 I0729 22:45:25.619176 28475 hierarchical_allocator_process.hpp:287] Initializing hierarchical allocator process with master : [email protected]:50658 I0729 22:45:25.619317 28475 hierarchical_allocator_process.hpp:470] Updated slave white list: { dummy-slave } I0729 22:45:25.619763 28475 hierarchical_allocator_process.hpp:684] No resources available to allocate! I0729 22:45:25.619192 28468 master.cpp:583] Elected as master! I0729 22:45:25.619485 28473 sched.cpp:178] New master at [email protected]:50658 I0729 22:45:25.619251 28470 slave.cpp:113] Slave started on 107)@67.195.138.61:50658 I0729 22:45:25.620242 28475 hierarchical_allocator_process.hpp:646] Performed allocation for 0 slaves in 483.245us I0729 22:45:25.621584 28472 master.cpp:633] Registering framework 201307292245-1032504131-50658-28293-0000 at scheduler(96)@67.195.138.61:50658 I0729 22:45:25.623105 28468 sched.cpp:237] Framework registered with 201307292245-1032504131-50658-28293-0000 I0729 22:45:25.623589 28468 sched.cpp:251] Scheduler::registered took 10.413us I0729 22:45:25.623172 28473 hierarchical_allocator_process.hpp:317] Added framework 201307292245-1032504131-50658-28293-0000 I0729 22:45:25.625191 28473 hierarchical_allocator_process.hpp:684] No resources available to allocate! I0729 22:45:25.625603 28473 hierarchical_allocator_process.hpp:646] Performed allocation for 0 slaves in 413.039us I0729 22:45:25.622045 28470 slave.cpp:213] Slave resources: cpus(*):2; mem(*):1024; disk(*):20814; ports(*):[31000-32000] I0729 22:45:25.623112 28472 master.cpp:620] Framework 201307292245-1032504131-50658-28293-0000 (scheduler(96)@67.195.138.61:50658) already registered, resending acknowledgement I0729 22:45:25.627744 28472 sched.cpp:232] Ignoring framework registered message because the driver is already connected! I0729 22:45:25.627959 28470 slave.cpp:537] New master detected at [email protected]:50658 I0729 22:45:25.628697 28470 slave.cpp:552] Postponing registration until recovery is complete I0729 22:45:25.628738 28471 status_update_manager.cpp:155] New master detected at [email protected]:50658 I0729 22:45:25.629331 28470 slave.cpp:398] Finished recovery I0729 22:45:25.630447 28473 master.cpp:962] Attempting to register slave on juno.apache.org at slave(107)@67.195.138.61:50658 I0729 22:45:25.630806 28473 master.cpp:1941] Adding slave 201307292245-1032504131-50658-28293-0 at juno.apache.org with cpus(*):2; mem(*):1024; disk(*):20814; ports(*):[31000-32000] I0729 22:45:25.631474 28469 slave.cpp:597] Registered with master [email protected]:50658; given slave ID 201307292245-1032504131-50658-28293-0 I0729 22:45:25.631523 28473 master.cpp:951] Slave 201307292245-1032504131-50658-28293-0 (juno.apache.org) already registered, resending acknowledgement I0729 22:45:25.631620 28472 hierarchical_allocator_process.hpp:430] Added slave 201307292245-1032504131-50658-28293-0 (juno.apache.org) with cpus(*):2; mem(*):1024; disk(*):20814; ports(*):[31000-32000] (and cpus(*):2; mem(*):1024; disk(*):20814; ports(*):[31000-32000] available) I0729 22:45:25.634016 28472 hierarchical_allocator_process.hpp:666] Performed allocation for slave 201307292245-1032504131-50658-28293-0 in 34.526us W0729 22:45:25.633560 28473 slave.cpp:621] Already registered with master [email protected]:50658 I0729 22:45:25.639657 28469 hierarchical_allocator_process.hpp:646] Performed allocation for 1 slaves in 46.307us I0729 22:45:25.649705 28471 hierarchical_allocator_process.hpp:646] Performed allocation for 1 slaves in 41.332us I0729 22:45:25.659764 28471 hierarchical_allocator_process.hpp:646] Performed allocation for 1 slaves in 34.972us I0729 22:45:25.669980 28474 hierarchical_allocator_process.hpp:646] Performed allocation for 1 slaves in 31.856us I0729 22:45:25.670034 28474 hierarchical_allocator_process.hpp:470] Updated slave white list: { dummy-slave, juno.apache.org } I0729 22:45:25.679879 28472 hierarchical_allocator_process.hpp:709] Offering cpus(*):2; mem(*):1024; disk(*):20814; ports(*):[31000-32000] on slave 201307292245-1032504131-50658-28293-0 to framework 201307292245-1032504131-50658-28293-0000 I0729 22:45:25.680035 28472 hierarchical_allocator_process.hpp:646] Performed allocation for 1 slaves in 184.024us I0729 22:45:25.680075 28468 master.hpp:328] Adding offer 201307292245-1032504131-50658-28293-0 with resources cpus(*):2; mem(*):1024; disk(*):20814; ports(*):[31000-32000] on slave 201307292245-1032504131-50658-28293-0 I0729 22:45:25.680722 28468 master.cpp:1322] Sending 1 offers to framework 201307292245-1032504131-50658-28293-0000 I0729 22:45:25.681522 28473 sched.cpp:340] Scheduler::resourceOffers took 26.858us I0729 22:45:25.689919 28468 sched.cpp:498] Stopping framework '201307292245-1032504131-50658-28293-0000' I0729 22:45:25.689929 28293 master.cpp:436] Master terminating I0729 22:45:25.690356 28468 slave.cpp:493] Slave asked to shut down by [email protected]:50658 I0729 22:45:25.690744 28468 slave.cpp:448] Slave terminating I0729 22:45:25.690359 28293 master.cpp:209] Shutting down master I0729 22:45:25.691740 28470 hierarchical_allocator_process.hpp:393] Deactivated framework 201307292245-1032504131-50658-28293-0000 I0729 22:45:25.692229 28470 hierarchical_allocator_process.hpp:595] Recovered cpus(*):2; mem(*):1024; disk(*):20814; ports(*):[31000-32000] (total allocatable: cpus(*):2; mem(*):1024; disk(*):20814; ports(*):[31000-32000]) on slave 201307292245-1032504131-50658-28293-0 from framework 201307292245-1032504131-50658-28293-0000 I0729 22:45:25.691867 28293 master.hpp:338] Removing offer 201307292245-1032504131-50658-28293-0 with resources cpus(*):2; mem(*):1024; disk(*):20814; ports(*):[31000-32000] on slave 201307292245-1032504131-50658-28293-0 I0729 22:45:25.693295 28469 hierarchical_allocator_process.hpp:348] Removed framework 201307292245-1032504131-50658-28293-0000 [ OK ] AllocatorTest/0.WhitelistSlave (76 ms) [ RUN ] AllocatorTest/0.RoleTest I0729 22:45:25.694150 28471 master.cpp:230] Master started on 67.195.138.61:50658 I0729 22:45:25.694236 28471 master.cpp:245] Master ID: 201307292245-1032504131-50658-28293 I0729 22:45:25.694530 28468 sched.cpp:178] New master at [email protected]:50658 W0729 22:45:25.694833 28469 master.cpp:83] No whitelist given. Advertising offers for all slaves I0729 22:45:25.694977 28471 master.cpp:583] Elected as master! I0729 22:45:25.695143 28468 hierarchical_allocator_process.hpp:287] Initializing hierarchical allocator process with master : [email protected]:50658 I0729 22:45:25.697470 28468 sched.cpp:178] New master at [email protected]:50658 I0729 22:45:25.697044 28471 sched.cpp:482] Got error 'Role 'role1' is not valid.' I0729 22:45:25.697974 28474 master.cpp:633] Registering framework 201307292245-1032504131-50658-28293-0000 at scheduler(98)@67.195.138.61:50658 I0729 22:45:25.698372 28471 sched.cpp:493] Scheduler::error took 10.794us I0729 22:45:25.699229 28471 sched.cpp:522] Aborting framework '' I0729 22:45:25.699630 28471 sched.cpp:527] Not sending a deactivate message as master is disconnected I0729 22:45:25.698940 28468 sched.cpp:237] Framework registered with 201307292245-1032504131-50658-28293-0000 I0729 22:45:25.700606 28468 sched.cpp:251] Scheduler::registered took 17.381us I0729 22:45:25.701076 28468 sched.cpp:498] Stopping framework '201307292245-1032504131-50658-28293-0000' I0729 22:45:25.700731 28469 sched.cpp:498] Stopping framework '' I0729 22:45:25.700758 28293 master.cpp:436] Master terminating I0729 22:45:25.699003 28473 hierarchical_allocator_process.hpp:317] Added framework 201307292245-1032504131-50658-28293-0000 I0729 22:45:25.702522 28293 master.cpp:209] Shutting down master I0729 22:45:25.702980 28473 hierarchical_allocator_process.hpp:684] No resources available to allocate! I0729 22:45:25.703964 28473 hierarchical_allocator_process.hpp:646] Performed allocation for 0 slaves in 983.606us [ OK ] AllocatorTest/0.RoleTest (12 ms) [----------] 10 tests from AllocatorTest/0 (864 ms total) [----------] 1 test from LoggingTest [ RUN ] LoggingTest.Toggle I0729 22:45:25.705947 28471 process.cpp:3150] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0729 22:45:25.706516 28476 process.cpp:986] Socket closed while receiving I0729 22:45:25.706894 28472 process.cpp:3150] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0729 22:45:25.707569 28476 process.cpp:986] Socket closed while receiving I0729 22:45:25.708197 28475 process.cpp:3150] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0729 22:45:25.708709 28476 process.cpp:986] Socket closed while receiving I0729 22:45:25.709228 28472 process.cpp:3150] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0729 22:45:25.709697 28476 process.cpp:986] Socket closed while receiving I0729 22:45:25.710954 28468 process.cpp:3150] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0729 22:45:25.711405 28476 process.cpp:986] Socket closed while receiving I0729 22:45:25.711881 28469 process.cpp:3150] Handling HTTP event for process 'logging' with path: '/logging/toggle' I0729 22:45:25.712509 28476 process.cpp:986] 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 (1 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 (1 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 (35 ms) [----------] 3 tests from FsTest (35 ms total) [----------] Global test environment tear-down [==========] 180 tests from 33 test cases ran. (82267 ms total) [ PASSED ] 179 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 Build step 'Execute shell' marked build as failure
