Pushed the fix: https://reviews.apache.org/r/52186/

Thanks Guangya!

On Thu, Sep 22, 2016 at 7:08 PM, Vinod Kone <vinodk...@gmail.com> wrote:

> Qian, can you take a look?
>
> @vinodkone
>
> > On Sep 22, 2016, at 7:02 PM, Apache Jenkins Server <
> jenk...@builds.apache.org> wrote:
> >
> > See <https://builds.apache.org/job/Mesos/BUILDTOOL=autotools,
> COMPILER=gcc,CONFIGURATION=--verbose%20--enable-libevent%
> 20--enable-ssl,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 17386 lines...]
> > I0923 02:01:40.432051 30192 hierarchical.cpp:272] Added framework
> c70d1f79-b5ba-4949-b931-578b1099cfc8-0000
> > I0923 02:01:40.432087 30203 slave.cpp:5233] Finished recovery
> > I0923 02:01:40.432134 30192 hierarchical.cpp:1691] No allocations
> performed
> > I0923 02:01:40.432173 30192 hierarchical.cpp:1786] No inverse offers to
> send out!
> > I0923 02:01:40.432091 30198 sched.cpp:743] Framework registered with
> c70d1f79-b5ba-4949-b931-578b1099cfc8-0000
> > I0923 02:01:40.432267 30192 hierarchical.cpp:1283] Performed allocation
> for 0 agents in 184439ns
> > I0923 02:01:40.432286 30198 sched.cpp:757] Scheduler::registered took
> 37729ns
> > I0923 02:01:40.432561 30203 slave.cpp:5405] Querying resource estimator
> for oversubscribable resources
> > I0923 02:01:40.432776 30203 slave.cpp:915] New master detected at
> master@172.17.0.2:47968
> > I0923 02:01:40.432798 30203 slave.cpp:974] Authenticating with master
> master@172.17.0.2:47968
> > I0923 02:01:40.432801 30193 status_update_manager.cpp:177] Pausing
> sending status updates
> > I0923 02:01:40.432847 30203 slave.cpp:985] Using default CRAM-MD5
> authenticatee
> > I0923 02:01:40.432971 30203 slave.cpp:947] Detecting new master
> > I0923 02:01:40.433126 30204 authenticatee.cpp:121] Creating new client
> SASL connection
> > I0923 02:01:40.433369 30197 master.cpp:6583] Authenticating (1)@
> 172.17.0.2:47968
> > I0923 02:01:40.433460 30201 authenticator.cpp:414] Starting
> authentication session for crammd5-authenticatee(33)@172.17.0.2:47968
> > I0923 02:01:40.433656 30203 authenticator.cpp:98] Creating new server
> SASL connection
> > I0923 02:01:40.433828 30195 authenticatee.cpp:213] Received SASL
> authentication mechanisms: CRAM-MD5
> > I0923 02:01:40.433861 30195 authenticatee.cpp:239] Attempting to
> authenticate with mechanism 'CRAM-MD5'
> > I0923 02:01:40.433987 30198 authenticator.cpp:204] Received SASL
> authentication start
> > I0923 02:01:40.434072 30198 authenticator.cpp:326] Authentication
> requires more steps
> > I0923 02:01:40.434195 30198 authenticatee.cpp:259] Received SASL
> authentication step
> > I0923 02:01:40.434325 30199 authenticator.cpp:232] Received SASL
> authentication step
> > I0923 02:01:40.434357 30199 auxprop.cpp:109] Request to lookup
> properties for user: 'test-principal' realm: 'ceb803c18b6b' server FQDN:
> 'ceb803c18b6b' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
> SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
> > I0923 02:01:40.434422 30199 auxprop.cpp:181] Looking up auxiliary
> property '*userPassword'
> > I0923 02:01:40.434515 30199 auxprop.cpp:181] Looking up auxiliary
> property '*cmusaslsecretCRAM-MD5'
> > I0923 02:01:40.434545 30199 auxprop.cpp:109] Request to lookup
> properties for user: 'test-principal' realm: 'ceb803c18b6b' server FQDN:
> 'ceb803c18b6b' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
> SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
> > I0923 02:01:40.434556 30199 auxprop.cpp:131] Skipping auxiliary property
> '*userPassword' since SASL_AUXPROP_AUTHZID == true
> > I0923 02:01:40.434564 30199 auxprop.cpp:131] Skipping auxiliary property
> '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
> > I0923 02:01:40.434581 30199 authenticator.cpp:318] Authentication success
> > I0923 02:01:40.434671 30195 authenticatee.cpp:299] Authentication success
> > I0923 02:01:40.434720 30204 master.cpp:6613] Successfully authenticated
> principal 'test-principal' at (1)@172.17.0.2:47968
> > I0923 02:01:40.434767 30191 authenticator.cpp:432] Authentication
> session cleanup for crammd5-authenticatee(33)@172.17.0.2:47968
> > I0923 02:01:40.435058 30194 slave.cpp:1069] Successfully authenticated
> with master master@172.17.0.2:47968
> > I0923 02:01:40.435202 30194 slave.cpp:1475] Will retry registration in
> 11.398618ms if necessary
> > I0923 02:01:40.435385 30198 master.cpp:5040] Registering agent at (1)@
> 172.17.0.2:47968 (ceb803c18b6b) with id c70d1f79-b5ba-4949-b931-
> 578b1099cfc8-S0
> > I0923 02:01:40.435829 30200 registrar.cpp:461] Applied 1 operations in
> 53913ns; attempting to update the registry
> > I0923 02:01:40.436503 30194 log.cpp:577] Attempting to append 337 bytes
> to the log
> > I0923 02:01:40.436610 30203 coordinator.cpp:348] Coordinator attempting
> to write APPEND action at position 3
> > I0923 02:01:40.437355 30192 replica.cpp:537] Replica received write
> request for position 3 from __req_res__(175)@172.17.0.2:47968
> > I0923 02:01:40.437592 30192 leveldb.cpp:341] Persisting action (356
> bytes) to leveldb took 194641ns
> > I0923 02:01:40.437618 30192 replica.cpp:708] Persisted action APPEND at
> position 3
> > I0923 02:01:40.438244 30199 replica.cpp:691] Replica received learned
> notice for position 3 from @0.0.0.0:0
> > I0923 02:01:40.438686 30199 leveldb.cpp:341] Persisting action (358
> bytes) to leveldb took 414752ns
> > I0923 02:01:40.438709 30199 replica.cpp:708] Persisted action APPEND at
> position 3
> > I0923 02:01:40.439976 30198 registrar.cpp:506] Successfully updated the
> registry in 4.082944ms
> > I0923 02:01:40.440181 30204 log.cpp:596] Attempting to truncate the log
> to 3
> > I0923 02:01:40.440351 30200 coordinator.cpp:348] Coordinator attempting
> to write TRUNCATE action at position 4
> > I0923 02:01:40.440735 30203 slave.cpp:4089] Received ping from
> slave-observer(8)@172.17.0.2:47968
> > I0923 02:01:40.440894 30202 master.cpp:5111] Registered agent
> c70d1f79-b5ba-4949-b931-578b1099cfc8-S0 at (1)@172.17.0.2:47968
> (ceb803c18b6b) with cpus(*):2; mem(*):1024; disk(*):1024;
> ports(*):[31000-32000]
> > I0923 02:01:40.440997 30203 slave.cpp:1115] Registered with master
> master@172.17.0.2:47968; given agent ID c70d1f79-b5ba-4949-b931-
> 578b1099cfc8-S0
> > I0923 02:01:40.441112 30203 fetcher.cpp:86] Clearing fetcher cache
> > I0923 02:01:40.441125 30197 replica.cpp:537] Replica received write
> request for position 4 from __req_res__(176)@172.17.0.2:47968
> > I0923 02:01:40.441251 30200 status_update_manager.cpp:184] Resuming
> sending status updates
> > I0923 02:01:40.441313 30197 leveldb.cpp:341] Persisting action (16
> bytes) to leveldb took 155927ns
> > I0923 02:01:40.441356 30197 replica.cpp:708] Persisted action TRUNCATE
> at position 4
> > I0923 02:01:40.441467 30203 slave.cpp:1138] Checkpointing SlaveInfo to
> '/tmp/HTTPCommandExecutorTest_TerminateWithACK_ZGnHvA/meta/
> slaves/c70d1f79-b5ba-4949-b931-578b1099cfc8-S0/slave.info'
> > I0923 02:01:40.442024 30201 replica.cpp:691] Replica received learned
> notice for position 4 from @0.0.0.0:0
> > I0923 02:01:40.442206 30192 hierarchical.cpp:482] Added agent
> c70d1f79-b5ba-4949-b931-578b1099cfc8-S0 (ceb803c18b6b) with cpus(*):2;
> mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
> > I0923 02:01:40.442227 30201 leveldb.cpp:341] Persisting action (18
> bytes) to leveldb took 173078ns
> > I0923 02:01:40.442281 30201 leveldb.cpp:399] Deleting ~2 keys from
> leveldb took 34237ns
> > I0923 02:01:40.442303 30201 replica.cpp:708] Persisted action TRUNCATE
> at position 4
> > I0923 02:01:40.443359 30192 hierarchical.cpp:1786] No inverse offers to
> send out!
> > I0923 02:01:40.443475 30192 hierarchical.cpp:1306] Performed allocation
> for agent c70d1f79-b5ba-4949-b931-578b1099cfc8-S0 in 1.230798ms
> > I0923 02:01:40.443985 30194 master.cpp:6412] Sending 1 offers to
> framework c70d1f79-b5ba-4949-b931-578b1099cfc8-0000 (default) at
> scheduler-3a7471c6-7413-43a9-a1f7-3a57c615bb24@172.17.0.2:47968
> > I0923 02:01:40.444576 30206 sched.cpp:917] Scheduler::resourceOffers
> took 101683ns
> > I0923 02:01:40.446993 30194 master.cpp:3521] Processing ACCEPT call for
> offers: [ c70d1f79-b5ba-4949-b931-578b1099cfc8-O0 ] on agent
> c70d1f79-b5ba-4949-b931-578b1099cfc8-S0 at (1)@172.17.0.2:47968
> (ceb803c18b6b) for framework c70d1f79-b5ba-4949-b931-578b1099cfc8-0000
> (default) at scheduler-3a7471c6-7413-43a9-a1f7-3a57c615bb24@172.17.0.2:
> 47968
> > I0923 02:01:40.447100 30194 master.cpp:3143] Authorizing framework
> principal 'test-principal' to launch task 64a804ba-b593-433d-90cc-
> be342ab8165d
> > I0923 02:01:40.449699 30194 master.cpp:8159] Adding task
> 64a804ba-b593-433d-90cc-be342ab8165d with resources cpus(*):2;
> mem(*):1024; disk(*):1024; ports(*):[31000-32000] on agent
> c70d1f79-b5ba-4949-b931-578b1099cfc8-S0 (ceb803c18b6b)
> > I0923 02:01:40.449970 30194 master.cpp:4121] Launching task
> 64a804ba-b593-433d-90cc-be342ab8165d of framework 
> c70d1f79-b5ba-4949-b931-578b1099cfc8-0000
> (default) at scheduler-3a7471c6-7413-43a9-a1f7-3a57c615bb24@172.17.0.2:
> 47968 with resources cpus(*):2; mem(*):1024; disk(*):1024;
> ports(*):[31000-32000] on agent c70d1f79-b5ba-4949-b931-578b1099cfc8-S0
> at (1)@172.17.0.2:47968 (ceb803c18b6b)
> > I0923 02:01:40.450726 30199 slave.cpp:1539] Got assigned task
> '64a804ba-b593-433d-90cc-be342ab8165d' for framework
> c70d1f79-b5ba-4949-b931-578b1099cfc8-0000
> > I0923 02:01:40.451771 30199 slave.cpp:1696] Launching task
> '64a804ba-b593-433d-90cc-be342ab8165d' for framework
> c70d1f79-b5ba-4949-b931-578b1099cfc8-0000
> > I0923 02:01:40.452410 30199 paths.cpp:536] Trying to chown
> '/tmp/HTTPCommandExecutorTest_TerminateWithACK_ZGnHvA/
> slaves/c70d1f79-b5ba-4949-b931-578b1099cfc8-S0/
> frameworks/c70d1f79-b5ba-4949-b931-578b1099cfc8-0000/
> executors/64a804ba-b593-433d-90cc-be342ab8165d/runs/
> c3a773f0-7be6-497c-be75-eb29e867b8e0' to user 'mesos'
> > I0923 02:01:40.488970 30199 slave.cpp:6122] Launching executor
> '64a804ba-b593-433d-90cc-be342ab8165d' of framework
> c70d1f79-b5ba-4949-b931-578b1099cfc8-0000 with resources cpus(*):0.1;
> mem(*):32 in work directory '/tmp/HTTPCommandExecutorTest_
> TerminateWithACK_ZGnHvA/slaves/c70d1f79-b5ba-4949-b931-578b1099cfc8-S0/
> frameworks/c70d1f79-b5ba-4949-b931-578b1099cfc8-0000/
> executors/64a804ba-b593-433d-90cc-be342ab8165d/runs/
> c3a773f0-7be6-497c-be75-eb29e867b8e0'
> > I0923 02:01:40.489878 30206 containerizer.cpp:836] Starting container
> c3a773f0-7be6-497c-be75-eb29e867b8e0 for executor
> '64a804ba-b593-433d-90cc-be342ab8165d' of framework
> c70d1f79-b5ba-4949-b931-578b1099cfc8-0000
> > I0923 02:01:40.490219 30199 slave.cpp:1982] Queued task
> '64a804ba-b593-433d-90cc-be342ab8165d' for executor
> '64a804ba-b593-433d-90cc-be342ab8165d' of framework
> c70d1f79-b5ba-4949-b931-578b1099cfc8-0000
> > I0923 02:01:40.490320 30199 slave.cpp:868] Successfully attached file
> '/tmp/HTTPCommandExecutorTest_TerminateWithACK_ZGnHvA/
> slaves/c70d1f79-b5ba-4949-b931-578b1099cfc8-S0/
> frameworks/c70d1f79-b5ba-4949-b931-578b1099cfc8-0000/
> executors/64a804ba-b593-433d-90cc-be342ab8165d/runs/
> c3a773f0-7be6-497c-be75-eb29e867b8e0'
> > I0923 02:01:40.495131 30192 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="9" --pipe_write="12" --pre_exec_commands="[]"
> --unshare_namespace_mnt="false" --user="mesos" --working_directory="/tmp/
> HTTPCommandExecutorTest_TerminateWithACK_ZGnHvA/slaves/c70d1f79-b5ba-4949-
> b931-578b1099cfc8-S0/frameworks/c70d1f79-b5ba-4949-b931-578b1099cfc8-0000/
> executors/64a804ba-b593-433d-90cc-be342ab8165d/runs/
> c3a773f0-7be6-497c-be75-eb29e867b8e0"'
> > I0923 02:01:40.496817 30192 launcher.cpp:153] Forked child with pid
> '30228' for container 'c3a773f0-7be6-497c-be75-eb29e867b8e0'
> > I0923 02:01:41.395885 30195 hierarchical.cpp:1691] No allocations
> performed
> > I0923 02:01:41.395980 30195 hierarchical.cpp:1786] No inverse offers to
> send out!
> > I0923 02:01:41.396064 30195 hierarchical.cpp:1283] Performed allocation
> for 1 agents in 395694ns
> > I0923 02:01:42.397562 30197 hierarchical.cpp:1691] No allocations
> performed
> > I0923 02:01:42.397639 30197 hierarchical.cpp:1786] No inverse offers to
> send out!
> > I0923 02:01:42.397730 30197 hierarchical.cpp:1283] Performed allocation
> for 1 agents in 352776ns
> > I0923 02:01:43.399085 30205 hierarchical.cpp:1691] No allocations
> performed
> > I0923 02:01:43.399165 30205 hierarchical.cpp:1786] No inverse offers to
> send out!
> > I0923 02:01:43.399250 30205 hierarchical.cpp:1283] Performed allocation
> for 1 agents in 372860ns
> > I0923 02:01:44.400029 30202 hierarchical.cpp:1691] No allocations
> performed
> > I0923 02:01:44.400105 30202 hierarchical.cpp:1786] No inverse offers to
> send out!
> > I0923 02:01:44.400178 30202 hierarchical.cpp:1283] Performed allocation
> for 1 agents in 369084ns
> > I0923 02:01:45.401547 30194 hierarchical.cpp:1691] No allocations
> performed
> > I0923 02:01:45.401618 30194 hierarchical.cpp:1786] No inverse offers to
> send out!
> > I0923 02:01:45.401706 30194 hierarchical.cpp:1283] Performed allocation
> for 1 agents in 395014ns
> > I0923 02:01:46.402977 30204 hierarchical.cpp:1691] No allocations
> performed
> > I0923 02:01:46.403056 30204 hierarchical.cpp:1786] No inverse offers to
> send out!
> > I0923 02:01:46.403156 30204 hierarchical.cpp:1283] Performed allocation
> for 1 agents in 377773ns
> > I0923 02:01:46.562500 30201 process.cpp:3336] Handling HTTP event for
> process '(1)' with path: '/(1)/api/v1/executor'
> > I0923 02:01:46.564327 30193 http.cpp:273] HTTP POST for
> /(1)/api/v1/executor from 172.17.0.2:35267
> > I0923 02:01:46.564826 30193 slave.cpp:3017] Received Subscribe request
> for HTTP executor '64a804ba-b593-433d-90cc-be342ab8165d' of framework
> c70d1f79-b5ba-4949-b931-578b1099cfc8-0000
> > I0923 02:01:46.568183 30199 slave.cpp:2186] Sending queued task
> '64a804ba-b593-433d-90cc-be342ab8165d' to executor
> '64a804ba-b593-433d-90cc-be342ab8165d' of framework
> c70d1f79-b5ba-4949-b931-578b1099cfc8-0000 (via HTTP)
> > I0923 02:01:46.583202 30197 process.cpp:3336] Handling HTTP event for
> process '(1)' with path: '/(1)/api/v1/executor'
> > I0923 02:01:46.584014 30204 http.cpp:273] HTTP POST for
> /(1)/api/v1/executor from 172.17.0.2:35268
> > I0923 02:01:46.584375 30204 slave.cpp:3606] Handling status update
> TASK_RUNNING (UUID: 7c8ecbbb-6705-48fc-82f0-6a0097b8215e) for task
> 64a804ba-b593-433d-90cc-be342ab8165d of framework c70d1f79-b5ba-4949-b931-
> 578b1099cfc8-0000
> > I0923 02:01:46.587401 30205 status_update_manager.cpp:323] Received
> status update TASK_RUNNING (UUID: 7c8ecbbb-6705-48fc-82f0-6a0097b8215e)
> for task 64a804ba-b593-433d-90cc-be342ab8165d of framework
> c70d1f79-b5ba-4949-b931-578b1099cfc8-0000
> > I0923 02:01:46.587502 30205 status_update_manager.cpp:500] Creating
> StatusUpdate stream for task 64a804ba-b593-433d-90cc-be342ab8165d of
> framework c70d1f79-b5ba-4949-b931-578b1099cfc8-0000
> > I0923 02:01:46.588310 30205 status_update_manager.cpp:377] Forwarding
> update TASK_RUNNING (UUID: 7c8ecbbb-6705-48fc-82f0-6a0097b8215e) for task
> 64a804ba-b593-433d-90cc-be342ab8165d of framework 
> c70d1f79-b5ba-4949-b931-578b1099cfc8-0000
> to the agent
> > I0923 02:01:46.588704 30200 slave.cpp:4007] Forwarding the update
> TASK_RUNNING (UUID: 7c8ecbbb-6705-48fc-82f0-6a0097b8215e) for task
> 64a804ba-b593-433d-90cc-be342ab8165d of framework 
> c70d1f79-b5ba-4949-b931-578b1099cfc8-0000
> to master@172.17.0.2:47968
> > I0923 02:01:46.589064 30200 slave.cpp:3901] Status update manager
> successfully handled status update TASK_RUNNING (UUID:
> 7c8ecbbb-6705-48fc-82f0-6a0097b8215e) for task 
> 64a804ba-b593-433d-90cc-be342ab8165d
> of framework c70d1f79-b5ba-4949-b931-578b1099cfc8-0000
> > I0923 02:01:46.589300 30196 master.cpp:5638] Status update TASK_RUNNING
> (UUID: 7c8ecbbb-6705-48fc-82f0-6a0097b8215e) for task
> 64a804ba-b593-433d-90cc-be342ab8165d of framework 
> c70d1f79-b5ba-4949-b931-578b1099cfc8-0000
> from agent c70d1f79-b5ba-4949-b931-578b1099cfc8-S0 at (1)@172.17.0.2:47968
> (ceb803c18b6b)
> > I0923 02:01:46.589401 30196 master.cpp:5700] Forwarding status update
> TASK_RUNNING (UUID: 7c8ecbbb-6705-48fc-82f0-6a0097b8215e) for task
> 64a804ba-b593-433d-90cc-be342ab8165d of framework c70d1f79-b5ba-4949-b931-
> 578b1099cfc8-0000
> > I0923 02:01:46.589704 30196 master.cpp:7537] Updating the state of task
> 64a804ba-b593-433d-90cc-be342ab8165d of framework 
> c70d1f79-b5ba-4949-b931-578b1099cfc8-0000
> (latest state: TASK_RUNNING, status update state: TASK_RUNNING)
> > I0923 02:01:46.590028 30195 sched.cpp:1025] Scheduler::statusUpdate took
> 193790ns
> > I0923 02:01:46.590524 30191 master.cpp:4756] Processing ACKNOWLEDGE call
> 7c8ecbbb-6705-48fc-82f0-6a0097b8215e for task 
> 64a804ba-b593-433d-90cc-be342ab8165d
> of framework c70d1f79-b5ba-4949-b931-578b1099cfc8-0000 (default) at
> scheduler-3a7471c6-7413-43a9-a1f7-3a57c615bb24@172.17.0.2:47968 on agent
> c70d1f79-b5ba-4949-b931-578b1099cfc8-S0
> > I0923 02:01:46.591197 30206 status_update_manager.cpp:395] Received
> status update acknowledgement (UUID: 7c8ecbbb-6705-48fc-82f0-6a0097b8215e)
> for task 64a804ba-b593-433d-90cc-be342ab8165d of framework
> c70d1f79-b5ba-4949-b931-578b1099cfc8-0000
> > I0923 02:01:46.591634 30191 slave.cpp:2953] Status update manager
> successfully handled status update acknowledgement (UUID:
> 7c8ecbbb-6705-48fc-82f0-6a0097b8215e) for task 
> 64a804ba-b593-433d-90cc-be342ab8165d
> of framework c70d1f79-b5ba-4949-b931-578b1099cfc8-0000
> > I0923 02:01:47.404165 30198 hierarchical.cpp:1691] No allocations
> performed
> > I0923 02:01:47.404239 30198 hierarchical.cpp:1786] No inverse offers to
> send out!
> > I0923 02:01:47.404323 30198 hierarchical.cpp:1283] Performed allocation
> for 1 agents in 372197ns
> > I0923 02:01:47.688933 30206 process.cpp:3336] Handling HTTP event for
> process '(1)' with path: '/(1)/api/v1/executor'
> > I0923 02:01:47.689882 30206 http.cpp:273] HTTP POST for
> /(1)/api/v1/executor from 172.17.0.2:35268
> > I0923 02:01:47.690167 30206 slave.cpp:3606] Handling status update
> TASK_FINISHED (UUID: 2cb6ff03-0867-443b-99ed-a0439b64c30f) for task
> 64a804ba-b593-433d-90cc-be342ab8165d of framework c70d1f79-b5ba-4949-b931-
> 578b1099cfc8-0000
> > I0923 02:01:47.693555 30196 status_update_manager.cpp:323] Received
> status update TASK_FINISHED (UUID: 2cb6ff03-0867-443b-99ed-a0439b64c30f)
> for task 64a804ba-b593-433d-90cc-be342ab8165d of framework
> c70d1f79-b5ba-4949-b931-578b1099cfc8-0000
> > I0923 02:01:47.693809 30196 status_update_manager.cpp:377] Forwarding
> update TASK_FINISHED (UUID: 2cb6ff03-0867-443b-99ed-a0439b64c30f) for
> task 64a804ba-b593-433d-90cc-be342ab8165d of framework
> c70d1f79-b5ba-4949-b931-578b1099cfc8-0000 to the agent
> > I0923 02:01:47.694231 30199 slave.cpp:4007] Forwarding the update
> TASK_FINISHED (UUID: 2cb6ff03-0867-443b-99ed-a0439b64c30f) for task
> 64a804ba-b593-433d-90cc-be342ab8165d of framework 
> c70d1f79-b5ba-4949-b931-578b1099cfc8-0000
> to master@172.17.0.2:47968
> > I0923 02:01:47.694506 30199 slave.cpp:3901] Status update manager
> successfully handled status update TASK_FINISHED (UUID:
> 2cb6ff03-0867-443b-99ed-a0439b64c30f) for task 
> 64a804ba-b593-433d-90cc-be342ab8165d
> of framework c70d1f79-b5ba-4949-b931-578b1099cfc8-0000
> > I0923 02:01:47.694656 30195 master.cpp:5638] Status update TASK_FINISHED
> (UUID: 2cb6ff03-0867-443b-99ed-a0439b64c30f) for task
> 64a804ba-b593-433d-90cc-be342ab8165d of framework 
> c70d1f79-b5ba-4949-b931-578b1099cfc8-0000
> from agent c70d1f79-b5ba-4949-b931-578b1099cfc8-S0 at (1)@172.17.0.2:47968
> (ceb803c18b6b)
> > I0923 02:01:47.694737 30195 master.cpp:5700] Forwarding status update
> TASK_FINISHED (UUID: 2cb6ff03-0867-443b-99ed-a0439b64c30f) for task
> 64a804ba-b593-433d-90cc-be342ab8165d of framework c70d1f79-b5ba-4949-b931-
> 578b1099cfc8-0000
> > I0923 02:01:47.695103 30195 master.cpp:7537] Updating the state of task
> 64a804ba-b593-433d-90cc-be342ab8165d of framework 
> c70d1f79-b5ba-4949-b931-578b1099cfc8-0000
> (latest state: TASK_FINISHED, status update state: TASK_FINISHED)
> > I0923 02:01:47.695369 30191 sched.cpp:1025] Scheduler::statusUpdate took
> 156432ns
> > I0923 02:01:47.696828 30203 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
> c70d1f79-b5ba-4949-b931-578b1099cfc8-S0 from framework
> c70d1f79-b5ba-4949-b931-578b1099cfc8-0000
> > I0923 02:01:47.697623 30195 master.cpp:4756] Processing ACKNOWLEDGE call
> 2cb6ff03-0867-443b-99ed-a0439b64c30f for task 
> 64a804ba-b593-433d-90cc-be342ab8165d
> of framework c70d1f79-b5ba-4949-b931-578b1099cfc8-0000 (default) at
> scheduler-3a7471c6-7413-43a9-a1f7-3a57c615bb24@172.17.0.2:47968 on agent
> c70d1f79-b5ba-4949-b931-578b1099cfc8-S0
> > I0923 02:01:47.697705 30195 master.cpp:7633] Removing task
> 64a804ba-b593-433d-90cc-be342ab8165d with resources cpus(*):2;
> mem(*):1024; disk(*):1024; ports(*):[31000-32000] of framework
> c70d1f79-b5ba-4949-b931-578b1099cfc8-0000 on agent
> c70d1f79-b5ba-4949-b931-578b1099cfc8-S0 at (1)@172.17.0.2:47968
> (ceb803c18b6b)
> > I0923 02:01:47.698447 30198 status_update_manager.cpp:395] Received
> status update acknowledgement (UUID: 2cb6ff03-0867-443b-99ed-a0439b64c30f)
> for task 64a804ba-b593-433d-90cc-be342ab8165d of framework
> c70d1f79-b5ba-4949-b931-578b1099cfc8-0000
> > I0923 02:01:47.698683 30198 status_update_manager.cpp:531] Cleaning up
> status update stream for task 64a804ba-b593-433d-90cc-be342ab8165d of
> framework c70d1f79-b5ba-4949-b931-578b1099cfc8-0000
> > I0923 02:01:47.699318 30206 slave.cpp:2953] Status update manager
> successfully handled status update acknowledgement (UUID:
> 2cb6ff03-0867-443b-99ed-a0439b64c30f) for task 
> 64a804ba-b593-433d-90cc-be342ab8165d
> of framework c70d1f79-b5ba-4949-b931-578b1099cfc8-0000
> > I0923 02:01:47.699398 30206 slave.cpp:6486] Completing task
> 64a804ba-b593-433d-90cc-be342ab8165d
> > I0923 02:01:47.795792 30194 containerizer.cpp:1842] Executor for
> container c3a773f0-7be6-497c-be75-eb29e867b8e0 has exited
> > I0923 02:01:47.795843 30194 containerizer.cpp:1612] Destroying container
> c3a773f0-7be6-497c-be75-eb29e867b8e0
> > I0923 02:01:47.801978 30203 provisioner.cpp:438] Ignoring destroy
> request for unknown container c3a773f0-7be6-497c-be75-eb29e867b8e0
> > I0923 02:01:47.802531 30202 slave.cpp:4498] Executor
> '64a804ba-b593-433d-90cc-be342ab8165d' of framework
> c70d1f79-b5ba-4949-b931-578b1099cfc8-0000 exited with status 0
> > I0923 02:01:47.802623 30202 slave.cpp:4602] Cleaning up executor
> '64a804ba-b593-433d-90cc-be342ab8165d' of framework
> c70d1f79-b5ba-4949-b931-578b1099cfc8-0000 (via HTTP)
> > I0923 02:01:47.803333 30202 slave.cpp:4690] Cleaning up framework
> c70d1f79-b5ba-4949-b931-578b1099cfc8-0000
> > I0923 02:01:47.803475 30191 status_update_manager.cpp:285] Closing
> status update streams for framework c70d1f79-b5ba-4949-b931-
> 578b1099cfc8-0000
> > I0923 02:01:48.406038 30202 hierarchical.cpp:1786] No inverse offers to
> send out!
> > I0923 02:01:48.406215 30202 hierarchical.cpp:1283] Performed allocation
> for 1 agents in 1.469451ms
> > I0923 02:01:48.406754 30194 master.cpp:6412] Sending 1 offers to
> framework c70d1f79-b5ba-4949-b931-578b1099cfc8-0000 (default) at
> scheduler-3a7471c6-7413-43a9-a1f7-3a57c615bb24@172.17.0.2:47968
> > I0923 02:01:48.407296 30205 sched.cpp:917] Scheduler::resourceOffers
> took 21684ns
> > I0923 02:01:49.407096 30206 hierarchical.cpp:1691] No allocations
> performed
> > I0923 02:01:49.407171 30206 hierarchical.cpp:1786] No inverse offers to
> send out!
> > I0923 02:01:49.407258 30206 hierarchical.cpp:1283] Performed allocation
> for 1 agents in 364799ns
> > I0923 02:01:50.408556 30192 hierarchical.cpp:1691] No allocations
> performed
> > I0923 02:01:50.408641 30192 hierarchical.cpp:1786] No inverse offers to
> send out!
> > I0923 02:01:50.408745 30192 hierarchical.cpp:1283] Performed allocation
> for 1 agents in 406343ns
> > I0923 02:01:51.409394 30199 hierarchical.cpp:1691] No allocations
> performed
> > I0923 02:01:51.409471 30199 hierarchical.cpp:1786] No inverse offers to
> send out!
> > I0923 02:01:51.409554 30199 hierarchical.cpp:1283] Performed allocation
> for 1 agents in 369762ns
> > I0923 02:01:52.410338 30202 hierarchical.cpp:1691] No allocations
> performed
> > I0923 02:01:52.410419 30202 hierarchical.cpp:1786] No inverse offers to
> send out!
> > I0923 02:01:52.410536 30202 hierarchical.cpp:1283] Performed allocation
> for 1 agents in 419315ns
> > I0923 02:01:53.411484 30198 hierarchical.cpp:1691] No allocations
> performed
> > I0923 02:01:53.411559 30198 hierarchical.cpp:1786] No inverse offers to
> send out!
> > I0923 02:01:53.411644 30198 hierarchical.cpp:1283] Performed allocation
> for 1 agents in 367727ns
> > I0923 02:01:54.413033 30199 hierarchical.cpp:1691] No allocations
> performed
> > I0923 02:01:54.413110 30199 hierarchical.cpp:1786] No inverse offers to
> send out!
> > I0923 02:01:54.413200 30199 hierarchical.cpp:1283] Performed allocation
> for 1 agents in 376654ns
> > I0923 02:01:55.414566 30198 hierarchical.cpp:1691] No allocations
> performed
> > I0923 02:01:55.414630 30198 hierarchical.cpp:1786] No inverse offers to
> send out!
> > I0923 02:01:55.414721 30198 hierarchical.cpp:1283] Performed allocation
> for 1 agents in 336148ns
> > I0923 02:01:55.442041 30205 slave.cpp:4089] Received ping from
> slave-observer(8)@172.17.0.2:47968
> > I0923 02:01:56.415900 30196 hierarchical.cpp:1691] No allocations
> performed
> > I0923 02:01:56.415973 30196 hierarchical.cpp:1786] No inverse offers to
> send out!
> > I0923 02:01:56.416060 30196 hierarchical.cpp:1283] Performed allocation
> for 1 agents in 364213ns
> > I0923 02:01:57.416712 30198 hierarchical.cpp:1691] No allocations
> performed
> > I0923 02:01:57.416779 30198 hierarchical.cpp:1786] No inverse offers to
> send out!
> > I0923 02:01:57.416853 30198 hierarchical.cpp:1283] Performed allocation
> for 1 agents in 318248ns
> > I0923 02:01:58.417743 30201 hierarchical.cpp:1691] No allocations
> performed
> > I0923 02:01:58.417814 30201 hierarchical.cpp:1786] No inverse offers to
> send out!
> > I0923 02:01:58.417896 30201 hierarchical.cpp:1283] Performed allocation
> for 1 agents in 340275ns
> > I0923 02:01:59.419083 30203 hierarchical.cpp:1691] No allocations
> performed
> > I0923 02:01:59.419159 30203 hierarchical.cpp:1786] No inverse offers to
> send out!
> > I0923 02:01:59.419244 30203 hierarchical.cpp:1283] Performed allocation
> for 1 agents in 365489ns
> > I0923 02:02:00.420297 30191 hierarchical.cpp:1691] No allocations
> performed
> > I0923 02:02:00.420374 30191 hierarchical.cpp:1786] No inverse offers to
> send out!
> > I0923 02:02:00.420469 30191 hierarchical.cpp:1283] Performed allocation
> for 1 agents in 380802ns
> > I0923 02:02:01.421715 30196 hierarchical.cpp:1691] No allocations
> performed
> > I0923 02:02:01.421802 30196 hierarchical.cpp:1786] No inverse offers to
> send out!
> > I0923 02:02:01.421906 30196 hierarchical.cpp:1283] Performed allocation
> for 1 agents in 403188ns
> > I0923 02:02:02.422824 30194 hierarchical.cpp:1691] No allocations
> performed
> > I0923 02:02:02.422924 30194 hierarchical.cpp:1786] No inverse offers to
> send out!
> > I0923 02:02:02.423038 30194 hierarchical.cpp:1283] Performed allocation
> for 1 agents in 468849ns
> > ../../src/tests/command_executor_tests.cpp:288: Failure
> > Failed to wait 15secs for termination
> > I0923 02:02:02.697932 30204 master.cpp:1297] Framework
> c70d1f79-b5ba-4949-b931-578b1099cfc8-0000 (default) at
> scheduler-3a7471c6-7413-43a9-a1f7-3a57c615bb24@172.17.0.2:47968
> disconnected
> > I0923 02:02:02.697996 30204 master.cpp:2888] Disconnecting framework
> c70d1f79-b5ba-4949-b931-578b1099cfc8-0000 (default) at
> scheduler-3a7471c6-7413-43a9-a1f7-3a57c615bb24@172.17.0.2:47968
> > I0923 02:02:02.698060 30204 master.cpp:2912] Deactivating framework
> c70d1f79-b5ba-4949-b931-578b1099cfc8-0000 (default) at
> scheduler-3a7471c6-7413-43a9-a1f7-3a57c615bb24@172.17.0.2:47968
> > I0923 02:02:02.698297 30195 hierarchical.cpp:383] Deactivated framework
> c70d1f79-b5ba-4949-b931-578b1099cfc8-0000
> > W0923 02:02:02.698882 30204 master.hpp:2265] Master attempted to send
> message to disconnected framework c70d1f79-b5ba-4949-b931-578b1099cfc8-0000
> (default) at scheduler-3a7471c6-7413-43a9-a1f7-3a57c615bb24@172.17.0.2:
> 47968
> > I0923 02:02:02.699007 30204 master.cpp:1310] Giving framework
> c70d1f79-b5ba-4949-b931-578b1099cfc8-0000 (default) at
> scheduler-3a7471c6-7413-43a9-a1f7-3a57c615bb24@172.17.0.2:47968 0ns to
> failover
> > ../../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:02:02.699373 30202 master.cpp:6264] Framework failover timeout,
> removing framework c70d1f79-b5ba-4949-b931-578b1099cfc8-0000 (default) at
> scheduler-3a7471c6-7413-43a9-a1f7-3a57c615bb24@172.17.0.2:47968
> > I0923 02:02:02.699419 30202 master.cpp:7000] Removing framework
> c70d1f79-b5ba-4949-b931-578b1099cfc8-0000 (default) at
> scheduler-3a7471c6-7413-43a9-a1f7-3a57c615bb24@172.17.0.2:47968
> > I0923 02:02:02.699589 30205 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
> c70d1f79-b5ba-4949-b931-578b1099cfc8-S0 from framework
> c70d1f79-b5ba-4949-b931-578b1099cfc8-0000
> > I0923 02:02:02.699683 30192 slave.cpp:2506] Asked to shut down framework
> c70d1f79-b5ba-4949-b931-578b1099cfc8-0000 by master@172.17.0.2:47968
> > W0923 02:02:02.699728 30192 slave.cpp:2521] Cannot shut down unknown
> framework c70d1f79-b5ba-4949-b931-578b1099cfc8-0000
> > I0923 02:02:02.700001 30191 hierarchical.cpp:334] Removed framework
> c70d1f79-b5ba-4949-b931-578b1099cfc8-0000
> > *** Aborted at 1474596122 (unix time) try "date -d @1474596122" if you
> are using GNU date ***
> > PC: @                0x0 (unknown)
> > *** SIGSEGV (@0x0) received by PID 30172 (TID 0x2b38d310b700) from PID
> 0; stack trace: ***
> >    @     0x2b38c89cb330 (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-1474594287-25749
> > Untagged: mesos-1474594287-25749:latest
> > Deleted: sha256:dac5fa06135b91f89969dd1ab75b52
> efe2ab6f849313d6e28f32e8fb100212e8
> > Deleted: sha256:d2434f0b0751739367858e47d65cc1
> b84a7a1814cc4e2cc634d1108a79043584
> > Deleted: sha256:a0ae0aa667dde45205b2e539818a38
> 05938fc39d13a6c719725eae6b7fde19eb
> > Deleted: sha256:e1c38ebd89157981af6ce61937a504
> 654e8b0fef0a0c8b4b1cbffc7bcfb37e5e
> > Deleted: sha256:c271150cf7cccbe306a8880a94c810
> a376cc881a0185ce4463055d840297f0da
> > Deleted: sha256:f427dea98dc8181afebe9a5fb5faa9
> 94352ed849af881df5a4e9be1f1ea5eab8
> > Deleted: sha256:5a92a9d442228e06bc3b174905c03d
> ed5f0bde820762e735fa558e3c9d05e192
> > Deleted: sha256:36dbeade7fe6fe6368b3b0e974a501
> b8a93b414ada2224d35c0984faf53505ab
> > Deleted: sha256:9c457c7a95f5255ec2a0897724c6ca
> c78f8d3a6527fe2cd0c427aef2d9c0be03
> > Deleted: sha256:f8ff54d12c3b10533c640f804a3bf2
> 0839de52f72349a03a47bf5f29ee812447
> > Deleted: sha256:ca5e21e6c3a68e7237f0613055eac2
> 691c56aa802f4a27153d268f0cf7090735
> > Deleted: sha256:f344968c1f98e5e1f8166f87015d03
> 5e028e7edddb4949259bb61a294b400a9c
> > Deleted: sha256:9a36b6ce6377f3d6002612fec486ca
> 614570be7bcc3cfd3334539e679b9d6917
> > Deleted: sha256:7ed9271bd09a195c61cfbb9f6f3984
> 6ade4bac8ee30644b75725ed2a976d2450
> > Deleted: sha256:e6449058f85384e0418b0bb1973603
> 78cb2b22a9c37463430d359afae187ba59
> > Deleted: sha256:43923f240374d78328794e0022551c
> d8083abb980321805294d5ba275aa76df5
> > Deleted: sha256:a4abadfb5b2a9718898059ebd7c916
> 6cc1533bbaa5a9ebc61a9a9dbe2135703a
> > Deleted: sha256:c4c2fff2511f4b6614f67d7a30b79e
> 1e8b8e79f65c57d3cd8fb46a4db625a3bf
> > Deleted: sha256:ccd6dd92d05485eee7036639d110aa
> 648d982e1b6253531f3a380084487ab591
> > Deleted: sha256:f5d5f4deecb28234ac9f54d4b35d40
> 25a000308031dae531daff8280f9a1c2cf
> > Deleted: sha256:7dafb4b97264741d9ff138ab586f9f
> f25463ee761eff7f9c006bce8937b22d90
> > Deleted: sha256:a8719430b4ecb659ed7c48c968e163
> ddfa89240f99f991cf72fb1d1affd465c5
> > Deleted: sha256:99531ea918c4b0968f103a0fe53791
> 74a28f5b57f0e8ae72b37503edc960cec4
> > Deleted: sha256:95768839c10277ed4772f31cfcad58
> 41e4bc270e18b0ab8e34d6c2505874acee
> > Build step 'Execute shell' marked build as failure
>

Reply via email to