See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/2101/changes>
Changes: [vinod] Used security group names to look up clusters instead of security group [bmahler] Fixed a flaky use of FUTURE_DISPATCH. [bmahler] Allowed timing of Future completions in Timer. ------------------------------------------ [...truncated 23546 lines...] I0430 17:34:47.697932 23809 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 15.779387ms I0430 17:34:47.697963 23809 replica.cpp:664] Persisted action at 0 I0430 17:34:47.697975 23809 replica.cpp:649] Replica learned NOP action at position 0 I0430 17:34:47.699738 23793 log.cpp:672] Writer started with ending position 0 I0430 17:34:47.701735 23815 leveldb.cpp:436] Reading position from leveldb took 11431ns I0430 17:34:47.704406 23794 registrar.cpp:308] Successfully recovered registrar I0430 17:34:47.704435 23794 registrar.cpp:379] Attempting to update the 'registry' I0430 17:34:47.706820 23814 log.cpp:680] Attempting to append 138 bytes to the log I0430 17:34:47.706949 23814 coordinator.cpp:339] Coordinator attempting to write APPEND action at position 1 I0430 17:34:47.707844 23800 replica.cpp:508] Replica received write request for position 1 I0430 17:34:47.722025 23800 leveldb.cpp:341] Persisting action (157 bytes) to leveldb took 14.1573ms I0430 17:34:47.722057 23800 replica.cpp:664] Persisted action at 1 I0430 17:34:47.722661 23802 replica.cpp:643] Replica received learned notice for position 1 I0430 17:34:47.738065 23802 leveldb.cpp:341] Persisting action (159 bytes) to leveldb took 15.187333ms I0430 17:34:47.738096 23802 replica.cpp:664] Persisted action at 1 I0430 17:34:47.738108 23802 replica.cpp:649] Replica learned APPEND action at position 1 I0430 17:34:47.738582 23813 registrar.cpp:427] Successfully updated 'registry' I0430 17:34:47.738726 23793 log.cpp:699] Attempting to truncate the log to 1 I0430 17:34:47.739212 23796 coordinator.cpp:339] Coordinator attempting to write TRUNCATE action at position 2 I0430 17:34:47.739686 23799 master.cpp:780] Recovered 0 slaves from the Registry (100B) ; allowing 10mins for slaves to re-register I0430 17:34:47.742480 23804 replica.cpp:508] Replica received write request for position 2 I0430 17:34:47.745002 23801 slave.cpp:140] Slave started on 68)@140.211.11.27:55610 I0430 17:34:47.745157 23801 credentials.hpp:35] Loading credentials for authentication W0430 17:34:47.745621 23801 credentials.hpp:48] Failed to stat credentials file 'file:///tmp/ExternalContainerizerTest_Launch_gUjOZ6/credential': No such file or directory I0430 17:34:47.745650 23801 slave.cpp:231] Slave using credential for: test-principal I0430 17:34:47.745751 23801 slave.cpp:244] Slave resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I0430 17:34:47.745826 23801 slave.cpp:272] Slave hostname: hemera.apache.org I0430 17:34:47.745836 23801 slave.cpp:273] Slave checkpoint: false I0430 17:34:47.746580 23806 state.cpp:33] Recovering state from '/tmp/ExternalContainerizerTest_Launch_gUjOZ6/meta' I0430 17:34:47.747006 23808 status_update_manager.cpp:193] Recovering status update manager I0430 17:34:47.747320 23815 slave.cpp:2943] Finished recovery I0430 17:34:47.748061 23810 slave.cpp:525] New master detected at [email protected]:55610 I0430 17:34:47.748155 23810 slave.cpp:585] Authenticating with master [email protected]:55610 I0430 17:34:47.748180 23803 status_update_manager.cpp:167] New master detected at [email protected]:55610 I0430 17:34:47.748216 23810 slave.cpp:558] Detecting new master I0430 17:34:47.748244 23797 authenticatee.hpp:128] Creating new client SASL connection I0430 17:34:47.748450 23803 master.cpp:2795] Authenticating slave(68)@140.211.11.27:55610 I0430 17:34:47.749156 23800 authenticator.hpp:148] Creating new server SASL connection I0430 17:34:47.749620 23795 authenticatee.hpp:219] Received SASL authentication mechanisms: CRAM-MD5 I0430 17:34:47.749706 23795 authenticatee.hpp:245] Attempting to authenticate with mechanism 'CRAM-MD5' I0430 17:34:47.749745 23795 authenticator.hpp:254] Received SASL authentication start I0430 17:34:47.749846 23795 authenticator.hpp:342] Authentication requires more steps I0430 17:34:47.750031 23795 authenticatee.hpp:265] Received SASL authentication step I0430 17:34:47.750159 23796 authenticator.hpp:282] Received SASL authentication step I0430 17:34:47.750186 23796 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 17:34:47.750195 23796 auxprop.cpp:153] Looking up auxiliary property '*userPassword' I0430 17:34:47.750206 23796 auxprop.cpp:153] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I0430 17:34:47.750216 23796 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 17:34:47.750222 23796 auxprop.cpp:103] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I0430 17:34:47.750228 23796 auxprop.cpp:103] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I0430 17:34:47.750241 23796 authenticator.hpp:334] Authentication success I0430 17:34:47.750278 23796 authenticatee.hpp:305] Authentication success I0430 17:34:47.750356 23814 master.cpp:2835] Successfully authenticated slave(68)@140.211.11.27:55610 I0430 17:34:47.750797 23793 slave.cpp:642] Successfully authenticated with master [email protected]:55610 I0430 17:34:47.750856 23793 slave.cpp:871] Will retry registration in 5.749806799secs if necessary I0430 17:34:47.751086 23808 registrar.cpp:379] Attempting to update the 'registry' I0430 17:34:47.752308 23773 sched.cpp:121] Version: 0.19.0 I0430 17:34:47.752837 23812 sched.cpp:217] New master detected at [email protected]:55610 I0430 17:34:47.752864 23812 sched.cpp:268] Authenticating with master [email protected]:55610 I0430 17:34:47.752943 23799 authenticatee.hpp:128] Creating new client SASL connection I0430 17:34:47.753176 23799 master.cpp:2795] Authenticating scheduler(78)@140.211.11.27:55610 I0430 17:34:47.753326 23797 authenticator.hpp:148] Creating new server SASL connection I0430 17:34:47.753536 23797 authenticatee.hpp:219] Received SASL authentication mechanisms: CRAM-MD5 I0430 17:34:47.753700 23797 authenticatee.hpp:245] Attempting to authenticate with mechanism 'CRAM-MD5' I0430 17:34:47.753880 23807 authenticator.hpp:254] Received SASL authentication start I0430 17:34:47.753983 23807 authenticator.hpp:342] Authentication requires more steps I0430 17:34:47.754142 23799 authenticatee.hpp:265] Received SASL authentication step I0430 17:34:47.754215 23799 authenticator.hpp:282] Received SASL authentication step I0430 17:34:47.754238 23799 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 17:34:47.754246 23799 auxprop.cpp:153] Looking up auxiliary property '*userPassword' I0430 17:34:47.754256 23799 auxprop.cpp:153] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I0430 17:34:47.754266 23799 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 17:34:47.754272 23799 auxprop.cpp:103] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I0430 17:34:47.754277 23799 auxprop.cpp:103] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I0430 17:34:47.754289 23799 authenticator.hpp:334] Authentication success I0430 17:34:47.754706 23807 master.cpp:2835] Successfully authenticated scheduler(78)@140.211.11.27:55610 I0430 17:34:47.754750 23795 authenticatee.hpp:305] Authentication success I0430 17:34:47.755344 23797 sched.cpp:342] Successfully authenticated with master [email protected]:55610 I0430 17:34:47.755367 23797 sched.cpp:461] Sending registration request to [email protected]:55610 I0430 17:34:47.755465 23800 master.cpp:981] Received registration request from scheduler(78)@140.211.11.27:55610 I0430 17:34:47.755707 23800 master.cpp:999] Registering framework 20140430-173447-453759884-55610-23773-0000 at scheduler(78)@140.211.11.27:55610 I0430 17:34:47.755936 23813 sched.cpp:392] Framework registered with 20140430-173447-453759884-55610-23773-0000 I0430 17:34:47.755985 23813 sched.cpp:406] Scheduler::registered took 25411ns I0430 17:34:47.756237 23807 hierarchical_allocator_process.hpp:332] Added framework 20140430-173447-453759884-55610-23773-0000 I0430 17:34:47.756259 23807 hierarchical_allocator_process.hpp:726] No resources available to allocate! I0430 17:34:47.756271 23807 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 13604ns I0430 17:34:47.758844 23804 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 16.340156ms I0430 17:34:47.758872 23804 replica.cpp:664] Persisted action at 2 I0430 17:34:47.759532 23813 replica.cpp:643] Replica received learned notice for position 2 I0430 17:34:47.774960 23813 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 15.404479ms I0430 17:34:47.775022 23813 leveldb.cpp:399] Deleting ~1 keys from leveldb took 34769ns I0430 17:34:47.775038 23813 replica.cpp:664] Persisted action at 2 I0430 17:34:47.775046 23813 replica.cpp:649] Replica learned TRUNCATE action at position 2 I0430 17:34:47.775553 23814 log.cpp:680] Attempting to append 332 bytes to the log I0430 17:34:47.776111 23801 coordinator.cpp:339] Coordinator attempting to write APPEND action at position 3 I0430 17:34:47.777572 23814 replica.cpp:508] Replica received write request for position 3 I0430 17:34:47.795042 23814 leveldb.cpp:341] Persisting action (351 bytes) to leveldb took 17.359961ms I0430 17:34:47.795071 23814 replica.cpp:664] Persisted action at 3 I0430 17:34:47.796146 23807 replica.cpp:643] Replica received learned notice for position 3 I0430 17:34:47.811107 23807 leveldb.cpp:341] Persisting action (353 bytes) to leveldb took 14.935802ms I0430 17:34:47.811139 23807 replica.cpp:664] Persisted action at 3 I0430 17:34:47.811151 23807 replica.cpp:649] Replica learned APPEND action at position 3 I0430 17:34:47.811533 23802 registrar.cpp:427] Successfully updated 'registry' I0430 17:34:47.811877 23813 log.cpp:699] Attempting to truncate the log to 3 I0430 17:34:47.812146 23800 coordinator.cpp:339] Coordinator attempting to write TRUNCATE action at position 4 I0430 17:34:47.812217 23807 master.cpp:2169] Admitted slave on hemera.apache.org at slave(68)@140.211.11.27:55610 I0430 17:34:47.812240 23807 master.cpp:3283] Adding slave 20140430-173447-453759884-55610-23773-0 at hemera.apache.org with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I0430 17:34:47.812356 23801 slave.cpp:675] Registered with master [email protected]:55610; given slave ID 20140430-173447-453759884-55610-23773-0 I0430 17:34:47.812572 23802 hierarchical_allocator_process.hpp:445] Added slave 20140430-173447-453759884-55610-23773-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 17:34:47.812644 23802 hierarchical_allocator_process.hpp:752] Offering cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140430-173447-453759884-55610-23773-0 to framework 20140430-173447-453759884-55610-23773-0000 I0430 17:34:47.813200 23802 hierarchical_allocator_process.hpp:708] Performed allocation for slave 20140430-173447-453759884-55610-23773-0 in 461307ns I0430 17:34:47.813387 23805 master.hpp:586] Adding offer 20140430-173447-453759884-55610-23773-0 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140430-173447-453759884-55610-23773-0 (hemera.apache.org) I0430 17:34:47.813451 23805 master.cpp:2744] Sending 1 offers to framework 20140430-173447-453759884-55610-23773-0000 I0430 17:34:47.813621 23805 sched.cpp:529] Scheduler::resourceOffers took 52681ns I0430 17:34:47.814942 23806 replica.cpp:508] Replica received write request for position 4 I0430 17:34:47.815641 23801 master.hpp:596] Removing offer 20140430-173447-453759884-55610-23773-0 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140430-173447-453759884-55610-23773-0 (hemera.apache.org) I0430 17:34:47.815709 23801 master.cpp:1806] Processing reply for offers: [ 20140430-173447-453759884-55610-23773-0 ] on slave 20140430-173447-453759884-55610-23773-0 (hemera.apache.org) for framework 20140430-173447-453759884-55610-23773-0000 I0430 17:34:47.815788 23801 master.hpp:558] Adding task 1 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140430-173447-453759884-55610-23773-0 (hemera.apache.org) I0430 17:34:47.815840 23801 master.cpp:2919] Launching task 1 of framework 20140430-173447-453759884-55610-23773-0000 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140430-173447-453759884-55610-23773-0 (hemera.apache.org) I0430 17:34:47.815979 23814 slave.cpp:905] Got assigned task 1 for framework 20140430-173447-453759884-55610-23773-0000 I0430 17:34:47.816241 23814 slave.cpp:1015] Launching task 1 for framework 20140430-173447-453759884-55610-23773-0000 I0430 17:34:47.818881 23814 slave.cpp:1125] Queuing task '1' for executor 1 of framework '20140430-173447-453759884-55610-23773-0000 I0430 17:34:47.818878 23799 external_containerizer.cpp:266] Launching container 'd7d27ddf-9f25-404f-bd4c-82ba6d562181' I0430 17:34:47.818953 23814 slave.cpp:486] Successfully attached file '/tmp/ExternalContainerizerTest_Launch_gUjOZ6/slaves/20140430-173447-453759884-55610-23773-0/frameworks/20140430-173447-453759884-55610-23773-0000/executors/1/runs/d7d27ddf-9f25-404f-bd4c-82ba6d562181' I0430 17:34:47.818992 23799 external_containerizer.cpp:804] Invoking external containerizer for method 'launch' I0430 17:34:47.819042 23799 external_containerizer.cpp:818] user: jenkins I0430 17:34:47.833279 23806 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 18.173754ms I0430 17:34:47.833389 23806 replica.cpp:664] Persisted action at 4 I0430 17:34:47.836529 23796 replica.cpp:643] Replica received learned notice for position 4 I0430 17:34:47.837968 23799 external_containerizer.cpp:378] Wait triggered on container 'd7d27ddf-9f25-404f-bd4c-82ba6d562181' I0430 17:34:47.851956 23796 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 15.394267ms I0430 17:34:47.852027 23796 leveldb.cpp:399] Deleting ~2 keys from leveldb took 37035ns I0430 17:34:47.852042 23796 replica.cpp:664] Persisted action at 4 I0430 17:34:47.852052 23796 replica.cpp:649] Replica learned TRUNCATE action at position 4 I0430 17:34:48.593334 23801 hierarchical_allocator_process.hpp:688] Performed allocation for 1 slaves in 176709ns I0430 17:34:49.594342 23796 hierarchical_allocator_process.hpp:688] Performed allocation for 1 slaves in 18749ns I0430 17:34:49.964293 23796 slave.cpp:1598] Got registration for executor '1' of framework 20140430-173447-453759884-55610-23773-0000 I0430 17:34:49.964897 23796 slave.cpp:1717] Flushing queued task 1 for executor '1' of framework 20140430-173447-453759884-55610-23773-0000 I0430 17:34:49.965246 23795 external_containerizer.cpp:476] Update triggered on container 'd7d27ddf-9f25-404f-bd4c-82ba6d562181' I0430 17:34:49.970732 23815 slave.cpp:1953] Handling status update TASK_RUNNING (UUID: d7ec60ad-35cf-40ed-b39e-e4b686daf0c1) for task 1 of framework 20140430-173447-453759884-55610-23773-0000 from executor(1)@140.211.11.27:46320 I0430 17:34:49.971582 23811 status_update_manager.cpp:320] Received status update TASK_RUNNING (UUID: d7ec60ad-35cf-40ed-b39e-e4b686daf0c1) for task 1 of framework 20140430-173447-453759884-55610-23773-0000 I0430 17:34:49.971606 23811 status_update_manager.cpp:499] Creating StatusUpdate stream for task 1 of framework 20140430-173447-453759884-55610-23773-0000 I0430 17:34:49.971721 23811 status_update_manager.cpp:373] Forwarding status update TASK_RUNNING (UUID: d7ec60ad-35cf-40ed-b39e-e4b686daf0c1) for task 1 of framework 20140430-173447-453759884-55610-23773-0000 to [email protected]:55610 I0430 17:34:49.971968 23815 master.cpp:2444] Status update TASK_RUNNING (UUID: d7ec60ad-35cf-40ed-b39e-e4b686daf0c1) for task 1 of framework 20140430-173447-453759884-55610-23773-0000 from slave(68)@140.211.11.27:55610 I0430 17:34:49.972627 23804 slave.cpp:2070] Status update manager successfully handled status update TASK_RUNNING (UUID: d7ec60ad-35cf-40ed-b39e-e4b686daf0c1) for task 1 of framework 20140430-173447-453759884-55610-23773-0000 I0430 17:34:49.972669 23804 slave.cpp:2076] Sending acknowledgement for status update TASK_RUNNING (UUID: d7ec60ad-35cf-40ed-b39e-e4b686daf0c1) for task 1 of framework 20140430-173447-453759884-55610-23773-0000 to executor(1)@140.211.11.27:46320 I0430 17:34:49.973084 23803 sched.cpp:620] Scheduler::statusUpdate took 279844ns I0430 17:34:49.973749 23795 status_update_manager.cpp:398] Received status update acknowledgement (UUID: d7ec60ad-35cf-40ed-b39e-e4b686daf0c1) for task 1 of framework 20140430-173447-453759884-55610-23773-0000 I0430 17:34:49.974501 23804 slave.cpp:1538] Status update manager successfully handled status update acknowledgement (UUID: d7ec60ad-35cf-40ed-b39e-e4b686daf0c1) for task 1 of framework 20140430-173447-453759884-55610-23773-0000 I0430 17:34:49.975860 23809 external_containerizer.cpp:550] Usage triggered on container 'd7d27ddf-9f25-404f-bd4c-82ba6d562181' I0430 17:34:50.594594 23803 hierarchical_allocator_process.hpp:688] Performed allocation for 1 slaves in 34763ns 2014-04-30 17:34:50,699:23773(0x2b27a943b700):ZOO_ERROR@handle_socket_error_msg@1697: Socket [127.0.0.1:34943] zk retcode=-4, errno=111(Connection refused): server refused to accept the client I0430 17:34:50.915688 23813 external_containerizer.cpp:341] Launch validation callback triggered on container 'd7d27ddf-9f25-404f-bd4c-82ba6d562181' I0430 17:34:50.915700 23813 external_containerizer.cpp:350] Launch finishing up for container 'd7d27ddf-9f25-404f-bd4c-82ba6d562181' I0430 17:34:50.916580 23813 external_containerizer.cpp:396] Wait continuation triggered on container 'd7d27ddf-9f25-404f-bd4c-82ba6d562181' I0430 17:34:50.916636 23813 external_containerizer.cpp:804] Invoking external containerizer for method 'wait' I0430 17:34:50.916642 23798 slave.cpp:2282] Monitoring executor '1' of framework '20140430-173447-453759884-55610-23773-0000' in container 'd7d27ddf-9f25-404f-bd4c-82ba6d562181' I0430 17:34:50.916647 23813 external_containerizer.cpp:818] user: jenkins I0430 17:34:50.933562 23813 external_containerizer.cpp:496] Update continuation triggered on container 'd7d27ddf-9f25-404f-bd4c-82ba6d562181' I0430 17:34:50.933627 23813 external_containerizer.cpp:804] Invoking external containerizer for method 'update' I0430 17:34:50.933640 23813 external_containerizer.cpp:818] user: jenkins I0430 17:34:50.947533 23813 external_containerizer.cpp:568] Usage continuation on container 'd7d27ddf-9f25-404f-bd4c-82ba6d562181' I0430 17:34:50.947564 23813 external_containerizer.cpp:804] Invoking external containerizer for method 'usage' I0430 17:34:50.947582 23813 external_containerizer.cpp:818] user: jenkins I0430 17:34:50.960595 23813 external_containerizer.cpp:364] Launch confirmation callback triggered on container 'd7d27ddf-9f25-404f-bd4c-82ba6d562181' I0430 17:34:51.595144 23803 hierarchical_allocator_process.hpp:688] Performed allocation for 1 slaves in 200297ns I0430 17:34:51.917400 23809 external_containerizer.cpp:532] Update callback triggered on container 'd7d27ddf-9f25-404f-bd4c-82ba6d562181' I0430 17:34:51.918601 23809 external_containerizer.cpp:611] Usage callback triggered on container 'd7d27ddf-9f25-404f-bd4c-82ba6d562181' I0430 17:34:51.921807 23796 master.cpp:1912] Asked to kill task 1 of framework 20140430-173447-453759884-55610-23773-0000 I0430 17:34:51.921900 23796 master.cpp:1995] Telling slave 20140430-173447-453759884-55610-23773-0 (hemera.apache.org) to kill task 1 of framework 20140430-173447-453759884-55610-23773-0000 I0430 17:34:51.922068 23812 external_containerizer.cpp:550] Usage triggered on container 'd7d27ddf-9f25-404f-bd4c-82ba6d562181' I0430 17:34:51.922088 23796 slave.cpp:1181] Asked to kill task 1 of framework 20140430-173447-453759884-55610-23773-0000 I0430 17:34:51.922320 23812 external_containerizer.cpp:568] Usage continuation on container 'd7d27ddf-9f25-404f-bd4c-82ba6d562181' I0430 17:34:51.922333 23812 external_containerizer.cpp:804] Invoking external containerizer for method 'usage' I0430 17:34:51.922430 23812 external_containerizer.cpp:818] user: jenkins I0430 17:34:52.593157 23799 master.cpp:104] No whitelist given. Advertising offers for all slaves I0430 17:34:52.596314 23804 hierarchical_allocator_process.hpp:688] Performed allocation for 1 slaves in 101623ns I0430 17:34:52.918819 23804 external_containerizer.cpp:611] Usage callback triggered on container 'd7d27ddf-9f25-404f-bd4c-82ba6d562181' I0430 17:34:52.973181 23794 slave.cpp:1953] Handling status update TASK_KILLED (UUID: 1eb2512e-cc68-43d9-978d-e8eb4b23d8c1) for task 1 of framework 20140430-173447-453759884-55610-23773-0000 from executor(1)@140.211.11.27:46320 I0430 17:34:52.973309 23794 slave.cpp:3444] Terminating task 1 I0430 17:34:52.975381 23814 external_containerizer.cpp:476] Update triggered on container 'd7d27ddf-9f25-404f-bd4c-82ba6d562181' I0430 17:34:52.975376 23803 status_update_manager.cpp:320] Received status update TASK_KILLED (UUID: 1eb2512e-cc68-43d9-978d-e8eb4b23d8c1) for task 1 of framework 20140430-173447-453759884-55610-23773-0000 I0430 17:34:52.975548 23814 external_containerizer.cpp:496] Update continuation triggered on container 'd7d27ddf-9f25-404f-bd4c-82ba6d562181' I0430 17:34:52.975594 23814 external_containerizer.cpp:804] Invoking external containerizer for method 'update' I0430 17:34:52.975594 23803 status_update_manager.cpp:373] Forwarding status update TASK_KILLED (UUID: 1eb2512e-cc68-43d9-978d-e8eb4b23d8c1) for task 1 of framework 20140430-173447-453759884-55610-23773-0000 to [email protected]:55610 I0430 17:34:52.975628 23814 external_containerizer.cpp:818] user: jenkins I0430 17:34:52.980075 23797 master.cpp:2444] Status update TASK_KILLED (UUID: 1eb2512e-cc68-43d9-978d-e8eb4b23d8c1) for task 1 of framework 20140430-173447-453759884-55610-23773-0000 from slave(68)@140.211.11.27:55610 I0430 17:34:52.980255 23800 slave.cpp:2070] Status update manager successfully handled status update TASK_KILLED (UUID: 1eb2512e-cc68-43d9-978d-e8eb4b23d8c1) for task 1 of framework 20140430-173447-453759884-55610-23773-0000 I0430 17:34:52.980366 23800 slave.cpp:2076] Sending acknowledgement for status update TASK_KILLED (UUID: 1eb2512e-cc68-43d9-978d-e8eb4b23d8c1) for task 1 of framework 20140430-173447-453759884-55610-23773-0000 to executor(1)@140.211.11.27:46320 I0430 17:34:52.981459 23797 master.hpp:576] Removing task 1 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140430-173447-453759884-55610-23773-0 (hemera.apache.org) I0430 17:34:52.981820 23805 sched.cpp:620] Scheduler::statusUpdate took 227997ns I0430 17:34:52.983749 23805 status_update_manager.cpp:398] Received status update acknowledgement (UUID: 1eb2512e-cc68-43d9-978d-e8eb4b23d8c1) for task 1 of framework 20140430-173447-453759884-55610-23773-0000 I0430 17:34:52.983983 23805 status_update_manager.cpp:530] Cleaning up status update stream for task 1 of framework 20140430-173447-453759884-55610-23773-0000 I0430 17:34:52.984702 23810 sched.cpp:731] Stopping framework '20140430-173447-453759884-55610-23773-0000' I0430 17:34:52.984524 23806 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-173447-453759884-55610-23773-0 from framework 20140430-173447-453759884-55610-23773-0000 I0430 17:34:52.984719 23773 master.cpp:551] Master terminating I0430 17:34:52.985010 23805 slave.cpp:1538] Status update manager successfully handled status update acknowledgement (UUID: 1eb2512e-cc68-43d9-978d-e8eb4b23d8c1) for task 1 of framework 20140430-173447-453759884-55610-23773-0000 I0430 17:34:52.985031 23805 slave.cpp:3468] Completing task 1 I0430 17:34:52.986140 23811 slave.cpp:2151] [email protected]:55610 exited W0430 17:34:52.986165 23811 slave.cpp:2154] Master disconnected! Waiting for a new master to be elected I0430 17:34:53.001719 23800 external_containerizer.cpp:378] Wait triggered on container 'd7d27ddf-9f25-404f-bd4c-82ba6d562181' I0430 17:34:53.001806 23800 external_containerizer.cpp:637] Destroy triggered on container 'd7d27ddf-9f25-404f-bd4c-82ba6d562181' I0430 17:34:53.001842 23800 external_containerizer.cpp:396] Wait continuation triggered on container 'd7d27ddf-9f25-404f-bd4c-82ba6d562181' I0430 17:34:53.001853 23800 external_containerizer.cpp:804] Invoking external containerizer for method 'wait' I0430 17:34:53.001863 23800 external_containerizer.cpp:818] user: jenkins I0430 17:34:53.017118 23800 external_containerizer.cpp:655] Destroy continuation on container 'd7d27ddf-9f25-404f-bd4c-82ba6d562181' I0430 17:34:53.017211 23800 external_containerizer.cpp:804] Invoking external containerizer for method 'destroy' I0430 17:34:53.017243 23800 external_containerizer.cpp:818] user: jenkins I0430 17:34:53.922402 23800 external_containerizer.cpp:550] Usage triggered on container 'd7d27ddf-9f25-404f-bd4c-82ba6d562181' I0430 17:34:53.923333 23800 external_containerizer.cpp:448] Wait callback triggered on container 'd7d27ddf-9f25-404f-bd4c-82ba6d562181' E0430 17:34:53.925525 23809 slave.cpp:2324] Termination of executor '1' of framework '20140430-173447-453759884-55610-23773-0000' failed: External containerizer failed (status: 1) I0430 17:34:53.927026 23809 slave.cpp:2479] Cleaning up executor '1' of framework 20140430-173447-453759884-55610-23773-0000 I0430 17:34:53.929193 23809 slave.cpp:2554] Cleaning up framework 20140430-173447-453759884-55610-23773-0000 I0430 17:34:53.930148 23801 gc.cpp:56] Scheduling '/tmp/ExternalContainerizerTest_Launch_gUjOZ6/slaves/20140430-173447-453759884-55610-23773-0/frameworks/20140430-173447-453759884-55610-23773-0000/executors/1/runs/d7d27ddf-9f25-404f-bd4c-82ba6d562181' for gc 6.99998926056889days in the future I0430 17:34:53.930593 23801 gc.cpp:56] Scheduling '/tmp/ExternalContainerizerTest_Launch_gUjOZ6/slaves/20140430-173447-453759884-55610-23773-0/frameworks/20140430-173447-453759884-55610-23773-0000/executors/1' for gc 6.99998925448days in the future I0430 17:34:53.930598 23803 status_update_manager.cpp:282] Closing status update streams for framework 20140430-173447-453759884-55610-23773-0000 I0430 17:34:53.930671 23801 gc.cpp:56] Scheduling '/tmp/ExternalContainerizerTest_Launch_gUjOZ6/slaves/20140430-173447-453759884-55610-23773-0/frameworks/20140430-173447-453759884-55610-23773-0000' for gc 6.99998924264889days in the future ../../src/tests/cluster.hpp:462: Failure (wait).failure(): External containerizer failed (status: 1) I0430 17:34:53.946579 23800 external_containerizer.cpp:762] Killed the following process tree/s: [ ] I0430 17:34:53.946650 23800 external_containerizer.cpp:717] Callback performing final cleanup of running state F0430 17:34:53.946650 23773 logging.cpp:55] RAW: Pure virtual method called @ 0x2b259137a9ad google::LogMessage::Fail() @ 0x2b259138251a google::RawLog__() @ 0x51e009 mesos::internal::tests::Cluster::Slaves::shutdown() @ 0x740519 mesos::internal::tests::MesosTest::ShutdownSlaves() @ 0x8bf70d testing::internal::HandleExceptionsInMethodIfSupported<>() @ 0x8b7cf3 testing::Test::Run() @ 0x8b7e26 testing::TestInfo::Run() @ 0x8b7f67 testing::TestCase::Run() @ 0x8b82ce testing::internal::UnitTestImpl::RunAllTests() @ 0x8bf28d testing::internal::HandleExceptionsInMethodIfSupported<>() @ 0x8b739e testing::UnitTest::Run() @ 0x4a5550 main @ 0x2b25927c676d (unknown) @ 0x4b0951 (unknown) make[4]: *** [check-local] Aborted (core dumped) make[4]: Leaving directory `/x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/build/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-Out-Of-Src-Set-JAVA_HOME/build/mesos-0.19.0/_build/src' make[2]: *** [check] Error 2 make[2]: Leaving directory `/x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/build/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-Out-Of-Src-Set-JAVA_HOME/build/mesos-0.19.0/_build' make: *** [distcheck] Error 1 Build step 'Execute shell' marked build as failure
