See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/1822/changes>
Changes: [idownes] Add tests/isolator.hpp to the distribution [niklas] Fixed infinite recursion in test containerizer. ------------------------------------------ [...truncated 7451 lines...] I0430 03:35:08.145576 6803 leveldb.cpp:436] Reading position from leveldb took 11321ns I0430 03:35:08.153414 6803 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 7.824323ms I0430 03:35:08.153432 6803 replica.cpp:664] Persisted action at 0 I0430 03:35:08.153657 6798 replica.cpp:643] Replica received learned notice for position 0 I0430 03:35:08.161741 6798 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 8.064793ms I0430 03:35:08.161756 6798 replica.cpp:664] Persisted action at 0 I0430 03:35:08.161767 6798 replica.cpp:649] Replica learned NOP action at position 0 I0430 03:35:08.162006 6805 log.cpp:672] Writer started with ending position 0 I0430 03:35:08.162435 6799 leveldb.cpp:436] Reading position from leveldb took 12882ns I0430 03:35:08.163938 6804 registrar.cpp:308] Successfully recovered registrar I0430 03:35:08.163960 6804 registrar.cpp:379] Attempting to update the 'registry' I0430 03:35:08.165436 6801 log.cpp:680] Attempting to append 136 bytes to the log I0430 03:35:08.165503 6802 coordinator.cpp:339] Coordinator attempting to write APPEND action at position 1 I0430 03:35:08.165807 6804 replica.cpp:508] Replica received write request for position 1 I0430 03:35:08.170066 6804 leveldb.cpp:341] Persisting action (155 bytes) to leveldb took 4.242735ms I0430 03:35:08.170084 6804 replica.cpp:664] Persisted action at 1 I0430 03:35:08.170323 6799 replica.cpp:643] Replica received learned notice for position 1 I0430 03:35:08.178401 6799 leveldb.cpp:341] Persisting action (157 bytes) to leveldb took 8.056105ms I0430 03:35:08.178421 6799 replica.cpp:664] Persisted action at 1 I0430 03:35:08.178429 6799 replica.cpp:649] Replica learned APPEND action at position 1 I0430 03:35:08.178748 6798 registrar.cpp:427] Successfully updated 'registry' I0430 03:35:08.178870 6798 master.cpp:780] Recovered 0 slaves from the Registry (98B) ; allowing 10mins for slaves to re-register I0430 03:35:08.178879 6803 log.cpp:699] Attempting to truncate the log to 1 I0430 03:35:08.178954 6803 coordinator.cpp:339] Coordinator attempting to write TRUNCATE action at position 2 I0430 03:35:08.179254 6803 replica.cpp:508] Replica received write request for position 2 I0430 03:35:08.193814 6803 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 14.544536ms I0430 03:35:08.193835 6803 replica.cpp:664] Persisted action at 2 I0430 03:35:08.194084 6801 replica.cpp:643] Replica received learned notice for position 2 I0430 03:35:08.194566 6802 slave.cpp:140] Slave started on 10)@67.195.138.61:33103 I0430 03:35:08.194581 6802 credentials.hpp:35] Loading credentials for authentication W0430 03:35:08.194625 6802 credentials.hpp:48] Failed to stat credentials file 'file:///tmp/ExternalContainerizerTest_Launch_gF2s74/credential': No such file or directory I0430 03:35:08.194644 6802 slave.cpp:231] Slave using credential for: test-principal I0430 03:35:08.194737 6802 slave.cpp:244] Slave resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I0430 03:35:08.194790 6802 slave.cpp:272] Slave hostname: juno.apache.org I0430 03:35:08.194802 6802 slave.cpp:273] Slave checkpoint: false I0430 03:35:08.195289 6802 state.cpp:33] Recovering state from '/tmp/ExternalContainerizerTest_Launch_gF2s74/meta' I0430 03:35:08.195456 6802 status_update_manager.cpp:193] Recovering status update manager I0430 03:35:08.195610 6802 slave.cpp:2943] Finished recovery I0430 03:35:08.195794 6802 slave.cpp:525] New master detected at [email protected]:33103 I0430 03:35:08.195812 6802 slave.cpp:585] Authenticating with master [email protected]:33103 I0430 03:35:08.195847 6802 slave.cpp:558] Detecting new master I0430 03:35:08.195878 6802 status_update_manager.cpp:167] New master detected at [email protected]:33103 I0430 03:35:08.195906 6802 authenticatee.hpp:128] Creating new client SASL connection I0430 03:35:08.196012 6802 master.cpp:2795] Authenticating slave(10)@67.195.138.61:33103 I0430 03:35:08.196076 6802 authenticator.hpp:148] Creating new server SASL connection I0430 03:35:08.196156 6802 authenticatee.hpp:219] Received SASL authentication mechanisms: CRAM-MD5 I0430 03:35:08.196171 6802 authenticatee.hpp:245] Attempting to authenticate with mechanism 'CRAM-MD5' I0430 03:35:08.196192 6802 authenticator.hpp:254] Received SASL authentication start I0430 03:35:08.196235 6802 authenticator.hpp:342] Authentication requires more steps I0430 03:35:08.196257 6802 authenticatee.hpp:265] Received SASL authentication step I0430 03:35:08.196286 6802 authenticator.hpp:282] Received SASL authentication step I0430 03:35:08.196301 6802 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 I0430 03:35:08.196310 6802 auxprop.cpp:153] Looking up auxiliary property '*userPassword' I0430 03:35:08.196318 6802 auxprop.cpp:153] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I0430 03:35:08.196327 6802 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 I0430 03:35:08.196333 6802 auxprop.cpp:103] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I0430 03:35:08.196338 6802 auxprop.cpp:103] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I0430 03:35:08.196349 6802 authenticator.hpp:334] Authentication success I0430 03:35:08.196418 6802 authenticatee.hpp:305] Authentication success I0430 03:35:08.196442 6805 master.cpp:2835] Successfully authenticated slave(10)@67.195.138.61:33103 I0430 03:35:08.196540 6798 slave.cpp:642] Successfully authenticated with master [email protected]:33103 I0430 03:35:08.196601 6798 slave.cpp:871] Will retry registration in 8.015642942secs if necessary I0430 03:35:08.196784 6803 registrar.cpp:379] Attempting to update the 'registry' I0430 03:35:08.196815 6455 sched.cpp:121] Version: 0.19.0 I0430 03:35:08.196949 6800 sched.cpp:217] New master detected at [email protected]:33103 I0430 03:35:08.196965 6800 sched.cpp:268] Authenticating with master [email protected]:33103 I0430 03:35:08.197031 6800 authenticatee.hpp:128] Creating new client SASL connection I0430 03:35:08.197129 6800 master.cpp:2795] Authenticating scheduler(10)@67.195.138.61:33103 I0430 03:35:08.197190 6800 authenticator.hpp:148] Creating new server SASL connection I0430 03:35:08.197263 6800 authenticatee.hpp:219] Received SASL authentication mechanisms: CRAM-MD5 I0430 03:35:08.197276 6800 authenticatee.hpp:245] Attempting to authenticate with mechanism 'CRAM-MD5' I0430 03:35:08.197296 6800 authenticator.hpp:254] Received SASL authentication start I0430 03:35:08.197327 6800 authenticator.hpp:342] Authentication requires more steps I0430 03:35:08.197346 6800 authenticatee.hpp:265] Received SASL authentication step I0430 03:35:08.197376 6800 authenticator.hpp:282] Received SASL authentication step I0430 03:35:08.197391 6800 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 I0430 03:35:08.197399 6800 auxprop.cpp:153] Looking up auxiliary property '*userPassword' I0430 03:35:08.197407 6800 auxprop.cpp:153] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I0430 03:35:08.197415 6800 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 I0430 03:35:08.197422 6800 auxprop.cpp:103] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I0430 03:35:08.197427 6800 auxprop.cpp:103] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I0430 03:35:08.197437 6800 authenticator.hpp:334] Authentication success I0430 03:35:08.197456 6800 authenticatee.hpp:305] Authentication success I0430 03:35:08.197474 6800 master.cpp:2835] Successfully authenticated scheduler(10)@67.195.138.61:33103 I0430 03:35:08.197523 6800 sched.cpp:342] Successfully authenticated with master [email protected]:33103 I0430 03:35:08.197535 6800 sched.cpp:461] Sending registration request to [email protected]:33103 I0430 03:35:08.197569 6800 master.cpp:981] Received registration request from scheduler(10)@67.195.138.61:33103 I0430 03:35:08.197592 6800 master.cpp:999] Registering framework 20140430-033508-1032504131-33103-6455-0000 at scheduler(10)@67.195.138.61:33103 I0430 03:35:08.197638 6800 sched.cpp:392] Framework registered with 20140430-033508-1032504131-33103-6455-0000 I0430 03:35:08.197666 6800 sched.cpp:406] Scheduler::registered took 18012ns I0430 03:35:08.197696 6800 hierarchical_allocator_process.hpp:332] Added framework 20140430-033508-1032504131-33103-6455-0000 I0430 03:35:08.197706 6800 hierarchical_allocator_process.hpp:726] No resources available to allocate! I0430 03:35:08.197713 6800 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 7140ns I0430 03:35:08.203392 6801 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 9.293589ms I0430 03:35:08.203420 6801 leveldb.cpp:399] Deleting ~1 keys from leveldb took 12306ns I0430 03:35:08.203429 6801 replica.cpp:664] Persisted action at 2 I0430 03:35:08.203436 6801 replica.cpp:649] Replica learned TRUNCATE action at position 2 I0430 03:35:08.203742 6800 log.cpp:680] Attempting to append 326 bytes to the log I0430 03:35:08.203814 6798 coordinator.cpp:339] Coordinator attempting to write APPEND action at position 3 I0430 03:35:08.204121 6805 replica.cpp:508] Replica received write request for position 3 I0430 03:35:08.211714 6805 leveldb.cpp:341] Persisting action (345 bytes) to leveldb took 7.577958ms I0430 03:35:08.211733 6805 replica.cpp:664] Persisted action at 3 I0430 03:35:08.212059 6799 replica.cpp:643] Replica received learned notice for position 3 I0430 03:35:08.220054 6799 leveldb.cpp:341] Persisting action (347 bytes) to leveldb took 7.976794ms I0430 03:35:08.220079 6799 replica.cpp:664] Persisted action at 3 I0430 03:35:08.220089 6799 replica.cpp:649] Replica learned APPEND action at position 3 I0430 03:35:08.220451 6801 registrar.cpp:427] Successfully updated 'registry' I0430 03:35:08.220562 6805 master.cpp:2169] Admitted slave on juno.apache.org at slave(10)@67.195.138.61:33103 I0430 03:35:08.220573 6798 log.cpp:699] Attempting to truncate the log to 3 I0430 03:35:08.220579 6805 master.cpp:3283] Adding slave 20140430-033508-1032504131-33103-6455-0 at juno.apache.org with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I0430 03:35:08.220664 6803 coordinator.cpp:339] Coordinator attempting to write TRUNCATE action at position 4 I0430 03:35:08.220688 6804 slave.cpp:675] Registered with master [email protected]:33103; given slave ID 20140430-033508-1032504131-33103-6455-0 I0430 03:35:08.220880 6801 hierarchical_allocator_process.hpp:445] Added slave 20140430-033508-1032504131-33103-6455-0 (juno.apache.org) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (and cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] available) I0430 03:35:08.220962 6801 hierarchical_allocator_process.hpp:752] Offering cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140430-033508-1032504131-33103-6455-0 to framework 20140430-033508-1032504131-33103-6455-0000 I0430 03:35:08.221007 6802 replica.cpp:508] Replica received write request for position 4 I0430 03:35:08.221118 6801 hierarchical_allocator_process.hpp:708] Performed allocation for slave 20140430-033508-1032504131-33103-6455-0 in 194431ns I0430 03:35:08.221184 6805 master.hpp:586] Adding offer 20140430-033508-1032504131-33103-6455-0 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140430-033508-1032504131-33103-6455-0 (juno.apache.org) I0430 03:35:08.221227 6805 master.cpp:2744] Sending 1 offers to framework 20140430-033508-1032504131-33103-6455-0000 I0430 03:35:08.221357 6803 sched.cpp:529] Scheduler::resourceOffers took 25997ns I0430 03:35:08.221817 6801 master.hpp:596] Removing offer 20140430-033508-1032504131-33103-6455-0 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140430-033508-1032504131-33103-6455-0 (juno.apache.org) I0430 03:35:08.221884 6801 master.cpp:1806] Processing reply for offers: [ 20140430-033508-1032504131-33103-6455-0 ] on slave 20140430-033508-1032504131-33103-6455-0 (juno.apache.org) for framework 20140430-033508-1032504131-33103-6455-0000 I0430 03:35:08.221976 6801 master.hpp:558] Adding task 1 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140430-033508-1032504131-33103-6455-0 (juno.apache.org) I0430 03:35:08.222034 6801 master.cpp:2919] Launching task 1 of framework 20140430-033508-1032504131-33103-6455-0000 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140430-033508-1032504131-33103-6455-0 (juno.apache.org) I0430 03:35:08.222132 6803 slave.cpp:905] Got assigned task 1 for framework 20140430-033508-1032504131-33103-6455-0000 I0430 03:35:08.222349 6803 slave.cpp:1015] Launching task 1 for framework 20140430-033508-1032504131-33103-6455-0000 I0430 03:35:08.228380 6802 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 7.355803ms I0430 03:35:08.228399 6802 replica.cpp:664] Persisted action at 4 I0430 03:35:08.228591 6802 replica.cpp:643] Replica received learned notice for position 4 I0430 03:35:08.239193 6803 slave.cpp:1125] Queuing task '1' for executor 1 of framework '20140430-033508-1032504131-33103-6455-0000 I0430 03:35:08.239194 6801 external_containerizer.cpp:266] Launching container '7d27cc84-ccc7-4412-95b7-5fbe9c3ef7c4' I0430 03:35:08.239249 6803 slave.cpp:486] Successfully attached file '/tmp/ExternalContainerizerTest_Launch_gF2s74/slaves/20140430-033508-1032504131-33103-6455-0/frameworks/20140430-033508-1032504131-33103-6455-0000/executors/1/runs/7d27cc84-ccc7-4412-95b7-5fbe9c3ef7c4' I0430 03:35:08.239292 6801 external_containerizer.cpp:804] Invoking external containerizer for method 'launch' I0430 03:35:08.239321 6801 external_containerizer.cpp:818] user: jenkins I0430 03:35:08.252161 6802 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 23.511159ms I0430 03:35:08.252207 6802 leveldb.cpp:399] Deleting ~2 keys from leveldb took 18773ns I0430 03:35:08.252218 6802 replica.cpp:664] Persisted action at 4 I0430 03:35:08.252225 6802 replica.cpp:649] Replica learned TRUNCATE action at position 4 I0430 03:35:08.293354 6801 external_containerizer.cpp:378] Wait triggered on container '7d27cc84-ccc7-4412-95b7-5fbe9c3ef7c4' I0430 03:35:09.088490 6799 hierarchical_allocator_process.hpp:688] Performed allocation for 1 slaves in 48685ns I0430 03:35:10.088695 6802 hierarchical_allocator_process.hpp:688] Performed allocation for 1 slaves in 22811ns I0430 03:35:11.034667 6800 slave.cpp:1598] Got registration for executor '1' of framework 20140430-033508-1032504131-33103-6455-0000 I0430 03:35:11.034878 6801 external_containerizer.cpp:476] Update triggered on container '7d27cc84-ccc7-4412-95b7-5fbe9c3ef7c4' I0430 03:35:11.034997 6800 slave.cpp:1717] Flushing queued task 1 for executor '1' of framework 20140430-033508-1032504131-33103-6455-0000 I0430 03:35:11.038748 6802 slave.cpp:1953] Handling status update TASK_RUNNING (UUID: a788d41f-09e3-404c-8823-43ce07c2be94) for task 1 of framework 20140430-033508-1032504131-33103-6455-0000 from executor(1)@67.195.138.61:44420 I0430 03:35:11.038930 6798 status_update_manager.cpp:320] Received status update TASK_RUNNING (UUID: a788d41f-09e3-404c-8823-43ce07c2be94) for task 1 of framework 20140430-033508-1032504131-33103-6455-0000 I0430 03:35:11.038955 6798 status_update_manager.cpp:499] Creating StatusUpdate stream for task 1 of framework 20140430-033508-1032504131-33103-6455-0000 I0430 03:35:11.039053 6798 status_update_manager.cpp:373] Forwarding status update TASK_RUNNING (UUID: a788d41f-09e3-404c-8823-43ce07c2be94) for task 1 of framework 20140430-033508-1032504131-33103-6455-0000 to [email protected]:33103 I0430 03:35:11.039222 6802 master.cpp:2444] Status update TASK_RUNNING (UUID: a788d41f-09e3-404c-8823-43ce07c2be94) for task 1 of framework 20140430-033508-1032504131-33103-6455-0000 from slave(10)@67.195.138.61:33103 I0430 03:35:11.039240 6805 slave.cpp:2070] Status update manager successfully handled status update TASK_RUNNING (UUID: a788d41f-09e3-404c-8823-43ce07c2be94) for task 1 of framework 20140430-033508-1032504131-33103-6455-0000 I0430 03:35:11.039273 6805 slave.cpp:2076] Sending acknowledgement for status update TASK_RUNNING (UUID: a788d41f-09e3-404c-8823-43ce07c2be94) for task 1 of framework 20140430-033508-1032504131-33103-6455-0000 to executor(1)@67.195.138.61:44420 I0430 03:35:11.039336 6799 sched.cpp:620] Scheduler::statusUpdate took 46078ns I0430 03:35:11.039671 6803 external_containerizer.cpp:550] Usage triggered on container '7d27cc84-ccc7-4412-95b7-5fbe9c3ef7c4' I0430 03:35:11.039813 6804 status_update_manager.cpp:398] Received status update acknowledgement (UUID: a788d41f-09e3-404c-8823-43ce07c2be94) for task 1 of framework 20140430-033508-1032504131-33103-6455-0000 I0430 03:35:11.039876 6804 slave.cpp:1538] Status update manager successfully handled status update acknowledgement (UUID: a788d41f-09e3-404c-8823-43ce07c2be94) for task 1 of framework 20140430-033508-1032504131-33103-6455-0000 I0430 03:35:11.089047 6799 hierarchical_allocator_process.hpp:688] Performed allocation for 1 slaves in 28763ns I0430 03:35:11.354437 6805 external_containerizer.cpp:341] Launch validation callback triggered on container '7d27cc84-ccc7-4412-95b7-5fbe9c3ef7c4' I0430 03:35:11.354462 6805 external_containerizer.cpp:350] Launch finishing up for container '7d27cc84-ccc7-4412-95b7-5fbe9c3ef7c4' I0430 03:35:11.354660 6805 external_containerizer.cpp:396] Wait continuation triggered on container '7d27cc84-ccc7-4412-95b7-5fbe9c3ef7c4' I0430 03:35:11.354665 6800 slave.cpp:2282] Monitoring executor '1' of framework '20140430-033508-1032504131-33103-6455-0000' in container '7d27cc84-ccc7-4412-95b7-5fbe9c3ef7c4' I0430 03:35:11.354677 6805 external_containerizer.cpp:804] Invoking external containerizer for method 'wait' I0430 03:35:11.354691 6805 external_containerizer.cpp:818] user: jenkins I0430 03:35:11.373183 6805 external_containerizer.cpp:496] Update continuation triggered on container '7d27cc84-ccc7-4412-95b7-5fbe9c3ef7c4' I0430 03:35:11.373339 6805 external_containerizer.cpp:804] Invoking external containerizer for method 'update' I0430 03:35:11.373414 6805 external_containerizer.cpp:818] user: jenkins I0430 03:35:11.389556 6805 external_containerizer.cpp:568] Usage continuation on container '7d27cc84-ccc7-4412-95b7-5fbe9c3ef7c4' I0430 03:35:11.389598 6805 external_containerizer.cpp:804] Invoking external containerizer for method 'usage' I0430 03:35:11.389614 6805 external_containerizer.cpp:818] user: jenkins I0430 03:35:11.405973 6805 external_containerizer.cpp:364] Launch confirmation callback triggered on container '7d27cc84-ccc7-4412-95b7-5fbe9c3ef7c4' I0430 03:35:12.090071 6799 hierarchical_allocator_process.hpp:688] Performed allocation for 1 slaves in 45421ns I0430 03:35:12.354639 6802 external_containerizer.cpp:532] Update callback triggered on container '7d27cc84-ccc7-4412-95b7-5fbe9c3ef7c4' I0430 03:35:12.354838 6801 external_containerizer.cpp:611] Usage callback triggered on container '7d27cc84-ccc7-4412-95b7-5fbe9c3ef7c4' I0430 03:35:12.355195 6800 master.cpp:1912] Asked to kill task 1 of framework 20140430-033508-1032504131-33103-6455-0000 I0430 03:35:12.355226 6800 master.cpp:1995] Telling slave 20140430-033508-1032504131-33103-6455-0 (juno.apache.org) to kill task 1 of framework 20140430-033508-1032504131-33103-6455-0000 I0430 03:35:12.355260 6800 slave.cpp:1181] Asked to kill task 1 of framework 20140430-033508-1032504131-33103-6455-0000 I0430 03:35:12.355448 6800 external_containerizer.cpp:550] Usage triggered on container '7d27cc84-ccc7-4412-95b7-5fbe9c3ef7c4' I0430 03:35:12.355506 6800 external_containerizer.cpp:568] Usage continuation on container '7d27cc84-ccc7-4412-95b7-5fbe9c3ef7c4' I0430 03:35:12.355518 6800 external_containerizer.cpp:804] Invoking external containerizer for method 'usage' I0430 03:35:12.355531 6800 external_containerizer.cpp:818] user: jenkins I0430 03:35:13.040079 6804 slave.cpp:1953] Handling status update TASK_KILLED (UUID: 75ab6e27-e703-4ad4-ab08-b1621447727f) for task 1 of framework 20140430-033508-1032504131-33103-6455-0000 from executor(1)@67.195.138.61:44420 I0430 03:35:13.040150 6804 slave.cpp:3444] Terminating task 1 I0430 03:35:13.040305 6803 external_containerizer.cpp:476] Update triggered on container '7d27cc84-ccc7-4412-95b7-5fbe9c3ef7c4' I0430 03:35:13.040366 6804 status_update_manager.cpp:320] Received status update TASK_KILLED (UUID: 75ab6e27-e703-4ad4-ab08-b1621447727f) for task 1 of framework 20140430-033508-1032504131-33103-6455-0000 I0430 03:35:13.040400 6803 external_containerizer.cpp:496] Update continuation triggered on container '7d27cc84-ccc7-4412-95b7-5fbe9c3ef7c4' I0430 03:35:13.040402 6804 status_update_manager.cpp:373] Forwarding status update TASK_KILLED (UUID: 75ab6e27-e703-4ad4-ab08-b1621447727f) for task 1 of framework 20140430-033508-1032504131-33103-6455-0000 to [email protected]:33103 I0430 03:35:13.040432 6803 external_containerizer.cpp:804] Invoking external containerizer for method 'update' I0430 03:35:13.040449 6803 external_containerizer.cpp:818] user: jenkins I0430 03:35:13.043001 6804 master.cpp:2444] Status update TASK_KILLED (UUID: 75ab6e27-e703-4ad4-ab08-b1621447727f) for task 1 of framework 20140430-033508-1032504131-33103-6455-0000 from slave(10)@67.195.138.61:33103 I0430 03:35:13.043169 6805 slave.cpp:2070] Status update manager successfully handled status update TASK_KILLED (UUID: 75ab6e27-e703-4ad4-ab08-b1621447727f) for task 1 of framework 20140430-033508-1032504131-33103-6455-0000 I0430 03:35:13.043218 6805 slave.cpp:2076] Sending acknowledgement for status update TASK_KILLED (UUID: 75ab6e27-e703-4ad4-ab08-b1621447727f) for task 1 of framework 20140430-033508-1032504131-33103-6455-0000 to executor(1)@67.195.138.61:44420 I0430 03:35:13.043292 6802 sched.cpp:620] Scheduler::statusUpdate took 87758ns I0430 03:35:13.043370 6804 master.hpp:576] Removing task 1 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140430-033508-1032504131-33103-6455-0 (juno.apache.org) I0430 03:35:13.043633 6801 status_update_manager.cpp:398] Received status update acknowledgement (UUID: 75ab6e27-e703-4ad4-ab08-b1621447727f) for task 1 of framework 20140430-033508-1032504131-33103-6455-0000 I0430 03:35:13.043678 6805 sched.cpp:731] Stopping framework '20140430-033508-1032504131-33103-6455-0000' I0430 03:35:13.043689 6801 status_update_manager.cpp:530] Cleaning up status update stream for task 1 of framework 20140430-033508-1032504131-33103-6455-0000 I0430 03:35:13.043752 6455 master.cpp:551] Master terminating I0430 03:35:13.043968 6805 slave.cpp:1538] Status update manager successfully handled status update acknowledgement (UUID: 75ab6e27-e703-4ad4-ab08-b1621447727f) for task 1 of framework 20140430-033508-1032504131-33103-6455-0000 I0430 03:35:13.043993 6805 slave.cpp:3468] Completing task 1 I0430 03:35:13.044086 6804 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-033508-1032504131-33103-6455-0 from framework 20140430-033508-1032504131-33103-6455-0000 I0430 03:35:13.044208 6802 slave.cpp:2151] [email protected]:33103 exited W0430 03:35:13.044225 6802 slave.cpp:2154] Master disconnected! Waiting for a new master to be elected I0430 03:35:13.059080 6803 external_containerizer.cpp:378] Wait triggered on container '7d27cc84-ccc7-4412-95b7-5fbe9c3ef7c4' I0430 03:35:13.059350 6803 external_containerizer.cpp:637] Destroy triggered on container '7d27cc84-ccc7-4412-95b7-5fbe9c3ef7c4' I0430 03:35:13.059450 6803 external_containerizer.cpp:396] Wait continuation triggered on container '7d27cc84-ccc7-4412-95b7-5fbe9c3ef7c4' I0430 03:35:13.059525 6803 external_containerizer.cpp:804] Invoking external containerizer for method 'wait' I0430 03:35:13.059603 6803 external_containerizer.cpp:818] user: jenkins I0430 03:35:13.076997 6803 external_containerizer.cpp:655] Destroy continuation on container '7d27cc84-ccc7-4412-95b7-5fbe9c3ef7c4' I0430 03:35:13.077033 6803 external_containerizer.cpp:804] Invoking external containerizer for method 'destroy' I0430 03:35:13.077049 6803 external_containerizer.cpp:818] user: jenkins I0430 03:35:13.355154 6798 external_containerizer.cpp:448] Wait callback triggered on container '7d27cc84-ccc7-4412-95b7-5fbe9c3ef7c4' E0430 03:35:13.355378 6801 slave.cpp:2324] Termination of executor '1' of framework '20140430-033508-1032504131-33103-6455-0000' failed: External containerizer failed (status: 1) I0430 03:35:13.355444 6801 slave.cpp:2479] Cleaning up executor '1' of framework 20140430-033508-1032504131-33103-6455-0000 I0430 03:35:13.355623 6801 slave.cpp:2554] Cleaning up framework 20140430-033508-1032504131-33103-6455-0000 ../../src/tests/cluster.hpp:462: Failure (wait).failure(): External containerizer failed (status: 1) I0430 03:35:13.355834 6801 status_update_manager.cpp:282] Closing status update streams for framework 20140430-033508-1032504131-33103-6455-0000 I0430 03:35:13.355620 6803 gc.cpp:56] Scheduling '/tmp/ExternalContainerizerTest_Launch_gF2s74/slaves/20140430-033508-1032504131-33103-6455-0/frameworks/20140430-033508-1032504131-33103-6455-0000/executors/1/runs/7d27cc84-ccc7-4412-95b7-5fbe9c3ef7c4' for gc 6.99999588512593days in the future I0430 03:35:13.355949 6803 gc.cpp:56] Scheduling '/tmp/ExternalContainerizerTest_Launch_gF2s74/slaves/20140430-033508-1032504131-33103-6455-0/frameworks/20140430-033508-1032504131-33103-6455-0000/executors/1' for gc 6.99999588424593days in the future I0430 03:35:13.356034 6803 gc.cpp:56] Scheduling '/tmp/ExternalContainerizerTest_Launch_gF2s74/slaves/20140430-033508-1032504131-33103-6455-0/frameworks/20140430-033508-1032504131-33103-6455-0000' for gc 6.99999588227852days in the future I0430 03:35:13.366230 6798 external_containerizer.cpp:762] Killed the following process tree/s: [ ] I0430 03:35:13.366252 6798 external_containerizer.cpp:717] Callback performing final cleanup of running state F0430 03:35:13.366252 6455 logging.cpp:55] RAW: Pure virtual method called I0430 03:35:14.040588 6806 process.cpp:1013] Socket closed while receiving @ 0x2b581683f9ed google::LogMessage::Fail() @ 0x2b581684755a google::RawLog__() @ 0x51cfe9 mesos::internal::tests::Cluster::Slaves::shutdown() @ 0x739739 mesos::internal::tests::MesosTest::ShutdownSlaves() @ 0x8b648d testing::internal::HandleExceptionsInMethodIfSupported<>() @ 0x8aea73 testing::Test::Run() @ 0x8aeba6 testing::TestInfo::Run() @ 0x8aece7 testing::TestCase::Run() @ 0x8af04e testing::internal::UnitTestImpl::RunAllTests() @ 0x8b600d testing::internal::HandleExceptionsInMethodIfSupported<>() @ 0x8ae11e testing::UnitTest::Run() @ 0x4a43e0 main @ 0x2b5817c8c76d (unknown) @ 0x4af7d1 (unknown) make[4]: *** [check-local] Aborted make[4]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/ws/mesos-0.19.0/_build/src'> make[3]: *** [check-am] Error 2 make[3]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/ws/mesos-0.19.0/_build/src'> make[2]: *** [check] Error 2 make[2]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/ws/mesos-0.19.0/_build/src'> make[1]: *** [check-recursive] Error 1 make[1]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/ws/mesos-0.19.0/_build'> make: *** [distcheck] Error 1 Build step 'Execute shell' marked build as failure
