See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/2501/changes>
Changes: [vinodkone] Fixed indentation, formatting and typos in docs. ------------------------------------------ [...truncated 80103 lines...] I0227 18:47:21.724942 17512 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 417982ns I0227 18:47:21.724961 17512 replica.cpp:679] Persisted action at 0 I0227 18:47:21.724975 17512 replica.cpp:664] Replica learned 1 action at position 0 I0227 18:47:21.725405 17514 log.cpp:676] Writer started with ending position 0 I0227 18:47:21.726153 17523 leveldb.cpp:438] Reading position from leveldb took 19865ns I0227 18:47:21.728466 17521 registrar.cpp:346] Successfully fetched the registry (0B) in 177.533184ms I0227 18:47:21.728538 17521 registrar.cpp:445] Applied 1 operations in 15249ns; attempting to update the 'registry' I0227 18:47:21.730592 17508 log.cpp:684] Attempting to append 139 bytes to the log I0227 18:47:21.730727 17516 coordinator.cpp:340] Coordinator attempting to write 2 action at position 1 I0227 18:47:21.731240 17518 replica.cpp:511] Replica received write request for position 1 I0227 18:47:21.731953 17518 leveldb.cpp:343] Persisting action (158 bytes) to leveldb took 688685ns I0227 18:47:21.731972 17518 replica.cpp:679] Persisted action at 1 I0227 18:47:21.732486 17523 replica.cpp:658] Replica received learned notice for position 1 I0227 18:47:21.732995 17523 leveldb.cpp:343] Persisting action (160 bytes) to leveldb took 486074ns I0227 18:47:21.733014 17523 replica.cpp:679] Persisted action at 1 I0227 18:47:21.733028 17523 replica.cpp:664] Replica learned 2 action at position 1 I0227 18:47:21.733619 17507 registrar.cpp:490] Successfully updated the 'registry' in 5.039872ms I0227 18:47:21.733716 17507 registrar.cpp:376] Successfully recovered registrar I0227 18:47:21.733881 17523 log.cpp:703] Attempting to truncate the log to 1 I0227 18:47:21.733916 17513 master.cpp:1214] Recovered 0 slaves from the Registry (101B) ; allowing 10mins for slaves to re-register I0227 18:47:21.734025 17518 coordinator.cpp:340] Coordinator attempting to write 3 action at position 2 I0227 18:47:21.734644 17520 replica.cpp:511] Replica received write request for position 2 I0227 18:47:21.735215 17520 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 543558ns I0227 18:47:21.735234 17520 replica.cpp:679] Persisted action at 2 I0227 18:47:21.735736 17519 replica.cpp:658] Replica received learned notice for position 2 I0227 18:47:21.736301 17519 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took 543621ns I0227 18:47:21.736341 17519 leveldb.cpp:401] Deleting ~1 keys from leveldb took 21583ns I0227 18:47:21.736354 17519 replica.cpp:679] Persisted action at 2 I0227 18:47:21.736371 17519 replica.cpp:664] Replica learned 3 action at position 2 I0227 18:47:21.748608 17522 slave.cpp:175] Slave started on 230)@67.195.81.187:45629 I0227 18:47:21.748644 17522 credentials.hpp:85] Loading credential for authentication from '/tmp/MesosSchedulerDriverTest_DropAckIfStopCalledBeforeAbort_12sZ5u/credential' I0227 18:47:21.748771 17522 slave.cpp:282] Slave using credential for: test-principal I0227 18:47:21.748955 17522 slave.cpp:300] Slave resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I0227 18:47:21.749032 17522 slave.cpp:329] Slave hostname: pomona.apache.org I0227 18:47:21.749045 17522 slave.cpp:330] Slave checkpoint: false W0227 18:47:21.749053 17522 slave.cpp:332] Disabling checkpointing is deprecated and the --checkpoint flag will be removed in a future release. Please avoid using this flag I0227 18:47:21.749645 17512 state.cpp:35] Recovering state from '/tmp/MesosSchedulerDriverTest_DropAckIfStopCalledBeforeAbort_12sZ5u/meta' I0227 18:47:21.749881 17517 status_update_manager.cpp:197] Recovering status update manager I0227 18:47:21.750099 17515 slave.cpp:3794] Finished recovery I0227 18:47:21.750588 17518 status_update_manager.cpp:171] Pausing sending status updates I0227 18:47:21.750593 17507 slave.cpp:625] New master detected at [email protected]:45629 I0227 18:47:21.750663 17507 slave.cpp:688] Authenticating with master [email protected]:45629 I0227 18:47:21.750684 17507 slave.cpp:693] Using default CRAM-MD5 authenticatee I0227 18:47:21.750789 17507 slave.cpp:661] Detecting new master I0227 18:47:21.750818 17517 authenticatee.hpp:139] Creating new client SASL connection I0227 18:47:21.750953 17513 master.cpp:3813] Authenticating slave(230)@67.195.81.187:45629 I0227 18:47:21.750975 17513 master.cpp:3824] Using default CRAM-MD5 authenticator I0227 18:47:21.751128 17521 authenticator.hpp:170] Creating new server SASL connection I0227 18:47:21.751235 17520 authenticatee.hpp:230] Received SASL authentication mechanisms: CRAM-MD5 I0227 18:47:21.751258 17520 authenticatee.hpp:256] Attempting to authenticate with mechanism 'CRAM-MD5' I0227 18:47:21.751404 17517 authenticator.hpp:276] Received SASL authentication start I0227 18:47:21.751446 17517 authenticator.hpp:398] Authentication requires more steps I0227 18:47:21.751524 17523 authenticatee.hpp:276] Received SASL authentication step I0227 18:47:21.751613 17523 authenticator.hpp:304] Received SASL authentication step I0227 18:47:21.751641 17523 auxprop.cpp:99] Request to lookup properties for user: 'test-principal' realm: 'pomona.apache.org' server FQDN: 'pomona.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I0227 18:47:21.751653 17523 auxprop.cpp:171] Looking up auxiliary property '*userPassword' I0227 18:47:21.751682 17523 auxprop.cpp:171] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I0227 18:47:21.751699 17523 auxprop.cpp:99] Request to lookup properties for user: 'test-principal' realm: 'pomona.apache.org' server FQDN: 'pomona.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I0227 18:47:21.751708 17523 auxprop.cpp:121] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I0227 18:47:21.751713 17523 auxprop.cpp:121] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I0227 18:47:21.751724 17523 authenticator.hpp:390] Authentication success I0227 18:47:21.751783 17518 authenticatee.hpp:316] Authentication success I0227 18:47:21.751804 17521 master.cpp:3871] Successfully authenticated principal 'test-principal' at slave(230)@67.195.81.187:45629 I0227 18:47:21.752004 17512 slave.cpp:759] Successfully authenticated with master [email protected]:45629 I0227 18:47:21.752094 17512 slave.cpp:1091] Will retry registration in 12.813137ms if necessary I0227 18:47:21.752226 17514 master.cpp:2938] Registering slave at slave(230)@67.195.81.187:45629 (pomona.apache.org) with id 20150227-184721-3142697795-45629-17472-S0 I0227 18:47:21.753473 17472 sched.cpp:157] Version: 0.23.0 I0227 18:47:21.765574 17519 slave.cpp:1091] Will retry registration in 22.067486ms if necessary I0227 18:47:21.811712 17524 process.cpp:2117] Dropped / Lost event for PID: [email protected]:45629 I0227 18:47:21.915875 17514 master.cpp:2926] Ignoring register slave message from slave(230)@67.195.81.187:45629 (pomona.apache.org) as admission is already in progress I0227 18:47:21.915910 17519 registrar.cpp:445] Applied 1 operations in 80979ns; attempting to update the 'registry' I0227 18:47:21.916028 17516 sched.cpp:254] New master detected at [email protected]:45629 I0227 18:47:21.916097 17516 sched.cpp:310] Authenticating with master [email protected]:45629 I0227 18:47:21.916115 17516 sched.cpp:317] Using default CRAM-MD5 authenticatee I0227 18:47:21.916337 17513 authenticatee.hpp:139] Creating new client SASL connection I0227 18:47:21.916497 17515 master.cpp:3813] Authenticating [email protected]:45629 I0227 18:47:21.916520 17515 master.cpp:3824] Using default CRAM-MD5 authenticator I0227 18:47:21.916681 17516 authenticator.hpp:170] Creating new server SASL connection I0227 18:47:21.916822 17511 authenticatee.hpp:230] Received SASL authentication mechanisms: CRAM-MD5 I0227 18:47:21.916846 17511 authenticatee.hpp:256] Attempting to authenticate with mechanism 'CRAM-MD5' I0227 18:47:21.916913 17511 authenticator.hpp:276] Received SASL authentication start I0227 18:47:21.916959 17511 authenticator.hpp:398] Authentication requires more steps I0227 18:47:21.917050 17521 authenticatee.hpp:276] Received SASL authentication step I0227 18:47:21.917131 17521 authenticator.hpp:304] Received SASL authentication step I0227 18:47:21.917156 17521 auxprop.cpp:99] Request to lookup properties for user: 'test-principal' realm: 'pomona.apache.org' server FQDN: 'pomona.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I0227 18:47:21.917171 17521 auxprop.cpp:171] Looking up auxiliary property '*userPassword' I0227 18:47:21.917217 17521 auxprop.cpp:171] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I0227 18:47:21.917237 17521 auxprop.cpp:99] Request to lookup properties for user: 'test-principal' realm: 'pomona.apache.org' server FQDN: 'pomona.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I0227 18:47:21.917245 17521 auxprop.cpp:121] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I0227 18:47:21.917250 17521 auxprop.cpp:121] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I0227 18:47:21.917263 17521 authenticator.hpp:390] Authentication success I0227 18:47:21.917363 17508 authenticatee.hpp:316] Authentication success I0227 18:47:21.917387 17522 master.cpp:3871] Successfully authenticated principal 'test-principal' at [email protected]:45629 I0227 18:47:21.917563 17521 sched.cpp:398] Successfully authenticated with master [email protected]:45629 I0227 18:47:21.917582 17521 sched.cpp:521] Sending registration request to [email protected]:45629 I0227 18:47:21.917637 17521 sched.cpp:554] Will retry registration in 1.364014258secs if necessary I0227 18:47:21.917708 17511 master.cpp:1574] Received registration request for framework 'default' at [email protected]:45629 I0227 18:47:21.917776 17511 master.cpp:1435] Authorizing framework principal 'test-principal' to receive offers for role '*' I0227 18:47:21.918122 17511 master.cpp:1638] Registering framework 20150227-184721-3142697795-45629-17472-0000 (default) at [email protected]:45629 I0227 18:47:21.918304 17513 log.cpp:684] Attempting to append 316 bytes to the log I0227 18:47:21.918426 17514 hierarchical.hpp:321] Added framework 20150227-184721-3142697795-45629-17472-0000 I0227 18:47:21.918453 17514 hierarchical.hpp:834] No resources available to allocate! I0227 18:47:21.918467 17514 hierarchical.hpp:741] Performed allocation for 0 slaves in 21881ns I0227 18:47:21.918473 17517 coordinator.cpp:340] Coordinator attempting to write 2 action at position 3 I0227 18:47:21.918525 17512 sched.cpp:448] Framework registered with 20150227-184721-3142697795-45629-17472-0000 I0227 18:47:21.918567 17512 sched.cpp:462] Scheduler::registered took 18811ns I0227 18:47:21.919080 17521 replica.cpp:511] Replica received write request for position 3 I0227 18:47:21.919956 17521 leveldb.cpp:343] Persisting action (335 bytes) to leveldb took 850342ns I0227 18:47:21.919977 17521 replica.cpp:679] Persisted action at 3 I0227 18:47:21.920485 17509 replica.cpp:658] Replica received learned notice for position 3 I0227 18:47:21.921097 17509 leveldb.cpp:343] Persisting action (337 bytes) to leveldb took 589872ns I0227 18:47:21.921118 17509 replica.cpp:679] Persisted action at 3 I0227 18:47:21.921133 17509 replica.cpp:664] Replica learned 2 action at position 3 I0227 18:47:21.921946 17520 registrar.cpp:490] Successfully updated the 'registry' in 5.970944ms I0227 18:47:21.922135 17515 log.cpp:703] Attempting to truncate the log to 3 I0227 18:47:21.922245 17519 coordinator.cpp:340] Coordinator attempting to write 3 action at position 4 I0227 18:47:21.922559 17517 slave.cpp:2831] Received ping from slave-observer(211)@67.195.81.187:45629 I0227 18:47:21.922631 17514 master.cpp:2995] Registered slave 20150227-184721-3142697795-45629-17472-S0 at slave(230)@67.195.81.187:45629 (pomona.apache.org) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I0227 18:47:21.922705 17517 slave.cpp:793] Registered with master [email protected]:45629; given slave ID 20150227-184721-3142697795-45629-17472-S0 I0227 18:47:21.922737 17509 hierarchical.hpp:455] Added slave 20150227-184721-3142697795-45629-17472-S0 (pomona.apache.org) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (and cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] available) I0227 18:47:21.922860 17520 replica.cpp:511] Replica received write request for position 4 I0227 18:47:21.922873 17511 status_update_manager.cpp:178] Resuming sending status updates I0227 18:47:21.923143 17509 hierarchical.hpp:759] Performed allocation for slave 20150227-184721-3142697795-45629-17472-S0 in 361180ns I0227 18:47:21.923403 17518 master.cpp:3755] Sending 1 offers to framework 20150227-184721-3142697795-45629-17472-0000 (default) at [email protected]:45629 I0227 18:47:21.923471 17520 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 585028ns I0227 18:47:21.923492 17520 replica.cpp:679] Persisted action at 4 I0227 18:47:21.924013 17519 replica.cpp:658] Replica received learned notice for position 4 I0227 18:47:21.924325 17514 sched.cpp:611] Scheduler::resourceOffers took 615167ns I0227 18:47:21.924623 17519 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took 589409ns I0227 18:47:21.924665 17519 leveldb.cpp:401] Deleting ~2 keys from leveldb took 25031ns I0227 18:47:21.924679 17519 replica.cpp:679] Persisted action at 4 I0227 18:47:21.924696 17519 replica.cpp:664] Replica learned 3 action at position 4 I0227 18:47:21.925083 17515 master.cpp:2268] Processing ACCEPT call for offers: [ 20150227-184721-3142697795-45629-17472-O0 ] on slave 20150227-184721-3142697795-45629-17472-S0 at slave(230)@67.195.81.187:45629 (pomona.apache.org) for framework 20150227-184721-3142697795-45629-17472-0000 (default) at [email protected]:45629 I0227 18:47:21.925122 17515 master.cpp:2112] Authorizing framework principal 'test-principal' to launch task 0 as user 'jenkins' W0227 18:47:21.926203 17516 validation.cpp:326] 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. I0227 18:47:22.056061 17524 process.cpp:2117] Dropped / Lost event for PID: [email protected]:45629 W0227 18:47:22.188205 17516 validation.cpp:338] 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. I0227 18:47:22.188673 17516 master.hpp:810] Adding task 0 with resources cpus(*):1; mem(*):16 on slave 20150227-184721-3142697795-45629-17472-S0 (pomona.apache.org) I0227 18:47:22.188750 17516 master.cpp:2545] Launching task 0 of framework 20150227-184721-3142697795-45629-17472-0000 (default) at [email protected]:45629 with resources cpus(*):1; mem(*):16 on slave 20150227-184721-3142697795-45629-17472-S0 at slave(230)@67.195.81.187:45629 (pomona.apache.org) I0227 18:47:22.189148 17508 slave.cpp:1122] Got assigned task 0 for framework 20150227-184721-3142697795-45629-17472-0000 I0227 18:47:22.189242 17523 hierarchical.hpp:648] Recovered cpus(*):1; mem(*):1008; disk(*):1024; ports(*):[31000-32000] (total allocatable: cpus(*):1; mem(*):1008; disk(*):1024; ports(*):[31000-32000]) on slave 20150227-184721-3142697795-45629-17472-S0 from framework 20150227-184721-3142697795-45629-17472-0000 I0227 18:47:22.189306 17523 hierarchical.hpp:684] Framework 20150227-184721-3142697795-45629-17472-0000 filtered slave 20150227-184721-3142697795-45629-17472-S0 for 5secs I0227 18:47:22.189502 17524 process.cpp:2117] Dropped / Lost event for PID: hierarchical-allocator(257)@67.195.81.187:45629 I0227 18:47:22.189679 17508 slave.cpp:1232] Launching task 0 for framework 20150227-184721-3142697795-45629-17472-0000 I0227 18:47:22.191745 17508 slave.cpp:4196] Launching executor default of framework 20150227-184721-3142697795-45629-17472-0000 in work directory '/tmp/MesosSchedulerDriverTest_DropAckIfStopCalledBeforeAbort_12sZ5u/slaves/20150227-184721-3142697795-45629-17472-S0/frameworks/20150227-184721-3142697795-45629-17472-0000/executors/default/runs/559b93e3-77a3-4ab9-a795-6f87ed1a0071' I0227 18:47:22.193876 17508 exec.cpp:132] Version: 0.23.0 I0227 18:47:22.194188 17512 exec.cpp:182] Executor started at: executor(81)@67.195.81.187:45629 with pid 17472 I0227 18:47:22.194277 17508 slave.cpp:1380] Queuing task '0' for executor default of framework '20150227-184721-3142697795-45629-17472-0000 I0227 18:47:22.194411 17508 slave.cpp:578] Successfully attached file '/tmp/MesosSchedulerDriverTest_DropAckIfStopCalledBeforeAbort_12sZ5u/slaves/20150227-184721-3142697795-45629-17472-S0/frameworks/20150227-184721-3142697795-45629-17472-0000/executors/default/runs/559b93e3-77a3-4ab9-a795-6f87ed1a0071' I0227 18:47:22.194517 17508 slave.cpp:3133] Monitoring executor 'default' of framework '20150227-184721-3142697795-45629-17472-0000' in container '559b93e3-77a3-4ab9-a795-6f87ed1a0071' I0227 18:47:22.194669 17508 slave.cpp:2141] Got registration for executor 'default' of framework 20150227-184721-3142697795-45629-17472-0000 from executor(81)@67.195.81.187:45629 I0227 18:47:22.194939 17521 exec.cpp:206] Executor registered on slave 20150227-184721-3142697795-45629-17472-S0 I0227 18:47:22.195204 17508 slave.cpp:1534] Sending queued task '0' to executor 'default' of framework 20150227-184721-3142697795-45629-17472-0000 I0227 18:47:22.196522 17521 exec.cpp:218] Executor::registered took 23591ns I0227 18:47:22.196651 17521 exec.cpp:293] Executor asked to run task '0' I0227 18:47:22.196715 17521 exec.cpp:302] Executor::launchTask took 48547ns I0227 18:47:22.198238 17521 exec.cpp:525] Executor sending status update TASK_RUNNING (UUID: ea8c03a9-e86e-4b5d-bb0d-73b77ea933ed) for task 0 of framework 20150227-184721-3142697795-45629-17472-0000 I0227 18:47:22.198446 17521 slave.cpp:2508] Handling status update TASK_RUNNING (UUID: ea8c03a9-e86e-4b5d-bb0d-73b77ea933ed) for task 0 of framework 20150227-184721-3142697795-45629-17472-0000 from executor(81)@67.195.81.187:45629 I0227 18:47:22.198678 17521 status_update_manager.cpp:317] Received status update TASK_RUNNING (UUID: ea8c03a9-e86e-4b5d-bb0d-73b77ea933ed) for task 0 of framework 20150227-184721-3142697795-45629-17472-0000 I0227 18:47:22.198714 17521 status_update_manager.cpp:494] Creating StatusUpdate stream for task 0 of framework 20150227-184721-3142697795-45629-17472-0000 I0227 18:47:22.198928 17521 status_update_manager.cpp:371] Forwarding update TASK_RUNNING (UUID: ea8c03a9-e86e-4b5d-bb0d-73b77ea933ed) for task 0 of framework 20150227-184721-3142697795-45629-17472-0000 to the slave I0227 18:47:22.199126 17509 slave.cpp:2751] Forwarding the update TASK_RUNNING (UUID: ea8c03a9-e86e-4b5d-bb0d-73b77ea933ed) for task 0 of framework 20150227-184721-3142697795-45629-17472-0000 to [email protected]:45629 I0227 18:47:22.199267 17509 slave.cpp:2678] Status update manager successfully handled status update TASK_RUNNING (UUID: ea8c03a9-e86e-4b5d-bb0d-73b77ea933ed) for task 0 of framework 20150227-184721-3142697795-45629-17472-0000 I0227 18:47:22.199319 17509 slave.cpp:2684] Sending acknowledgement for status update TASK_RUNNING (UUID: ea8c03a9-e86e-4b5d-bb0d-73b77ea933ed) for task 0 of framework 20150227-184721-3142697795-45629-17472-0000 to executor(81)@67.195.81.187:45629 I0227 18:47:22.199411 17513 master.cpp:3295] Status update TASK_RUNNING (UUID: ea8c03a9-e86e-4b5d-bb0d-73b77ea933ed) for task 0 of framework 20150227-184721-3142697795-45629-17472-0000 from slave 20150227-184721-3142697795-45629-17472-S0 at slave(230)@67.195.81.187:45629 (pomona.apache.org) I0227 18:47:22.199443 17513 master.cpp:3336] Forwarding status update TASK_RUNNING (UUID: ea8c03a9-e86e-4b5d-bb0d-73b77ea933ed) for task 0 of framework 20150227-184721-3142697795-45629-17472-0000 I0227 18:47:22.199455 17507 exec.cpp:339] Executor received status update acknowledgement ea8c03a9-e86e-4b5d-bb0d-73b77ea933ed for task 0 of framework 20150227-184721-3142697795-45629-17472-0000 I0227 18:47:22.199538 17513 master.cpp:4615] Updating the latest state of task 0 of framework 20150227-184721-3142697795-45629-17472-0000 to TASK_RUNNING I0227 18:47:22.199606 17507 sched.cpp:1589] Asked to stop the driver I0227 18:47:22.199645 17507 sched.cpp:1623] Asked to abort the driver I0227 18:47:22.199668 17507 sched.cpp:1626] Ignoring abort because the status of the driver is DRIVER_STOPPED I0227 18:47:22.199704 17507 sched.cpp:717] Scheduler::statusUpdate took 116009ns I0227 18:47:22.199720 17507 sched.cpp:724] Not sending status update acknowledgment message because the driver is not running! I0227 18:47:22.199753 17507 sched.cpp:831] Stopping framework '20150227-184721-3142697795-45629-17472-0000' I0227 18:47:22.199985 17520 master.cpp:1898] Asked to unregister framework 20150227-184721-3142697795-45629-17472-0000 I0227 18:47:22.200008 17520 master.cpp:4183] Removing framework 20150227-184721-3142697795-45629-17472-0000 (default) at [email protected]:45629 I0227 18:47:22.200105 17512 hierarchical.hpp:400] Deactivated framework 20150227-184721-3142697795-45629-17472-0000 I0227 18:47:22.200137 17509 slave.cpp:1747] Asked to shut down framework 20150227-184721-3142697795-45629-17472-0000 by [email protected]:45629 I0227 18:47:22.200166 17509 slave.cpp:1772] Shutting down framework 20150227-184721-3142697795-45629-17472-0000 I0227 18:47:22.200237 17509 slave.cpp:3441] Shutting down executor 'default' of framework 20150227-184721-3142697795-45629-17472-0000 I0227 18:47:22.200381 17511 exec.cpp:379] Executor asked to shutdown I0227 18:47:22.200415 17511 exec.cpp:394] Executor::shutdown took 17522ns I0227 18:47:22.201725 17520 master.cpp:4615] Updating the latest state of task 0 of framework 20150227-184721-3142697795-45629-17472-0000 to TASK_KILLED I0227 18:47:22.201901 17514 hierarchical.hpp:648] Recovered cpus(*):1; mem(*):16 (total allocatable: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]) on slave 20150227-184721-3142697795-45629-17472-S0 from framework 20150227-184721-3142697795-45629-17472-0000 I0227 18:47:22.202036 17520 master.cpp:4682] Removing task 0 with resources cpus(*):1; mem(*):16 of framework 20150227-184721-3142697795-45629-17472-0000 on slave 20150227-184721-3142697795-45629-17472-S0 at slave(230)@67.195.81.187:45629 (pomona.apache.org) I0227 18:47:22.521858 17520 master.cpp:4711] Removing executor 'default' with resources of framework 20150227-184721-3142697795-45629-17472-0000 on slave 20150227-184721-3142697795-45629-17472-S0 at slave(230)@67.195.81.187:45629 (pomona.apache.org) I0227 18:47:22.522508 17507 hierarchical.hpp:354] Removed framework 20150227-184721-3142697795-45629-17472-0000 I0227 18:47:22.523602 17472 sched.cpp:1589] Asked to stop the driver I0227 18:47:22.523636 17472 sched.cpp:1592] Ignoring stop because the status of the driver is DRIVER_STOPPED I0227 18:47:22.523705 17513 master.cpp:787] Master terminating I0227 18:47:22.524209 17523 slave.cpp:2916] [email protected]:45629 exited W0227 18:47:22.524231 17523 slave.cpp:2919] Master disconnected! Waiting for a new master to be elected I0227 18:47:22.525631 17472 process.cpp:2117] Dropped / Lost event for PID: [email protected]:45629 I0227 18:47:22.525727 17472 process.cpp:2117] Dropped / Lost event for PID: [email protected]:45629 I0227 18:47:22.537545 17472 process.cpp:2117] Dropped / Lost event for PID: executor(81)@67.195.81.187:45629 I0227 18:47:22.537667 17507 slave.cpp:3191] Executor 'default' of framework 20150227-184721-3142697795-45629-17472-0000 exited with status 0 I0227 18:47:22.537835 17507 process.cpp:2117] Dropped / Lost event for PID: [email protected]:45629 I0227 18:47:22.537858 17507 slave.cpp:3300] Cleaning up executor 'default' of framework 20150227-184721-3142697795-45629-17472-0000 I0227 18:47:22.538085 17523 gc.cpp:56] Scheduling '/tmp/MesosSchedulerDriverTest_DropAckIfStopCalledBeforeAbort_12sZ5u/slaves/20150227-184721-3142697795-45629-17472-S0/frameworks/20150227-184721-3142697795-45629-17472-0000/executors/default/runs/559b93e3-77a3-4ab9-a795-6f87ed1a0071' for gc 6.99999768588148days in the future I0227 18:47:22.538143 17507 slave.cpp:3379] Cleaning up framework 20150227-184721-3142697795-45629-17472-0000 I0227 18:47:22.538244 17508 status_update_manager.cpp:279] Closing status update streams for framework 20150227-184721-3142697795-45629-17472-0000 I0227 18:47:22.538295 17523 gc.cpp:56] Scheduling '/tmp/MesosSchedulerDriverTest_DropAckIfStopCalledBeforeAbort_12sZ5u/slaves/20150227-184721-3142697795-45629-17472-S0/frameworks/20150227-184721-3142697795-45629-17472-0000/executors/default' for gc 6.99999768588148days in the future I0227 18:47:22.538342 17508 status_update_manager.cpp:525] Cleaning up status update stream for task 0 of framework 20150227-184721-3142697795-45629-17472-0000 I0227 18:47:22.538385 17523 gc.cpp:56] Scheduling '/tmp/MesosSchedulerDriverTest_DropAckIfStopCalledBeforeAbort_12sZ5u/slaves/20150227-184721-3142697795-45629-17472-S0/frameworks/20150227-184721-3142697795-45629-17472-0000' for gc 6.99999768588148days in the future I0227 18:47:22.558053 17516 slave.cpp:507] Slave terminating I0227 18:47:22.559224 17472 process.cpp:2117] Dropped / Lost event for PID: slave(230)@67.195.81.187:45629 I0227 18:47:22.559852 17472 process.cpp:2117] Dropped / Lost event for PID: [email protected]:45629 [ OK ] MesosSchedulerDriverTest.DropAckIfStopCalledBeforeAbort (1019 ms) [----------] 5 tests from MesosSchedulerDriverTest (2529 ms total) [----------] 4 tests from SorterTest [ RUN ] SorterTest.DRFSorter I0227 18:47:22.561748 17524 process.cpp:2117] Dropped / Lost event for PID: hierarchical-allocator(258)@67.195.81.187:45629 I0227 18:47:22.561779 17524 process.cpp:2117] Dropped / Lost event for PID: [email protected]:45629 I0227 18:47:22.561805 17524 process.cpp:2117] Dropped / Lost event for PID: hierarchical-allocator(259)@67.195.81.187:45629 [ OK ] SorterTest.DRFSorter (1 ms) [ RUN ] SorterTest.WDRFSorter [ OK ] SorterTest.WDRFSorter (1 ms) [ RUN ] SorterTest.SplitResourceShares [ OK ] SorterTest.SplitResourceShares (1 ms) [ RUN ] SorterTest.Update [ OK ] SorterTest.Update (0 ms) [----------] 4 tests from SorterTest (3 ms total) [----------] 1 test from ResourcesOperationTest [ RUN ] ResourcesOperationTest.CreatePersistentVolume [ OK ] ResourcesOperationTest.CreatePersistentVolume (0 ms) [----------] 1 test from ResourcesOperationTest (0 ms total) [----------] Global test environment tear-down [==========] 540 tests from 85 test cases ran. (276319 ms total) [ PASSED ] 539 tests. [ FAILED ] 1 test, listed below: [ FAILED ] MasterAllocatorTest/0.SlaveReregistersFirst, where TypeParam = mesos::internal::master::allocator::MesosAllocator<mesos::internal::master::allocator::HierarchicalAllocatorProcess<mesos::internal::master::allocator::DRFSorter, mesos::internal::master::allocator::DRFSorter> > 1 FAILED TEST YOU HAVE 9 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
