See 
<https://builds.apache.org/job/Mesos/BUILDTOOL=autotools,COMPILER=gcc,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-6)/2671/changes>

Changes:

[vinodkone] Terminate when receiving the ACK of terminal status update.

[vinodkone] Added the test `HTTPCommandExecutorTest.TerminateWithACK`.

------------------------------------------
[...truncated 17234 lines...]
I0923 02:41:33.848503 28957 master.cpp:2049] Authorizing framework principal 
'test-principal' to receive offers for role '*'
I0923 02:41:33.848865 28955 master.cpp:2663] Subscribing framework default with 
checkpointing disabled and capabilities [  ]
I0923 02:41:33.849200 28952 provisioner.cpp:253] Provisioner recovery complete
I0923 02:41:33.849331 28947 sched.cpp:743] Framework registered with 
e47a7e7d-e963-41a2-93cf-8908b72c0a03-0000
I0923 02:41:33.849402 28947 sched.cpp:757] Scheduler::registered took 43447ns
I0923 02:41:33.849508 28953 hierarchical.cpp:272] Added framework 
e47a7e7d-e963-41a2-93cf-8908b72c0a03-0000
I0923 02:41:33.849582 28953 hierarchical.cpp:1691] No allocations performed
I0923 02:41:33.849640 28955 slave.cpp:5233] Finished recovery
I0923 02:41:33.849689 28953 hierarchical.cpp:1786] No inverse offers to send 
out!
I0923 02:41:33.849747 28953 hierarchical.cpp:1283] Performed allocation for 0 
agents in 212126ns
I0923 02:41:33.850050 28955 slave.cpp:5405] Querying resource estimator for 
oversubscribable resources
I0923 02:41:33.850244 28951 status_update_manager.cpp:177] Pausing sending 
status updates
I0923 02:41:33.850245 28955 slave.cpp:915] New master detected at 
master@172.17.0.3:39720
I0923 02:41:33.850275 28955 slave.cpp:974] Authenticating with master 
master@172.17.0.3:39720
I0923 02:41:33.850327 28955 slave.cpp:985] Using default CRAM-MD5 authenticatee
I0923 02:41:33.850450 28955 slave.cpp:947] Detecting new master
I0923 02:41:33.850486 28945 authenticatee.cpp:121] Creating new client SASL 
connection
I0923 02:41:33.850689 28957 master.cpp:6583] Authenticating (1)@172.17.0.3:39720
I0923 02:41:33.850775 28953 authenticator.cpp:414] Starting authentication 
session for crammd5-authenticatee(33)@172.17.0.3:39720
I0923 02:41:33.850976 28943 authenticator.cpp:98] Creating new server SASL 
connection
I0923 02:41:33.851155 28946 authenticatee.cpp:213] Received SASL authentication 
mechanisms: CRAM-MD5
I0923 02:41:33.851181 28946 authenticatee.cpp:239] Attempting to authenticate 
with mechanism 'CRAM-MD5'
I0923 02:41:33.851265 28946 authenticator.cpp:204] Received SASL authentication 
start
I0923 02:41:33.851367 28946 authenticator.cpp:326] Authentication requires more 
steps
I0923 02:41:33.851443 28946 authenticatee.cpp:259] Received SASL authentication 
step
I0923 02:41:33.851559 28954 authenticator.cpp:232] Received SASL authentication 
step
I0923 02:41:33.851585 28954 auxprop.cpp:109] Request to lookup properties for 
user: 'test-principal' realm: 'f12a1aebe6c7' server FQDN: 'f12a1aebe6c7' 
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false 
SASL_AUXPROP_AUTHZID: false 
I0923 02:41:33.851596 28954 auxprop.cpp:181] Looking up auxiliary property 
'*userPassword'
I0923 02:41:33.851627 28954 auxprop.cpp:181] Looking up auxiliary property 
'*cmusaslsecretCRAM-MD5'
I0923 02:41:33.851660 28954 auxprop.cpp:109] Request to lookup properties for 
user: 'test-principal' realm: 'f12a1aebe6c7' server FQDN: 'f12a1aebe6c7' 
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false 
SASL_AUXPROP_AUTHZID: true 
I0923 02:41:33.851680 28954 auxprop.cpp:131] Skipping auxiliary property 
'*userPassword' since SASL_AUXPROP_AUTHZID == true
I0923 02:41:33.851696 28954 auxprop.cpp:131] Skipping auxiliary property 
'*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0923 02:41:33.851717 28954 authenticator.cpp:318] Authentication success
I0923 02:41:33.851788 28946 authenticatee.cpp:299] Authentication success
I0923 02:41:33.851845 28942 master.cpp:6613] Successfully authenticated 
principal 'test-principal' at (1)@172.17.0.3:39720
I0923 02:41:33.851905 28946 authenticator.cpp:432] Authentication session 
cleanup for crammd5-authenticatee(33)@172.17.0.3:39720
I0923 02:41:33.852056 28957 slave.cpp:1069] Successfully authenticated with 
master master@172.17.0.3:39720
I0923 02:41:33.852212 28957 slave.cpp:1475] Will retry registration in 
13.963305ms if necessary
I0923 02:41:33.852366 28947 master.cpp:5040] Registering agent at 
(1)@172.17.0.3:39720 (f12a1aebe6c7) with id 
e47a7e7d-e963-41a2-93cf-8908b72c0a03-S0
I0923 02:41:33.852771 28948 registrar.cpp:461] Applied 1 operations in 47277ns; 
attempting to update the registry
I0923 02:41:33.853615 28952 log.cpp:577] Attempting to append 337 bytes to the 
log
I0923 02:41:33.853776 28947 coordinator.cpp:348] Coordinator attempting to 
write APPEND action at position 3
I0923 02:41:33.854737 28952 replica.cpp:537] Replica received write request for 
position 3 from __req_res__(175)@172.17.0.3:39720
I0923 02:41:33.854959 28952 leveldb.cpp:341] Persisting action (356 bytes) to 
leveldb took 176129ns
I0923 02:41:33.854990 28952 replica.cpp:708] Persisted action APPEND at 
position 3
I0923 02:41:33.855710 28955 replica.cpp:691] Replica received learned notice 
for position 3 from @0.0.0.0:0
I0923 02:41:33.856173 28955 leveldb.cpp:341] Persisting action (358 bytes) to 
leveldb took 426300ns
I0923 02:41:33.856204 28955 replica.cpp:708] Persisted action APPEND at 
position 3
I0923 02:41:33.857899 28946 registrar.cpp:506] Successfully updated the 
registry in 5.064192ms
I0923 02:41:33.858202 28955 log.cpp:596] Attempting to truncate the log to 3
I0923 02:41:33.858438 28947 coordinator.cpp:348] Coordinator attempting to 
write TRUNCATE action at position 4
I0923 02:41:33.858922 28951 slave.cpp:4089] Received ping from 
slave-observer(8)@172.17.0.3:39720
I0923 02:41:33.859205 28945 master.cpp:5111] Registered agent 
e47a7e7d-e963-41a2-93cf-8908b72c0a03-S0 at (1)@172.17.0.3:39720 (f12a1aebe6c7) 
with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0923 02:41:33.859426 28955 replica.cpp:537] Replica received write request for 
position 4 from __req_res__(176)@172.17.0.3:39720
I0923 02:41:33.859482 28948 slave.cpp:1115] Registered with master 
master@172.17.0.3:39720; given agent ID e47a7e7d-e963-41a2-93cf-8908b72c0a03-S0
I0923 02:41:33.859536 28948 fetcher.cpp:86] Clearing fetcher cache
I0923 02:41:33.859774 28946 hierarchical.cpp:482] Added agent 
e47a7e7d-e963-41a2-93cf-8908b72c0a03-S0 (f12a1aebe6c7) with cpus(*):2; 
mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
I0923 02:41:33.859797 28955 leveldb.cpp:341] Persisting action (16 bytes) to 
leveldb took 319024ns
I0923 02:41:33.859815 28949 status_update_manager.cpp:184] Resuming sending 
status updates
I0923 02:41:33.859870 28955 replica.cpp:708] Persisted action TRUNCATE at 
position 4
I0923 02:41:33.860221 28948 slave.cpp:1138] Checkpointing SlaveInfo to 
'/tmp/HTTPCommandExecutorTest_TerminateWithACK_Qf7nf2/meta/slaves/e47a7e7d-e963-41a2-93cf-8908b72c0a03-S0/slave.info'
I0923 02:41:33.862079 28946 hierarchical.cpp:1786] No inverse offers to send 
out!
I0923 02:41:33.862185 28946 hierarchical.cpp:1306] Performed allocation for 
agent e47a7e7d-e963-41a2-93cf-8908b72c0a03-S0 in 2.333179ms
I0923 02:41:33.862270 28943 replica.cpp:691] Replica received learned notice 
for position 4 from @0.0.0.0:0
I0923 02:41:33.862771 28943 leveldb.cpp:341] Persisting action (18 bytes) to 
leveldb took 389336ns
I0923 02:41:33.862854 28943 leveldb.cpp:399] Deleting ~2 keys from leveldb took 
48913ns
I0923 02:41:33.862890 28943 replica.cpp:708] Persisted action TRUNCATE at 
position 4
I0923 02:41:33.863163 28947 master.cpp:6412] Sending 1 offers to framework 
e47a7e7d-e963-41a2-93cf-8908b72c0a03-0000 (default) at 
scheduler-03e2baec-3285-4c25-820d-d84b4609e8be@172.17.0.3:39720
I0923 02:41:33.863961 28956 sched.cpp:917] Scheduler::resourceOffers took 
146900ns
I0923 02:41:33.867112 28942 master.cpp:3521] Processing ACCEPT call for offers: 
[ e47a7e7d-e963-41a2-93cf-8908b72c0a03-O0 ] on agent 
e47a7e7d-e963-41a2-93cf-8908b72c0a03-S0 at (1)@172.17.0.3:39720 (f12a1aebe6c7) 
for framework e47a7e7d-e963-41a2-93cf-8908b72c0a03-0000 (default) at 
scheduler-03e2baec-3285-4c25-820d-d84b4609e8be@172.17.0.3:39720
I0923 02:41:33.867266 28942 master.cpp:3143] Authorizing framework principal 
'test-principal' to launch task a8969412-b62c-47bc-bc16-84464185a8ec
I0923 02:41:33.870740 28944 master.cpp:8159] Adding task 
a8969412-b62c-47bc-bc16-84464185a8ec with resources cpus(*):2; mem(*):1024; 
disk(*):1024; ports(*):[31000-32000] on agent 
e47a7e7d-e963-41a2-93cf-8908b72c0a03-S0 (f12a1aebe6c7)
I0923 02:41:33.871186 28944 master.cpp:4121] Launching task 
a8969412-b62c-47bc-bc16-84464185a8ec of framework 
e47a7e7d-e963-41a2-93cf-8908b72c0a03-0000 (default) at 
scheduler-03e2baec-3285-4c25-820d-d84b4609e8be@172.17.0.3:39720 with resources 
cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on agent 
e47a7e7d-e963-41a2-93cf-8908b72c0a03-S0 at (1)@172.17.0.3:39720 (f12a1aebe6c7)
I0923 02:41:33.872187 28946 slave.cpp:1539] Got assigned task 
'a8969412-b62c-47bc-bc16-84464185a8ec' for framework 
e47a7e7d-e963-41a2-93cf-8908b72c0a03-0000
I0923 02:41:33.873469 28946 slave.cpp:1696] Launching task 
'a8969412-b62c-47bc-bc16-84464185a8ec' for framework 
e47a7e7d-e963-41a2-93cf-8908b72c0a03-0000
I0923 02:41:33.874320 28946 paths.cpp:536] Trying to chown 
'/tmp/HTTPCommandExecutorTest_TerminateWithACK_Qf7nf2/slaves/e47a7e7d-e963-41a2-93cf-8908b72c0a03-S0/frameworks/e47a7e7d-e963-41a2-93cf-8908b72c0a03-0000/executors/a8969412-b62c-47bc-bc16-84464185a8ec/runs/2d6bc9cd-72c7-4676-b1df-fdf0cc45efef'
 to user 'mesos'
