See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/2076/changes>
Changes: [yan] Fixed an incorrect CHECK in Group. ------------------------------------------ [...truncated 39913 lines...] I0420 23:22:01.202941 9371 replica.cpp:508] Replica received write request for position 2 I0420 23:22:01.203482 9329 containerizer.cpp:177] Using isolation: posix/cpu,posix/mem I0420 23:22:01.207036 9373 slave.cpp:129] Slave started on 143)@140.211.11.27:55410 I0420 23:22:01.207175 9373 slave.cpp:217] Slave resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I0420 23:22:01.207341 9373 slave.cpp:245] Slave hostname: hemera.apache.org I0420 23:22:01.207363 9373 slave.cpp:246] Slave checkpoint: true I0420 23:22:01.208019 9373 state.cpp:33] Recovering state from '/tmp/SlaveRecoveryTest_0_RemoveNonCheckpointingFramework_lk36kH/meta' I0420 23:22:01.208195 9373 status_update_manager.cpp:193] Recovering status update manager I0420 23:22:01.208289 9373 mesos_containerizer.cpp:171] Recovering containerizer I0420 23:22:01.208663 9373 slave.cpp:2791] Finished recovery I0420 23:22:01.210376 9355 slave.cpp:496] New master detected at [email protected]:55410 I0420 23:22:01.210459 9373 status_update_manager.cpp:167] New master detected at [email protected]:55410 I0420 23:22:01.210486 9355 slave.cpp:521] Detecting new master I0420 23:22:01.210721 9355 registrar.cpp:314] Attempting to update the 'registry' I0420 23:22:01.211155 9329 sched.cpp:121] Version: 0.19.0 I0420 23:22:01.211359 9373 sched.cpp:217] New master detected at [email protected]:55410 I0420 23:22:01.211387 9373 sched.cpp:268] Authenticating with master [email protected]:55410 I0420 23:22:01.211513 9365 authenticatee.hpp:128] Creating new client SASL connection I0420 23:22:01.211695 9365 master.cpp:2715] Authenticating framework at scheduler(136)@140.211.11.27:55410 I0420 23:22:01.211843 9373 authenticator.hpp:148] Creating new server SASL connection I0420 23:22:01.212012 9373 authenticatee.hpp:219] Received SASL authentication mechanisms: CRAM-MD5 I0420 23:22:01.212038 9373 authenticatee.hpp:245] Attempting to authenticate with mechanism 'CRAM-MD5' I0420 23:22:01.212081 9373 authenticator.hpp:254] Received SASL authentication start I0420 23:22:01.212173 9373 authenticator.hpp:342] Authentication requires more steps I0420 23:22:01.214223 9371 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 11.246188ms I0420 23:22:01.214273 9371 replica.cpp:664] Persisted action at 2 I0420 23:22:01.217913 9359 authenticatee.hpp:265] Received SASL authentication step I0420 23:22:01.218015 9359 authenticator.hpp:282] Received SASL authentication step I0420 23:22:01.218045 9359 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 I0420 23:22:01.218060 9359 auxprop.cpp:153] Looking up auxiliary property '*userPassword' I0420 23:22:01.218077 9359 auxprop.cpp:153] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I0420 23:22:01.218092 9359 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 I0420 23:22:01.218104 9359 auxprop.cpp:103] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I0420 23:22:01.218113 9359 auxprop.cpp:103] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I0420 23:22:01.218133 9359 authenticator.hpp:334] Authentication success I0420 23:22:01.218183 9359 authenticatee.hpp:305] Authentication success I0420 23:22:01.218222 9359 master.cpp:2755] Successfully authenticated framework at scheduler(136)@140.211.11.27:55410 I0420 23:22:01.218358 9359 sched.cpp:342] Successfully authenticated with master [email protected]:55410 I0420 23:22:01.218377 9359 sched.cpp:461] Sending registration request to [email protected]:55410 I0420 23:22:01.218454 9359 master.cpp:997] Received registration request from scheduler(136)@140.211.11.27:55410 I0420 23:22:01.218502 9359 master.cpp:1015] Registering framework 20140420-232201-453759884-55410-9329-0000 at scheduler(136)@140.211.11.27:55410 I0420 23:22:01.218601 9359 sched.cpp:392] Framework registered with 20140420-232201-453759884-55410-9329-0000 I0420 23:22:01.218632 9359 sched.cpp:406] Scheduler::registered took 14553ns I0420 23:22:01.218684 9359 hierarchical_allocator_process.hpp:332] Added framework 20140420-232201-453759884-55410-9329-0000 I0420 23:22:01.218698 9359 hierarchical_allocator_process.hpp:726] No resources available to allocate! I0420 23:22:01.218708 9359 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 10745ns I0420 23:22:01.219423 9361 replica.cpp:643] Replica received learned notice for position 2 I0420 23:22:01.230386 9361 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 10.920499ms I0420 23:22:01.230499 9361 leveldb.cpp:399] Deleting ~1 keys from leveldb took 51903ns I0420 23:22:01.230521 9361 replica.cpp:664] Persisted action at 2 I0420 23:22:01.230538 9361 replica.cpp:649] Replica learned TRUNCATE action at position 2 I0420 23:22:01.231210 9361 log.cpp:663] Attempting to append 330 bytes to the log I0420 23:22:01.231407 9361 coordinator.cpp:339] Coordinator attempting to write APPEND action at position 3 I0420 23:22:01.231681 9361 replica.cpp:508] Replica received write request for position 3 I0420 23:22:01.242457 9361 leveldb.cpp:341] Persisting action (349 bytes) to leveldb took 10.722373ms I0420 23:22:01.242516 9361 replica.cpp:664] Persisted action at 3 I0420 23:22:01.242812 9361 replica.cpp:643] Replica received learned notice for position 3 I0420 23:22:01.254533 9361 leveldb.cpp:341] Persisting action (351 bytes) to leveldb took 11.695244ms I0420 23:22:01.254580 9361 replica.cpp:664] Persisted action at 3 I0420 23:22:01.254590 9361 replica.cpp:649] Replica learned APPEND action at position 3 I0420 23:22:01.254927 9363 registrar.cpp:356] Successfully updated 'registry' I0420 23:22:01.255056 9368 log.cpp:682] Attempting to truncate the log to 3 I0420 23:22:01.255060 9367 master.cpp:2150] Admitted slave on hemera.apache.org at slave(143)@140.211.11.27:55410 I0420 23:22:01.255079 9367 master.cpp:3204] Adding slave 20140420-232201-453759884-55410-9329-0 at hemera.apache.org with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I0420 23:22:01.255175 9369 coordinator.cpp:339] Coordinator attempting to write TRUNCATE action at position 4 I0420 23:22:01.255702 9358 hierarchical_allocator_process.hpp:445] Added slave 20140420-232201-453759884-55410-9329-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) I0420 23:22:01.255812 9358 hierarchical_allocator_process.hpp:752] Offering cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140420-232201-453759884-55410-9329-0 to framework 20140420-232201-453759884-55410-9329-0000 I0420 23:22:01.255903 9361 replica.cpp:508] Replica received write request for position 4 I0420 23:22:01.255888 9354 slave.cpp:539] Registered with master [email protected]:55410; given slave ID 20140420-232201-453759884-55410-9329-0 I0420 23:22:01.255929 9358 hierarchical_allocator_process.hpp:708] Performed allocation for slave 20140420-232201-453759884-55410-9329-0 in 187233ns I0420 23:22:01.255997 9371 master.hpp:524] Adding offer 20140420-232201-453759884-55410-9329-0 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140420-232201-453759884-55410-9329-0 (hemera.apache.org) I0420 23:22:01.256067 9354 slave.cpp:552] Checkpointing SlaveInfo to '/tmp/SlaveRecoveryTest_0_RemoveNonCheckpointingFramework_lk36kH/meta/slaves/20140420-232201-453759884-55410-9329-0/slave.info' I0420 23:22:01.256089 9371 master.cpp:2676] Sending 1 offers to framework 20140420-232201-453759884-55410-9329-0000 I0420 23:22:01.256330 9371 sched.cpp:529] Scheduler::resourceOffers took 111222ns W0420 23:22:01.259619 9363 sched.cpp:902] Attempting to launch task 61c3f349-ba84-4ba3-b270-61d9b4b29c44 with an unknown offer 20140420-232201-453759884-55410-9329-0 I0420 23:22:01.259794 9369 master.hpp:534] Removing offer 20140420-232201-453759884-55410-9329-0 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140420-232201-453759884-55410-9329-0 (hemera.apache.org) I0420 23:22:01.259838 9369 master.cpp:1815] Processing reply for offers: [ 20140420-232201-453759884-55410-9329-0 ] on slave 20140420-232201-453759884-55410-9329-0 (hemera.apache.org) for framework 20140420-232201-453759884-55410-9329-0000 I0420 23:22:01.259874 9369 master.hpp:496] Adding task 00f7dd55-27d8-484b-8155-dc35dae1bab6 with resources cpus(*):1; mem(*):512 on slave 20140420-232201-453759884-55410-9329-0 (hemera.apache.org) I0420 23:22:01.259907 9369 master.cpp:2839] Launching task 00f7dd55-27d8-484b-8155-dc35dae1bab6 of framework 20140420-232201-453759884-55410-9329-0000 with resources cpus(*):1; mem(*):512 on slave 20140420-232201-453759884-55410-9329-0 (hemera.apache.org) I0420 23:22:01.259974 9369 master.hpp:496] Adding task 61c3f349-ba84-4ba3-b270-61d9b4b29c44 with resources cpus(*):1; mem(*):512 on slave 20140420-232201-453759884-55410-9329-0 (hemera.apache.org) I0420 23:22:01.259984 9364 slave.cpp:754] Got assigned task 00f7dd55-27d8-484b-8155-dc35dae1bab6 for framework 20140420-232201-453759884-55410-9329-0000 I0420 23:22:01.259996 9369 master.cpp:2839] Launching task 61c3f349-ba84-4ba3-b270-61d9b4b29c44 of framework 20140420-232201-453759884-55410-9329-0000 with resources cpus(*):1; mem(*):512 on slave 20140420-232201-453759884-55410-9329-0 (hemera.apache.org) I0420 23:22:01.260088 9366 hierarchical_allocator_process.hpp:547] Framework 20140420-232201-453759884-55410-9329-0000 left disk(*):1024; ports(*):[31000-32000] unused on slave 20140420-232201-453759884-55410-9329-0 I0420 23:22:01.260159 9366 hierarchical_allocator_process.hpp:590] Framework 20140420-232201-453759884-55410-9329-0000 filtered slave 20140420-232201-453759884-55410-9329-0 for 5secs I0420 23:22:01.260202 9364 slave.cpp:754] Got assigned task 61c3f349-ba84-4ba3-b270-61d9b4b29c44 for framework 20140420-232201-453759884-55410-9329-0000 I0420 23:22:01.260300 9364 slave.cpp:863] Launching task 00f7dd55-27d8-484b-8155-dc35dae1bab6 for framework 20140420-232201-453759884-55410-9329-0000 I0420 23:22:01.262119 9364 slave.cpp:973] Queuing task '00f7dd55-27d8-484b-8155-dc35dae1bab6' for executor 00f7dd55-27d8-484b-8155-dc35dae1bab6 of framework '20140420-232201-453759884-55410-9329-0000 I0420 23:22:01.262249 9371 mesos_containerizer.cpp:385] Starting container '89a203ed-b577-43d0-894b-908cf7aba57e' for executor '00f7dd55-27d8-484b-8155-dc35dae1bab6' of framework '20140420-232201-453759884-55410-9329-0000' I0420 23:22:01.262266 9364 slave.cpp:863] Launching task 61c3f349-ba84-4ba3-b270-61d9b4b29c44 for framework 20140420-232201-453759884-55410-9329-0000 I0420 23:22:01.262608 9371 mesos_containerizer.cpp:507] Fetching URIs for container '89a203ed-b577-43d0-894b-908cf7aba57e' using command '/x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/build/mesos-0.19.0/_build/src/mesos-fetcher' I0420 23:22:01.270736 9364 slave.cpp:973] Queuing task '61c3f349-ba84-4ba3-b270-61d9b4b29c44' for executor 61c3f349-ba84-4ba3-b270-61d9b4b29c44 of framework '20140420-232201-453759884-55410-9329-0000 I0420 23:22:01.270959 9364 slave.cpp:457] Successfully attached file '/tmp/SlaveRecoveryTest_0_RemoveNonCheckpointingFramework_lk36kH/slaves/20140420-232201-453759884-55410-9329-0/frameworks/20140420-232201-453759884-55410-9329-0000/executors/00f7dd55-27d8-484b-8155-dc35dae1bab6/runs/89a203ed-b577-43d0-894b-908cf7aba57e' I0420 23:22:01.271006 9364 slave.cpp:457] Successfully attached file '/tmp/SlaveRecoveryTest_0_RemoveNonCheckpointingFramework_lk36kH/slaves/20140420-232201-453759884-55410-9329-0/frameworks/20140420-232201-453759884-55410-9329-0000/executors/61c3f349-ba84-4ba3-b270-61d9b4b29c44/runs/226a74b0-dce0-4d2d-a7e9-629253e48e45' I0420 23:22:01.271997 9361 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 16.063821ms I0420 23:22:01.272054 9361 replica.cpp:664] Persisted action at 4 I0420 23:22:01.273387 9372 replica.cpp:643] Replica received learned notice for position 4 I0420 23:22:01.278170 9371 mesos_containerizer.cpp:385] Starting container '226a74b0-dce0-4d2d-a7e9-629253e48e45' for executor '61c3f349-ba84-4ba3-b270-61d9b4b29c44' of framework '20140420-232201-453759884-55410-9329-0000' I0420 23:22:01.279294 9359 mesos_containerizer.cpp:507] Fetching URIs for container '226a74b0-dce0-4d2d-a7e9-629253e48e45' using command '/x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/build/mesos-0.19.0/_build/src/mesos-fetcher' I0420 23:22:01.288699 9372 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 15.273222ms I0420 23:22:01.288838 9372 leveldb.cpp:399] Deleting ~2 keys from leveldb took 42933ns I0420 23:22:01.288856 9372 replica.cpp:664] Persisted action at 4 I0420 23:22:01.288869 9372 replica.cpp:649] Replica learned TRUNCATE action at position 4 I0420 23:22:01.590369 9365 launcher.cpp:117] Forked child with pid '13635' for container '89a203ed-b577-43d0-894b-908cf7aba57e' I0420 23:22:01.594457 9365 launcher.cpp:117] Forked child with pid '13636' for container '226a74b0-dce0-4d2d-a7e9-629253e48e45' I0420 23:22:01.596091 9355 slave.cpp:2118] Monitoring executor '61c3f349-ba84-4ba3-b270-61d9b4b29c44' of framework '20140420-232201-453759884-55410-9329-0000' in container '226a74b0-dce0-4d2d-a7e9-629253e48e45' I0420 23:22:01.599998 9353 slave.cpp:2118] Monitoring executor '00f7dd55-27d8-484b-8155-dc35dae1bab6' of framework '20140420-232201-453759884-55410-9329-0000' in container '89a203ed-b577-43d0-894b-908cf7aba57e' WARNING: Logging before InitGoogleLogging() is written to STDERR WARNING: Logging before InitGoogleLogging() is written to STDERR I0420 23:22:01.644233 13641 process.cpp:1589] libprocess is initialized on 140.211.11.27:51591 for 24 cpus I0420 23:22:01.644220 13642 process.cpp:1589] libprocess is initialized on 140.211.11.27:43746 for 24 cpus I0420 23:22:01.645511 13642 exec.cpp:131] Version: 0.19.0 I0420 23:22:01.645648 13641 exec.cpp:131] Version: 0.19.0 I0420 23:22:01.646708 13686 exec.cpp:181] Executor started at: executor(1)@140.211.11.27:43746 with pid 13642 I0420 23:22:01.647003 13692 exec.cpp:181] Executor started at: executor(1)@140.211.11.27:51591 with pid 13641 I0420 23:22:01.647203 9355 slave.cpp:1446] Got registration for executor '00f7dd55-27d8-484b-8155-dc35dae1bab6' of framework 20140420-232201-453759884-55410-9329-0000 I0420 23:22:01.647922 9355 slave.cpp:1565] Flushing queued task 00f7dd55-27d8-484b-8155-dc35dae1bab6 for executor '00f7dd55-27d8-484b-8155-dc35dae1bab6' of framework 20140420-232201-453759884-55410-9329-0000 I0420 23:22:01.648088 9355 slave.cpp:1446] Got registration for executor '61c3f349-ba84-4ba3-b270-61d9b4b29c44' of framework 20140420-232201-453759884-55410-9329-0000 I0420 23:22:01.648169 13719 process.cpp:1008] Socket closed while receiving I0420 23:22:01.648200 13681 exec.cpp:205] Executor registered on slave 20140420-232201-453759884-55410-9329-0 I0420 23:22:01.648222 9355 slave.cpp:1565] Flushing queued task 61c3f349-ba84-4ba3-b270-61d9b4b29c44 for executor '61c3f349-ba84-4ba3-b270-61d9b4b29c44' of framework 20140420-232201-453759884-55410-9329-0000 I0420 23:22:01.648402 13718 process.cpp:1008] Socket closed while receiving I0420 23:22:01.648454 13690 exec.cpp:205] Executor registered on slave 20140420-232201-453759884-55410-9329-0 I0420 23:22:01.649363 13681 exec.cpp:217] Executor::registered took 81712ns Registered executor on hemera.apache.org I0420 23:22:01.649433 13681 exec.cpp:292] Executor asked to run task '00f7dd55-27d8-484b-8155-dc35dae1bab6' I0420 23:22:01.649468 13681 exec.cpp:301] Executor::launchTask took 22776ns Starting task 00f7dd55-27d8-484b-8155-dc35dae1bab6 I0420 23:22:01.649590 13690 exec.cpp:217] Executor::registered took 70056ns Registered executor on hemera.apache.org I0420 23:22:01.649662 13690 exec.cpp:292] Executor asked to run task '61c3f349-ba84-4ba3-b270-61d9b4b29c44' I0420 23:22:01.649696 13690 exec.cpp:301] Executor::launchTask took 22290ns Starting task 61c3f349-ba84-4ba3-b270-61d9b4b29c44 Forked command at 13720 sh -c 'sleep 1000' sh -c 'sleep 1000' Forked command at 13721 I0420 23:22:01.651680 13685 exec.cpp:524] Executor sending status update TASK_RUNNING (UUID: c9179358-7e0f-466f-a964-09d3d4aa55bc) for task 61c3f349-ba84-4ba3-b270-61d9b4b29c44 of framework 20140420-232201-453759884-55410-9329-0000 I0420 23:22:01.651770 13716 exec.cpp:524] Executor sending status update TASK_RUNNING (UUID: 36bb9e17-7812-438d-a699-6c0374ef0f4f) for task 00f7dd55-27d8-484b-8155-dc35dae1bab6 of framework 20140420-232201-453759884-55410-9329-0000 I0420 23:22:01.651947 9357 slave.cpp:1775] Handling status update TASK_RUNNING (UUID: c9179358-7e0f-466f-a964-09d3d4aa55bc) for task 61c3f349-ba84-4ba3-b270-61d9b4b29c44 of framework 20140420-232201-453759884-55410-9329-0000 from executor(1)@140.211.11.27:51591 I0420 23:22:01.652099 9354 status_update_manager.cpp:320] Received status update TASK_RUNNING (UUID: c9179358-7e0f-466f-a964-09d3d4aa55bc) for task 61c3f349-ba84-4ba3-b270-61d9b4b29c44 of framework 20140420-232201-453759884-55410-9329-0000 I0420 23:22:01.652117 9357 slave.cpp:1775] Handling status update TASK_RUNNING (UUID: 36bb9e17-7812-438d-a699-6c0374ef0f4f) for task 00f7dd55-27d8-484b-8155-dc35dae1bab6 of framework 20140420-232201-453759884-55410-9329-0000 from executor(1)@140.211.11.27:43746 I0420 23:22:01.652132 9354 status_update_manager.cpp:499] Creating StatusUpdate stream for task 61c3f349-ba84-4ba3-b270-61d9b4b29c44 of framework 20140420-232201-453759884-55410-9329-0000 I0420 23:22:01.652256 9354 status_update_manager.cpp:373] Forwarding status update TASK_RUNNING (UUID: c9179358-7e0f-466f-a964-09d3d4aa55bc) for task 61c3f349-ba84-4ba3-b270-61d9b4b29c44 of framework 20140420-232201-453759884-55410-9329-0000 to [email protected]:55410 I0420 23:22:01.652626 9354 status_update_manager.cpp:320] Received status update TASK_RUNNING (UUID: 36bb9e17-7812-438d-a699-6c0374ef0f4f) for task 00f7dd55-27d8-484b-8155-dc35dae1bab6 of framework 20140420-232201-453759884-55410-9329-0000 I0420 23:22:01.652642 9354 status_update_manager.cpp:499] Creating StatusUpdate stream for task 00f7dd55-27d8-484b-8155-dc35dae1bab6 of framework 20140420-232201-453759884-55410-9329-0000 I0420 23:22:01.652683 9359 master.cpp:2382] Status update TASK_RUNNING (UUID: c9179358-7e0f-466f-a964-09d3d4aa55bc) for task 61c3f349-ba84-4ba3-b270-61d9b4b29c44 of framework 20140420-232201-453759884-55410-9329-0000 from slave(143)@140.211.11.27:55410 I0420 23:22:01.652693 9354 status_update_manager.cpp:373] Forwarding status update TASK_RUNNING (UUID: 36bb9e17-7812-438d-a699-6c0374ef0f4f) for task 00f7dd55-27d8-484b-8155-dc35dae1bab6 of framework 20140420-232201-453759884-55410-9329-0000 to [email protected]:55410 I0420 23:22:01.652735 9358 slave.cpp:1892] Status update manager successfully handled status update TASK_RUNNING (UUID: c9179358-7e0f-466f-a964-09d3d4aa55bc) for task 61c3f349-ba84-4ba3-b270-61d9b4b29c44 of framework 20140420-232201-453759884-55410-9329-0000 I0420 23:22:01.652755 9358 slave.cpp:1898] Sending acknowledgement for status update TASK_RUNNING (UUID: c9179358-7e0f-466f-a964-09d3d4aa55bc) for task 61c3f349-ba84-4ba3-b270-61d9b4b29c44 of framework 20140420-232201-453759884-55410-9329-0000 to executor(1)@140.211.11.27:51591 I0420 23:22:01.652763 9371 sched.cpp:620] Scheduler::statusUpdate took 40138ns I0420 23:22:01.653095 13718 process.cpp:1008] Socket closed while receiving I0420 23:22:01.653156 13703 exec.cpp:338] Executor received status update acknowledgement c9179358-7e0f-466f-a964-09d3d4aa55bc for task 61c3f349-ba84-4ba3-b270-61d9b4b29c44 of framework 20140420-232201-453759884-55410-9329-0000 I0420 23:22:01.653197 9366 master.cpp:2382] Status update TASK_RUNNING (UUID: 36bb9e17-7812-438d-a699-6c0374ef0f4f) for task 00f7dd55-27d8-484b-8155-dc35dae1bab6 of framework 20140420-232201-453759884-55410-9329-0000 from slave(143)@140.211.11.27:55410 I0420 23:22:01.653317 9365 status_update_manager.cpp:398] Received status update acknowledgement (UUID: c9179358-7e0f-466f-a964-09d3d4aa55bc) for task 61c3f349-ba84-4ba3-b270-61d9b4b29c44 of framework 20140420-232201-453759884-55410-9329-0000 I0420 23:22:01.653316 9352 slave.cpp:1892] Status update manager successfully handled status update TASK_RUNNING (UUID: 36bb9e17-7812-438d-a699-6c0374ef0f4f) for task 00f7dd55-27d8-484b-8155-dc35dae1bab6 of framework 20140420-232201-453759884-55410-9329-0000 I0420 23:22:01.653338 9352 slave.cpp:1898] Sending acknowledgement for status update TASK_RUNNING (UUID: 36bb9e17-7812-438d-a699-6c0374ef0f4f) for task 00f7dd55-27d8-484b-8155-dc35dae1bab6 of framework 20140420-232201-453759884-55410-9329-0000 to executor(1)@140.211.11.27:43746 I0420 23:22:01.653383 9364 sched.cpp:620] Scheduler::statusUpdate took 76860ns I0420 23:22:01.653445 9352 slave.cpp:1386] Status update manager successfully handled status update acknowledgement (UUID: c9179358-7e0f-466f-a964-09d3d4aa55bc) for task 61c3f349-ba84-4ba3-b270-61d9b4b29c44 of framework 20140420-232201-453759884-55410-9329-0000 I0420 23:22:01.653516 13719 process.cpp:1008] Socket closed while receiving I0420 23:22:01.653545 13675 exec.cpp:338] Executor received status update acknowledgement 36bb9e17-7812-438d-a699-6c0374ef0f4f for task 00f7dd55-27d8-484b-8155-dc35dae1bab6 of framework 20140420-232201-453759884-55410-9329-0000 I0420 23:22:01.653635 9360 status_update_manager.cpp:398] Received status update acknowledgement (UUID: 36bb9e17-7812-438d-a699-6c0374ef0f4f) for task 00f7dd55-27d8-484b-8155-dc35dae1bab6 of framework 20140420-232201-453759884-55410-9329-0000 I0420 23:22:01.653687 9356 slave.cpp:386] Slave terminating I0420 23:22:01.653704 9356 slave.cpp:1157] Asked to shut down framework 20140420-232201-453759884-55410-9329-0000 by @0.0.0.0:0 I0420 23:22:01.653714 9356 slave.cpp:1182] Shutting down framework 20140420-232201-453759884-55410-9329-0000 I0420 23:22:01.653723 9356 slave.cpp:2461] Shutting down executor '00f7dd55-27d8-484b-8155-dc35dae1bab6' of framework 20140420-232201-453759884-55410-9329-0000 I0420 23:22:01.653800 9356 slave.cpp:2461] Shutting down executor '61c3f349-ba84-4ba3-b270-61d9b4b29c44' of framework 20140420-232201-453759884-55410-9329-0000 I0420 23:22:01.653879 13719 process.cpp:1008] Socket closed while receiving I0420 23:22:01.653898 13673 exec.cpp:378] Executor asked to shutdown III0420 23:22:01.653941 9373 master.cpp:711] Slave 20140420-232201-453759884-55410-9329-0 (hemera.apache.org) disconnected 0420 23:22:01.653945 13718 process.cpp:1008] Socket closed while receiving 0420 23:22:01.653946 13673 exec.cpp:393] Executor::shutdown took 10936ns I0420 23:22:01.653962 9373 master.cpp:1277] Disconnecting slave 20140420-232201-453759884-55410-9329-0 Shutting down Sending SIGTERM to process tree at pid 13720 I0420 23:22:01.653970 13680 exec.cpp:378] Executor asked to shutdown I0420 23:22:01.653978 13687 exec.cpp:77] Scheduling shutdown of the executor I0420 23:22:01.654006 9373 master.cpp:1293] Removing non-checkpointing framework 20140420-232201-453759884-55410-9329-0000 from disconnected slave 20140420-232201-453759884-55410-9329-0(hemera.apache.org) I0420 23:22:01.654021 9352 hierarchical_allocator_process.hpp:484] Slave 20140420-232201-453759884-55410-9329-0 disconnected I0420 23:22:01.654032 9373 master.cpp:3156] Removing framework 20140420-232201-453759884-55410-9329-0000 from slave 20140420-232201-453759884-55410-9329-0 (hemera.apache.org) Shutting down Sending SIGTERM to process tree at pid 13721 I0420 23:22:01.654063 13680 exec.cpp:393] Executor::shutdown took 38122ns I0420 23:22:01.654065 13692 exec.cpp:77] Scheduling shutdown of the executor I0420 23:22:01.655503 9373 master.cpp:2382] Status update TASK_LOST (UUID: 26b43530-c927-47b4-93d3-cc264b5e51fa) for task 61c3f349-ba84-4ba3-b270-61d9b4b29c44 of framework 20140420-232201-453759884-55410-9329-0000 from @0.0.0.0:0 I0420 23:22:01.655612 9354 sched.cpp:620] Scheduler::statusUpdate took 36698ns I0420 23:22:01.655697 9373 master.hpp:514] Removing task 61c3f349-ba84-4ba3-b270-61d9b4b29c44 with resources cpus(*):1; mem(*):512 on slave 20140420-232201-453759884-55410-9329-0 (hemera.apache.org) I0420 23:22:01.655897 9364 hierarchical_allocator_process.hpp:637] Recovered cpus(*):1; mem(*):512 (total allocatable: disk(*):1024; ports(*):[31000-32000]; cpus(*):1; mem(*):512) on slave 20140420-232201-453759884-55410-9329-0 from framework 20140420-232201-453759884-55410-9329-0000 I0420 23:22:01.657470 9373 master.cpp:2382] Status update TASK_LOST (UUID: 2d6b3f30-8648-4fe5-9fee-500556f2742f) for task 00f7dd55-27d8-484b-8155-dc35dae1bab6 of framework 20140420-232201-453759884-55410-9329-0000 from @0.0.0.0:0 I0420 23:22:01.657542 9373 master.hpp:514] Removing task 00f7dd55-27d8-484b-8155-dc35dae1bab6 with resources cpus(*):1; mem(*):512 on slave 20140420-232201-453759884-55410-9329-0 (hemera.apache.org) I0420 23:22:01.657621 9371 sched.cpp:620] Scheduler::statusUpdate took 49433ns I0420 23:22:01.657651 9353 hierarchical_allocator_process.hpp:637] Recovered cpus(*):1; mem(*):512 (total allocatable: disk(*):1024; ports(*):[31000-32000]; cpus(*):2; mem(*):1024) on slave 20140420-232201-453759884-55410-9329-0 from framework 20140420-232201-453759884-55410-9329-0000 I0420 23:22:01.657950 9372 master.cpp:573] Master terminating I0420 23:22:01.657992 9374 sched.cpp:731] Stopping framework '20140420-232201-453759884-55410-9329-0000' [ OK ] SlaveRecoveryTest/0.RemoveNonCheckpointingFramework (785 ms) [----------] 23 tests from SlaveRecoveryTest/0 (53179 ms total) [----------] 1 test from ProtobufIOTest [ RUN ] ProtobufIOTest.Basic Killing the following process trees: [ -+- 13720 sh -c sleep 1000 \--- 13722 sleep 1000 ] Killing the following process trees: [ -+- 13721 sh -c sleep 1000 \--- 13723 sleep 1000 ] [ OK ] ProtobufIOTest.Basic (711 ms) [----------] 1 test from ProtobufIOTest (711 ms total) [----------] Global test environment tear-down ../../src/tests/environment.cpp:258: Failure Failed Tests completed with child processes remaining: -+- 9329 /x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/build/mesos-0.19.0/_build/src/.libs/lt-mesos-tests |-+- 13635 sh -c /x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/build/mesos-0.19.0/_build/src/mesos-executor | \-+- 13642 /x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/build/mesos-0.19.0/_build/src/.libs/lt-mesos-executor | \--- 13720 () \-+- 13636 sh -c /x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/build/mesos-0.19.0/_build/src/mesos-executor \-+- 13641 /x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/build/mesos-0.19.0/_build/src/.libs/lt-mesos-executor \--- 13721 () [==========] 289 tests from 50 test cases ran. (320801 ms total) [ PASSED ] 289 tests. [ FAILED ] 0 tests, listed below: 0 FAILED TESTS YOU HAVE 1 DISABLED TEST II0420 23:22:02.481328 13718 process.cpp:1008] Socket closed while receiving 0420 23:22:02.481319 13719 process.cpp:1008] Socket closed while receiving I0420 23:22:02.481426 13683 exec.cpp:439] Ignoring exited event because the driver is aborted! I0420 23:22:02.481482 13701 exec.cpp:439] Ignoring exited event because the driver is aborted! make[4]: *** [check-local] Error 1 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 Command terminated with signal Terminated (pid: 13721) Command terminated with signal Terminated (pid: 13720) I0420 23:22:02.652971 13675 exec.cpp:524] Executor sending status update TASK_KILLED (UUID: 4862a7f4-9968-4d01-b525-559d348f7b46) for task 00f7dd55-27d8-484b-8155-dc35dae1bab6 of framework 20140420-232201-453759884-55410-9329-0000 II0420 23:22:02.653146 13704 exec.cpp:524] Executor sending status update TASK_KILLED (UUID: 8fcad71b-ca54-44fd-99ff-d7295b622fa8) for task 61c3f349-ba84-4ba3-b270-61d9b4b29c44 of framework 20140420-232201-453759884-55410-9329-0000 0420 23:22:02.653159 13719 process.cpp:1181] Socket error while connecting Build step 'Execute shell' marked build as failure
