Build failed in Jenkins: Mesos » autotools,gcc,--verbose --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(docker||Hadoop)&&(!ubuntu-us1)&&(!ubuntu-6) #2671

[Apache Jenkins Server <je...@builds.apache.org>]

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:dac5fa06135b91f89969dd1ab75b52efe2ab6f849313d6e28f32e8fb100212e8
Deleted: sha256:d2434f0b0751739367858e47d65cc1b84a7a1814cc4e2cc634d1108a79043584
Deleted: sha256:a0ae0aa667dde45205b2e539818a3805938fc39d13a6c719725eae6b7fde19eb
Deleted: sha256:e1c38ebd89157981af6ce61937a504654e8b0fef0a0c8b4b1cbffc7bcfb37e5e
Deleted: sha256:c271150cf7cccbe306a8880a94c810a376cc881a0185ce4463055d840297f0da
Deleted: sha256:f427dea98dc8181afebe9a5fb5faa994352ed849af881df5a4e9be1f1ea5eab8
Deleted: sha256:5a92a9d442228e06bc3b174905c03ded5f0bde820762e735fa558e3c9d05e192
Deleted: sha256:36dbeade7fe6fe6368b3b0e974a501b8a93b414ada2224d35c0984faf53505ab
Deleted: sha256:9c457c7a95f5255ec2a0897724c6cac78f8d3a6527fe2cd0c427aef2d9c0be03
Deleted: sha256:f8ff54d12c3b10533c640f804a3bf20839de52f72349a03a47bf5f29ee812447
Deleted: sha256:ca5e21e6c3a68e7237f0613055eac2691c56aa802f4a27153d268f0cf7090735
Deleted: sha256:f344968c1f98e5e1f8166f87015d035e028e7edddb4949259bb61a294b400a9c
Deleted: sha256:9a36b6ce6377f3d6002612fec486ca614570be7bcc3cfd3334539e679b9d6917
Deleted: sha256:7ed9271bd09a195c61cfbb9f6f39846ade4bac8ee30644b75725ed2a976d2450
Deleted: sha256:e6449058f85384e0418b0bb197360378cb2b22a9c37463430d359afae187ba59
Deleted: sha256:43923f240374d78328794e0022551cd8083abb980321805294d5ba275aa76df5
Deleted: sha256:a4abadfb5b2a9718898059ebd7c9166cc1533bbaa5a9ebc61a9a9dbe2135703a
Deleted: sha256:c4c2fff2511f4b6614f67d7a30b79e1e8b8e79f65c57d3cd8fb46a4db625a3bf
Deleted: sha256:ccd6dd92d05485eee7036639d110aa648d982e1b6253531f3a380084487ab591
Deleted: sha256:f5d5f4deecb28234ac9f54d4b35d4025a000308031dae531daff8280f9a1c2cf
Deleted: sha256:7dafb4b97264741d9ff138ab586f9ff25463ee761eff7f9c006bce8937b22d90
Deleted: sha256:a8719430b4ecb659ed7c48c968e163ddfa89240f99f991cf72fb1d1affd465c5
Deleted: sha256:99531ea918c4b0968f103a0fe5379174a28f5b57f0e8ae72b37503edc960cec4
Deleted: sha256:95768839c10277ed4772f31cfcad5841e4bc270e18b0ab8e34d6c2505874acee
Build step 'Execute shell' marked build as failure

Re: Build failed in Jenkins: Mesos » autotools,gcc,--verbose --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(docker||Hadoop)&&(!ubuntu-us1)&&(!ubuntu-6) #2671

[Vinod Kone <vi...@apache.org>]

thanks guys!

On Thu, Sep 22, 2016 at 7:43 PM, Joseph Wu <jo...@mesosphere.io> wrote:

> Pushed the fix: https://reviews.apache.org/r/52186/
>
> Thanks Guangya!
>
> On Thu, Sep 22, 2016 at 7:08 PM, Vinod Kone <vi...@gmail.com> wrote:
>
> > Qian, can you take a look?
> >
> > @vinodkone
> >
> > > On Sep 22, 2016, at 7:02 PM, Apache Jenkins Server <
> > jenkins@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
> >
>

