Build failed in Jenkins: Mesos-Buildbot » cmake,clang,--verbose --disable-libtool-wrappers --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23) #4597

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

See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=clang,CONFIGURATION=--verbose%20--disable-libtool-wrappers%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4597/display/redirect?page=changes>

Changes:

[gregorywmann] Made `StatusUpdateManagerProcess` fill in the latest status.

[gregorywmann] Initialized offer operation status update manager in SLRP.

[gregorywmann] Fixed a typo in 'agent.proto'.

------------------------------------------
[...truncated 29.18 MB...]
3: I1214 01:53:39.428457 18411 master.cpp:10679] Removing offer 4270c100-fbda-47bf-bb00-269e26af4523-O0
3: I1214 01:53:39.428603 18411 master.cpp:4273] Processing ACCEPT call for offers: [ 4270c100-fbda-47bf-bb00-269e26af4523-O0 ] on agent 4270c100-fbda-47bf-bb00-269e26af4523-S0 at slave(810)@172.17.0.4:35744 (dd450c97a83d) for framework 4270c100-fbda-47bf-bb00-269e26af4523-0000 (default) at scheduler-b8792c8e-407a-47db-953f-933609d0c569@172.17.0.4:35744
3: I1214 01:53:39.428706 18411 master.cpp:3593] Authorizing framework principal 'test-principal' to launch task ed0edd9b-5756-42c7-b7a1-e3b214e910ca
3: W1214 01:53:39.430398 18419 validation.cpp:1387] Executor 'default' for task 'ed0edd9b-5756-42c7-b7a1-e3b214e910ca' uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases.
3: W1214 01:53:39.430431 18419 validation.cpp:1399] Executor 'default' for task 'ed0edd9b-5756-42c7-b7a1-e3b214e910ca' uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases.
3: I1214 01:53:39.430928 18419 master.cpp:11434] Adding task ed0edd9b-5756-42c7-b7a1-e3b214e910ca with resources cpus(allocated: *):2; mem(allocated: *):1024; disk(allocated: *):1024; ports(allocated: *):[31000-32000] on agent 4270c100-fbda-47bf-bb00-269e26af4523-S0 at slave(810)@172.17.0.4:35744 (dd450c97a83d)
3: I1214 01:53:39.431298 18419 master.cpp:5024] Launching task ed0edd9b-5756-42c7-b7a1-e3b214e910ca of framework 4270c100-fbda-47bf-bb00-269e26af4523-0000 (default) at scheduler-b8792c8e-407a-47db-953f-933609d0c569@172.17.0.4:35744 with resources [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}] on agent 4270c100-fbda-47bf-bb00-269e26af4523-S0 at slave(810)@172.17.0.4:35744 (dd450c97a83d)
3: I1214 01:53:39.431987 18422 slave.cpp:1807] Got assigned task 'ed0edd9b-5756-42c7-b7a1-e3b214e910ca' for framework 4270c100-fbda-47bf-bb00-269e26af4523-0000
3: I1214 01:53:39.432631 18422 slave.cpp:2077] Authorizing task 'ed0edd9b-5756-42c7-b7a1-e3b214e910ca' for framework 4270c100-fbda-47bf-bb00-269e26af4523-0000
3: I1214 01:53:39.432691 18422 slave.cpp:7792] Authorizing framework principal 'test-principal' to launch task ed0edd9b-5756-42c7-b7a1-e3b214e910ca
3: I1214 01:53:39.433516 18429 slave.cpp:2471] Launching task 'ed0edd9b-5756-42c7-b7a1-e3b214e910ca' for framework 4270c100-fbda-47bf-bb00-269e26af4523-0000
3: I1214 01:53:39.433959 18429 paths.cpp:756] Trying to chown '/tmp/ContentType_ExecutorHttpApiTest_Subscribe_0_ntsIuy/slaves/4270c100-fbda-47bf-bb00-269e26af4523-S0/frameworks/4270c100-fbda-47bf-bb00-269e26af4523-0000/executors/default/runs/6a9c06b5-d10f-4679-aad6-70fe0ad00fb1' to user 'mesos'
3: I1214 01:53:39.434178 18429 slave.cpp:8266] Launching executor 'default' of framework 4270c100-fbda-47bf-bb00-269e26af4523-0000 with resources [] in work directory '/tmp/ContentType_ExecutorHttpApiTest_Subscribe_0_ntsIuy/slaves/4270c100-fbda-47bf-bb00-269e26af4523-S0/frameworks/4270c100-fbda-47bf-bb00-269e26af4523-0000/executors/default/runs/6a9c06b5-d10f-4679-aad6-70fe0ad00fb1'
3: I1214 01:53:39.434674 18429 slave.cpp:3023] Launching container 6a9c06b5-d10f-4679-aad6-70fe0ad00fb1 for executor 'default' of framework 4270c100-fbda-47bf-bb00-269e26af4523-0000
3: I1214 01:53:39.435184 18429 slave.cpp:2557] Queued task 'ed0edd9b-5756-42c7-b7a1-e3b214e910ca' for executor 'default' of framework 4270c100-fbda-47bf-bb00-269e26af4523-0000
3: I1214 01:53:39.435272 18429 slave.cpp:984] Successfully attached '/tmp/ContentType_ExecutorHttpApiTest_Subscribe_0_ntsIuy/slaves/4270c100-fbda-47bf-bb00-269e26af4523-S0/frameworks/4270c100-fbda-47bf-bb00-269e26af4523-0000/executors/default/runs/6a9c06b5-d10f-4679-aad6-70fe0ad00fb1' to virtual path '/tmp/ContentType_ExecutorHttpApiTest_Subscribe_0_ntsIuy/slaves/4270c100-fbda-47bf-bb00-269e26af4523-S0/frameworks/4270c100-fbda-47bf-bb00-269e26af4523-0000/executors/default/runs/latest'
3: I1214 01:53:39.435310 18429 slave.cpp:984] Successfully attached '/tmp/ContentType_ExecutorHttpApiTest_Subscribe_0_ntsIuy/slaves/4270c100-fbda-47bf-bb00-269e26af4523-S0/frameworks/4270c100-fbda-47bf-bb00-269e26af4523-0000/executors/default/runs/6a9c06b5-d10f-4679-aad6-70fe0ad00fb1' to virtual path '/frameworks/4270c100-fbda-47bf-bb00-269e26af4523-0000/executors/default/runs/latest'
3: I1214 01:53:39.435339 18429 slave.cpp:984] Successfully attached '/tmp/ContentType_ExecutorHttpApiTest_Subscribe_0_ntsIuy/slaves/4270c100-fbda-47bf-bb00-269e26af4523-S0/frameworks/4270c100-fbda-47bf-bb00-269e26af4523-0000/executors/default/runs/6a9c06b5-d10f-4679-aad6-70fe0ad00fb1' to virtual path '/tmp/ContentType_ExecutorHttpApiTest_Subscribe_0_ntsIuy/slaves/4270c100-fbda-47bf-bb00-269e26af4523-S0/frameworks/4270c100-fbda-47bf-bb00-269e26af4523-0000/executors/default/runs/6a9c06b5-d10f-4679-aad6-70fe0ad00fb1'
3: W1214 01:53:39.437657 18427 process.cpp:2756] Attempted to spawn already running process version@172.17.0.4:35744
3: I1214 01:53:39.437824 18427 exec.cpp:162] Version: 1.5.0
3: I1214 01:53:39.438083 18425 exec.cpp:212] Executor started at: executor(202)@172.17.0.4:35744 with pid 18410
3: I1214 01:53:39.440748 18419 process.cpp:3503] Handling HTTP event for process 'slave(810)' with path: '/slave(810)/api/v1/executor'
3: I1214 01:53:39.442075 18434 http.cpp:1185] HTTP POST for /slave(810)/api/v1/executor from 172.17.0.4:59690
3: I1214 01:53:39.442287 18434 slave.cpp:4097] Received Subscribe request for HTTP executor 'default' of framework 4270c100-fbda-47bf-bb00-269e26af4523-0000
3: I1214 01:53:39.443804 18416 slave.cpp:2762] Sending queued task 'ed0edd9b-5756-42c7-b7a1-e3b214e910ca' to executor 'default' of framework 4270c100-fbda-47bf-bb00-269e26af4523-0000 (via HTTP)
3: I1214 01:53:39.445202 18410 sched.cpp:2009] Asked to stop the driver
3: I1214 01:53:39.445291 18413 sched.cpp:1191] Stopping framework 4270c100-fbda-47bf-bb00-269e26af4523-0000
3: I1214 01:53:39.445533 18434 master.cpp:9438] Processing TEARDOWN call for framework 4270c100-fbda-47bf-bb00-269e26af4523-0000 (default) at scheduler-b8792c8e-407a-47db-953f-933609d0c569@172.17.0.4:35744
3: I1214 01:53:39.445560 18434 master.cpp:9450] Removing framework 4270c100-fbda-47bf-bb00-269e26af4523-0000 (default) at scheduler-b8792c8e-407a-47db-953f-933609d0c569@172.17.0.4:35744
3: I1214 01:53:39.445571 18434 master.cpp:3327] Deactivating framework 4270c100-fbda-47bf-bb00-269e26af4523-0000 (default) at scheduler-b8792c8e-407a-47db-953f-933609d0c569@172.17.0.4:35744
3: I1214 01:53:39.445713 18428 hierarchical.cpp:405] Deactivated framework 4270c100-fbda-47bf-bb00-269e26af4523-0000
3: I1214 01:53:39.445771 18427 slave.cpp:3375] Asked to shut down framework 4270c100-fbda-47bf-bb00-269e26af4523-0000 by master@172.17.0.4:35744
3: I1214 01:53:39.445780 18434 master.cpp:10154] Updating the state of task ed0edd9b-5756-42c7-b7a1-e3b214e910ca of framework 4270c100-fbda-47bf-bb00-269e26af4523-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED)
3: I1214 01:53:39.445804 18427 slave.cpp:3400] Shutting down framework 4270c100-fbda-47bf-bb00-269e26af4523-0000
3: I1214 01:53:39.445858 18427 slave.cpp:6114] Shutting down executor 'default' of framework 4270c100-fbda-47bf-bb00-269e26af4523-0000 (via HTTP)
3: I1214 01:53:39.446327 18416 slave.cpp:909] Agent terminating
3: I1214 01:53:39.446382 18416 slave.cpp:3375] Asked to shut down framework 4270c100-fbda-47bf-bb00-269e26af4523-0000 by @0.0.0.0:0
3: W1214 01:53:39.446410 18416 slave.cpp:3396] Ignoring shutdown framework 4270c100-fbda-47bf-bb00-269e26af4523-0000 because it is terminating
3: I1214 01:53:39.446473 18434 master.cpp:10260] Removing task ed0edd9b-5756-42c7-b7a1-e3b214e910ca with resources cpus(allocated: *):2; mem(allocated: *):1024; disk(allocated: *):1024; ports(allocated: *):[31000-32000] of framework 4270c100-fbda-47bf-bb00-269e26af4523-0000 on agent 4270c100-fbda-47bf-bb00-269e26af4523-S0 at slave(810)@172.17.0.4:35744 (dd450c97a83d)
3: I1214 01:53:39.446584 18419 exec.cpp:517] Agent exited ... shutting down
3: 
3: GMOCK WARNING:
3: Uninteresting mock function call - returning directly.
3:     Function call: shutdown(0x2aab18038fa0)
3: NOTE: You can safely ignore the above warning unless this call should not happen.  Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call.  See https://github.com/google/googletest/blob/master/googlemock/docs/CookBook.md#knowing-when-to-expect for details.
3: I1214 01:53:39.446672 18419 exec.cpp:534] Executor::shutdown took 77293ns
3: I1214 01:53:39.446759 18434 master.cpp:10289] Removing executor 'default' with resources [] of framework 4270c100-fbda-47bf-bb00-269e26af4523-0000 on agent 4270c100-fbda-47bf-bb00-269e26af4523-S0 at slave(810)@172.17.0.4:35744 (dd450c97a83d)
3: I1214 01:53:39.446822 18431 hierarchical.cpp:1192] Recovered cpus(allocated: *):2; mem(allocated: *):1024; disk(allocated: *):1024; ports(allocated: *):[31000-32000] (total: cpus:2; mem:1024; disk:1024; ports:[31000-32000], allocated: {}) on agent 4270c100-fbda-47bf-bb00-269e26af4523-S0 from framework 4270c100-fbda-47bf-bb00-269e26af4523-0000
3: I1214 01:53:39.447199 18434 master.cpp:1305] Agent 4270c100-fbda-47bf-bb00-269e26af4523-S0 at slave(810)@172.17.0.4:35744 (dd450c97a83d) disconnected
3: I1214 01:53:39.447218 18434 master.cpp:3364] Disconnecting agent 4270c100-fbda-47bf-bb00-269e26af4523-S0 at slave(810)@172.17.0.4:35744 (dd450c97a83d)
3: I1214 01:53:39.447262 18434 master.cpp:3383] Deactivating agent 4270c100-fbda-47bf-bb00-269e26af4523-S0 at slave(810)@172.17.0.4:35744 (dd450c97a83d)
3: I1214 01:53:39.447324 18427 hierarchical.cpp:344] Removed framework 4270c100-fbda-47bf-bb00-269e26af4523-0000
3: I1214 01:53:39.447377 18427 hierarchical.cpp:766] Agent 4270c100-fbda-47bf-bb00-269e26af4523-S0 deactivated
3: I1214 01:53:39.451311 18426 master.cpp:1147] Master terminating
3: I1214 01:53:39.451853 18416 hierarchical.cpp:609] Removed agent 4270c100-fbda-47bf-bb00-269e26af4523-S0
3: [       OK ] ContentType/ExecutorHttpApiTest.Subscribe/0 (63 ms)
3: [ RUN      ] ContentType/ExecutorHttpApiTest.Subscribe/1
3: I1214 01:53:39.458060 18410 cluster.cpp:172] Creating default 'local' authorizer
3: I1214 01:53:39.460858 18424 master.cpp:456] Master 14a7f8aa-71ab-4604-80a2-5b8bf52e936c (dd450c97a83d) started on 172.17.0.4:35744
3: I1214 01:53:39.460886 18424 master.cpp:458] Flags at startup: --acls="" --agent_ping_timeout="15secs" --agent_reregister_timeout="10mins" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate_agents="true" --authenticate_frameworks="true" --authenticate_http_frameworks="true" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/G6Vk5f/credentials" --filter_gpu_resources="true" --framework_sorter="drf" --help="false" --hostname_lookup="true" --http_authenticators="basic" --http_framework_authenticators="basic" --initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO" --max_agent_ping_timeouts="5" --max_completed_frameworks="50" --max_completed_tasks_per_framework="1000" --max_unreachable_tasks_per_framework="1000" --port="5050" --quiet="false" --recovery_agent_removal_limit="100%" --registry="in_memory" --registry_fetch_timeout="1mins" --registry_gc_interval="15mins" --registry_max_agent_age="2weeks" --registry_max_agent_count="102400" --registry_store_timeout="100secs" --registry_strict="false" --root_submissions="true" --user_sorter="drf" --version="false" --webui_dir="/usr/local/share/mesos/webui" --work_dir="/tmp/G6Vk5f/master" --zk_session_timeout="10secs"
3: I1214 01:53:39.461138 18424 master.cpp:507] Master only allowing authenticated frameworks to register
3: I1214 01:53:39.461148 18424 master.cpp:513] Master only allowing authenticated agents to register
3: I1214 01:53:39.461151 18424 master.cpp:519] Master only allowing authenticated HTTP frameworks to register
3: I1214 01:53:39.461158 18424 credentials.hpp:37] Loading credentials for authentication from '/tmp/G6Vk5f/credentials'
3: I1214 01:53:39.461395 18424 master.cpp:563] Using default 'crammd5' authenticator
3: I1214 01:53:39.461536 18424 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
3: I1214 01:53:39.461690 18424 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
3: I1214 01:53:39.461802 18424 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
3: I1214 01:53:39.461908 18424 master.cpp:642] Authorization enabled
3: I1214 01:53:39.462054 18416 hierarchical.cpp:175] Initialized hierarchical allocator process
3: I1214 01:53:39.462124 18427 whitelist_watcher.cpp:77] No whitelist given
3: I1214 01:53:39.464679 18423 master.cpp:2209] Elected as the leading master!
3: I1214 01:53:39.464699 18423 master.cpp:1689] Recovering from registrar
3: I1214 01:53:39.464828 18411 registrar.cpp:347] Recovering registrar
3: I1214 01:53:39.465458 18411 registrar.cpp:391] Successfully fetched the registry (0B) in 599040ns
3: I1214 01:53:39.465579 18411 registrar.cpp:495] Applied 1 operations in 44717ns; attempting to update the registry
3: I1214 01:53:39.466122 18411 registrar.cpp:552] Successfully updated the registry in 488960ns
3: I1214 01:53:39.466233 18411 registrar.cpp:424] Successfully recovered registrar
3: I1214 01:53:39.466575 18431 master.cpp:1802] Recovered 0 agents from the registry (135B); allowing 10mins for agents to re-register
3: I1214 01:53:39.466601 18421 hierarchical.cpp:213] Skipping recovery of hierarchical allocator: nothing to recover
3: W1214 01:53:39.471524 18410 process.cpp:2756] Attempted to spawn already running process files@172.17.0.4:35744
3: I1214 01:53:39.471799 18410 cluster.cpp:460] Creating default 'local' authorizer
3: I1214 01:53:39.474081 18417 slave.cpp:258] Mesos agent started on (811)@172.17.0.4:35744
3: I1214 01:53:39.474115 18417 slave.cpp:259] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/ContentType_ExecutorHttpApiTest_Subscribe_1_zztD56/store/appc" --authenticate_http_executors="false" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticatee="crammd5" --authentication_backoff_factor="1secs" --authorizer="local" --cgroups_cpu_enable_pids_and_tids_count="false" --cgroups_enable_cfs="false" --cgroups_hierarchy="/sys/fs/cgroup" --cgroups_limit_swap="false" --cgroups_root="mesos" --container_disk_watch_interval="15secs" --containerizers="mesos" --credential="/tmp/ContentType_ExecutorHttpApiTest_Subscribe_1_zztD56/credential" --default_role="*" --disallow_sharing_agent_pid_namespace="false" --disk_watch_interval="1mins" --docker="docker" --docker_kill_orphans="true" --docker_registry="https://registry-1.docker.io" --docker_remove_delay="6hrs" --docker_socket="/var/run/docker.sock" --docker_stop_timeout="0ns" --docker_store_dir="/tmp/ContentType_ExecutorHttpApiTest_Subscribe_1_zztD56/store/docker" --docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_reregistration_timeout="2secs" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/ContentType_ExecutorHttpApiTest_Subscribe_1_zztD56/fetch" --fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks" --gc_disk_headroom="0.1" --hadoop_home="" --help="false" --hostname_lookup="true" --http_command_executor="false" --http_credentials="/tmp/ContentType_ExecutorHttpApiTest_Subscribe_1_zztD56/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/build/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --port="5051" --qos_correction_interval_min="0ns" --quiet="false" --reconfiguration_policy="equal" --recover="reconnect" --recovery_timeout="15mins" --registration_backoff_factor="10ms" --resources="cpus:2;gpus:0;mem:1024;disk:1024;ports:[31000-32000]" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/ContentType_ExecutorHttpApiTest_Subscribe_1_zztD56" --sandbox_directory="/mnt/mesos/sandbox" --strict="true" --switch_user="true" --systemd_enable_support="true" --systemd_runtime_directory="/run/systemd/system" --version="false" --work_dir="/tmp/ContentType_ExecutorHttpApiTest_Subscribe_1_uQrtAb" --zk_session_timeout="10secs"
3: I1214 01:53:39.474547 18417 credentials.hpp:86] Loading credential for authentication from '/tmp/ContentType_ExecutorHttpApiTest_Subscribe_1_zztD56/credential'
3: W1214 01:53:39.474664 18410 process.cpp:2756] Attempted to spawn already running process version@172.17.0.4:35744
3: I1214 01:53:39.474709 18417 slave.cpp:291] Agent using credential for: test-principal
3: I1214 01:53:39.474726 18417 credentials.hpp:37] Loading credentials for authentication from '/tmp/ContentType_ExecutorHttpApiTest_Subscribe_1_zztD56/http_credentials'
3: I1214 01:53:39.474934 18417 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
3: I1214 01:53:39.475096 18417 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
3: I1214 01:53:39.475561 18410 sched.cpp:232] Version: 1.5.0
3: I1214 01:53:39.476128 18427 sched.cpp:336] New master detected at master@172.17.0.4:35744
3: I1214 01:53:39.476213 18427 sched.cpp:396] Authenticating with master master@172.17.0.4:35744
3: I1214 01:53:39.476228 18427 sched.cpp:403] Using default CRAM-MD5 authenticatee
3: I1214 01:53:39.476442 18416 authenticatee.cpp:121] Creating new client SASL connection
3: I1214 01:53:39.476706 18424 master.cpp:8928] Authenticating scheduler-4c3e244d-65d2-4b77-91cf-489e9ec46e74@172.17.0.4:35744
3: I1214 01:53:39.476594 18417 slave.cpp:590] Agent resources: [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
3: I1214 01:53:39.476806 18417 slave.cpp:598] Agent attributes: [  ]
3: I1214 01:53:39.476810 18426 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1440)@172.17.0.4:35744
3: I1214 01:53:39.476819 18417 slave.cpp:607] Agent hostname: dd450c97a83d
3: I1214 01:53:39.476987 18414 task_status_update_manager.cpp:181] Pausing sending task status updates
3: I1214 01:53:39.477105 18423 authenticator.cpp:98] Creating new server SASL connection
3: I1214 01:53:39.477330 18413 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
3: I1214 01:53:39.477358 18413 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
3: I1214 01:53:39.477464 18419 authenticator.cpp:204] Received SASL authentication start
3: I1214 01:53:39.477514 18419 authenticator.cpp:326] Authentication requires more steps
3: I1214 01:53:39.477625 18425 authenticatee.cpp:259] Received SASL authentication step
3: I1214 01:53:39.477733 18428 authenticator.cpp:232] Received SASL authentication step
3: I1214 01:53:39.477764 18428 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'dd450c97a83d' server FQDN: 'dd450c97a83d' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
3: I1214 01:53:39.477776 18428 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
3: I1214 01:53:39.477810 18428 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
3: I1214 01:53:39.477826 18428 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'dd450c97a83d' server FQDN: 'dd450c97a83d' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
3: I1214 01:53:39.477835 18428 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
3: I1214 01:53:39.477839 18428 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
3: I1214 01:53:39.477854 18428 authenticator.cpp:318] Authentication success
3: I1214 01:53:39.477953 18420 authenticatee.cpp:299] Authentication success
3: I1214 01:53:39.478024 18429 master.cpp:8958] Successfully authenticated principal 'test-principal' at scheduler-4c3e244d-65d2-4b77-91cf-489e9ec46e74@172.17.0.4:35744
3: I1214 01:53:39.478076 18418 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1440)@172.17.0.4:35744
3: I1214 01:53:39.478204 18411 sched.cpp:502] Successfully authenticated with master master@172.17.0.4:35744
3: I1214 01:53:39.478221 18411 sched.cpp:824] Sending SUBSCRIBE call to master@172.17.0.4:35744
3: I1214 01:53:39.478337 18411 sched.cpp:857] Will retry registration in 615.17938ms if necessary
3: I1214 01:53:39.478502 18421 master.cpp:2957] Received SUBSCRIBE call for framework 'default' at scheduler-4c3e244d-65d2-4b77-91cf-489e9ec46e74@172.17.0.4:35744
3: I1214 01:53:39.478520 18421 master.cpp:2274] Authorizing framework principal 'test-principal' to receive offers for roles '{ * }'
3: I1214 01:53:39.478703 18432 state.cpp:66] Recovering state from '/tmp/ContentType_ExecutorHttpApiTest_Subscribe_1_uQrtAb/meta'
3: I1214 01:53:39.478984 18422 task_status_update_manager.cpp:207] Recovering task status update manager
3: I1214 01:53:39.479030 18424 master.cpp:3037] Subscribing framework default with checkpointing disabled and capabilities [ MULTI_ROLE, RESERVATION_REFINEMENT ]
3: I1214 01:53:39.479220 18424 master.cpp:9149] Adding framework 14a7f8aa-71ab-4604-80a2-5b8bf52e936c-0000 (default) at scheduler-4c3e244d-65d2-4b77-91cf-489e9ec46e74@172.17.0.4:35744 with roles {  } suppressed
3: I1214 01:53:39.479326 18423 slave.cpp:6747] Finished recovery
3: I1214 01:53:39.479635 18425 sched.cpp:751] Framework registered with 14a7f8aa-71ab-4604-80a2-5b8bf52e936c-0000
3: I1214 01:53:39.479727 18425 sched.cpp:765] Scheduler::registered took 72819ns
3: I1214 01:53:39.479815 18413 hierarchical.cpp:297] Added framework 14a7f8aa-71ab-4604-80a2-5b8bf52e936c-0000
3: I1214 01:53:39.480072 18413 hierarchical.cpp:1517] Performed allocation for 0 agents in 108316ns
3: I1214 01:53:39.480095 18431 task_status_update_manager.cpp:181] Pausing sending task status updates
3: I1214 01:53:39.480101 18411 slave.cpp:1033] New master detected at master@172.17.0.4:35744
3: I1214 01:53:39.480193 18411 slave.cpp:1088] Detecting new master
3: I1214 01:53:39.484350 18416 slave.cpp:1115] Authenticating with master master@172.17.0.4:35744
3: I1214 01:53:39.484408 18416 slave.cpp:1124] Using default CRAM-MD5 authenticatee
3: I1214 01:53:39.484575 18434 authenticatee.cpp:121] Creating new client SASL connection
3: I1214 01:53:39.484771 18427 master.cpp:8928] Authenticating slave(811)@172.17.0.4:35744
3: I1214 01:53:39.484879 18422 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1441)@172.17.0.4:35744
3: I1214 01:53:39.485105 18414 authenticator.cpp:98] Creating new server SASL connection
3: I1214 01:53:39.485312 18426 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
3: I1214 01:53:39.485334 18426 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
3: I1214 01:53:39.485431 18424 authenticator.cpp:204] Received SASL authentication start
3: I1214 01:53:39.485481 18424 authenticator.cpp:326] Authentication requires more steps
3: I1214 01:53:39.485569 18419 authenticatee.cpp:259] Received SASL authentication step
3: I1214 01:53:39.485672 18433 authenticator.cpp:232] Received SASL authentication step
3: I1214 01:53:39.485698 18433 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'dd450c97a83d' server FQDN: 'dd450c97a83d' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
3: I1214 01:53:39.485709 18433 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
3: I1214 01:53:39.485744 18433 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
3: I1214 01:53:39.485761 18433 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'dd450c97a83d' server FQDN: 'dd450c97a83d' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
3: I1214 01:53:39.485770 18433 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
3: I1214 01:53:39.485775 18433 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
3: I1214 01:53:39.485787 18433 authenticator.cpp:318] Authentication success
3: I1214 01:53:39.485857 18425 authenticatee.cpp:299] Authentication success
3: I1214 01:53:39.485947 18430 master.cpp:8958] Successfully authenticated principal 'test-principal' at slave(811)@172.17.0.4:35744
3: I1214 01:53:39.485991 18418 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1441)@172.17.0.4:35744
3: I1214 01:53:39.486124 18412 slave.cpp:1207] Successfully authenticated with master master@172.17.0.4:35744
3: I1214 01:53:39.486321 18412 slave.cpp:1684] Will retry registration in 10.125262ms if necessary
3: I1214 01:53:39.486460 18428 master.cpp:6077] Received register agent message from slave(811)@172.17.0.4:35744 (dd450c97a83d)
3: I1214 01:53:39.486498 18428 master.cpp:3866] Authorizing agent with principal 'test-principal'
3: I1214 01:53:39.486870 18431 master.cpp:6147] Authorized registration of agent at slave(811)@172.17.0.4:35744 (dd450c97a83d)
3: I1214 01:53:39.486946 18431 master.cpp:6240] Registering agent at slave(811)@172.17.0.4:35744 (dd450c97a83d) with id 14a7f8aa-71ab-4604-80a2-5b8bf52e936c-S0
3: I1214 01:53:39.487375 18417 registrar.cpp:495] Applied 1 operations in 97649ns; attempting to update the registry
3: I1214 01:53:39.487974 18417 registrar.cpp:552] Successfully updated the registry in 541952ns
3: I1214 01:53:39.488116 18434 master.cpp:6288] Admitted agent 14a7f8aa-71ab-4604-80a2-5b8bf52e936c-S0 at slave(811)@172.17.0.4:35744 (dd450c97a83d)
3: I1214 01:53:39.488701 18434 master.cpp:6337] Registered agent 14a7f8aa-71ab-4604-80a2-5b8bf52e936c-S0 at slave(811)@172.17.0.4:35744 (dd450c97a83d) with cpus:2; mem:1024; disk:1024; ports:[31000-32000]
3: I1214 01:53:39.488781 18422 slave.cpp:1253] Registered with master master@172.17.0.4:35744; given agent ID 14a7f8aa-71ab-4604-80a2-5b8bf52e936c-S0
3: I1214 01:53:39.488860 18424 task_status_update_manager.cpp:188] Resuming sending task status updates
3: I1214 01:53:39.489051 18414 hierarchical.cpp:574] Added agent 14a7f8aa-71ab-4604-80a2-5b8bf52e936c-S0 (dd450c97a83d) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] (allocated: {})
3: I1214 01:53:39.489075 18422 slave.cpp:1273] Checkpointing SlaveInfo to '/tmp/ContentType_ExecutorHttpApiTest_Subscribe_1_uQrtAb/meta/slaves/14a7f8aa-71ab-4604-80a2-5b8bf52e936c-S0/slave.info'
3: I1214 01:53:39.489450 18422 slave.cpp:1330] Forwarding agent update {"offer_operations":{},"resource_version_uuid":"yw0ogeHESX6L\/XQ9xP3H0Q==","slave_id":{"value":"14a7f8aa-71ab-4604-80a2-5b8bf52e936c-S0"},"update_oversubscribed_resources":true}
3: I1214 01:53:39.489667 18425 master.cpp:7266] Received update of agent 14a7f8aa-71ab-4604-80a2-5b8bf52e936c-S0 at slave(811)@172.17.0.4:35744 (dd450c97a83d) with total oversubscribed resources {}
3: I1214 01:53:39.489892 18425 master.cpp:7364] Ignoring update on agent 14a7f8aa-71ab-4604-80a2-5b8bf52e936c-S0 at slave(811)@172.17.0.4:35744 (dd450c97a83d) as it reports no changes
3: I1214 01:53:39.490286 18414 hierarchical.cpp:1517] Performed allocation for 1 agents in 1.092336ms
3: I1214 01:53:39.490725 18433 master.cpp:8758] Sending 1 offers to framework 14a7f8aa-71ab-4604-80a2-5b8bf52e936c-0000 (default) at scheduler-4c3e244d-65d2-4b77-91cf-489e9ec46e74@172.17.0.4:35744
3: I1214 01:53:39.491219 18430 sched.cpp:921] Scheduler::resourceOffers took 131782ns
3: I1214 01:53:39.492859 18413 master.cpp:10679] Removing offer 14a7f8aa-71ab-4604-80a2-5b8bf52e936c-O0
3: I1214 01:53:39.492991 18413 master.cpp:4273] Processing ACCEPT call for offers: [ 14a7f8aa-71ab-4604-80a2-5b8bf52e936c-O0 ] on agent 14a7f8aa-71ab-4604-80a2-5b8bf52e936c-S0 at slave(811)@172.17.0.4:35744 (dd450c97a83d) for framework 14a7f8aa-71ab-4604-80a2-5b8bf52e936c-0000 (default) at scheduler-4c3e244d-65d2-4b77-91cf-489e9ec46e74@172.17.0.4:35744
3: I1214 01:53:39.493089 18413 master.cpp:3593] Authorizing framework principal 'test-principal' to launch task c89e7e73-c567-47d0-82a8-cf7c982c808c
3: W1214 01:53:39.494840 18416 validation.cpp:1387] Executor 'default' for task 'c89e7e73-c567-47d0-82a8-cf7c982c808c' uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases.
3: W1214 01:53:39.494868 18416 validation.cpp:1399] Executor 'default' for task 'c89e7e73-c567-47d0-82a8-cf7c982c808c' uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases.
3: I1214 01:53:39.495354 18416 master.cpp:11434] Adding task c89e7e73-c567-47d0-82a8-cf7c982c808c with resources cpus(allocated: *):2; mem(allocated: *):1024; disk(allocated: *):1024; ports(allocated: *):[31000-32000] on agent 14a7f8aa-71ab-4604-80a2-5b8bf52e936c-S0 at slave(811)@172.17.0.4:35744 (dd450c97a83d)
3: I1214 01:53:39.495684 18416 master.cpp:5024] Launching task c89e7e73-c567-47d0-82a8-cf7c982c808c of framework 14a7f8aa-71ab-4604-80a2-5b8bf52e936c-0000 (default) at scheduler-4c3e244d-65d2-4b77-91cf-489e9ec46e74@172.17.0.4:35744 with resources [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}] on agent 14a7f8aa-71ab-4604-80a2-5b8bf52e936c-S0 at slave(811)@172.17.0.4:35744 (dd450c97a83d)
3: I1214 01:53:39.496317 18427 slave.cpp:1807] Got assigned task 'c89e7e73-c567-47d0-82a8-cf7c982c808c' for framework 14a7f8aa-71ab-4604-80a2-5b8bf52e936c-0000
3: I1214 01:53:39.496901 18427 slave.cpp:2077] Authorizing task 'c89e7e73-c567-47d0-82a8-cf7c982c808c' for framework 14a7f8aa-71ab-4604-80a2-5b8bf52e936c-0000
3: I1214 01:53:39.496958 18427 slave.cpp:7792] Authorizing framework principal 'test-principal' to launch task c89e7e73-c567-47d0-82a8-cf7c982c808c
3: I1214 01:53:39.497794 18419 slave.cpp:2471] Launching task 'c89e7e73-c567-47d0-82a8-cf7c982c808c' for framework 14a7f8aa-71ab-4604-80a2-5b8bf52e936c-0000
3: I1214 01:53:39.498246 18419 paths.cpp:756] Trying to chown '/tmp/ContentType_ExecutorHttpApiTest_Subscribe_1_uQrtAb/slaves/14a7f8aa-71ab-4604-80a2-5b8bf52e936c-S0/frameworks/14a7f8aa-71ab-4604-80a2-5b8bf52e936c-0000/executors/default/runs/361cc87a-7a10-48fb-8962-cf55a9e7fea1' to user 'mesos'
3: I1214 01:53:39.498462 18419 slave.cpp:8266] Launching executor 'default' of framework 14a7f8aa-71ab-4604-80a2-5b8bf52e936c-0000 with resources [] in work directory '/tmp/ContentType_ExecutorHttpApiTest_Subscribe_1_uQrtAb/slaves/14a7f8aa-71ab-4604-80a2-5b8bf52e936c-S0/frameworks/14a7f8aa-71ab-4604-80a2-5b8bf52e936c-0000/executors/default/runs/361cc87a-7a10-48fb-8962-cf55a9e7fea1'
3: I1214 01:53:39.498945 18419 slave.cpp:3023] Launching container 361cc87a-7a10-48fb-8962-cf55a9e7fea1 for executor 'default' of framework 14a7f8aa-71ab-4604-80a2-5b8bf52e936c-0000
3: I1214 01:53:39.499436 18419 slave.cpp:2557] Queued task 'c89e7e73-c567-47d0-82a8-cf7c982c808c' for executor 'default' of framework 14a7f8aa-71ab-4604-80a2-5b8bf52e936c-0000
3: I1214 01:53:39.499526 18419 slave.cpp:984] Successfully attached '/tmp/ContentType_ExecutorHttpApiTest_Subscribe_1_uQrtAb/slaves/14a7f8aa-71ab-4604-80a2-5b8bf52e936c-S0/frameworks/14a7f8aa-71ab-4604-80a2-5b8bf52e936c-0000/executors/default/runs/361cc87a-7a10-48fb-8962-cf55a9e7fea1' to virtual path '/tmp/ContentType_ExecutorHttpApiTest_Subscribe_1_uQrtAb/slaves/14a7f8aa-71ab-4604-80a2-5b8bf52e936c-S0/frameworks/14a7f8aa-71ab-4604-80a2-5b8bf52e936c-0000/executors/default/runs/latest'
3: I1214 01:53:39.499562 18419 slave.cpp:984] Successfully attached '/tmp/ContentType_ExecutorHttpApiTest_Subscribe_1_uQrtAb/slaves/14a7f8aa-71ab-4604-80a2-5b8bf52e936c-S0/frameworks/14a7f8aa-71ab-4604-80a2-5b8bf52e936c-0000/executors/default/runs/361cc87a-7a10-48fb-8962-cf55a9e7fea1' to virtual path '/frameworks/14a7f8aa-71ab-4604-80a2-5b8bf52e936c-0000/executors/default/runs/latest'
3: I1214 01:53:39.499589 18419 slave.cpp:984] Successfully attached '/tmp/ContentType_ExecutorHttpApiTest_Subscribe_1_uQrtAb/slaves/14a7f8aa-71ab-4604-80a2-5b8bf52e936c-S0/frameworks/14a7f8aa-71ab-4604-80a2-5b8bf52e936c-0000/executors/default/runs/361cc87a-7a10-48fb-8962-cf55a9e7fea1' to virtual path '/tmp/ContentType_ExecutorHttpApiTest_Subscribe_1_uQrtAb/slaves/14a7f8aa-71ab-4604-80a2-5b8bf52e936c-S0/frameworks/14a7f8aa-71ab-4604-80a2-5b8bf52e936c-0000/executors/default/runs/361cc87a-7a10-48fb-8962-cf55a9e7fea1'
3: W1214 01:53:39.501967 18433 process.cpp:2756] Attempted to spawn already running process version@172.17.0.4:35744
3: I1214 01:53:39.502135 18433 exec.cpp:162] Version: 1.5.0
3: I1214 01:53:39.502393 18423 exec.cpp:212] Executor started at: executor(203)@172.17.0.4:35744 with pid 18410
3: I1214 01:53:39.504956 18416 process.cpp:3503] Handling HTTP event for process 'slave(811)' with path: '/slave(811)/api/v1/executor'
3: I1214 01:53:39.506117 18422 http.cpp:1185] HTTP POST for /slave(811)/api/v1/executor from 172.17.0.4:59692
3: I1214 01:53:39.506428 18422 slave.cpp:4097] Received Subscribe request for HTTP executor 'default' of framework 14a7f8aa-71ab-4604-80a2-5b8bf52e936c-0000
3: I1214 01:53:39.508494 18423 slave.cpp:2762] Sending queued task 'c89e7e73-c567-47d0-82a8-cf7c982c808c' to executor 'default' of framework 14a7f8aa-71ab-4604-80a2-5b8bf52e936c-0000 (via HTTP)
3: I1214 01:53:39.510248 18410 sched.cpp:2009] Asked to stop the driver
3: I1214 01:53:39.510326 18425 sched.cpp:1191] Stopping framework 14a7f8aa-71ab-4604-80a2-5b8bf52e936c-0000
3: I1214 01:53:39.510609 18430 master.cpp:9438] Processing TEARDOWN call for framework 14a7f8aa-71ab-4604-80a2-5b8bf52e936c-0000 (default) at scheduler-4c3e244d-65d2-4b77-91cf-489e9ec46e74@172.17.0.4:35744
3: I1214 01:53:39.510639 18430 master.cpp:9450] Removing framework 14a7f8aa-71ab-4604-80a2-5b8bf52e936c-0000 (default) at scheduler-4c3e244d-65d2-4b77-91cf-489e9ec46e74@172.17.0.4:35744
3: I1214 01:53:39.510650 18430 master.cpp:3327] Deactivating framework 14a7f8aa-71ab-4604-80a2-5b8bf52e936c-0000 (default) at scheduler-4c3e244d-65d2-4b77-91cf-489e9ec46e74@172.17.0.4:35744
3: I1214 01:53:39.510759 18421 hierarchical.cpp:405] Deactivated framework 14a7f8aa-71ab-4604-80a2-5b8bf52e936c-0000
3: I1214 01:53:39.510814 18414 slave.cpp:3375] Asked to shut down framework 14a7f8aa-71ab-4604-80a2-5b8bf52e936c-0000 by master@172.17.0.4:35744
3: I1214 01:53:39.510848 18414 slave.cpp:3400] Shutting down framework 14a7f8aa-71ab-4604-80a2-5b8bf52e936c-0000
3: I1214 01:53:39.510855 18430 master.cpp:10154] Updating the state of task c89e7e73-c567-47d0-82a8-cf7c982c808c of framework 14a7f8aa-71ab-4604-80a2-5b8bf52e936c-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED)
3: I1214 01:53:39.510900 18414 slave.cpp:6114] Shutting down executor 'default' of framework 14a7f8aa-71ab-4604-80a2-5b8bf52e936c-0000 (via HTTP)
3: I1214 01:53:39.511531 18422 slave.cpp:909] Agent terminating
3: I1214 01:53:39.511591 18422 slave.cpp:3375] Asked to shut down framework 14a7f8aa-71ab-4604-80a2-5b8bf52e936c-0000 by @0.0.0.0:0
3: W1214 01:53:39.511616 18422 slave.cpp:3396] Ignoring shutdown framework 14a7f8aa-71ab-4604-80a2-5b8bf52e936c-0000 because it is terminating
3: I1214 01:53:39.511638 18430 master.cpp:10260] Removing task c89e7e73-c567-47d0-82a8-cf7c982c808c with resources cpus(allocated: *):2; mem(allocated: *):1024; disk(allocated: *):1024; ports(allocated: *):[31000-32000] of framework 14a7f8aa-71ab-4604-80a2-5b8bf52e936c-0000 on agent 14a7f8aa-71ab-4604-80a2-5b8bf52e936c-S0 at slave(811)@172.17.0.4:35744 (dd450c97a83d)
3: I1214 01:53:39.511756 18434 exec.cpp:517] Agent exited ... shutting down
3: 
3: GMOCK WARNING:
3: Uninteresting mock function call - returning directly.
3:     Function call: shutdown(0x2aab3002e990)
3: NOTE: You can safely ignore the above warning unless this call should not happen.  Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call.  See https://github.com/google/googletest/blob/master/googlemock/docs/CookBook.md#knowing-when-to-expect for details.
3: I1214 01:53:39.511797 18434 exec.cpp:534] Executor::shutdown took 35024ns
3: I1214 01:53:39.511909 18430 master.cpp:10289] Removing executor 'default' with resources [] of framework 14a7f8aa-71ab-4604-80a2-5b8bf52e936c-0000 on agent 14a7f8aa-71ab-4604-80a2-5b8bf52e936c-S0 at slave(811)@172.17.0.4:35744 (dd450c97a83d)
3: I1214 01:53:39.512256 18412 hierarchical.cpp:1192] Recovered cpus(allocated: *):2; mem(allocated: *):1024; disk(allocated: *):1024; ports(allocated: *):[31000-32000] (total: cpus:2; mem:1024; disk:1024; ports:[31000-32000], allocated: {}) on agent 14a7f8aa-71ab-4604-80a2-5b8bf52e936c-S0 from framework 14a7f8aa-71ab-4604-80a2-5b8bf52e936c-0000
3: I1214 01:53:39.512364 18430 master.cpp:1305] Agent 14a7f8aa-71ab-4604-80a2-5b8bf52e936c-S0 at slave(811)@172.17.0.4:35744 (dd450c97a83d) disconnected
3: I1214 01:53:39.512385 18430 master.cpp:3364] Disconnecting agent 14a7f8aa-71ab-4604-80a2-5b8bf52e936c-S0 at slave(811)@172.17.0.4:35744 (dd450c97a83d)
3: I1214 01:53:39.512434 18430 master.cpp:3383] Deactivating agent 14a7f8aa-71ab-4604-80a2-5b8bf52e936c-S0 at slave(811)@172.17.0.4:35744 (dd450c97a83d)
3: I1214 01:53:39.512769 18412 hierarchical.cpp:344] Removed framework 14a7f8aa-71ab-4604-80a2-5b8bf52e936c-0000
3: I1214 01:53:39.512843 18412 hierarchical.cpp:766] Agent 14a7f8aa-71ab-4604-80a2-5b8bf52e936c-S0 deactivated
3: I1214 01:53:39.516621 18410 master.cpp:1147] Master terminating
3: I1214 01:53:39.517179 18417 hierarchical.cpp:609] Removed agent 14a7f8aa-71ab-4604-80a2-5b8bf52e936c-S0
3: [       OK ] ContentType/ExecutorHttpApiTest.Subscribe/1 (64 ms)
3: [----------] 18 tests from ContentType/ExecutorHttpApiTest (1195 ms total)
3: 
3: [----------] 4 tests from ReservationTypeSwitch/HierarchicalAllocatorTestWithReservations
3: [ RUN      ] ReservationTypeSwitch/HierarchicalAllocatorTestWithReservations.ReservationUnallocated/0
3: I1214 01:53:39.524106 18413 hierarchical.cpp:175] Initialized hierarchical allocator process
3: I1214 01:53:39.524961 18422 hierarchical.cpp:574] Added agent agent1 (agent1) with cpus(reservations: [(DYNAMIC,quota-role)]):1; mem(reservations: [(DYNAMIC,quota-role)]):1024 (allocated: {})
3: I1214 01:53:39.525383 18422 hierarchical.cpp:1377] Set quota [{"name":"cpus","scalar":{"value":1.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"}] for role 'quota-role'
3: I1214 01:53:39.525877 18422 hierarchical.cpp:297] Added framework framework1
3: I1214 01:53:39.527081 18422 hierarchical.cpp:1517] Performed allocation for 1 agents in 1.119664ms
write /dev/stdout: resource temporarily unavailable
+ docker rmi mesos-1513214346-18878
Error response from daemon: conflict: unable to remove repository reference "mesos-1513214346-18878" (must force) - container dd450c97a83d is using its referenced image 3e742b2976c1
Build step 'Execute shell' marked build as failure
Not sending mail to unregistered user songzihao1990@gmail.com
Not sending mail to unregistered user gregorywmann@gmail.com
Not sending mail to unregistered user yujie.jay@gmail.com

Jenkins build is back to normal : Mesos-Buildbot » cmake,clang,--verbose --disable-libtool-wrappers --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23) #4598

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

See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=clang,CONFIGURATION=--verbose%20--disable-libtool-wrappers%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4598/display/redirect>