I0923 02:41:33.879381 28946 slave.cpp:6122] Launching executor 
'a8969412-b62c-47bc-bc16-84464185a8ec' of framework 
e47a7e7d-e963-41a2-93cf-8908b72c0a03-0000 with resources cpus(*):0.1; mem(*):32 
in work directory 
'/tmp/HTTPCommandExecutorTest_TerminateWithACK_Qf7nf2/slaves/e47a7e7d-e963-41a2-93cf-8908b72c0a03-S0/frameworks/e47a7e7d-e963-41a2-93cf-8908b72c0a03-0000/executors/a8969412-b62c-47bc-bc16-84464185a8ec/runs/2d6bc9cd-72c7-4676-b1df-fdf0cc45efef'
I0923 02:41:33.880501 28955 containerizer.cpp:836] Starting container 
2d6bc9cd-72c7-4676-b1df-fdf0cc45efef for executor 
'a8969412-b62c-47bc-bc16-84464185a8ec' of framework 
e47a7e7d-e963-41a2-93cf-8908b72c0a03-0000
I0923 02:41:33.880878 28946 slave.cpp:1982] Queued task 
'a8969412-b62c-47bc-bc16-84464185a8ec' for executor 
'a8969412-b62c-47bc-bc16-84464185a8ec' of framework 
e47a7e7d-e963-41a2-93cf-8908b72c0a03-0000
I0923 02:41:33.881006 28946 slave.cpp:868] Successfully attached file 
'/tmp/HTTPCommandExecutorTest_TerminateWithACK_Qf7nf2/slaves/e47a7e7d-e963-41a2-93cf-8908b72c0a03-S0/frameworks/e47a7e7d-e963-41a2-93cf-8908b72c0a03-0000/executors/a8969412-b62c-47bc-bc16-84464185a8ec/runs/2d6bc9cd-72c7-4676-b1df-fdf0cc45efef'
I0923 02:41:33.887323 28949 containerizer.cpp:1247] Launching 
'mesos-containerizer' with flags 
'--command="{"arguments":["mesos-executor","--launcher_dir=\/mesos\/mesos-1.1.0\/_build\/src"],"shell":false,"value":"\/mesos\/mesos-1.1.0\/_build\/src\/mesos-executor"}"
 --help="false" --pipe_read="8" --pipe_write="11" --pre_exec_commands="[]" 
