See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/1823/changes>
Changes: [vinod] Fixed distclean break during maven clean up. [idownes] Fixed some indentation. [benjamin.hindman] Better error message for protobuf::write. [benjamin.hindman] Added 'mesos-usage' for use by external containerizers. [benjamin.hindman] Added test for slave stopping before containerizer launches. [benjamin.hindman] Allowed passing headers to http::get/post. [benjamin.hindman] Updated uses of http::post. [benjamin.hindman] Used memory::shared_ptr instead of boost::shared_ptr. [benjamin.hindman] Added 'cref' and 'ref' to lambda:: namespace. [benjamin.hindman] Added process::async overloads for void functions. ------------------------------------------ [...truncated 24328 lines...] I0430 05:46:22.558820 8587 replica.cpp:664] Persisted action at 0 I0430 05:46:22.562062 8582 replica.cpp:508] Replica received write request for position 0 I0430 05:46:22.562094 8582 leveldb.cpp:436] Reading position from leveldb took 11710ns I0430 05:46:22.574862 8582 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 12.750768ms I0430 05:46:22.574892 8582 replica.cpp:664] Persisted action at 0 I0430 05:46:22.575268 8582 replica.cpp:643] Replica received learned notice for position 0 I0430 05:46:22.590899 8582 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 15.611073ms I0430 05:46:22.590931 8582 replica.cpp:664] Persisted action at 0 I0430 05:46:22.590942 8582 replica.cpp:649] Replica learned NOP action at position 0 I0430 05:46:22.591187 8574 log.cpp:672] Writer started with ending position 0 I0430 05:46:22.592519 8575 leveldb.cpp:436] Reading position from leveldb took 12908ns I0430 05:46:22.595152 8566 registrar.cpp:308] Successfully recovered registrar I0430 05:46:22.595185 8566 registrar.cpp:379] Attempting to update the 'registry' I0430 05:46:22.597784 8568 log.cpp:680] Attempting to append 137 bytes to the log I0430 05:46:22.597970 8574 coordinator.cpp:339] Coordinator attempting to write APPEND action at position 1 I0430 05:46:22.599417 8586 replica.cpp:508] Replica received write request for position 1 I0430 05:46:22.614928 8586 leveldb.cpp:341] Persisting action (156 bytes) to leveldb took 15.485377ms I0430 05:46:22.614959 8586 replica.cpp:664] Persisted action at 1 I0430 05:46:22.615391 8572 replica.cpp:643] Replica received learned notice for position 1 2014-04-30 05:46:22,625:8544(0x2b6f84602700):ZOO_ERROR@handle_socket_error_msg@1697: Socket [127.0.0.1:46401] zk retcode=-4, errno=111(Connection refused): server refused to accept the client I0430 05:46:22.631079 8572 leveldb.cpp:341] Persisting action (158 bytes) to leveldb took 15.663104ms I0430 05:46:22.631113 8572 replica.cpp:664] Persisted action at 1 I0430 05:46:22.631124 8572 replica.cpp:649] Replica learned APPEND action at position 1 I0430 05:46:22.631425 8583 registrar.cpp:427] Successfully updated 'registry' I0430 05:46:22.631865 8571 log.cpp:699] Attempting to truncate the log to 1 I0430 05:46:22.632184 8573 master.cpp:780] Recovered 0 slaves from the Registry (99B) ; allowing 10mins for slaves to re-register I0430 05:46:22.632349 8568 coordinator.cpp:339] Coordinator attempting to write TRUNCATE action at position 2 I0430 05:46:22.633383 8580 replica.cpp:508] Replica received write request for position 2 I0430 05:46:22.636288 8579 slave.cpp:140] Slave started on 47)@140.211.11.27:49560 I0430 05:46:22.636373 8579 credentials.hpp:35] Loading credentials for authentication W0430 05:46:22.636570 8579 credentials.hpp:48] Failed to stat credentials file 'file:///tmp/ExternalContainerizerTest_Launch_XWYTV0/credential': No such file or directory I0430 05:46:22.636603 8579 slave.cpp:231] Slave using credential for: test-principal I0430 05:46:22.636706 8579 slave.cpp:244] Slave resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I0430 05:46:22.636780 8579 slave.cpp:272] Slave hostname: hemera.apache.org I0430 05:46:22.636793 8579 slave.cpp:273] Slave checkpoint: false I0430 05:46:22.637437 8579 state.cpp:33] Recovering state from '/tmp/ExternalContainerizerTest_Launch_XWYTV0/meta' I0430 05:46:22.637997 8568 status_update_manager.cpp:193] Recovering status update manager I0430 05:46:22.639364 8582 slave.cpp:2943] Finished recovery I0430 05:46:22.640589 8544 sched.cpp:121] Version: 0.19.0 I0430 05:46:22.641007 8579 sched.cpp:217] New master detected at [email protected]:49560 I0430 05:46:22.641034 8579 sched.cpp:268] Authenticating with master [email protected]:49560 I0430 05:46:22.641266 8564 authenticatee.hpp:128] Creating new client SASL connection I0430 05:46:22.641993 8575 master.cpp:2795] Authenticating scheduler(39)@140.211.11.27:49560 I0430 05:46:22.642226 8587 authenticator.hpp:148] Creating new server SASL connection I0430 05:46:22.642526 8572 authenticatee.hpp:219] Received SASL authentication mechanisms: CRAM-MD5 I0430 05:46:22.642551 8572 authenticatee.hpp:245] Attempting to authenticate with mechanism 'CRAM-MD5' I0430 05:46:22.642750 8575 authenticator.hpp:254] Received SASL authentication start I0430 05:46:22.642863 8575 authenticator.hpp:342] Authentication requires more steps I0430 05:46:22.643053 8576 authenticatee.hpp:265] Received SASL authentication step I0430 05:46:22.643117 8576 authenticator.hpp:282] Received SASL authentication step I0430 05:46:22.643138 8576 auxprop.cpp:81] Request to lookup properties for user: 'test-principal' realm: 'hemera.apache.org' server FQDN: 'hemera.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I0430 05:46:22.643147 8576 auxprop.cpp:153] Looking up auxiliary property '*userPassword' I0430 05:46:22.643157 8576 auxprop.cpp:153] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I0430 05:46:22.643167 8576 auxprop.cpp:81] Request to lookup properties for user: 'test-principal' realm: 'hemera.apache.org' server FQDN: 'hemera.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I0430 05:46:22.643174 8576 auxprop.cpp:103] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I0430 05:46:22.643180 8576 auxprop.cpp:103] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I0430 05:46:22.643193 8576 authenticator.hpp:334] Authentication success I0430 05:46:22.643473 8572 authenticatee.hpp:305] Authentication success I0430 05:46:22.643487 8576 master.cpp:2835] Successfully authenticated scheduler(39)@140.211.11.27:49560 I0430 05:46:22.643748 8570 slave.cpp:525] New master detected at [email protected]:49560 I0430 05:46:22.643792 8570 slave.cpp:585] Authenticating with master [email protected]:49560 I0430 05:46:22.643839 8570 slave.cpp:558] Detecting new master I0430 05:46:22.643949 8574 status_update_manager.cpp:167] New master detected at [email protected]:49560 I0430 05:46:22.643975 8585 authenticatee.hpp:128] Creating new client SASL connection I0430 05:46:22.644130 8585 master.cpp:2795] Authenticating slave(47)@140.211.11.27:49560 I0430 05:46:22.644223 8564 authenticator.hpp:148] Creating new server SASL connection I0430 05:46:22.644306 8567 sched.cpp:342] Successfully authenticated with master [email protected]:49560 I0430 05:46:22.644331 8567 sched.cpp:461] Sending registration request to [email protected]:49560 I0430 05:46:22.644629 8566 master.cpp:981] Received registration request from scheduler(39)@140.211.11.27:49560 I0430 05:46:22.644639 8584 authenticatee.hpp:219] Received SASL authentication mechanisms: CRAM-MD5 I0430 05:46:22.644668 8584 authenticatee.hpp:245] Attempting to authenticate with mechanism 'CRAM-MD5' I0430 05:46:22.644927 8566 master.cpp:999] Registering framework 20140430-054622-453759884-49560-8544-0000 at scheduler(39)@140.211.11.27:49560 I0430 05:46:22.645072 8567 sched.cpp:392] Framework registered with 20140430-054622-453759884-49560-8544-0000 I0430 05:46:22.645138 8566 hierarchical_allocator_process.hpp:332] Added framework 20140430-054622-453759884-49560-8544-0000 I0430 05:46:22.645153 8566 hierarchical_allocator_process.hpp:726] No resources available to allocate! I0430 05:46:22.645159 8566 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 7019ns I0430 05:46:22.645305 8567 sched.cpp:406] Scheduler::registered took 195268ns I0430 05:46:22.645645 8574 authenticator.hpp:254] Received SASL authentication start I0430 05:46:22.645851 8574 authenticator.hpp:342] Authentication requires more steps I0430 05:46:22.646549 8576 authenticatee.hpp:265] Received SASL authentication step I0430 05:46:22.646926 8583 authenticator.hpp:282] Received SASL authentication step I0430 05:46:22.646955 8583 auxprop.cpp:81] Request to lookup properties for user: 'test-principal' realm: 'hemera.apache.org' server FQDN: 'hemera.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I0430 05:46:22.646965 8583 auxprop.cpp:153] Looking up auxiliary property '*userPassword' I0430 05:46:22.646975 8583 auxprop.cpp:153] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I0430 05:46:22.646986 8583 auxprop.cpp:81] Request to lookup properties for user: 'test-principal' realm: 'hemera.apache.org' server FQDN: 'hemera.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I0430 05:46:22.646992 8583 auxprop.cpp:103] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I0430 05:46:22.646998 8583 auxprop.cpp:103] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I0430 05:46:22.647011 8583 authenticator.hpp:334] Authentication success I0430 05:46:22.647083 8564 authenticatee.hpp:305] Authentication success I0430 05:46:22.647207 8566 master.cpp:2835] Successfully authenticated slave(47)@140.211.11.27:49560 I0430 05:46:22.647253 8580 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 13.844729ms I0430 05:46:22.647277 8580 replica.cpp:664] Persisted action at 2 I0430 05:46:22.648201 8583 slave.cpp:642] Successfully authenticated with master [email protected]:49560 I0430 05:46:22.648252 8583 slave.cpp:871] Will retry registration in 5.449601584secs if necessary I0430 05:46:22.648866 8566 registrar.cpp:379] Attempting to update the 'registry' I0430 05:46:22.649536 8578 replica.cpp:643] Replica received learned notice for position 2 I0430 05:46:22.663552 8578 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 13.993911ms I0430 05:46:22.663614 8578 leveldb.cpp:399] Deleting ~1 keys from leveldb took 31140ns I0430 05:46:22.663629 8578 replica.cpp:664] Persisted action at 2 I0430 05:46:22.663637 8578 replica.cpp:649] Replica learned TRUNCATE action at position 2 I0430 05:46:22.664121 8569 log.cpp:680] Attempting to append 330 bytes to the log I0430 05:46:22.664829 8570 coordinator.cpp:339] Coordinator attempting to write APPEND action at position 3 I0430 05:46:22.666887 8578 replica.cpp:508] Replica received write request for position 3 I0430 05:46:22.679610 8578 leveldb.cpp:341] Persisting action (349 bytes) to leveldb took 12.677374ms I0430 05:46:22.679642 8578 replica.cpp:664] Persisted action at 3 I0430 05:46:22.679895 8568 replica.cpp:643] Replica received learned notice for position 3 I0430 05:46:22.695678 8568 leveldb.cpp:341] Persisting action (351 bytes) to leveldb took 15.7615ms I0430 05:46:22.695710 8568 replica.cpp:664] Persisted action at 3 I0430 05:46:22.695721 8568 replica.cpp:649] Replica learned APPEND action at position 3 I0430 05:46:22.696105 8581 registrar.cpp:427] Successfully updated 'registry' I0430 05:46:22.696152 8571 log.cpp:699] Attempting to truncate the log to 3 I0430 05:46:22.696477 8574 coordinator.cpp:339] Coordinator attempting to write TRUNCATE action at position 4 I0430 05:46:22.696616 8571 master.cpp:2169] Admitted slave on hemera.apache.org at slave(47)@140.211.11.27:49560 I0430 05:46:22.696638 8571 master.cpp:3283] Adding slave 20140430-054622-453759884-49560-8544-0 at hemera.apache.org with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I0430 05:46:22.697588 8570 slave.cpp:675] Registered with master [email protected]:49560; given slave ID 20140430-054622-453759884-49560-8544-0 I0430 05:46:22.697808 8576 hierarchical_allocator_process.hpp:445] Added slave 20140430-054622-453759884-49560-8544-0 (hemera.apache.org) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (and cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] available) I0430 05:46:22.697885 8576 hierarchical_allocator_process.hpp:752] Offering cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140430-054622-453759884-49560-8544-0 to framework 20140430-054622-453759884-49560-8544-0000 I0430 05:46:22.698118 8576 hierarchical_allocator_process.hpp:708] Performed allocation for slave 20140430-054622-453759884-49560-8544-0 in 257746ns I0430 05:46:22.698252 8586 master.hpp:586] Adding offer 20140430-054622-453759884-49560-8544-0 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140430-054622-453759884-49560-8544-0 (hemera.apache.org) I0430 05:46:22.698384 8586 master.cpp:2744] Sending 1 offers to framework 20140430-054622-453759884-49560-8544-0000 I0430 05:46:22.698565 8572 sched.cpp:529] Scheduler::resourceOffers took 26032ns I0430 05:46:22.699877 8564 master.hpp:596] Removing offer 20140430-054622-453759884-49560-8544-0 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140430-054622-453759884-49560-8544-0 (hemera.apache.org) I0430 05:46:22.699949 8564 master.cpp:1806] Processing reply for offers: [ 20140430-054622-453759884-49560-8544-0 ] on slave 20140430-054622-453759884-49560-8544-0 (hemera.apache.org) for framework 20140430-054622-453759884-49560-8544-0000 I0430 05:46:22.700019 8583 replica.cpp:508] Replica received write request for position 4 I0430 05:46:22.700073 8564 master.hpp:558] Adding task 1 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140430-054622-453759884-49560-8544-0 (hemera.apache.org) I0430 05:46:22.700129 8564 master.cpp:2919] Launching task 1 of framework 20140430-054622-453759884-49560-8544-0000 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140430-054622-453759884-49560-8544-0 (hemera.apache.org) I0430 05:46:22.700270 8567 slave.cpp:905] Got assigned task 1 for framework 20140430-054622-453759884-49560-8544-0000 I0430 05:46:22.700568 8567 slave.cpp:1015] Launching task 1 for framework 20140430-054622-453759884-49560-8544-0000 I0430 05:46:22.703065 8567 slave.cpp:1125] Queuing task '1' for executor 1 of framework '20140430-054622-453759884-49560-8544-0000 I0430 05:46:22.703071 8580 external_containerizer.cpp:266] Launching container '0d79e54b-6d04-4dcd-9534-89f02effa414' I0430 05:46:22.703131 8567 slave.cpp:486] Successfully attached file '/tmp/ExternalContainerizerTest_Launch_XWYTV0/slaves/20140430-054622-453759884-49560-8544-0/frameworks/20140430-054622-453759884-49560-8544-0000/executors/1/runs/0d79e54b-6d04-4dcd-9534-89f02effa414' I0430 05:46:22.703145 8580 external_containerizer.cpp:804] Invoking external containerizer for method 'launch' I0430 05:46:22.703169 8580 external_containerizer.cpp:818] user: jenkins I0430 05:46:22.717458 8583 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 17.350188ms I0430 05:46:22.717602 8583 replica.cpp:664] Persisted action at 4 I0430 05:46:22.721099 8565 replica.cpp:643] Replica received learned notice for position 4 I0430 05:46:22.722059 8580 external_containerizer.cpp:378] Wait triggered on container '0d79e54b-6d04-4dcd-9534-89f02effa414' I0430 05:46:22.736465 8565 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 15.292829ms I0430 05:46:22.736541 8565 leveldb.cpp:399] Deleting ~2 keys from leveldb took 43555ns I0430 05:46:22.736556 8565 replica.cpp:664] Persisted action at 4 I0430 05:46:22.736565 8565 replica.cpp:649] Replica learned TRUNCATE action at position 4 I0430 05:46:22.906224 8568 slave.cpp:1598] Got registration for executor '1' of framework 20140430-054622-453759884-49560-8544-0000 I0430 05:46:22.906800 8565 external_containerizer.cpp:476] Update triggered on container '0d79e54b-6d04-4dcd-9534-89f02effa414' I0430 05:46:22.907776 8568 slave.cpp:1717] Flushing queued task 1 for executor '1' of framework 20140430-054622-453759884-49560-8544-0000 I0430 05:46:22.913355 8581 slave.cpp:1953] Handling status update TASK_RUNNING (UUID: 574e5c5e-83a1-4290-9451-39ae3ebd959f) for task 1 of framework 20140430-054622-453759884-49560-8544-0000 from executor(1)@140.211.11.27:50921 I0430 05:46:22.914161 8564 status_update_manager.cpp:320] Received status update TASK_RUNNING (UUID: 574e5c5e-83a1-4290-9451-39ae3ebd959f) for task 1 of framework 20140430-054622-453759884-49560-8544-0000 I0430 05:46:22.914183 8564 status_update_manager.cpp:499] Creating StatusUpdate stream for task 1 of framework 20140430-054622-453759884-49560-8544-0000 I0430 05:46:22.914312 8564 status_update_manager.cpp:373] Forwarding status update TASK_RUNNING (UUID: 574e5c5e-83a1-4290-9451-39ae3ebd959f) for task 1 of framework 20140430-054622-453759884-49560-8544-0000 to [email protected]:49560 I0430 05:46:22.914561 8581 master.cpp:2444] Status update TASK_RUNNING (UUID: 574e5c5e-83a1-4290-9451-39ae3ebd959f) for task 1 of framework 20140430-054622-453759884-49560-8544-0000 from slave(47)@140.211.11.27:49560 I0430 05:46:22.914692 8564 slave.cpp:2070] Status update manager successfully handled status update TASK_RUNNING (UUID: 574e5c5e-83a1-4290-9451-39ae3ebd959f) for task 1 of framework 20140430-054622-453759884-49560-8544-0000 I0430 05:46:22.914710 8564 slave.cpp:2076] Sending acknowledgement for status update TASK_RUNNING (UUID: 574e5c5e-83a1-4290-9451-39ae3ebd959f) for task 1 of framework 20140430-054622-453759884-49560-8544-0000 to executor(1)@140.211.11.27:50921 I0430 05:46:22.915367 8566 sched.cpp:620] Scheduler::statusUpdate took 57089ns I0430 05:46:22.916677 8580 status_update_manager.cpp:398] Received status update acknowledgement (UUID: 574e5c5e-83a1-4290-9451-39ae3ebd959f) for task 1 of framework 20140430-054622-453759884-49560-8544-0000 I0430 05:46:22.917464 8580 slave.cpp:1538] Status update manager successfully handled status update acknowledgement (UUID: 574e5c5e-83a1-4290-9451-39ae3ebd959f) for task 1 of framework 20140430-054622-453759884-49560-8544-0000 I0430 05:46:22.918118 8583 external_containerizer.cpp:550] Usage triggered on container '0d79e54b-6d04-4dcd-9534-89f02effa414' I0430 05:46:23.495302 8587 hierarchical_allocator_process.hpp:688] Performed allocation for 1 slaves in 150345ns I0430 05:46:23.636519 8566 external_containerizer.cpp:341] Launch validation callback triggered on container '0d79e54b-6d04-4dcd-9534-89f02effa414' I0430 05:46:23.636605 8566 external_containerizer.cpp:350] Launch finishing up for container '0d79e54b-6d04-4dcd-9534-89f02effa414' I0430 05:46:23.636836 8580 slave.cpp:2282] Monitoring executor '1' of framework '20140430-054622-453759884-49560-8544-0000' in container '0d79e54b-6d04-4dcd-9534-89f02effa414' I0430 05:46:23.636945 8566 external_containerizer.cpp:396] Wait continuation triggered on container '0d79e54b-6d04-4dcd-9534-89f02effa414' I0430 05:46:23.637208 8566 external_containerizer.cpp:804] Invoking external containerizer for method 'wait' I0430 05:46:23.637231 8566 external_containerizer.cpp:818] user: jenkins I0430 05:46:23.654535 8566 external_containerizer.cpp:496] Update continuation triggered on container '0d79e54b-6d04-4dcd-9534-89f02effa414' I0430 05:46:23.654569 8566 external_containerizer.cpp:804] Invoking external containerizer for method 'update' I0430 05:46:23.654593 8566 external_containerizer.cpp:818] user: jenkins I0430 05:46:23.669009 8566 external_containerizer.cpp:568] Usage continuation on container '0d79e54b-6d04-4dcd-9534-89f02effa414' I0430 05:46:23.669042 8566 external_containerizer.cpp:804] Invoking external containerizer for method 'usage' I0430 05:46:23.669054 8566 external_containerizer.cpp:818] user: jenkins I0430 05:46:23.683019 8566 external_containerizer.cpp:364] Launch confirmation callback triggered on container '0d79e54b-6d04-4dcd-9534-89f02effa414' I0430 05:46:24.496639 8583 hierarchical_allocator_process.hpp:688] Performed allocation for 1 slaves in 77061ns I0430 05:46:24.639055 8565 external_containerizer.cpp:532] Update callback triggered on container '0d79e54b-6d04-4dcd-9534-89f02effa414' I0430 05:46:24.640202 8582 external_containerizer.cpp:550] Usage triggered on container '0d79e54b-6d04-4dcd-9534-89f02effa414' I0430 05:46:24.640547 8582 external_containerizer.cpp:611] Usage callback triggered on container '0d79e54b-6d04-4dcd-9534-89f02effa414' I0430 05:46:24.640725 8582 external_containerizer.cpp:568] Usage continuation on container '0d79e54b-6d04-4dcd-9534-89f02effa414' I0430 05:46:24.640764 8582 external_containerizer.cpp:804] Invoking external containerizer for method 'usage' I0430 05:46:24.640776 8582 external_containerizer.cpp:818] user: jenkins I0430 05:46:24.645252 8570 master.cpp:1912] Asked to kill task 1 of framework 20140430-054622-453759884-49560-8544-0000 I0430 05:46:24.645328 8570 master.cpp:1995] Telling slave 20140430-054622-453759884-49560-8544-0 (hemera.apache.org) to kill task 1 of framework 20140430-054622-453759884-49560-8544-0000 I0430 05:46:24.645361 8570 slave.cpp:1181] Asked to kill task 1 of framework 20140430-054622-453759884-49560-8544-0000 I0430 05:46:24.914868 8573 slave.cpp:1953] Handling status update TASK_KILLED (UUID: 79f33226-c975-4b32-ad58-f24595766741) for task 1 of framework 20140430-054622-453759884-49560-8544-0000 from executor(1)@140.211.11.27:50921 I0430 05:46:24.915102 8573 slave.cpp:3444] Terminating task 1 I0430 05:46:24.916079 8587 external_containerizer.cpp:476] Update triggered on container '0d79e54b-6d04-4dcd-9534-89f02effa414' I0430 05:46:24.916108 8573 status_update_manager.cpp:320] Received status update TASK_KILLED (UUID: 79f33226-c975-4b32-ad58-f24595766741) for task 1 of framework 20140430-054622-453759884-49560-8544-0000 I0430 05:46:24.916584 8573 status_update_manager.cpp:373] Forwarding status update TASK_KILLED (UUID: 79f33226-c975-4b32-ad58-f24595766741) for task 1 of framework 20140430-054622-453759884-49560-8544-0000 to [email protected]:49560 I0430 05:46:24.916847 8587 external_containerizer.cpp:496] Update continuation triggered on container '0d79e54b-6d04-4dcd-9534-89f02effa414' I0430 05:46:24.917515 8587 external_containerizer.cpp:804] Invoking external containerizer for method 'update' I0430 05:46:24.917532 8587 external_containerizer.cpp:818] user: jenkins I0430 05:46:24.917549 8573 slave.cpp:2070] Status update manager successfully handled status update TASK_KILLED (UUID: 79f33226-c975-4b32-ad58-f24595766741) for task 1 of framework 20140430-054622-453759884-49560-8544-0000 I0430 05:46:24.917565 8573 slave.cpp:2076] Sending acknowledgement for status update TASK_KILLED (UUID: 79f33226-c975-4b32-ad58-f24595766741) for task 1 of framework 20140430-054622-453759884-49560-8544-0000 to executor(1)@140.211.11.27:50921 I0430 05:46:24.920877 8571 master.cpp:2444] Status update TASK_KILLED (UUID: 79f33226-c975-4b32-ad58-f24595766741) for task 1 of framework 20140430-054622-453759884-49560-8544-0000 from slave(47)@140.211.11.27:49560 I0430 05:46:24.921614 8571 master.hpp:576] Removing task 1 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140430-054622-453759884-49560-8544-0 (hemera.apache.org) I0430 05:46:24.921973 8574 sched.cpp:620] Scheduler::statusUpdate took 284876ns I0430 05:46:24.924342 8577 hierarchical_allocator_process.hpp:637] Recovered cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (total allocatable: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]) on slave 20140430-054622-453759884-49560-8544-0 from framework 20140430-054622-453759884-49560-8544-0000 I0430 05:46:24.924448 8586 status_update_manager.cpp:398] Received status update acknowledgement (UUID: 79f33226-c975-4b32-ad58-f24595766741) for task 1 of framework 20140430-054622-453759884-49560-8544-0000 I0430 05:46:24.924674 8574 master.cpp:551] Master terminating I0430 05:46:24.924754 8586 status_update_manager.cpp:530] Cleaning up status update stream for task 1 of framework 20140430-054622-453759884-49560-8544-0000 I0430 05:46:24.924846 8582 sched.cpp:731] Stopping framework '20140430-054622-453759884-49560-8544-0000' I0430 05:46:24.925962 8571 slave.cpp:2151] [email protected]:49560 exited W0430 05:46:24.925990 8571 slave.cpp:2154] Master disconnected! Waiting for a new master to be elected I0430 05:46:24.928550 8583 slave.cpp:1538] Status update manager successfully handled status update acknowledgement (UUID: 79f33226-c975-4b32-ad58-f24595766741) for task 1 of framework 20140430-054622-453759884-49560-8544-0000 I0430 05:46:24.928638 8583 slave.cpp:3468] Completing task 1 I0430 05:46:24.938603 8587 external_containerizer.cpp:378] Wait triggered on container '0d79e54b-6d04-4dcd-9534-89f02effa414' I0430 05:46:24.939239 8587 external_containerizer.cpp:637] Destroy triggered on container '0d79e54b-6d04-4dcd-9534-89f02effa414' I0430 05:46:24.939314 8587 external_containerizer.cpp:396] Wait continuation triggered on container '0d79e54b-6d04-4dcd-9534-89f02effa414' I0430 05:46:24.939332 8587 external_containerizer.cpp:804] Invoking external containerizer for method 'wait' I0430 05:46:24.939348 8587 external_containerizer.cpp:818] user: jenkins I0430 05:46:24.954838 8587 external_containerizer.cpp:655] Destroy continuation on container '0d79e54b-6d04-4dcd-9534-89f02effa414' I0430 05:46:24.954872 8587 external_containerizer.cpp:804] Invoking external containerizer for method 'destroy' I0430 05:46:24.954884 8587 external_containerizer.cpp:818] user: jenkins I0430 05:46:25.643040 8578 external_containerizer.cpp:448] Wait callback triggered on container '0d79e54b-6d04-4dcd-9534-89f02effa414' E0430 05:46:25.644098 8581 slave.cpp:2324] Termination of executor '1' of framework '20140430-054622-453759884-49560-8544-0000' failed: External containerizer failed (status: 1) I0430 05:46:25.645514 8581 slave.cpp:2479] Cleaning up executor '1' of framework 20140430-054622-453759884-49560-8544-0000 I0430 05:46:25.647639 8581 slave.cpp:2554] Cleaning up framework 20140430-054622-453759884-49560-8544-0000 I0430 05:46:25.648021 8568 gc.cpp:56] Scheduling '/tmp/ExternalContainerizerTest_Launch_XWYTV0/slaves/20140430-054622-453759884-49560-8544-0/frameworks/20140430-054622-453759884-49560-8544-0000/executors/1/runs/0d79e54b-6d04-4dcd-9534-89f02effa414' for gc 6.99999251694222days in the future I0430 05:46:25.648206 8586 status_update_manager.cpp:282] Closing status update streams for framework 20140430-054622-453759884-49560-8544-0000 ../../src/tests/cluster.hpp:462: Failure (wait).failure(): External containerizer failed (status: 1) I0430 05:46:25.648211 8568 gc.cpp:56] Scheduling '/tmp/ExternalContainerizerTest_Launch_XWYTV0/slaves/20140430-054622-453759884-49560-8544-0/frameworks/20140430-054622-453759884-49560-8544-0000/executors/1' for gc 6.99999251295111days in the future I0430 05:46:25.648308 8568 gc.cpp:56] Scheduling '/tmp/ExternalContainerizerTest_Launch_XWYTV0/slaves/20140430-054622-453759884-49560-8544-0/frameworks/20140430-054622-453759884-49560-8544-0000' for gc 6.99999250096days in the future I0430 05:46:25.664010 8578 external_containerizer.cpp:762] Killed the following process tree/s: [ ] I0430 05:46:25.664049 8578 external_containerizer.cpp:717] Callback performing final cleanup of running state F0430 05:46:25.664049 8544 logging.cpp:55] RAW: Pure virtual method called @ 0x2b6d26c34a2d google::LogMessage::Fail() @ 0x2b6d26c3c59a google::RawLog__() @ 0x51dd59 mesos::internal::tests::Cluster::Slaves::shutdown() @ 0x7402f9 mesos::internal::tests::MesosTest::ShutdownSlaves() @ 0x8bf4bd testing::internal::HandleExceptionsInMethodIfSupported<>() @ 0x8b7aa3 testing::Test::Run() @ 0x8b7bd6 testing::TestInfo::Run() @ 0x8b7d17 testing::TestCase::Run() @ 0x8b807e testing::internal::UnitTestImpl::RunAllTests() @ 0x8bf03d testing::internal::HandleExceptionsInMethodIfSupported<>() @ 0x8b714e testing::UnitTest::Run() @ 0x4a5490 main @ 0x2b6d2808076d (unknown) @ 0x4b0891 (unknown) make[4]: *** [check-local] Aborted (core dumped) make[4]: Leaving directory `/x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/mesos-0.19.0/_build/src' make[3]: *** [check-am] Error 2 make[3]: Leaving directory `/x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/mesos-0.19.0/_build/src' make[2]: *** [check] Error 2 make[2]: Leaving directory `/x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/mesos-0.19.0/_build/src' make[1]: *** [check-recursive] Error 1 make[1]: Leaving directory `/x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/mesos-0.19.0/_build' make: *** [distcheck] Error 1 Build step 'Execute shell' marked build as failure