Re: Build failed in Jenkins: Mesos » autotools,gcc,--verbose --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(docker||Hadoop)&&(!ubuntu-us1)&&(!ubuntu-6) #2671

[Joseph Wu <jo...@mesosphere.io>]

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

Thanks Guangya!

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

> Qian, can you take a look?
>
> @vinodkone
>
> > On Sep 22, 2016, at 7:02 PM, Apache Jenkins Server <
> jenkins@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
>

Re: Build failed in Jenkins: Mesos » autotools,gcc,--verbose --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(docker||Hadoop)&&(!ubuntu-us1)&&(!ubuntu-6) #2671

[Vinod Kone <vi...@gmail.com>]

Qian, can you take a look?

@vinodkone

> On Sep 22, 2016, at 7:02 PM, Apache Jenkins Server <je...@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:dac5fa06135b91f89969dd1ab75b52efe2ab6f849313d6e28f32e8fb100212e8
> Deleted: sha256:d2434f0b0751739367858e47d65cc1b84a7a1814cc4e2cc634d1108a79043584
> Deleted: sha256:a0ae0aa667dde45205b2e539818a3805938fc39d13a6c719725eae6b7fde19eb
> Deleted: sha256:e1c38ebd89157981af6ce61937a504654e8b0fef0a0c8b4b1cbffc7bcfb37e5e
> Deleted: sha256:c271150cf7cccbe306a8880a94c810a376cc881a0185ce4463055d840297f0da
> Deleted: sha256:f427dea98dc8181afebe9a5fb5faa994352ed849af881df5a4e9be1f1ea5eab8
> Deleted: sha256:5a92a9d442228e06bc3b174905c03ded5f0bde820762e735fa558e3c9d05e192
> Deleted: sha256:36dbeade7fe6fe6368b3b0e974a501b8a93b414ada2224d35c0984faf53505ab
> Deleted: sha256:9c457c7a95f5255ec2a0897724c6cac78f8d3a6527fe2cd0c427aef2d9c0be03
> Deleted: sha256:f8ff54d12c3b10533c640f804a3bf20839de52f72349a03a47bf5f29ee812447
> Deleted: sha256:ca5e21e6c3a68e7237f0613055eac2691c56aa802f4a27153d268f0cf7090735
> Deleted: sha256:f344968c1f98e5e1f8166f87015d035e028e7edddb4949259bb61a294b400a9c
> Deleted: sha256:9a36b6ce6377f3d6002612fec486ca614570be7bcc3cfd3334539e679b9d6917
> Deleted: sha256:7ed9271bd09a195c61cfbb9f6f39846ade4bac8ee30644b75725ed2a976d2450
> Deleted: sha256:e6449058f85384e0418b0bb197360378cb2b22a9c37463430d359afae187ba59
> Deleted: sha256:43923f240374d78328794e0022551cd8083abb980321805294d5ba275aa76df5
> Deleted: sha256:a4abadfb5b2a9718898059ebd7c9166cc1533bbaa5a9ebc61a9a9dbe2135703a
> Deleted: sha256:c4c2fff2511f4b6614f67d7a30b79e1e8b8e79f65c57d3cd8fb46a4db625a3bf
> Deleted: sha256:ccd6dd92d05485eee7036639d110aa648d982e1b6253531f3a380084487ab591
> Deleted: sha256:f5d5f4deecb28234ac9f54d4b35d4025a000308031dae531daff8280f9a1c2cf
> Deleted: sha256:7dafb4b97264741d9ff138ab586f9ff25463ee761eff7f9c006bce8937b22d90
> Deleted: sha256:a8719430b4ecb659ed7c48c968e163ddfa89240f99f991cf72fb1d1affd465c5
> Deleted: sha256:99531ea918c4b0968f103a0fe5379174a28f5b57f0e8ae72b37503edc960cec4
> Deleted: sha256:95768839c10277ed4772f31cfcad5841e4bc270e18b0ab8e34d6c2505874acee
> Build step 'Execute shell' marked build as failure

Jenkins build is back to normal : Mesos » autotools,gcc,--verbose --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(docker||Hadoop)&&(!ubuntu-us1)&&(!ubuntu-6) #2672

[Apache Jenkins Server <je...@builds.apache.org>]

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)/2672/changes>