See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/2315/changes>
Changes: [niklas] Added a reviewboard config to make ./support/post-reviews easier to use. ------------------------------------------ [...truncated 73024 lines...] I1124 21:40:19.680874 27283 registrar.cpp:445] Applied 1 operations in 76084ns; attempting to update the 'registry' I1124 21:40:19.684085 27297 log.cpp:680] Attempting to append 571 bytes to the log I1124 21:40:19.684281 27283 coordinator.cpp:340] Coordinator attempting to write APPEND action at position 5 I1124 21:40:19.685169 27292 replica.cpp:508] Replica received write request for position 5 I1124 21:40:19.685438 27292 leveldb.cpp:343] Persisting action (590 bytes) to leveldb took 237579ns I1124 21:40:19.685462 27292 replica.cpp:676] Persisted action at 5 I1124 21:40:19.686157 27293 replica.cpp:655] Replica received learned notice for position 5 I1124 21:40:19.686632 27297 master.cpp:3019] Ignoring register slave message from slave(229)@67.195.81.189:56053 (proserpina.apache.org) as admission is already in progress I1124 21:40:19.686636 27296 slave.cpp:1067] Will retry registration in 12.110411ms if necessary I1124 21:40:19.686700 27293 leveldb.cpp:343] Persisting action (592 bytes) to leveldb took 513850ns I1124 21:40:19.686720 27293 replica.cpp:676] Persisted action at 5 I1124 21:40:19.686740 27293 replica.cpp:661] Replica learned APPEND action at position 5 I1124 21:40:19.687974 27294 registrar.cpp:490] Successfully updated the 'registry' in 7.040768ms I1124 21:40:19.688488 27286 log.cpp:699] Attempting to truncate the log to 5 I1124 21:40:19.688611 27289 coordinator.cpp:340] Coordinator attempting to write TRUNCATE action at position 6 I1124 21:40:19.688655 27297 slave.cpp:2553] Received ping from slave-observer(209)@67.195.81.189:56053 I1124 21:40:19.688658 27288 master.cpp:3085] Registered slave 20141124-214019-3176252227-56053-27268-S1 at slave(229)@67.195.81.189:56053 (proserpina.apache.org) with cpus(role3):4; mem(role3):1024; ports(*):[31000-32000] I1124 21:40:19.689096 27297 slave.cpp:773] Registered with master [email protected]:56053; given slave ID 20141124-214019-3176252227-56053-27268-S1 I1124 21:40:19.689167 27295 hierarchical_allocator_process.hpp:442] Added slave 20141124-214019-3176252227-56053-27268-S1 (proserpina.apache.org) with cpus(role3):4; mem(role3):1024; ports(*):[31000-32000] (and cpus(role3):4; mem(role3):1024; ports(*):[31000-32000] available) I1124 21:40:19.689224 27296 status_update_manager.cpp:178] Resuming sending status updates I1124 21:40:19.689383 27295 hierarchical_allocator_process.hpp:679] Performed allocation for slave 20141124-214019-3176252227-56053-27268-S1 in 139627ns I1124 21:40:19.689885 27291 replica.cpp:508] Replica received write request for position 6 I1124 21:40:19.690515 27291 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 591759ns I1124 21:40:19.690546 27291 replica.cpp:676] Persisted action at 6 I1124 21:40:19.691148 27288 replica.cpp:655] Replica received learned notice for position 6 I1124 21:40:19.691665 27288 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took 486081ns I1124 21:40:19.691725 27288 leveldb.cpp:401] Deleting ~2 keys from leveldb took 29546ns I1124 21:40:19.691746 27288 replica.cpp:676] Persisted action at 6 I1124 21:40:19.691769 27288 replica.cpp:661] Replica learned TRUNCATE action at position 6 I1124 21:40:19.695711 27268 sched.cpp:148] Version: 0.22.0 I1124 21:40:19.696493 27287 sched.cpp:245] New master detected at [email protected]:56053 I1124 21:40:19.696555 27287 sched.cpp:301] Authenticating with master [email protected]:56053 I1124 21:40:19.696575 27287 sched.cpp:308] Using default CRAM-MD5 authenticatee I1124 21:40:19.696776 27289 authenticatee.hpp:138] Creating new client SASL connection I1124 21:40:19.696933 27287 master.cpp:3874] Authenticating [email protected]:56053 I1124 21:40:19.696960 27287 master.cpp:3885] Using default CRAM-MD5 authenticator I1124 21:40:19.697294 27292 authenticator.hpp:170] Creating new server SASL connection I1124 21:40:19.697661 27294 authenticatee.hpp:229] Received SASL authentication mechanisms: CRAM-MD5 I1124 21:40:19.697690 27294 authenticatee.hpp:255] Attempting to authenticate with mechanism 'CRAM-MD5' I1124 21:40:19.697773 27294 authenticator.hpp:276] Received SASL authentication start I1124 21:40:19.697820 27294 authenticator.hpp:398] Authentication requires more steps I1124 21:40:19.697904 27294 authenticatee.hpp:275] Received SASL authentication step I1124 21:40:19.698035 27295 authenticator.hpp:304] Received SASL authentication step I1124 21:40:19.698092 27295 auxprop.cpp:99] Request to lookup properties for user: 'test-principal' realm: 'proserpina.apache.org' server FQDN: 'proserpina.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I1124 21:40:19.698107 27295 auxprop.cpp:171] Looking up auxiliary property '*userPassword' I1124 21:40:19.698140 27295 auxprop.cpp:171] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I1124 21:40:19.698164 27295 auxprop.cpp:99] Request to lookup properties for user: 'test-principal' realm: 'proserpina.apache.org' server FQDN: 'proserpina.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I1124 21:40:19.698175 27295 auxprop.cpp:121] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I1124 21:40:19.698184 27295 auxprop.cpp:121] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I1124 21:40:19.698201 27295 authenticator.hpp:390] Authentication success I1124 21:40:19.698303 27295 authenticatee.hpp:315] Authentication success I1124 21:40:19.698339 27294 master.cpp:3932] Successfully authenticated principal 'test-principal' at [email protected]:56053 I1124 21:40:19.698601 27284 sched.cpp:389] Successfully authenticated with master [email protected]:56053 I1124 21:40:19.812132 27284 sched.cpp:512] Sending registration request to [email protected]:56053 I1124 21:40:19.729635 27296 hierarchical_allocator_process.hpp:659] Performed allocation for 2 slaves in 258301ns I1124 21:40:19.812230 27284 sched.cpp:545] Will retry registration in 1.44640593secs if necessary I1124 21:40:19.812481 27294 master.cpp:1383] Received registration request for framework 'framework1' at [email protected]:56053 I1124 21:40:19.812551 27294 master.cpp:1342] Authorizing framework principal 'test-principal' to receive offers for role 'role1' I1124 21:40:19.812954 27296 master.cpp:1447] Registering framework 20141124-214019-3176252227-56053-27268-0000 (framework1) at [email protected]:56053 I1124 21:40:19.813626 27292 sched.cpp:439] Framework registered with 20141124-214019-3176252227-56053-27268-0000 I1124 21:40:19.813633 27287 hierarchical_allocator_process.hpp:329] Added framework 20141124-214019-3176252227-56053-27268-0000 I1124 21:40:19.813745 27292 sched.cpp:453] Scheduler::registered took 24499ns I1124 21:40:19.814000 27287 hierarchical_allocator_process.hpp:734] Offering cpus(*):1; mem(*):200; ports(*):[31000-32000]; cpus(role1):1; mem(role1):200 on slave 20141124-214019-3176252227-56053-27268-S0 to framework 20141124-214019-3176252227-56053-27268-0000 I1124 21:40:19.814432 27287 hierarchical_allocator_process.hpp:659] Performed allocation for 2 slaves in 688355ns I1124 21:40:19.814760 27287 master.cpp:3816] Sending 1 offers to framework 20141124-214019-3176252227-56053-27268-0000 (framework1) at [email protected]:56053 I1124 21:40:19.815646 27288 sched.cpp:602] Scheduler::resourceOffers took 551694ns I1124 21:40:19.816462 27288 master.cpp:2363] Processing reply for offers: [ 20141124-214019-3176252227-56053-27268-O0 ] on slave 20141124-214019-3176252227-56053-27268-S0 at slave(228)@67.195.81.189:56053 (proserpina.apache.org) for framework 20141124-214019-3176252227-56053-27268-0000 (framework1) at [email protected]:56053 I1124 21:40:19.816496 27288 master.cpp:2461] Authorizing framework principal 'test-principal' to launch task 0 as user 'user1' W1124 21:40:19.817342 27285 master.cpp:1998] Executor default for task 0 uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases. W1124 21:40:19.817394 27285 master.cpp:2010] Executor default for task 0 uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases. I1124 21:40:19.817669 27285 master.hpp:759] Adding task 0 with resources cpus(role1):1; mem(role1):100 on slave 20141124-214019-3176252227-56053-27268-S0 (proserpina.apache.org) I1124 21:40:19.817750 27285 master.cpp:2531] Launching task 0 of framework 20141124-214019-3176252227-56053-27268-0000 (framework1) at [email protected]:56053 with resources cpus(role1):1; mem(role1):100 on slave 20141124-214019-3176252227-56053-27268-S0 at slave(228)@67.195.81.189:56053 (proserpina.apache.org) I1124 21:40:19.818135 27293 slave.cpp:1098] Got assigned task 0 for framework 20141124-214019-3176252227-56053-27268-0000 I1124 21:40:19.818367 27295 hierarchical_allocator_process.hpp:563] Recovered cpus(*):1; mem(*):200; ports(*):[31000-32000]; mem(role1):100 (total allocatable: cpus(role2):2; mem(role2):600; cpus(*):1; mem(*):200; ports(*):[31000-32000]; mem(role1):100) on slave 20141124-214019-3176252227-56053-27268-S0 from framework 20141124-214019-3176252227-56053-27268-0000 I1124 21:40:19.818658 27293 slave.cpp:1208] Launching task 0 for framework 20141124-214019-3176252227-56053-27268-0000 I1124 21:40:19.821410 27293 slave.cpp:3864] Launching executor default of framework 20141124-214019-3176252227-56053-27268-0000 in work directory '/tmp/ReservationAllocatorTest_ResourcesReturned_N11T6s/slaves/20141124-214019-3176252227-56053-27268-S0/frameworks/20141124-214019-3176252227-56053-27268-0000/executors/default/runs/79b951a8-42dd-45a2-ae41-8658079f1ced' I1124 21:40:19.824630 27293 exec.cpp:132] Version: 0.22.0 I1124 21:40:19.824918 27295 exec.cpp:182] Executor started at: executor(78)@67.195.81.189:56053 with pid 27268 I1124 21:40:19.825001 27293 slave.cpp:1331] Queuing task '0' for executor default of framework '20141124-214019-3176252227-56053-27268-0000 I1124 21:40:19.825139 27293 slave.cpp:558] Successfully attached file '/tmp/ReservationAllocatorTest_ResourcesReturned_N11T6s/slaves/20141124-214019-3176252227-56053-27268-S0/frameworks/20141124-214019-3176252227-56053-27268-0000/executors/default/runs/79b951a8-42dd-45a2-ae41-8658079f1ced' I1124 21:40:19.825260 27293 slave.cpp:2855] Monitoring executor 'default' of framework '20141124-214019-3176252227-56053-27268-0000' in container '79b951a8-42dd-45a2-ae41-8658079f1ced' I1124 21:40:19.825487 27293 slave.cpp:1875] Got registration for executor 'default' of framework 20141124-214019-3176252227-56053-27268-0000 from executor(78)@67.195.81.189:56053 I1124 21:40:19.825841 27293 slave.cpp:1994] Flushing queued task 0 for executor 'default' of framework 20141124-214019-3176252227-56053-27268-0000 I1124 21:40:19.825989 27287 exec.cpp:206] Executor registered on slave 20141124-214019-3176252227-56053-27268-S0 I1124 21:40:19.828258 27287 exec.cpp:218] Executor::registered took 24662ns I1124 21:40:19.828430 27287 exec.cpp:293] Executor asked to run task '0' I1124 21:40:19.828554 27287 exec.cpp:302] Executor::launchTask took 100871ns I1124 21:40:19.830953 27287 exec.cpp:525] Executor sending status update TASK_RUNNING (UUID: 2dc1db0b-a446-4dcd-8367-6b94941aca31) for task 0 of framework 20141124-214019-3176252227-56053-27268-0000 I1124 21:40:19.831198 27287 slave.cpp:2230] Handling status update TASK_RUNNING (UUID: 2dc1db0b-a446-4dcd-8367-6b94941aca31) for task 0 of framework 20141124-214019-3176252227-56053-27268-0000 from executor(78)@67.195.81.189:56053 I1124 21:40:19.831513 27288 status_update_manager.cpp:317] Received status update TASK_RUNNING (UUID: 2dc1db0b-a446-4dcd-8367-6b94941aca31) for task 0 of framework 20141124-214019-3176252227-56053-27268-0000 I1124 21:40:19.831558 27288 status_update_manager.cpp:494] Creating StatusUpdate stream for task 0 of framework 20141124-214019-3176252227-56053-27268-0000 I1124 21:40:19.831727 27288 status_update_manager.cpp:371] Forwarding update TASK_RUNNING (UUID: 2dc1db0b-a446-4dcd-8367-6b94941aca31) for task 0 of framework 20141124-214019-3176252227-56053-27268-0000 to the slave I1124 21:40:19.831928 27287 slave.cpp:2473] Forwarding the update TASK_RUNNING (UUID: 2dc1db0b-a446-4dcd-8367-6b94941aca31) for task 0 of framework 20141124-214019-3176252227-56053-27268-0000 to [email protected]:56053 I1124 21:40:19.832139 27287 slave.cpp:2400] Status update manager successfully handled status update TASK_RUNNING (UUID: 2dc1db0b-a446-4dcd-8367-6b94941aca31) for task 0 of framework 20141124-214019-3176252227-56053-27268-0000 I1124 21:40:19.832171 27287 slave.cpp:2406] Sending acknowledgement for status update TASK_RUNNING (UUID: 2dc1db0b-a446-4dcd-8367-6b94941aca31) for task 0 of framework 20141124-214019-3176252227-56053-27268-0000 to executor(78)@67.195.81.189:56053 I1124 21:40:19.832229 27291 master.cpp:3409] Forwarding status update TASK_RUNNING (UUID: 2dc1db0b-a446-4dcd-8367-6b94941aca31) for task 0 of framework 20141124-214019-3176252227-56053-27268-0000 I1124 21:40:19.832346 27292 exec.cpp:339] Executor received status update acknowledgement 2dc1db0b-a446-4dcd-8367-6b94941aca31 for task 0 of framework 20141124-214019-3176252227-56053-27268-0000 I1124 21:40:19.832365 27291 master.cpp:3381] Status update TASK_RUNNING (UUID: 2dc1db0b-a446-4dcd-8367-6b94941aca31) for task 0 of framework 20141124-214019-3176252227-56053-27268-0000 from slave 20141124-214019-3176252227-56053-27268-S0 at slave(228)@67.195.81.189:56053 (proserpina.apache.org) I1124 21:40:19.832494 27290 sched.cpp:693] Scheduler::statusUpdate took 20814ns I1124 21:40:19.910812 27291 master.cpp:4666] Updating the latest state of task 0 of framework 20141124-214019-3176252227-56053-27268-0000 to TASK_RUNNING I1124 21:40:19.862932 27284 hierarchical_allocator_process.hpp:734] Offering cpus(*):1; mem(*):200; ports(*):[31000-32000]; mem(role1):100 on slave 20141124-214019-3176252227-56053-27268-S0 to framework 20141124-214019-3176252227-56053-27268-0000 I1124 21:40:19.911079 27286 master.cpp:2881] Forwarding status update acknowledgement 2dc1db0b-a446-4dcd-8367-6b94941aca31 for task 0 of framework 20141124-214019-3176252227-56053-27268-0000 (framework1) at [email protected]:56053 to slave 20141124-214019-3176252227-56053-27268-S0 at slave(228)@67.195.81.189:56053 (proserpina.apache.org) I1124 21:40:19.911325 27284 hierarchical_allocator_process.hpp:659] Performed allocation for 2 slaves in 48.625543ms I1124 21:40:19.911425 27286 status_update_manager.cpp:389] Received status update acknowledgement (UUID: 2dc1db0b-a446-4dcd-8367-6b94941aca31) for task 0 of framework 20141124-214019-3176252227-56053-27268-0000 I1124 21:40:19.911630 27286 slave.cpp:1815] Status update manager successfully handled status update acknowledgement (UUID: 2dc1db0b-a446-4dcd-8367-6b94941aca31) for task 0 of framework 20141124-214019-3176252227-56053-27268-0000 I1124 21:40:19.911680 27287 master.cpp:3816] Sending 1 offers to framework 20141124-214019-3176252227-56053-27268-0000 (framework1) at [email protected]:56053 I1124 21:40:19.912235 27285 sched.cpp:602] Scheduler::resourceOffers took 50248ns I1124 21:40:19.917914 27268 sched.cpp:148] Version: 0.22.0 I1124 21:40:19.918714 27291 sched.cpp:245] New master detected at [email protected]:56053 I1124 21:40:19.918768 27291 sched.cpp:301] Authenticating with master [email protected]:56053 I1124 21:40:19.918786 27291 sched.cpp:308] Using default CRAM-MD5 authenticatee I1124 21:40:19.919214 27283 authenticatee.hpp:138] Creating new client SASL connection I1124 21:40:19.919735 27287 master.cpp:3874] Authenticating [email protected]:56053 I1124 21:40:19.919766 27287 master.cpp:3885] Using default CRAM-MD5 authenticator I1124 21:40:19.920378 27295 authenticator.hpp:170] Creating new server SASL connection I1124 21:40:19.920583 27296 authenticatee.hpp:229] Received SASL authentication mechanisms: CRAM-MD5 I1124 21:40:19.920611 27296 authenticatee.hpp:255] Attempting to authenticate with mechanism 'CRAM-MD5' I1124 21:40:19.920783 27284 authenticator.hpp:276] Received SASL authentication start I1124 21:40:19.920836 27284 authenticator.hpp:398] Authentication requires more steps I1124 21:40:19.920934 27287 authenticatee.hpp:275] Received SASL authentication step I1124 21:40:19.921051 27282 authenticator.hpp:304] Received SASL authentication step I1124 21:40:19.921114 27282 auxprop.cpp:99] Request to lookup properties for user: 'test-principal' realm: 'proserpina.apache.org' server FQDN: 'proserpina.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I1124 21:40:19.921133 27282 auxprop.cpp:171] Looking up auxiliary property '*userPassword' I1124 21:40:19.921164 27282 auxprop.cpp:171] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I1124 21:40:19.921188 27282 auxprop.cpp:99] Request to lookup properties for user: 'test-principal' realm: 'proserpina.apache.org' server FQDN: 'proserpina.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I1124 21:40:19.921200 27282 auxprop.cpp:121] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I1124 21:40:19.921210 27282 auxprop.cpp:121] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I1124 21:40:19.921226 27282 authenticator.hpp:390] Authentication success I1124 21:40:19.921339 27293 authenticatee.hpp:315] Authentication success I1124 21:40:19.921398 27295 master.cpp:3932] Successfully authenticated principal 'test-principal' at [email protected]:56053 I1124 21:40:19.921857 27296 sched.cpp:389] Successfully authenticated with master [email protected]:56053 I1124 21:40:19.921885 27296 sched.cpp:512] Sending registration request to [email protected]:56053 I1124 21:40:19.921932 27296 sched.cpp:545] Will retry registration in 589.853653ms if necessary I1124 21:40:19.922204 27295 master.cpp:1383] Received registration request for framework 'framework2' at [email protected]:56053 I1124 21:40:19.922271 27295 master.cpp:1342] Authorizing framework principal 'test-principal' to receive offers for role 'role2' I1124 21:40:19.922794 27289 master.cpp:1447] Registering framework 20141124-214019-3176252227-56053-27268-0001 (framework2) at [email protected]:56053 I1124 21:40:19.923161 27284 sched.cpp:439] Framework registered with 20141124-214019-3176252227-56053-27268-0001 I1124 21:40:19.923215 27284 sched.cpp:453] Scheduler::registered took 22892ns I1124 21:40:19.923287 27292 hierarchical_allocator_process.hpp:329] Added framework 20141124-214019-3176252227-56053-27268-0001 I1124 21:40:19.923694 27292 hierarchical_allocator_process.hpp:734] Offering cpus(role2):2; mem(role2):600 on slave 20141124-214019-3176252227-56053-27268-S0 to framework 20141124-214019-3176252227-56053-27268-0001 I1124 21:40:19.923885 27292 hierarchical_allocator_process.hpp:659] Performed allocation for 2 slaves in 570080ns I1124 21:40:19.924172 27285 master.cpp:3816] Sending 1 offers to framework 20141124-214019-3176252227-56053-27268-0001 (framework2) at [email protected]:56053 I1124 21:40:19.924576 27292 sched.cpp:602] Scheduler::resourceOffers took 50035ns I1124 21:40:19.927363 27284 exec.cpp:525] Executor sending status update TASK_FINISHED (UUID: d03a0afe-75f0-4e7d-862c-2208362fe201) for task 0 of framework 20141124-214019-3176252227-56053-27268-0000 I1124 21:40:19.927573 27284 slave.cpp:2230] Handling status update TASK_FINISHED (UUID: d03a0afe-75f0-4e7d-862c-2208362fe201) for task 0 of framework 20141124-214019-3176252227-56053-27268-0000 from executor(78)@67.195.81.189:56053 I1124 21:40:19.927655 27284 slave.cpp:4172] Terminating task 0 I1124 21:40:19.928150 27286 status_update_manager.cpp:317] Received status update TASK_FINISHED (UUID: d03a0afe-75f0-4e7d-862c-2208362fe201) for task 0 of framework 20141124-214019-3176252227-56053-27268-0000 I1124 21:40:19.928249 27286 status_update_manager.cpp:371] Forwarding update TASK_FINISHED (UUID: d03a0afe-75f0-4e7d-862c-2208362fe201) for task 0 of framework 20141124-214019-3176252227-56053-27268-0000 to the slave I1124 21:40:19.928525 27290 slave.cpp:2473] Forwarding the update TASK_FINISHED (UUID: d03a0afe-75f0-4e7d-862c-2208362fe201) for task 0 of framework 20141124-214019-3176252227-56053-27268-0000 to [email protected]:56053 I1124 21:40:19.928710 27290 slave.cpp:2400] Status update manager successfully handled status update TASK_FINISHED (UUID: d03a0afe-75f0-4e7d-862c-2208362fe201) for task 0 of framework 20141124-214019-3176252227-56053-27268-0000 I1124 21:40:19.928750 27290 slave.cpp:2406] Sending acknowledgement for status update TASK_FINISHED (UUID: d03a0afe-75f0-4e7d-862c-2208362fe201) for task 0 of framework 20141124-214019-3176252227-56053-27268-0000 to executor(78)@67.195.81.189:56053 I1124 21:40:19.928804 27296 master.cpp:3409] Forwarding status update TASK_FINISHED (UUID: d03a0afe-75f0-4e7d-862c-2208362fe201) for task 0 of framework 20141124-214019-3176252227-56053-27268-0000 I1124 21:40:19.928920 27284 exec.cpp:339] Executor received status update acknowledgement d03a0afe-75f0-4e7d-862c-2208362fe201 for task 0 of framework 20141124-214019-3176252227-56053-27268-0000 I1124 21:40:19.962307 27286 hierarchical_allocator_process.hpp:659] Performed allocation for 2 slaves in 502295ns I1124 21:40:20.007750 27296 master.cpp:3381] Status update TASK_FINISHED (UUID: d03a0afe-75f0-4e7d-862c-2208362fe201) for task 0 of framework 20141124-214019-3176252227-56053-27268-0000 from slave 20141124-214019-3176252227-56053-27268-S0 at slave(228)@67.195.81.189:56053 (proserpina.apache.org) I1124 21:40:20.007803 27296 master.cpp:4666] Updating the latest state of task 0 of framework 20141124-214019-3176252227-56053-27268-0000 to TASK_FINISHED I1124 21:40:20.007838 27284 sched.cpp:693] Scheduler::statusUpdate took 16972ns I1124 21:40:20.008170 27296 master.cpp:4725] Removing task 0 with resources cpus(role1):1; mem(role1):100 of framework 20141124-214019-3176252227-56053-27268-0000 on slave 20141124-214019-3176252227-56053-27268-S0 at slave(228)@67.195.81.189:56053 (proserpina.apache.org) I1124 21:40:20.008213 27283 hierarchical_allocator_process.hpp:563] Recovered cpus(role1):1; mem(role1):100 (total allocatable: cpus(role1):1; mem(role1):100) on slave 20141124-214019-3176252227-56053-27268-S0 from framework 20141124-214019-3176252227-56053-27268-0000 I1124 21:40:20.008318 27296 master.cpp:2881] Forwarding status update acknowledgement d03a0afe-75f0-4e7d-862c-2208362fe201 for task 0 of framework 20141124-214019-3176252227-56053-27268-0000 (framework1) at [email protected]:56053 to slave 20141124-214019-3176252227-56053-27268-S0 at slave(228)@67.195.81.189:56053 (proserpina.apache.org) I1124 21:40:20.008590 27293 status_update_manager.cpp:389] Received status update acknowledgement (UUID: d03a0afe-75f0-4e7d-862c-2208362fe201) for task 0 of framework 20141124-214019-3176252227-56053-27268-0000 I1124 21:40:20.008671 27293 status_update_manager.cpp:525] Cleaning up status update stream for task 0 of framework 20141124-214019-3176252227-56053-27268-0000 I1124 21:40:20.008962 27293 slave.cpp:1815] Status update manager successfully handled status update acknowledgement (UUID: d03a0afe-75f0-4e7d-862c-2208362fe201) for task 0 of framework 20141124-214019-3176252227-56053-27268-0000 I1124 21:40:20.009011 27293 slave.cpp:4211] Completing task 0 I1124 21:40:20.058949 27289 hierarchical_allocator_process.hpp:734] Offering cpus(role1):1; mem(role1):100 on slave 20141124-214019-3176252227-56053-27268-S0 to framework 20141124-214019-3176252227-56053-27268-0000 I1124 21:40:20.059392 27289 hierarchical_allocator_process.hpp:659] Performed allocation for 2 slaves in 637120ns I1124 21:40:20.059638 27296 master.cpp:3816] Sending 1 offers to framework 20141124-214019-3176252227-56053-27268-0000 (framework1) at [email protected]:56053 I1124 21:40:20.060009 27285 sched.cpp:602] Scheduler::resourceOffers took 54131ns I1124 21:40:20.060585 27268 sched.cpp:1364] Asked to stop the driver I1124 21:40:20.060645 27268 sched.cpp:1364] Asked to stop the driver I1124 21:40:20.060750 27294 sched.cpp:805] Stopping framework '20141124-214019-3176252227-56053-27268-0001' I1124 21:40:20.060803 27291 sched.cpp:805] Stopping framework '20141124-214019-3176252227-56053-27268-0000' I1124 21:40:20.060927 27296 master.cpp:1707] Asked to unregister framework 20141124-214019-3176252227-56053-27268-0001 I1124 21:40:20.060957 27296 master.cpp:4244] Removing framework 20141124-214019-3176252227-56053-27268-0001 (framework2) at [email protected]:56053 I1124 21:40:20.061350 27289 slave.cpp:1548] Asked to shut down framework 20141124-214019-3176252227-56053-27268-0001 by [email protected]:56053 W1124 21:40:20.061383 27289 slave.cpp:1563] Cannot shut down unknown framework 20141124-214019-3176252227-56053-27268-0001 I1124 21:40:20.061383 27290 slave.cpp:1548] Asked to shut down framework 20141124-214019-3176252227-56053-27268-0001 by [email protected]:56053 I1124 21:40:20.061404 27283 hierarchical_allocator_process.hpp:405] Deactivated framework 20141124-214019-3176252227-56053-27268-0001 W1124 21:40:20.061413 27290 slave.cpp:1563] Cannot shut down unknown framework 20141124-214019-3176252227-56053-27268-0001 I1124 21:40:20.061594 27297 hierarchical_allocator_process.hpp:563] Recovered cpus(role2):2; mem(role2):600 (total allocatable: cpus(role2):2; mem(role2):600) on slave 20141124-214019-3176252227-56053-27268-S0 from framework 20141124-214019-3176252227-56053-27268-0001 I1124 21:40:20.061771 27296 master.cpp:1707] Asked to unregister framework 20141124-214019-3176252227-56053-27268-0000 I1124 21:40:20.061806 27296 master.cpp:4244] Removing framework 20141124-214019-3176252227-56053-27268-0000 (framework1) at [email protected]:56053 I1124 21:40:20.061810 27282 hierarchical_allocator_process.hpp:360] Removed framework 20141124-214019-3176252227-56053-27268-0001 I1124 21:40:20.062132 27286 hierarchical_allocator_process.hpp:405] Deactivated framework 20141124-214019-3176252227-56053-27268-0000 I1124 21:40:20.062183 27284 slave.cpp:1548] Asked to shut down framework 20141124-214019-3176252227-56053-27268-0000 by [email protected]:56053 W1124 21:40:20.062214 27284 slave.cpp:1563] Cannot shut down unknown framework 20141124-214019-3176252227-56053-27268-0000 I1124 21:40:20.062214 27285 slave.cpp:1548] Asked to shut down framework 20141124-214019-3176252227-56053-27268-0000 by [email protected]:56053 I1124 21:40:20.062250 27285 slave.cpp:1573] Shutting down framework 20141124-214019-3176252227-56053-27268-0000 I1124 21:40:20.062314 27285 slave.cpp:3161] Shutting down executor 'default' of framework 20141124-214019-3176252227-56053-27268-0000 I1124 21:40:20.062441 27295 exec.cpp:379] Executor asked to shutdown I1124 21:40:20.062451 27283 hierarchical_allocator_process.hpp:563] Recovered cpus(role1):1; mem(role1):100 (total allocatable: cpus(role2):2; mem(role2):600; cpus(role1):1; mem(role1):100) on slave 20141124-214019-3176252227-56053-27268-S0 from framework 20141124-214019-3176252227-56053-27268-0000 I1124 21:40:20.062537 27295 exec.cpp:394] Executor::shutdown took 67762ns I1124 21:40:20.062701 27296 master.cpp:4754] Removing executor 'default' with resources of framework 20141124-214019-3176252227-56053-27268-0000 on slave 20141124-214019-3176252227-56053-27268-S0 at slave(228)@67.195.81.189:56053 (proserpina.apache.org) I1124 21:40:20.062958 27292 hierarchical_allocator_process.hpp:563] Recovered cpus(*):1; mem(*):200; ports(*):[31000-32000]; mem(role1):100 (total allocatable: cpus(role2):2; mem(role2):600; cpus(role1):1; mem(role1):200; cpus(*):1; mem(*):200; ports(*):[31000-32000]) on slave 20141124-214019-3176252227-56053-27268-S0 from framework 20141124-214019-3176252227-56053-27268-0000 I1124 21:40:20.063398 27296 master.cpp:698] Master terminating I1124 21:40:20.063954 27295 hierarchical_allocator_process.hpp:360] Removed framework 20141124-214019-3176252227-56053-27268-0000 I1124 21:40:20.064136 27285 slave.cpp:2638] [email protected]:56053 exited W1124 21:40:20.064163 27285 slave.cpp:2641] Master disconnected! Waiting for a new master to be elected I1124 21:40:20.064268 27296 slave.cpp:2638] [email protected]:56053 exited W1124 21:40:20.064297 27296 slave.cpp:2641] Master disconnected! Waiting for a new master to be elected I1124 21:40:20.080767 27285 slave.cpp:2913] Executor 'default' of framework 20141124-214019-3176252227-56053-27268-0000 exited with status 0 I1124 21:40:20.081015 27285 slave.cpp:3022] Cleaning up executor 'default' of framework 20141124-214019-3176252227-56053-27268-0000 I1124 21:40:20.081333 27294 gc.cpp:56] Scheduling '/tmp/ReservationAllocatorTest_ResourcesReturned_N11T6s/slaves/20141124-214019-3176252227-56053-27268-S0/frameworks/20141124-214019-3176252227-56053-27268-0000/executors/default/runs/79b951a8-42dd-45a2-ae41-8658079f1ced' for gc 6.99999906061333days in the future I1124 21:40:20.081436 27285 slave.cpp:3099] Cleaning up framework 20141124-214019-3176252227-56053-27268-0000 I1124 21:40:20.083390 27289 status_update_manager.cpp:279] Closing status update streams for framework 20141124-214019-3176252227-56053-27268-0000 I1124 21:40:20.083430 27294 gc.cpp:56] Scheduling '/tmp/ReservationAllocatorTest_ResourcesReturned_N11T6s/slaves/20141124-214019-3176252227-56053-27268-S0/frameworks/20141124-214019-3176252227-56053-27268-0000/executors/default' for gc 6.99999905798815days in the future I1124 21:40:20.083457 27285 slave.cpp:487] Slave terminating I1124 21:40:20.083544 27294 gc.cpp:56] Scheduling '/tmp/ReservationAllocatorTest_ResourcesReturned_N11T6s/slaves/20141124-214019-3176252227-56053-27268-S0/frameworks/20141124-214019-3176252227-56053-27268-0000' for gc 6.99999903477037days in the future I1124 21:40:20.093085 27268 slave.cpp:487] Slave terminating [ OK ] ReservationAllocatorTest.ResourcesReturned (698 ms) [----------] 2 tests from ReservationAllocatorTest (1512 ms total) [----------] 7 tests from InMemoryStateTest [ RUN ] InMemoryStateTest.FetchAndStoreAndStoreAndFetch [ OK ] InMemoryStateTest.FetchAndStoreAndStoreAndFetch (10 ms) [ RUN ] InMemoryStateTest.Names [ OK ] InMemoryStateTest.Names (5 ms) [ RUN ] InMemoryStateTest.FetchAndStoreAndExpungeAndFetch [ OK ] InMemoryStateTest.FetchAndStoreAndExpungeAndFetch (9 ms) [ RUN ] InMemoryStateTest.FetchAndStoreAndFetch [ OK ] InMemoryStateTest.FetchAndStoreAndFetch (6 ms) [ RUN ] InMemoryStateTest.FetchAndStoreAndExpungeAndStoreAndFetch [ OK ] InMemoryStateTest.FetchAndStoreAndExpungeAndStoreAndFetch (9 ms) [ RUN ] InMemoryStateTest.FetchAndStoreAndExpungeAndExpunge [ OK ] InMemoryStateTest.FetchAndStoreAndExpungeAndExpunge (7 ms) [ RUN ] InMemoryStateTest.FetchAndStoreAndStoreFailAndFetch [ OK ] InMemoryStateTest.FetchAndStoreAndStoreFailAndFetch (9 ms) [----------] 7 tests from InMemoryStateTest (55 ms total) [----------] Global test environment tear-down [==========] 483 tests from 77 test cases ran. (488107 ms total) [ PASSED ] 482 tests. [ FAILED ] 1 test, listed below: [ FAILED ] ExamplesTest.LowLevelSchedulerLibprocess 1 FAILED TEST YOU HAVE 5 DISABLED TESTS make[3]: *** [check-local] Error 1 make[3]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/ws/src'> make[2]: *** [check-am] Error 2 make[2]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/ws/src'> make[1]: *** [check] Error 2 make[1]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/ws/src'> make: *** [check-recursive] Error 1 Test finished Exit status: 2 Build step 'Execute shell' marked build as failure Recording test results