--unshare_namespace_mnt="false" --user="mesos" 
--working_directory="/tmp/HTTPCommandExecutorTest_TerminateWithACK_Qf7nf2/slaves/e47a7e7d-e963-41a2-93cf-8908b72c0a03-S0/frameworks/e47a7e7d-e963-41a2-93cf-8908b72c0a03-0000/executors/a8969412-b62c-47bc-bc16-84464185a8ec/runs/2d6bc9cd-72c7-4676-b1df-fdf0cc45efef"'
I0923 02:41:33.889605 28949 launcher.cpp:153] Forked child with pid '28979' for 
container '2d6bc9cd-72c7-4676-b1df-fdf0cc45efef'
I0923 02:41:34.819341 28948 hierarchical.cpp:1691] No allocations performed
I0923 02:41:34.819677 28948 hierarchical.cpp:1786] No inverse offers to send 
out!
I0923 02:41:34.820127 28948 hierarchical.cpp:1283] Performed allocation for 1 
agents in 1.051552ms
I0923 02:41:35.821370 28948 hierarchical.cpp:1691] No allocations performed
I0923 02:41:35.821457 28948 hierarchical.cpp:1786] No inverse offers to send 
out!
I0923 02:41:35.821547 28948 hierarchical.cpp:1283] Performed allocation for 1 
agents in 397665ns
I0923 02:41:36.823182 28948 hierarchical.cpp:1691] No allocations performed
I0923 02:41:36.823915 28948 hierarchical.cpp:1786] No inverse offers to send 
out!
I0923 02:41:36.824283 28948 hierarchical.cpp:1283] Performed allocation for 1 
agents in 1.324764ms
I0923 02:41:37.825139 28952 hierarchical.cpp:1691] No allocations performed
I0923 02:41:37.825225 28952 hierarchical.cpp:1786] No inverse offers to send 
out!
I0923 02:41:37.825338 28952 hierarchical.cpp:1283] Performed allocation for 1 
agents in 439314ns
I0923 02:41:38.826375 28946 hierarchical.cpp:1691] No allocations performed
I0923 02:41:38.826467 28946 hierarchical.cpp:1786] No inverse offers to send 
out!
I0923 02:41:38.826589 28946 hierarchical.cpp:1283] Performed allocation for 1 
agents in 465207ns
I0923 02:41:39.566181 28957 process.cpp:3336] Handling HTTP event for process 
'(1)' with path: '/(1)/api/v1/executor'
I0923 02:41:39.567771 28944 http.cpp:273] HTTP POST for /(1)/api/v1/executor 
from 172.17.0.3:60722
I0923 02:41:39.568189 28944 slave.cpp:3017] Received Subscribe request for HTTP 
executor 'a8969412-b62c-47bc-bc16-84464185a8ec' of framework 
e47a7e7d-e963-41a2-93cf-8908b72c0a03-0000
I0923 02:41:39.571125 28948 slave.cpp:2186] Sending queued task 
'a8969412-b62c-47bc-bc16-84464185a8ec' to executor 
'a8969412-b62c-47bc-bc16-84464185a8ec' of framework 
e47a7e7d-e963-41a2-93cf-8908b72c0a03-0000 (via HTTP)
I0923 02:41:39.584228 28953 process.cpp:3336] Handling HTTP event for process 
'(1)' with path: '/(1)/api/v1/executor'
I0923 02:41:39.585384 28953 http.cpp:273] HTTP POST for /(1)/api/v1/executor 
from 172.17.0.3:60723
I0923 02:41:39.585877 28953 slave.cpp:3606] Handling status update TASK_RUNNING 
(UUID: f8e1c3a2-18d5-4aa3-9e89-10ef713a44c7) for task 
a8969412-b62c-47bc-bc16-84464185a8ec of framework 
e47a7e7d-e963-41a2-93cf-8908b72c0a03-0000
I0923 02:41:39.589959 28944 status_update_manager.cpp:323] Received status 
update TASK_RUNNING (UUID: f8e1c3a2-18d5-4aa3-9e89-10ef713a44c7) for task 
a8969412-b62c-47bc-bc16-84464185a8ec of framework 
e47a7e7d-e963-41a2-93cf-8908b72c0a03-0000
I0923 02:41:39.590039 28944 status_update_manager.cpp:500] Creating 
StatusUpdate stream for task a8969412-b62c-47bc-bc16-84464185a8ec of framework 
e47a7e7d-e963-41a2-93cf-8908b72c0a03-0000
I0923 02:41:39.590999 28944 status_update_manager.cpp:377] Forwarding update 
TASK_RUNNING (UUID: f8e1c3a2-18d5-4aa3-9e89-10ef713a44c7) for task 
a8969412-b62c-47bc-bc16-84464185a8ec of framework 
e47a7e7d-e963-41a2-93cf-8908b72c0a03-0000 to the agent
I0923 02:41:39.591475 28949 slave.cpp:4007] Forwarding the update TASK_RUNNING 
(UUID: f8e1c3a2-18d5-4aa3-9e89-10ef713a44c7) for task 
a8969412-b62c-47bc-bc16-84464185a8ec of framework 
e47a7e7d-e963-41a2-93cf-8908b72c0a03-0000 to master@172.17.0.3:39720
I0923 02:41:39.591778 28949 slave.cpp:3901] Status update manager successfully 
handled status update TASK_RUNNING (UUID: f8e1c3a2-18d5-4aa3-9e89-10ef713a44c7) 
for task a8969412-b62c-47bc-bc16-84464185a8ec of framework 
e47a7e7d-e963-41a2-93cf-8908b72c0a03-0000
I0923 02:41:39.591992 28944 master.cpp:5638] Status update TASK_RUNNING (UUID: 
f8e1c3a2-18d5-4aa3-9e89-10ef713a44c7) for task 
a8969412-b62c-47bc-bc16-84464185a8ec of framework 
e47a7e7d-e963-41a2-93cf-8908b72c0a03-0000 from agent 
e47a7e7d-e963-41a2-93cf-8908b72c0a03-S0 at (1)@172.17.0.3:39720 (f12a1aebe6c7)
I0923 02:41:39.592061 28944 master.cpp:5700] Forwarding status update 
TASK_RUNNING (UUID: f8e1c3a2-18d5-4aa3-9e89-10ef713a44c7) for task 
a8969412-b62c-47bc-bc16-84464185a8ec of framework 
e47a7e7d-e963-41a2-93cf-8908b72c0a03-0000
I0923 02:41:39.592309 28944 master.cpp:7537] Updating the state of task 
a8969412-b62c-47bc-bc16-84464185a8ec of framework 
e47a7e7d-e963-41a2-93cf-8908b72c0a03-0000 (latest state: TASK_RUNNING, status 
update state: TASK_RUNNING)
I0923 02:41:39.592592 28953 sched.cpp:1025] Scheduler::statusUpdate took 
162574ns
I0923 02:41:39.592898 28949 master.cpp:4756] Processing ACKNOWLEDGE call 
f8e1c3a2-18d5-4aa3-9e89-10ef713a44c7 for task 
a8969412-b62c-47bc-bc16-84464185a8ec of framework 
e47a7e7d-e963-41a2-93cf-8908b72c0a03-0000 (default) at 
scheduler-03e2baec-3285-4c25-820d-d84b4609e8be@172.17.0.3:39720 on agent 
e47a7e7d-e963-41a2-93cf-8908b72c0a03-S0
I0923 02:41:39.593389 28954 status_update_manager.cpp:395] Received status 
update acknowledgement (UUID: f8e1c3a2-18d5-4aa3-9e89-10ef713a44c7) for task 
a8969412-b62c-47bc-bc16-84464185a8ec of framework 
e47a7e7d-e963-41a2-93cf-8908b72c0a03-0000
I0923 02:41:39.593734 28948 slave.cpp:2953] Status update manager successfully 
handled status update acknowledgement (UUID: 
f8e1c3a2-18d5-4aa3-9e89-10ef713a44c7) for task 
a8969412-b62c-47bc-bc16-84464185a8ec of framework 
e47a7e7d-e963-41a2-93cf-8908b72c0a03-0000
I0923 02:41:39.827802 28955 hierarchical.cpp:1691] No allocations performed
I0923 02:41:39.827891 28955 hierarchical.cpp:1786] No inverse offers to send 
out!
I0923 02:41:39.828001 28955 hierarchical.cpp:1283] Performed allocation for 1 
agents in 507939ns
I0923 02:41:40.694375 28951 process.cpp:3336] Handling HTTP event for process 
'(1)' with path: '/(1)/api/v1/executor'
I0923 02:41:40.696080 28951 http.cpp:273] HTTP POST for /(1)/api/v1/executor 
from 172.17.0.3:60723
I0923 02:41:40.696475 28951 slave.cpp:3606] Handling status update 
TASK_FINISHED (UUID: f1977720-5907-41f5-8170-072b67e2c963) for task 
a8969412-b62c-47bc-bc16-84464185a8ec of framework 
e47a7e7d-e963-41a2-93cf-8908b72c0a03-0000
I0923 02:41:40.703332 28951 status_update_manager.cpp:323] Received status 
update TASK_FINISHED (UUID: f1977720-5907-41f5-8170-072b67e2c963) for task 
a8969412-b62c-47bc-bc16-84464185a8ec of framework 
e47a7e7d-e963-41a2-93cf-8908b72c0a03-0000
I0923 02:41:40.703618 28951 status_update_manager.cpp:377] Forwarding update 
TASK_FINISHED (UUID: f1977720-5907-41f5-8170-072b67e2c963) for task 
a8969412-b62c-47bc-bc16-84464185a8ec of framework 
e47a7e7d-e963-41a2-93cf-8908b72c0a03-0000 to the agent
I0923 02:41:40.704126 28951 slave.cpp:4007] Forwarding the update TASK_FINISHED 
(UUID: f1977720-5907-41f5-8170-072b67e2c963) for task 
a8969412-b62c-47bc-bc16-84464185a8ec of framework 
e47a7e7d-e963-41a2-93cf-8908b72c0a03-0000 to master@172.17.0.3:39720
I0923 02:41:40.704354 28951 slave.cpp:3901] Status update manager successfully 
handled status update TASK_FINISHED (UUID: 
f1977720-5907-41f5-8170-072b67e2c963) for task 
a8969412-b62c-47bc-bc16-84464185a8ec of framework 
e47a7e7d-e963-41a2-93cf-8908b72c0a03-0000
I0923 02:41:40.704815 28951 master.cpp:5638] Status update TASK_FINISHED (UUID: 
f1977720-5907-41f5-8170-072b67e2c963) for task 
a8969412-b62c-47bc-bc16-84464185a8ec of framework 
e47a7e7d-e963-41a2-93cf-8908b72c0a03-0000 from agent 
e47a7e7d-e963-41a2-93cf-8908b72c0a03-S0 at (1)@172.17.0.3:39720 (f12a1aebe6c7)
I0923 02:41:40.704886 28951 master.cpp:5700] Forwarding status update 
TASK_FINISHED (UUID: f1977720-5907-41f5-8170-072b67e2c963) for task 
a8969412-b62c-47bc-bc16-84464185a8ec of framework 
e47a7e7d-e963-41a2-93cf-8908b72c0a03-0000
I0923 02:41:40.705106 28951 master.cpp:7537] Updating the state of task 
a8969412-b62c-47bc-bc16-84464185a8ec of framework 
e47a7e7d-e963-41a2-93cf-8908b72c0a03-0000 (latest state: TASK_FINISHED, status 
update state: TASK_FINISHED)
I0923 02:41:40.706148 28947 sched.cpp:1025] Scheduler::statusUpdate took 
148131ns
I0923 02:41:40.706615 28947 master.cpp:4756] Processing ACKNOWLEDGE call 
f1977720-5907-41f5-8170-072b67e2c963 for task 
a8969412-b62c-47bc-bc16-84464185a8ec of framework 
e47a7e7d-e963-41a2-93cf-8908b72c0a03-0000 (default) at 
scheduler-03e2baec-3285-4c25-820d-d84b4609e8be@172.17.0.3:39720 on agent 
e47a7e7d-e963-41a2-93cf-8908b72c0a03-S0
I0923 02:41:40.706717 28947 master.cpp:7633] Removing task 
a8969412-b62c-47bc-bc16-84464185a8ec with resources cpus(*):2; mem(*):1024; 
disk(*):1024; ports(*):[31000-32000] of framework 
e47a7e7d-e963-41a2-93cf-8908b72c0a03-0000 on agent 
e47a7e7d-e963-41a2-93cf-8908b72c0a03-S0 at (1)@172.17.0.3:39720 (f12a1aebe6c7)
I0923 02:41:40.706923 28946 hierarchical.cpp:1015] Recovered cpus(*):2; 
mem(*):1024; disk(*):1024; ports(*):[31000-32000] (total: cpus(*):2; 
mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: {}) on agent 
e47a7e7d-e963-41a2-93cf-8908b72c0a03-S0 from framework 
e47a7e7d-e963-41a2-93cf-8908b72c0a03-0000
I0923 02:41:40.707473 28947 status_update_manager.cpp:395] Received status 
update acknowledgement (UUID: f1977720-5907-41f5-8170-072b67e2c963) for task 
a8969412-b62c-47bc-bc16-84464185a8ec of framework 
e47a7e7d-e963-41a2-93cf-8908b72c0a03-0000
I0923 02:41:40.708308 28947 status_update_manager.cpp:531] Cleaning up status 
update stream for task a8969412-b62c-47bc-bc16-84464185a8ec of framework 
e47a7e7d-e963-41a2-93cf-8908b72c0a03-0000
I0923 02:41:40.708947 28949 slave.cpp:2953] Status update manager successfully 
handled status update acknowledgement (UUID: 
f1977720-5907-41f5-8170-072b67e2c963) for task 
a8969412-b62c-47bc-bc16-84464185a8ec of framework 
e47a7e7d-e963-41a2-93cf-8908b72c0a03-0000
I0923 02:41:40.709022 28949 slave.cpp:6486] Completing task 
a8969412-b62c-47bc-bc16-84464185a8ec
I0923 02:41:40.747550 28945 containerizer.cpp:1842] Executor for container 
2d6bc9cd-72c7-4676-b1df-fdf0cc45efef has exited
I0923 02:41:40.747694 28945 containerizer.cpp:1612] Destroying container 
2d6bc9cd-72c7-4676-b1df-fdf0cc45efef
I0923 02:41:40.754868 28953 provisioner.cpp:438] Ignoring destroy request for 
unknown container 2d6bc9cd-72c7-4676-b1df-fdf0cc45efef
I0923 02:41:40.755395 28943 slave.cpp:4498] Executor 
'a8969412-b62c-47bc-bc16-84464185a8ec' of framework 
e47a7e7d-e963-41a2-93cf-8908b72c0a03-0000 exited with status 0
I0923 02:41:40.755506 28943 slave.cpp:4602] Cleaning up executor 
'a8969412-b62c-47bc-bc16-84464185a8ec' of framework 
e47a7e7d-e963-41a2-93cf-8908b72c0a03-0000 (via HTTP)
I0923 02:41:40.756271 28943 slave.cpp:4690] Cleaning up framework 
e47a7e7d-e963-41a2-93cf-8908b72c0a03-0000
I0923 02:41:40.756497 28956 status_update_manager.cpp:285] Closing status 
update streams for framework e47a7e7d-e963-41a2-93cf-8908b72c0a03-0000
I0923 02:41:40.830523 28943 hierarchical.cpp:1786] No inverse offers to send 
out!
I0923 02:41:40.830803 28943 hierarchical.cpp:1283] Performed allocation for 1 
agents in 1.832611ms
I0923 02:41:40.831248 28944 master.cpp:6412] Sending 1 offers to framework 
e47a7e7d-e963-41a2-93cf-8908b72c0a03-0000 (default) at 
scheduler-03e2baec-3285-4c25-820d-d84b4609e8be@172.17.0.3:39720
I0923 02:41:40.831888 28955 sched.cpp:917] Scheduler::resourceOffers took 
24980ns
I0923 02:41:41.831907 28948 hierarchical.cpp:1691] No allocations performed
I0923 02:41:41.832132 28948 hierarchical.cpp:1786] No inverse offers to send 
out!
I0923 02:41:41.832298 28948 hierarchical.cpp:1283] Performed allocation for 1 
agents in 608542ns
I0923 02:41:42.833405 28954 hierarchical.cpp:1691] No allocations performed
I0923 02:41:42.833485 28954 hierarchical.cpp:1786] No inverse offers to send 
out!
I0923 02:41:42.833595 28954 hierarchical.cpp:1283] Performed allocation for 1 
agents in 417691ns
I0923 02:41:43.834763 28953 hierarchical.cpp:1691] No allocations performed
I0923 02:41:43.834851 28953 hierarchical.cpp:1786] No inverse offers to send 
out!
I0923 02:41:43.834972 28953 hierarchical.cpp:1283] Performed allocation for 1 
agents in 441233ns
I0923 02:41:44.836014 28953 hierarchical.cpp:1691] No allocations performed
I0923 02:41:44.836102 28953 hierarchical.cpp:1786] No inverse offers to send 
out!
I0923 02:41:44.836195 28953 hierarchical.cpp:1283] Performed allocation for 1 
agents in 406617ns
I0923 02:41:45.837573 28957 hierarchical.cpp:1691] No allocations performed
I0923 02:41:45.837945 28957 hierarchical.cpp:1786] No inverse offers to send 
out!
I0923 02:41:45.838270 28957 hierarchical.cpp:1283] Performed allocation for 1 
agents in 932437ns
I0923 02:41:46.839174 28956 hierarchical.cpp:1691] No allocations performed
I0923 02:41:46.839259 28956 hierarchical.cpp:1786] No inverse offers to send 
out!
I0923 02:41:46.839349 28956 hierarchical.cpp:1283] Performed allocation for 1 
agents in 401754ns
I0923 02:41:47.840481 28948 hierarchical.cpp:1691] No allocations performed
I0923 02:41:47.840560 28948 hierarchical.cpp:1786] No inverse offers to send 
out!
I0923 02:41:47.840641 28948 hierarchical.cpp:1283] Performed allocation for 1 
agents in 375297ns
I0923 02:41:48.842147 28955 hierarchical.cpp:1691] No allocations performed
I0923 02:41:48.842440 28955 hierarchical.cpp:1786] No inverse offers to send 
out!
I0923 02:41:48.842577 28955 hierarchical.cpp:1283] Performed allocation for 1 
agents in 640321ns
I0923 02:41:48.859679 28950 slave.cpp:4089] Received ping from 
slave-observer(8)@172.17.0.3:39720
I0923 02:41:49.844039 28957 hierarchical.cpp:1691] No allocations performed
I0923 02:41:49.844110 28957 hierarchical.cpp:1786] No inverse offers to send 
out!
I0923 02:41:49.844193 28957 hierarchical.cpp:1283] Performed allocation for 1 
agents in 330095ns
I0923 02:41:50.845656 28942 hierarchical.cpp:1691] No allocations performed
I0923 02:41:50.846269 28942 hierarchical.cpp:1786] No inverse offers to send 
out!
I0923 02:41:50.846462 28942 hierarchical.cpp:1283] Performed allocation for 1 
agents in 1.015731ms
I0923 02:41:51.847376 28955 hierarchical.cpp:1691] No allocations performed
I0923 02:41:51.847448 28955 hierarchical.cpp:1786] No inverse offers to send 
out!
I0923 02:41:51.847657 28955 hierarchical.cpp:1283] Performed allocation for 1 
agents in 475890ns
I0923 02:41:52.848394 28954 hierarchical.cpp:1691] No allocations performed
I0923 02:41:52.848462 28954 hierarchical.cpp:1786] No inverse offers to send 
out!
I0923 02:41:52.848528 28954 hierarchical.cpp:1283] Performed allocation for 1 
agents in 333797ns
I0923 02:41:53.850215 28951 hierarchical.cpp:1691] No allocations performed
I0923 02:41:53.850332 28951 hierarchical.cpp:1786] No inverse offers to send 
out!
I0923 02:41:53.850425 28951 hierarchical.cpp:1283] Performed allocation for 1 
agents in 412239ns
I0923 02:41:54.851995 28947 hierarchical.cpp:1691] No allocations performed
I0923 02:41:54.852401 28947 hierarchical.cpp:1786] No inverse offers to send 
out!
I0923 02:41:54.852670 28947 hierarchical.cpp:1283] Performed allocation for 1 
agents in 881599ns
../../src/tests/command_executor_tests.cpp:288: Failure
Failed to wait 15secs for termination
I0923 02:41:55.714504 28947 master.cpp:1297] Framework 
e47a7e7d-e963-41a2-93cf-8908b72c0a03-0000 (default) at 
scheduler-03e2baec-3285-4c25-820d-d84b4609e8be@172.17.0.3:39720 disconnected
I0923 02:41:55.714560 28947 master.cpp:2888] Disconnecting framework 
e47a7e7d-e963-41a2-93cf-8908b72c0a03-0000 (default) at 
scheduler-03e2baec-3285-4c25-820d-d84b4609e8be@172.17.0.3:39720
I0923 02:41:55.714607 28947 master.cpp:2912] Deactivating framework 
e47a7e7d-e963-41a2-93cf-8908b72c0a03-0000 (default) at 
scheduler-03e2baec-3285-4c25-820d-d84b4609e8be@172.17.0.3:39720
I0923 02:41:55.714900 28955 hierarchical.cpp:383] Deactivated framework 
e47a7e7d-e963-41a2-93cf-8908b72c0a03-0000
W0923 02:41:55.715349 28947 master.hpp:2265] Master attempted to send message 
to disconnected framework e47a7e7d-e963-41a2-93cf-8908b72c0a03-0000 (default) 
at scheduler-03e2baec-3285-4c25-820d-d84b4609e8be@172.17.0.3:39720
I0923 02:41:55.715476 28947 master.cpp:1310] Giving framework 
e47a7e7d-e963-41a2-93cf-8908b72c0a03-0000 (default) at 
scheduler-03e2baec-3285-4c25-820d-d84b4609e8be@172.17.0.3:39720 0ns to failover
I0923 02:41:55.716583 28955 hierarchical.cpp:1015] Recovered cpus(*):2; 
mem(*):1024; disk(*):1024; ports(*):[31000-32000] (total: cpus(*):2; 
mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: {}) on agent 
e47a7e7d-e963-41a2-93cf-8908b72c0a03-S0 from framework 
e47a7e7d-e963-41a2-93cf-8908b72c0a03-0000
../../src/tests/command_executor_tests.cpp:274: Failure
Actual function call count doesn't match EXPECT_CALL(slave, 
executorTerminated(_, _, _))...
         Expected: to be called once
           Actual: never called - unsatisfied and active
