See <https://builds.apache.org/job/Mesos-Ubuntu-distcheck/55/changes>
Changes: [vinod] Refactored reading credentials out of master.cpp. [vinod] Added a message string to the slave ShutdownMessage. [vinod] Updated mesos-style.py with already-passing rules. ------------------------------------------ [...truncated 42478 lines...] I0425 18:30:07.709398 23965 replica.cpp:664] Persisted action at 0 I0425 18:30:07.709408 23965 replica.cpp:649] Replica learned NOP action at position 0 I0425 18:30:07.709750 23961 log.cpp:672] Writer started with ending position 0 I0425 18:30:07.710237 23968 leveldb.cpp:436] Reading position from leveldb took 10650ns I0425 18:30:07.711864 23965 registrar.cpp:308] Successfully recovered registrar I0425 18:30:07.711900 23965 registrar.cpp:379] Attempting to update the 'registry' I0425 18:30:07.713429 23964 log.cpp:680] Attempting to append 137 bytes to the log I0425 18:30:07.713505 23968 coordinator.cpp:339] Coordinator attempting to write APPEND action at position 1 I0425 18:30:07.713829 23968 replica.cpp:508] Replica received write request for position 1 I0425 18:30:07.740947 23968 leveldb.cpp:341] Persisting action (156 bytes) to leveldb took 27.084869ms I0425 18:30:07.740972 23968 replica.cpp:664] Persisted action at 1 I0425 18:30:07.741286 23962 replica.cpp:643] Replica received learned notice for position 1 I0425 18:30:07.747712 23968 hierarchical_allocator_process.hpp:726] No resources available to allocate! I0425 18:30:07.747728 23968 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 22490ns I0425 18:30:07.759327 23962 leveldb.cpp:341] Persisting action (158 bytes) to leveldb took 18.018786ms I0425 18:30:07.759351 23962 replica.cpp:664] Persisted action at 1 I0425 18:30:07.759361 23962 replica.cpp:649] Replica learned APPEND action at position 1 I0425 18:30:07.759667 23964 registrar.cpp:427] Successfully updated 'registry' I0425 18:30:07.759799 23964 master.cpp:776] Recovered 0 slaves from the Registry (99B) ; allowing 10mins for slaves to re-register I0425 18:30:07.759881 23963 log.cpp:699] Attempting to truncate the log to 1 I0425 18:30:07.759930 23963 coordinator.cpp:339] Coordinator attempting to write TRUNCATE action at position 2 I0425 18:30:07.760151 23963 replica.cpp:508] Replica received write request for position 2 I0425 18:30:07.762591 23966 slave.cpp:130] Slave started on 145)@67.195.138.61:35984 I0425 18:30:07.762713 23966 slave.cpp:218] Slave resources: cpus(*):3; mem(*):1024; disk(*):23038; ports(*):[31000-32000] I0425 18:30:07.762780 23966 slave.cpp:246] Slave hostname: juno.apache.org I0425 18:30:07.762787 23966 slave.cpp:247] Slave checkpoint: false I0425 18:30:07.763244 23967 state.cpp:33] Recovering state from '/tmp/AllocatorTest_0_FrameworkExited_xJb7ko/meta' I0425 18:30:07.763381 23968 status_update_manager.cpp:193] Recovering status update manager I0425 18:30:07.763536 23966 slave.cpp:2804] Finished recovery I0425 18:30:07.763763 23961 slave.cpp:499] New master detected at [email protected]:35984 I0425 18:30:07.763806 23961 slave.cpp:733] Will retry registration in 8.864270254secs if necessary I0425 18:30:07.763824 23961 slave.cpp:524] Detecting new master I0425 18:30:07.763854 23961 status_update_manager.cpp:167] New master detected at [email protected]:35984 I0425 18:30:07.764006 23961 registrar.cpp:379] Attempting to update the 'registry' I0425 18:30:07.765175 23940 sched.cpp:121] Version: 0.19.0 I0425 18:30:07.765466 23965 sched.cpp:217] New master detected at [email protected]:35984 I0425 18:30:07.765480 23965 sched.cpp:268] Authenticating with master [email protected]:35984 I0425 18:30:07.765555 23964 authenticatee.hpp:128] Creating new client SASL connection I0425 18:30:07.765661 23968 master.cpp:2724] Authenticating framework at scheduler(135)@67.195.138.61:35984 I0425 18:30:07.765722 23964 authenticator.hpp:148] Creating new server SASL connection I0425 18:30:07.765830 23967 authenticatee.hpp:219] Received SASL authentication mechanisms: CRAM-MD5 I0425 18:30:07.765848 23967 authenticatee.hpp:245] Attempting to authenticate with mechanism 'CRAM-MD5' I0425 18:30:07.765882 23967 authenticator.hpp:254] Received SASL authentication start I0425 18:30:07.765916 23967 authenticator.hpp:342] Authentication requires more steps I0425 18:30:07.765945 23967 authenticatee.hpp:265] Received SASL authentication step I0425 18:30:07.765992 23967 authenticator.hpp:282] Received SASL authentication step I0425 18:30:07.766013 23967 auxprop.cpp:81] Request to lookup properties for user: 'test-principal' realm: 'juno.apache.org' server FQDN: 'juno.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I0425 18:30:07.766021 23967 auxprop.cpp:153] Looking up auxiliary property '*userPassword' I0425 18:30:07.766029 23967 auxprop.cpp:153] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I0425 18:30:07.766037 23967 auxprop.cpp:81] Request to lookup properties for user: 'test-principal' realm: 'juno.apache.org' server FQDN: 'juno.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I0425 18:30:07.766043 23967 auxprop.cpp:103] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I0425 18:30:07.766047 23967 auxprop.cpp:103] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I0425 18:30:07.766057 23967 authenticator.hpp:334] Authentication success I0425 18:30:07.766109 23962 authenticatee.hpp:305] Authentication success I0425 18:30:07.766111 23967 master.cpp:2764] Successfully authenticated framework at scheduler(135)@67.195.138.61:35984 I0425 18:30:07.766178 23962 sched.cpp:342] Successfully authenticated with master [email protected]:35984 I0425 18:30:07.766191 23962 sched.cpp:461] Sending registration request to [email protected]:35984 I0425 18:30:07.766290 23967 master.cpp:977] Received registration request from scheduler(135)@67.195.138.61:35984 I0425 18:30:07.766317 23967 master.cpp:995] Registering framework 20140425-183007-1032504131-35984-23940-0000 at scheduler(135)@67.195.138.61:35984 I0425 18:30:07.766381 23968 sched.cpp:392] Framework registered with 20140425-183007-1032504131-35984-23940-0000 I0425 18:30:07.766402 23968 sched.cpp:406] Scheduler::registered took 10284ns I0425 18:30:07.766499 23961 hierarchical_allocator_process.hpp:332] Added framework 20140425-183007-1032504131-35984-23940-0000 I0425 18:30:07.766513 23961 hierarchical_allocator_process.hpp:726] No resources available to allocate! I0425 18:30:07.766520 23961 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 6227ns I0425 18:30:07.790921 23963 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 30.754295ms I0425 18:30:07.790946 23963 replica.cpp:664] Persisted action at 2 I0425 18:30:07.791201 23968 replica.cpp:643] Replica received learned notice for position 2 I0425 18:30:07.797916 23961 hierarchical_allocator_process.hpp:726] No resources available to allocate! I0425 18:30:07.797930 23961 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 22145ns I0425 18:30:07.817641 23968 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 26.418183ms I0425 18:30:07.817682 23968 leveldb.cpp:399] Deleting ~1 keys from leveldb took 18101ns I0425 18:30:07.817693 23968 replica.cpp:664] Persisted action at 2 I0425 18:30:07.817703 23968 replica.cpp:649] Replica learned TRUNCATE action at position 2 I0425 18:30:07.818037 23961 log.cpp:680] Attempting to append 328 bytes to the log I0425 18:30:07.818116 23968 coordinator.cpp:339] Coordinator attempting to write APPEND action at position 3 I0425 18:30:07.818390 23966 replica.cpp:508] Replica received write request for position 3 I0425 18:30:07.848122 23965 hierarchical_allocator_process.hpp:726] No resources available to allocate! I0425 18:30:07.848137 23965 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 26189ns I0425 18:30:07.850954 23966 leveldb.cpp:341] Persisting action (347 bytes) to leveldb took 32.541873ms I0425 18:30:07.850980 23966 replica.cpp:664] Persisted action at 3 I0425 18:30:07.851235 23966 replica.cpp:643] Replica received learned notice for position 3 I0425 18:30:07.874212 23966 leveldb.cpp:341] Persisting action (349 bytes) to leveldb took 22.961546ms I0425 18:30:07.874232 23966 replica.cpp:664] Persisted action at 3 I0425 18:30:07.874239 23966 replica.cpp:649] Replica learned APPEND action at position 3 I0425 18:30:07.874569 23968 registrar.cpp:427] Successfully updated 'registry' I0425 18:30:07.874678 23966 log.cpp:699] Attempting to truncate the log to 3 I0425 18:30:07.874709 23962 master.cpp:2139] Admitted slave on juno.apache.org at slave(145)@67.195.138.61:35984 I0425 18:30:07.874749 23967 coordinator.cpp:339] Coordinator attempting to write TRUNCATE action at position 4 I0425 18:30:07.874738 23962 master.cpp:3213] Adding slave 20140425-183007-1032504131-35984-23940-0 at juno.apache.org with cpus(*):3; mem(*):1024; disk(*):23038; ports(*):[31000-32000] I0425 18:30:07.874824 23966 slave.cpp:542] Registered with master [email protected]:35984; given slave ID 20140425-183007-1032504131-35984-23940-0 I0425 18:30:07.875084 23966 hierarchical_allocator_process.hpp:445] Added slave 20140425-183007-1032504131-35984-23940-0 (juno.apache.org) with cpus(*):3; mem(*):1024; disk(*):23038; ports(*):[31000-32000] (and cpus(*):3; mem(*):1024; disk(*):23038; ports(*):[31000-32000] available) I0425 18:30:07.875111 23962 replica.cpp:508] Replica received write request for position 4 I0425 18:30:07.875152 23966 hierarchical_allocator_process.hpp:752] Offering cpus(*):3; mem(*):1024; disk(*):23038; ports(*):[31000-32000] on slave 20140425-183007-1032504131-35984-23940-0 to framework 20140425-183007-1032504131-35984-23940-0000 I0425 18:30:07.875313 23966 hierarchical_allocator_process.hpp:708] Performed allocation for slave 20140425-183007-1032504131-35984-23940-0 in 193259ns I0425 18:30:07.875387 23967 master.hpp:585] Adding offer 20140425-183007-1032504131-35984-23940-0 with resources cpus(*):3; mem(*):1024; disk(*):23038; ports(*):[31000-32000] on slave 20140425-183007-1032504131-35984-23940-0 (juno.apache.org) I0425 18:30:07.875437 23967 master.cpp:2685] Sending 1 offers to framework 20140425-183007-1032504131-35984-23940-0000 I0425 18:30:07.875783 23968 sched.cpp:529] Scheduler::resourceOffers took 205159ns I0425 18:30:07.875989 23967 master.hpp:595] Removing offer 20140425-183007-1032504131-35984-23940-0 with resources cpus(*):3; mem(*):1024; disk(*):23038; ports(*):[31000-32000] on slave 20140425-183007-1032504131-35984-23940-0 (juno.apache.org) I0425 18:30:07.876042 23967 master.cpp:1797] Processing reply for offers: [ 20140425-183007-1032504131-35984-23940-0 ] on slave 20140425-183007-1032504131-35984-23940-0 (juno.apache.org) for framework 20140425-183007-1032504131-35984-23940-0000 I0425 18:30:07.876106 23967 master.hpp:557] Adding task 0 with resources cpus(*):2; mem(*):512 on slave 20140425-183007-1032504131-35984-23940-0 (juno.apache.org) I0425 18:30:07.876130 23967 master.cpp:2848] Launching task 0 of framework 20140425-183007-1032504131-35984-23940-0000 with resources cpus(*):2; mem(*):512 on slave 20140425-183007-1032504131-35984-23940-0 (juno.apache.org) I0425 18:30:07.876263 23967 slave.cpp:767] Got assigned task 0 for framework 20140425-183007-1032504131-35984-23940-0000 I0425 18:30:07.876487 23968 hierarchical_allocator_process.hpp:547] Framework 20140425-183007-1032504131-35984-23940-0000 left cpus(*):1; mem(*):512; disk(*):23038; ports(*):[31000-32000] unused on slave 20140425-183007-1032504131-35984-23940-0 I0425 18:30:07.876515 23967 slave.cpp:876] Launching task 0 for framework 20140425-183007-1032504131-35984-23940-0000 I0425 18:30:07.876596 23968 hierarchical_allocator_process.hpp:590] Framework 20140425-183007-1032504131-35984-23940-0000 filtered slave 20140425-183007-1032504131-35984-23940-0 for 5secs I0425 18:30:07.878860 23967 exec.cpp:131] Version: 0.19.0 I0425 18:30:07.878937 23963 exec.cpp:181] Executor started at: executor(46)@67.195.138.61:35984 with pid 23940 I0425 18:30:07.878953 23967 slave.cpp:986] Queuing task '0' for executor executor-1 of framework '20140425-183007-1032504131-35984-23940-0000 I0425 18:30:07.878991 23967 slave.cpp:460] Successfully attached file '/tmp/AllocatorTest_0_FrameworkExited_xJb7ko/slaves/20140425-183007-1032504131-35984-23940-0/frameworks/20140425-183007-1032504131-35984-23940-0000/executors/executor-1/runs/fc869994-8a4e-4a5b-97a2-f26e9b27cac6' I0425 18:30:07.879011 23967 slave.cpp:2143] Monitoring executor 'executor-1' of framework '20140425-183007-1032504131-35984-23940-0000' in container 'fc869994-8a4e-4a5b-97a2-f26e9b27cac6' I0425 18:30:07.879065 23967 slave.cpp:1459] Got registration for executor 'executor-1' of framework 20140425-183007-1032504131-35984-23940-0000 I0425 18:30:07.879156 23967 slave.cpp:1578] Flushing queued task 0 for executor 'executor-1' of framework 20140425-183007-1032504131-35984-23940-0000 I0425 18:30:07.879217 23964 exec.cpp:205] Executor registered on slave 20140425-183007-1032504131-35984-23940-0 I0425 18:30:07.880506 23964 exec.cpp:217] Executor::registered took 11043ns I0425 18:30:07.880568 23964 exec.cpp:292] Executor asked to run task '0' I0425 18:30:07.880604 23964 exec.cpp:301] Executor::launchTask took 18488ns I0425 18:30:07.882411 23940 sched.cpp:121] Version: 0.19.0 I0425 18:30:07.882582 23968 sched.cpp:217] New master detected at [email protected]:35984 I0425 18:30:07.882602 23968 sched.cpp:268] Authenticating with master [email protected]:35984 I0425 18:30:07.882700 23961 authenticatee.hpp:128] Creating new client SASL connection I0425 18:30:07.882840 23961 master.cpp:2724] Authenticating framework at scheduler(136)@67.195.138.61:35984 I0425 18:30:07.882946 23965 authenticator.hpp:148] Creating new server SASL connection I0425 18:30:07.883069 23964 authenticatee.hpp:219] Received SASL authentication mechanisms: CRAM-MD5 I0425 18:30:07.883090 23964 authenticatee.hpp:245] Attempting to authenticate with mechanism 'CRAM-MD5' I0425 18:30:07.883126 23964 authenticator.hpp:254] Received SASL authentication start I0425 18:30:07.883164 23964 authenticator.hpp:342] Authentication requires more steps I0425 18:30:07.883203 23964 authenticatee.hpp:265] Received SASL authentication step I0425 18:30:07.883255 23964 authenticator.hpp:282] Received SASL authentication step I0425 18:30:07.883277 23964 auxprop.cpp:81] Request to lookup properties for user: 'test-principal' realm: 'juno.apache.org' server FQDN: 'juno.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I0425 18:30:07.883286 23964 auxprop.cpp:153] Looking up auxiliary property '*userPassword' I0425 18:30:07.883297 23964 auxprop.cpp:153] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I0425 18:30:07.883307 23964 auxprop.cpp:81] Request to lookup properties for user: 'test-principal' realm: 'juno.apache.org' server FQDN: 'juno.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I0425 18:30:07.883316 23964 auxprop.cpp:103] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I0425 18:30:07.883321 23964 auxprop.cpp:103] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I0425 18:30:07.883337 23964 authenticator.hpp:334] Authentication success I0425 18:30:07.883378 23965 authenticatee.hpp:305] Authentication success I0425 18:30:07.883394 23964 master.cpp:2764] Successfully authenticated framework at scheduler(136)@67.195.138.61:35984 I0425 18:30:07.883527 23965 sched.cpp:342] Successfully authenticated with master [email protected]:35984 I0425 18:30:07.883541 23965 sched.cpp:461] Sending registration request to [email protected]:35984 I0425 18:30:07.883610 23968 master.cpp:977] Received registration request from scheduler(136)@67.195.138.61:35984 I0425 18:30:07.883643 23968 master.cpp:995] Registering framework 20140425-183007-1032504131-35984-23940-0001 at scheduler(136)@67.195.138.61:35984 I0425 18:30:07.883715 23965 sched.cpp:392] Framework registered with 20140425-183007-1032504131-35984-23940-0001 I0425 18:30:07.883736 23965 sched.cpp:406] Scheduler::registered took 10374ns I0425 18:30:07.883772 23965 hierarchical_allocator_process.hpp:332] Added framework 20140425-183007-1032504131-35984-23940-0001 I0425 18:30:07.883818 23965 hierarchical_allocator_process.hpp:752] Offering cpus(*):1; mem(*):512; disk(*):23038; ports(*):[31000-32000] on slave 20140425-183007-1032504131-35984-23940-0 to framework 20140425-183007-1032504131-35984-23940-0001 I0425 18:30:07.883947 23965 hierarchical_allocator_process.hpp:835] Filtered on slave 20140425-183007-1032504131-35984-23940-0 for framework 20140425-183007-1032504131-35984-23940-0000 I0425 18:30:07.883960 23965 hierarchical_allocator_process.hpp:688] Performed allocation for 1 slaves in 177461ns I0425 18:30:07.884023 23963 master.hpp:585] Adding offer 20140425-183007-1032504131-35984-23940-1 with resources cpus(*):1; mem(*):512; disk(*):23038; ports(*):[31000-32000] on slave 20140425-183007-1032504131-35984-23940-0 (juno.apache.org) I0425 18:30:07.884081 23963 master.cpp:2685] Sending 1 offers to framework 20140425-183007-1032504131-35984-23940-0001 I0425 18:30:07.884327 23968 sched.cpp:529] Scheduler::resourceOffers took 149262ns I0425 18:30:07.884522 23963 master.hpp:595] Removing offer 20140425-183007-1032504131-35984-23940-1 with resources cpus(*):1; mem(*):512; disk(*):23038; ports(*):[31000-32000] on slave 20140425-183007-1032504131-35984-23940-0 (juno.apache.org) I0425 18:30:07.884582 23963 master.cpp:1797] Processing reply for offers: [ 20140425-183007-1032504131-35984-23940-1 ] on slave 20140425-183007-1032504131-35984-23940-0 (juno.apache.org) for framework 20140425-183007-1032504131-35984-23940-0001 I0425 18:30:07.884662 23963 master.hpp:557] Adding task 0 with resources cpus(*):1; mem(*):256 on slave 20140425-183007-1032504131-35984-23940-0 (juno.apache.org) I0425 18:30:07.884699 23963 master.cpp:2848] Launching task 0 of framework 20140425-183007-1032504131-35984-23940-0001 with resources cpus(*):1; mem(*):256 on slave 20140425-183007-1032504131-35984-23940-0 (juno.apache.org) I0425 18:30:07.884783 23968 slave.cpp:767] Got assigned task 0 for framework 20140425-183007-1032504131-35984-23940-0001 I0425 18:30:07.884924 23963 hierarchical_allocator_process.hpp:547] Framework 20140425-183007-1032504131-35984-23940-0001 left mem(*):256; disk(*):23038; ports(*):[31000-32000] unused on slave 20140425-183007-1032504131-35984-23940-0 I0425 18:30:07.884980 23968 slave.cpp:876] Launching task 0 for framework 20140425-183007-1032504131-35984-23940-0001 I0425 18:30:07.885028 23963 hierarchical_allocator_process.hpp:590] Framework 20140425-183007-1032504131-35984-23940-0001 filtered slave 20140425-183007-1032504131-35984-23940-0 for 5secs I0425 18:30:07.887830 23968 exec.cpp:131] Version: 0.19.0 I0425 18:30:07.887897 23965 exec.cpp:181] Executor started at: executor(47)@67.195.138.61:35984 with pid 23940 I0425 18:30:07.887946 23968 slave.cpp:986] Queuing task '0' for executor executor-2 of framework '20140425-183007-1032504131-35984-23940-0001 I0425 18:30:07.887994 23968 slave.cpp:460] Successfully attached file '/tmp/AllocatorTest_0_FrameworkExited_xJb7ko/slaves/20140425-183007-1032504131-35984-23940-0/frameworks/20140425-183007-1032504131-35984-23940-0001/executors/executor-2/runs/1aa1824b-83d7-44a1-b68d-30592b522dd2' I0425 18:30:07.888021 23968 slave.cpp:2143] Monitoring executor 'executor-2' of framework '20140425-183007-1032504131-35984-23940-0001' in container '1aa1824b-83d7-44a1-b68d-30592b522dd2' I0425 18:30:07.888083 23968 slave.cpp:1459] Got registration for executor 'executor-2' of framework 20140425-183007-1032504131-35984-23940-0001 I0425 18:30:07.888217 23968 slave.cpp:1578] Flushing queued task 0 for executor 'executor-2' of framework 20140425-183007-1032504131-35984-23940-0001 I0425 18:30:07.888242 23963 exec.cpp:205] Executor registered on slave 20140425-183007-1032504131-35984-23940-0 I0425 18:30:07.889499 23963 exec.cpp:217] Executor::registered took 11645ns I0425 18:30:07.889550 23963 exec.cpp:292] Executor asked to run task '0' I0425 18:30:07.889585 23963 exec.cpp:301] Executor::launchTask took 23864ns I0425 18:30:07.889801 23968 sched.cpp:731] Stopping framework '20140425-183007-1032504131-35984-23940-0000' I0425 18:30:07.889889 23967 master.cpp:1189] Asked to unregister framework 20140425-183007-1032504131-35984-23940-0000 I0425 18:30:07.889904 23967 master.cpp:3083] Removing framework 20140425-183007-1032504131-35984-23940-0000 I0425 18:30:07.889967 23967 master.hpp:575] Removing task 0 with resources cpus(*):2; mem(*):512 on slave 20140425-183007-1032504131-35984-23940-0 (juno.apache.org) I0425 18:30:07.889992 23965 hierarchical_allocator_process.hpp:408] Deactivated framework 20140425-183007-1032504131-35984-23940-0000 I0425 18:30:07.889999 23966 slave.cpp:1170] Asked to shut down framework 20140425-183007-1032504131-35984-23940-0000 by [email protected]:35984 I0425 18:30:07.890012 23966 slave.cpp:1195] Shutting down framework 20140425-183007-1032504131-35984-23940-0000 I0425 18:30:07.890025 23966 slave.cpp:2486] Shutting down executor 'executor-1' of framework 20140425-183007-1032504131-35984-23940-0000 I0425 18:30:07.890063 23966 exec.cpp:378] Executor asked to shutdown I0425 18:30:07.890085 23966 exec.cpp:393] Executor::shutdown took 11501ns I0425 18:30:07.890197 23964 hierarchical_allocator_process.hpp:637] Recovered cpus(*):2; mem(*):512 (total allocatable: mem(*):768; disk(*):23038; ports(*):[31000-32000]; cpus(*):2) on slave 20140425-183007-1032504131-35984-23940-0 from framework 20140425-183007-1032504131-35984-23940-0000 I0425 18:30:07.890264 23964 hierarchical_allocator_process.hpp:363] Removed framework 20140425-183007-1032504131-35984-23940-0000 I0425 18:30:07.898417 23968 hierarchical_allocator_process.hpp:752] Offering mem(*):768; disk(*):23038; ports(*):[31000-32000]; cpus(*):2 on slave 20140425-183007-1032504131-35984-23940-0 to framework 20140425-183007-1032504131-35984-23940-0001 I0425 18:30:07.898582 23968 hierarchical_allocator_process.hpp:688] Performed allocation for 1 slaves in 221164ns I0425 18:30:07.898654 23966 master.hpp:585] Adding offer 20140425-183007-1032504131-35984-23940-2 with resources mem(*):768; disk(*):23038; ports(*):[31000-32000]; cpus(*):2 on slave 20140425-183007-1032504131-35984-23940-0 (juno.apache.org) I0425 18:30:07.898708 23966 master.cpp:2685] Sending 1 offers to framework 20140425-183007-1032504131-35984-23940-0001 I0425 18:30:07.898804 23966 sched.cpp:529] Scheduler::resourceOffers took 14159ns I0425 18:30:07.898936 23940 master.cpp:547] Master terminating I0425 18:30:07.898947 23966 sched.cpp:731] Stopping framework '20140425-183007-1032504131-35984-23940-0001' I0425 18:30:07.898993 23940 master.hpp:575] Removing task 0 with resources cpus(*):1; mem(*):256 on slave 20140425-183007-1032504131-35984-23940-0 (juno.apache.org) I0425 18:30:07.899080 23940 master.hpp:595] Removing offer 20140425-183007-1032504131-35984-23940-2 with resources mem(*):768; disk(*):23038; ports(*):[31000-32000]; cpus(*):2 on slave 20140425-183007-1032504131-35984-23940-0 (juno.apache.org) I0425 18:30:07.899205 23961 hierarchical_allocator_process.hpp:637] Recovered cpus(*):1; mem(*):256 (total allocatable: cpus(*):1; mem(*):256) on slave 20140425-183007-1032504131-35984-23940-0 from framework 20140425-183007-1032504131-35984-23940-0001 I0425 18:30:07.899284 23966 slave.cpp:2012] [email protected]:35984 exited W0425 18:30:07.899298 23966 slave.cpp:2015] Master disconnected! Waiting for a new master to be elected I0425 18:30:07.907524 23962 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 32.391016ms I0425 18:30:07.907544 23962 replica.cpp:664] Persisted action at 4 I0425 18:30:07.907855 23940 slave.cpp:388] Slave terminating I0425 18:30:07.907872 23940 slave.cpp:1170] Asked to shut down framework 20140425-183007-1032504131-35984-23940-0001 by @0.0.0.0:0 I0425 18:30:07.907883 23940 slave.cpp:1195] Shutting down framework 20140425-183007-1032504131-35984-23940-0001 I0425 18:30:07.907896 23940 slave.cpp:2486] Shutting down executor 'executor-2' of framework 20140425-183007-1032504131-35984-23940-0001 I0425 18:30:07.907922 23940 slave.cpp:1170] Asked to shut down framework 20140425-183007-1032504131-35984-23940-0000 by @0.0.0.0:0 W0425 18:30:07.907932 23940 slave.cpp:1191] Ignoring shutdown framework 20140425-183007-1032504131-35984-23940-0000 because it is terminating I0425 18:30:07.908334 23961 exec.cpp:378] Executor asked to shutdown I0425 18:30:07.908382 23961 exec.cpp:393] Executor::shutdown took 35795ns [ OK ] AllocatorTest/0.FrameworkExited (629 ms) [----------] 10 tests from AllocatorTest/0 (10706 ms total) [----------] 7 tests from InMemoryStateTest [ RUN ] InMemoryStateTest.FetchAndStoreAndFetch [ OK ] InMemoryStateTest.FetchAndStoreAndFetch (3 ms) [ RUN ] InMemoryStateTest.FetchAndStoreAndExpungeAndFetch [ OK ] InMemoryStateTest.FetchAndStoreAndExpungeAndFetch (6 ms) [ RUN ] InMemoryStateTest.FetchAndStoreAndExpungeAndExpunge [ OK ] InMemoryStateTest.FetchAndStoreAndExpungeAndExpunge (3 ms) [ RUN ] InMemoryStateTest.FetchAndStoreAndStoreAndFetch [ OK ] InMemoryStateTest.FetchAndStoreAndStoreAndFetch (5 ms) [ RUN ] InMemoryStateTest.FetchAndStoreAndStoreFailAndFetch [ OK ] InMemoryStateTest.FetchAndStoreAndStoreFailAndFetch (4 ms) [ RUN ] InMemoryStateTest.Names [ OK ] InMemoryStateTest.Names (2 ms) [ RUN ] InMemoryStateTest.FetchAndStoreAndExpungeAndStoreAndFetch [ OK ] InMemoryStateTest.FetchAndStoreAndExpungeAndStoreAndFetch (5 ms) [----------] 7 tests from InMemoryStateTest (28 ms total) [----------] 2 tests from PathsTest [ RUN ] PathsTest.format [ OK ] PathsTest.format (1 ms) [ RUN ] PathsTest.CreateExecutorDirectory [ OK ] PathsTest.CreateExecutorDirectory (2 ms) [----------] 2 tests from PathsTest (3 ms total) [----------] Global test environment tear-down [==========] 299 tests from 51 test cases ran. (334515 ms total) [ PASSED ] 298 tests. [ FAILED ] 1 test, listed below: [ FAILED ] ReservationAllocatorTest.ResourcesReturned 1 FAILED TEST YOU HAVE 1 DISABLED TEST make[4]: *** [check-local] Error 1 make[4]: Leaving directory `<https://builds.apache.org/job/Mesos-Ubuntu-distcheck/ws/build/mesos-0.19.0/_build/src'> make[3]: *** [check-am] Error 2 make[3]: Leaving directory `<https://builds.apache.org/job/Mesos-Ubuntu-distcheck/ws/build/mesos-0.19.0/_build/src'> make[2]: *** [check] Error 2 make[2]: Leaving directory `<https://builds.apache.org/job/Mesos-Ubuntu-distcheck/ws/build/mesos-0.19.0/_build/src'> make[1]: *** [check-recursive] Error 1 make[1]: Leaving directory `<https://builds.apache.org/job/Mesos-Ubuntu-distcheck/ws/build/mesos-0.19.0/_build'> make: *** [distcheck] Error 1 Build step 'Execute shell' marked build as failure