I0923 02:41:55.718471 28956 master.cpp:6264] Framework failover timeout, 
removing framework e47a7e7d-e963-41a2-93cf-8908b72c0a03-0000 (default) at 
scheduler-03e2baec-3285-4c25-820d-d84b4609e8be@172.17.0.3:39720
I0923 02:41:55.718603 28956 master.cpp:7000] Removing framework 
e47a7e7d-e963-41a2-93cf-8908b72c0a03-0000 (default) at 
scheduler-03e2baec-3285-4c25-820d-d84b4609e8be@172.17.0.3:39720
I0923 02:41:55.720059 28956 slave.cpp:2506] Asked to shut down framework 
e47a7e7d-e963-41a2-93cf-8908b72c0a03-0000 by master@172.17.0.3:39720
W0923 02:41:55.721038 28956 slave.cpp:2521] Cannot shut down unknown framework 
e47a7e7d-e963-41a2-93cf-8908b72c0a03-0000
*** Aborted at 1474598515 (unix time) try "date -d @1474598515" if you are 
using GNU date ***
I0923 02:41:55.720706 28953 hierarchical.cpp:334] Removed framework 
e47a7e7d-e963-41a2-93cf-8908b72c0a03-0000
PC: @                0x0 (unknown)
*** SIGSEGV (@0x0) received by PID 28923 (TID 0x2b0d0e9bd700) from PID 0; stack 
trace: ***
    @     0x2b0d0492a330 (unknown)
make[4]: *** [check-local] Segmentation fault
make[4]: Leaving directory `/mesos/mesos-1.1.0/_build/src'
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/mesos/mesos-1.1.0/_build/src'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/mesos/mesos-1.1.0/_build/src'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-1.1.0/_build'
make: *** [distcheck] Error 1
+ docker rmi mesos-1474596564-28585
Untagged: mesos-1474596564-28585:latest
Deleted: sha256:65815a8a3b90eb95d88e0b74c645edb22b2b84071aacb79c79993f0f1ce1cea7
Deleted: sha256:2fc6dc9ad9d1005ffbfaf4e404b299acaa8191d6b84130152305ee2dcb8b6832
Deleted: sha256:4691f197a4dad79eb6670547d0afaad1f327a250524c634be2ed07e6dca963c9
Deleted: sha256:08c433f3862fa0c36683f904e51c5cd8de50560287dcced0a47800330a262356
Deleted: sha256:6573a672be0d97e71446509c13ac2f882069d9c279b841716f767cde67c1906a
Deleted: sha256:1b2cb7d22a09459cafcb053d675bc8f61c5eb9cd7aa1ae553cebc646b1e6c891
Deleted: sha256:7a9cf6dd830e7ff3160ba9c4b0d054ec5f73f05291bae004480f2c582ab2bd76
Deleted: sha256:85d3549de052bb40ee7d9a39c4ec0d912a2fcd0e08099069339b0e606e5ae763
Deleted: sha256:7565b87096036fd53f4b89d49e2a245d8913086462350bac748b721985b5a1e0
Deleted: sha256:690f42b81af709012a0ec92dc1fca09b45543dfca03875e456f598ac68248253
Deleted: sha256:2de6ee58a8ad820dea0126ba4a98eef113db5004b08caeb4945ecef0f0c66e8a
Deleted: sha256:3031f373f5742ada4164d6dbe4af16f327349c16a8dd73caedaf5d62ef90cf14
Deleted: sha256:191899097192322142b1692153d73f5ec2c686288873996d8fbef1004f4cbab1
Deleted: sha256:40781998ed3230874938210ad9e14f7a802e3fbc06ce962bd0b9c595bfadb06e
Deleted: sha256:89148512edc7cbc81ba0943c0e9af2fa4cc35bfe84692e9a0ea1f137bdfa9e42
Deleted: sha256:c6a67589bfb86d0a7101544b0a1fc09a5fe8758ce3ce47fd51831a90cc1a04b7
Deleted: sha256:a1da36d12d26a9431d07909ed707ff86b58d47ec284980b65c0902de04d067da
Deleted: sha256:41dd03e60b68eced852cb81e75b433585bbb1d6fce1c20b4776c59abafb9f4da
Deleted: sha256:f5b4ae4959da10049d84c70663b3922fe47b6be38433eb0b8554dd9228a98b67
Deleted: sha256:28c1f9ebb3b0a16597ed44f206a27e76c2e00c4ba39b9e0702831fe70b7e0833
Deleted: sha256:4063d06006ec123ff2d345279125b3fd25e0400c93f0652e014f27c40d22607d
Deleted: sha256:79727600af0682136ce93e234a1e564c035434f0024ad42c93d262693ba30059
Deleted: sha256:5bea2f7d58be68693c990772ca61b21d4aaf2df14c21f1b402b81cea686275a5
Deleted: sha256:dcdc6bf0ae1b45094c6d6796879653712fcd1a4ffee4613b15225012afaf3515
Build step 'Execute shell' marked build as failure

Reply via email to