You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@mesos.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2016/12/08 19:27:15 UTC
Build failed in Jenkins: Mesos » cmake,gcc,--verbose --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,centos:7,(docker||Hadoop)&&(!ubuntu-us1)&&(!ubuntu-6)&&(!ubuntu-eu2) #3021
See <https://builds.apache.org/job/Mesos/BUILDTOOL=cmake,COMPILER=gcc,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-6)&&(!ubuntu-eu2)/3021/changes>
Changes:
[mpark] Fixed some formatting in `os/windows/socket.hpp`.
------------------------------------------
[...truncated 165913 lines...]
I1208 19:23:51.577116 26720 scheduler.cpp:484] Waiting for 0ns before initiating a re-(connection) attempt with the master
I1208 19:23:51.577836 26720 scheduler.cpp:359] Connected with the master at http://172.17.0.2:40262/master/api/v1/scheduler
I1208 19:23:51.578577 26725 scheduler.cpp:446] Lost leading master
I1208 19:23:51.578768 26725 scheduler.cpp:393] Ignoring disconnection attempt from stale connection
I1208 19:23:51.578968 26725 scheduler.cpp:393] Ignoring disconnection attempt from stale connection
I1208 19:23:51.579195 26718 scheduler.cpp:293] Ignoring reconnect request from scheduler since we are disconnected
I1208 19:23:51.579792 26711 master.cpp:1097] Master terminating
I1208 19:23:51.584697 26711 containerizer.cpp:217] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W1208 19:23:51.585075 26711 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges, but is running as user mesos
W1208 19:23:51.585165 26711 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I1208 19:23:51.586799 26724 slave.cpp:208] Mesos agent started on (655)@172.17.0.2:40262
I1208 19:23:51.586822 26724 slave.cpp:209] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/mesos/store/appc" --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/Endpoint_SlaveEndpointTest_AuthorizedRequest_0_yrXD68/credential" --default_role="*" --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/mesos/store/docker" --docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/Endpoint_SlaveEndpointTest_AuthorizedRequest_0_yrXD68/fetch" --fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks" --gc_disk_headroom="0.1" --hadoop_home="" --help="false" --hostname_lookup="true" --http_authenticators="basic" --http_command_executor="false" --http_credentials="/tmp/Endpoint_SlaveEndpointTest_AuthorizedRequest_0_yrXD68/http_credentials" --image_provisioner_backend="copy" --initialize_driver_logging="true" --io_switchboard_enable_server="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" --qos_correction_interval_min="0ns" --quiet="false" --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/Endpoint_SlaveEndpointTest_AuthorizedRequest_0_yrXD68" --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/Endpoint_SlaveEndpointTest_AuthorizedRequest_0_u8MzcM"
I1208 19:23:51.587312 26724 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_AuthorizedRequest_0_yrXD68/credential'
I1208 19:23:51.587450 26724 slave.cpp:346] Agent using credential for: test-principal
I1208 19:23:51.587468 26724 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_AuthorizedRequest_0_yrXD68/http_credentials'
I1208 19:23:51.587610 26724 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I1208 19:23:51.587673 26724 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I1208 19:23:51.588448 26724 slave.cpp:533] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I1208 19:23:51.588492 26724 slave.cpp:541] Agent attributes: [ ]
I1208 19:23:51.588500 26724 slave.cpp:546] Agent hostname: 03e5d86df58c
I1208 19:23:51.589035 26715 state.cpp:57] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_AuthorizedRequest_0_u8MzcM/meta'
I1208 19:23:51.589211 26721 status_update_manager.cpp:203] Recovering status update manager
I1208 19:23:51.589525 26726 containerizer.cpp:591] Recovering containerizer
I1208 19:23:51.590234 26723 provisioner.cpp:253] Provisioner recovery complete
I1208 19:23:51.590477 26713 slave.cpp:5414] Finished recovery
I1208 19:23:51.591083 26713 slave.cpp:5588] Querying resource estimator for oversubscribable resources
I1208 19:23:51.591300 26721 slave.cpp:5602] Received oversubscribable resources {} from the resource estimator
I1208 19:23:51.592170 26713 process.cpp:3679] Handling HTTP event for process 'slave(655)' with path: '/slave(655)/monitor/statistics'
I1208 19:23:51.592682 26717 http.cpp:874] Authorizing principal 'test-principal' to GET the '/monitor/statistics' endpoint
I1208 19:23:51.594740 26718 slave.cpp:790] Agent terminating
I1208 19:23:51.600678 26711 containerizer.cpp:217] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W1208 19:23:51.601054 26711 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges, but is running as user mesos
W1208 19:23:51.601187 26711 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I1208 19:23:51.602701 26723 slave.cpp:208] Mesos agent started on (656)@172.17.0.2:40262
I1208 19:23:51.602733 26723 slave.cpp:209] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/mesos/store/appc" --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/Endpoint_SlaveEndpointTest_AuthorizedRequest_1_kQ3NRC/credential" --default_role="*" --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/mesos/store/docker" --docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/Endpoint_SlaveEndpointTest_AuthorizedRequest_1_kQ3NRC/fetch" --fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks" --gc_disk_headroom="0.1" --hadoop_home="" --help="false" --hostname_lookup="true" --http_authenticators="basic" --http_command_executor="false" --http_credentials="/tmp/Endpoint_SlaveEndpointTest_AuthorizedRequest_1_kQ3NRC/http_credentials" --image_provisioner_backend="copy" --initialize_driver_logging="true" --io_switchboard_enable_server="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" --qos_correction_interval_min="0ns" --quiet="false" --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/Endpoint_SlaveEndpointTest_AuthorizedRequest_1_kQ3NRC" --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/Endpoint_SlaveEndpointTest_AuthorizedRequest_1_VBB7Uf"
I1208 19:23:51.603154 26723 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_AuthorizedRequest_1_kQ3NRC/credential'
I1208 19:23:51.603322 26723 slave.cpp:346] Agent using credential for: test-principal
I1208 19:23:51.603343 26723 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_AuthorizedRequest_1_kQ3NRC/http_credentials'
I1208 19:23:51.603482 26723 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I1208 19:23:51.603557 26723 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I1208 19:23:51.603981 26723 slave.cpp:533] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I1208 19:23:51.604029 26723 slave.cpp:541] Agent attributes: [ ]
I1208 19:23:51.604043 26723 slave.cpp:546] Agent hostname: 03e5d86df58c
I1208 19:23:51.604498 26717 state.cpp:57] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_AuthorizedRequest_1_VBB7Uf/meta'
I1208 19:23:51.604737 26723 status_update_manager.cpp:203] Recovering status update manager
I1208 19:23:51.604936 26723 containerizer.cpp:591] Recovering containerizer
I1208 19:23:51.605852 26719 provisioner.cpp:253] Provisioner recovery complete
I1208 19:23:51.606266 26715 slave.cpp:5414] Finished recovery
I1208 19:23:51.606868 26715 slave.cpp:5588] Querying resource estimator for oversubscribable resources
I1208 19:23:51.607024 26715 slave.cpp:5602] Received oversubscribable resources {} from the resource estimator
I1208 19:23:51.607821 26723 process.cpp:3679] Handling HTTP event for process 'slave(656)' with path: '/slave(656)/monitor/statistics.json'
I1208 19:23:51.608438 26714 http.cpp:874] Authorizing principal 'test-principal' to GET the '/monitor/statistics.json' endpoint
I1208 19:23:51.610236 26721 slave.cpp:790] Agent terminating
I1208 19:23:51.616552 26711 containerizer.cpp:217] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W1208 19:23:51.616937 26711 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges, but is running as user mesos
W1208 19:23:51.617034 26711 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I1208 19:23:51.618355 26713 slave.cpp:208] Mesos agent started on (657)@172.17.0.2:40262
I1208 19:23:51.618382 26713 slave.cpp:209] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/mesos/store/appc" --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/Endpoint_SlaveEndpointTest_AuthorizedRequest_2_oBfbN6/credential" --default_role="*" --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/mesos/store/docker" --docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/Endpoint_SlaveEndpointTest_AuthorizedRequest_2_oBfbN6/fetch" --fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks" --gc_disk_headroom="0.1" --hadoop_home="" --help="false" --hostname_lookup="true" --http_authenticators="basic" --http_command_executor="false" --http_credentials="/tmp/Endpoint_SlaveEndpointTest_AuthorizedRequest_2_oBfbN6/http_credentials" --image_provisioner_backend="copy" --initialize_driver_logging="true" --io_switchboard_enable_server="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" --qos_correction_interval_min="0ns" --quiet="false" --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/Endpoint_SlaveEndpointTest_AuthorizedRequest_2_oBfbN6" --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/Endpoint_SlaveEndpointTest_AuthorizedRequest_2_NDTWNJ"
I1208 19:23:51.618962 26713 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_AuthorizedRequest_2_oBfbN6/credential'
I1208 19:23:51.619092 26713 slave.cpp:346] Agent using credential for: test-principal
I1208 19:23:51.619110 26713 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_AuthorizedRequest_2_oBfbN6/http_credentials'
I1208 19:23:51.619232 26713 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I1208 19:23:51.619283 26713 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I1208 19:23:51.619668 26713 slave.cpp:533] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I1208 19:23:51.619714 26713 slave.cpp:541] Agent attributes: [ ]
I1208 19:23:51.619720 26713 slave.cpp:546] Agent hostname: 03e5d86df58c
I1208 19:23:51.620173 26718 state.cpp:57] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_AuthorizedRequest_2_NDTWNJ/meta'
I1208 19:23:51.620383 26713 status_update_manager.cpp:203] Recovering status update manager
I1208 19:23:51.620532 26716 containerizer.cpp:591] Recovering containerizer
I1208 19:23:51.621204 26717 provisioner.cpp:253] Provisioner recovery complete
I1208 19:23:51.621503 26724 slave.cpp:5414] Finished recovery
I1208 19:23:51.622056 26724 slave.cpp:5588] Querying resource estimator for oversubscribable resources
I1208 19:23:51.622206 26713 slave.cpp:5602] Received oversubscribable resources {} from the resource estimator
I1208 19:23:51.623131 26717 process.cpp:3679] Handling HTTP event for process 'slave(657)' with path: '/slave(657)/containers'
I1208 19:23:51.623811 26727 http.cpp:874] Authorizing principal 'test-principal' to GET the '/containers' endpoint
I1208 19:23:51.625560 26719 slave.cpp:790] Agent terminating
I1208 19:23:51.631356 26711 containerizer.cpp:217] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W1208 19:23:51.631716 26711 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges, but is running as user mesos
W1208 19:23:51.631808 26711 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I1208 19:23:51.632936 26718 slave.cpp:208] Mesos agent started on (658)@172.17.0.2:40262
I1208 19:23:51.632956 26718 slave.cpp:209] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/mesos/store/appc" --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/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_zuWlSA/credential" --default_role="*" --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/mesos/store/docker" --docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_zuWlSA/fetch" --fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks" --gc_disk_headroom="0.1" --hadoop_home="" --help="false" --hostname_lookup="true" --http_authenticators="basic" --http_command_executor="false" --http_credentials="/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_zuWlSA/http_credentials" --image_provisioner_backend="copy" --initialize_driver_logging="true" --io_switchboard_enable_server="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" --qos_correction_interval_min="0ns" --quiet="false" --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/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_zuWlSA" --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/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_Fx3FQd"
I1208 19:23:51.633334 26718 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_zuWlSA/credential'
I1208 19:23:51.633457 26718 slave.cpp:346] Agent using credential for: test-principal
I1208 19:23:51.633473 26718 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_zuWlSA/http_credentials'
I1208 19:23:51.633610 26718 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I1208 19:23:51.633674 26718 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I1208 19:23:51.634167 26718 slave.cpp:533] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I1208 19:23:51.634213 26718 slave.cpp:541] Agent attributes: [ ]
I1208 19:23:51.634224 26718 slave.cpp:546] Agent hostname: 03e5d86df58c
I1208 19:23:51.634738 26714 state.cpp:57] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_Fx3FQd/meta'
I1208 19:23:51.635082 26727 status_update_manager.cpp:203] Recovering status update manager
I1208 19:23:51.635303 26723 containerizer.cpp:591] Recovering containerizer
I1208 19:23:51.636018 26712 provisioner.cpp:253] Provisioner recovery complete
I1208 19:23:51.636314 26723 slave.cpp:5414] Finished recovery
I1208 19:23:51.636850 26723 slave.cpp:5588] Querying resource estimator for oversubscribable resources
I1208 19:23:51.637020 26719 slave.cpp:5602] Received oversubscribable resources {} from the resource estimator
I1208 19:23:51.637755 26718 process.cpp:3679] Handling HTTP event for process 'slave(658)' with path: '/slave(658)/monitor/statistics'
I1208 19:23:51.638267 26723 http.cpp:874] Authorizing principal 'test-principal' to GET the '/monitor/statistics' endpoint
I1208 19:23:51.639551 26717 slave.cpp:790] Agent terminating
I1208 19:23:51.645875 26711 containerizer.cpp:217] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W1208 19:23:51.646301 26711 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges, but is running as user mesos
W1208 19:23:51.646396 26711 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I1208 19:23:51.647625 26716 slave.cpp:208] Mesos agent started on (659)@172.17.0.2:40262
I1208 19:23:51.647650 26716 slave.cpp:209] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/mesos/store/appc" --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/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_zriQ64/credential" --default_role="*" --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/mesos/store/docker" --docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_zriQ64/fetch" --fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks" --gc_disk_headroom="0.1" --hadoop_home="" --help="false" --hostname_lookup="true" --http_authenticators="basic" --http_command_executor="false" --http_credentials="/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_zriQ64/http_credentials" --image_provisioner_backend="copy" --initialize_driver_logging="true" --io_switchboard_enable_server="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" --qos_correction_interval_min="0ns" --quiet="false" --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/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_zriQ64" --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/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_4NgR2H"
I1208 19:23:51.648041 26716 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_zriQ64/credential'
I1208 19:23:51.648147 26716 slave.cpp:346] Agent using credential for: test-principal
I1208 19:23:51.648166 26716 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_zriQ64/http_credentials'
I1208 19:23:51.648295 26716 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I1208 19:23:51.648363 26716 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I1208 19:23:51.648905 26716 slave.cpp:533] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I1208 19:23:51.648948 26716 slave.cpp:541] Agent attributes: [ ]
I1208 19:23:51.648957 26716 slave.cpp:546] Agent hostname: 03e5d86df58c
I1208 19:23:51.649399 26712 state.cpp:57] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_4NgR2H/meta'
I1208 19:23:51.649597 26721 status_update_manager.cpp:203] Recovering status update manager
I1208 19:23:51.649797 26712 containerizer.cpp:591] Recovering containerizer
I1208 19:23:51.650593 26718 provisioner.cpp:253] Provisioner recovery complete
I1208 19:23:51.650797 26719 slave.cpp:5414] Finished recovery
I1208 19:23:51.651311 26719 slave.cpp:5588] Querying resource estimator for oversubscribable resources
I1208 19:23:51.651458 26714 slave.cpp:5602] Received oversubscribable resources {} from the resource estimator
I1208 19:23:51.652518 26716 process.cpp:3679] Handling HTTP event for process 'slave(659)' with path: '/slave(659)/monitor/statistics.json'
I1208 19:23:51.653157 26718 http.cpp:874] Authorizing principal 'test-principal' to GET the '/monitor/statistics.json' endpoint
I1208 19:23:51.654489 26719 slave.cpp:790] Agent terminating
I1208 19:23:51.661593 26711 containerizer.cpp:217] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W1208 19:23:51.662201 26711 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges, but is running as user mesos
W1208 19:23:51.662322 26711 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I1208 19:23:51.663720 26720 slave.cpp:208] Mesos agent started on (660)@172.17.0.2:40262
I1208 19:23:51.663895 26720 slave.cpp:209] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/mesos/store/appc" --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/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_xsfbvz/credential" --default_role="*" --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/mesos/store/docker" --docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_xsfbvz/fetch" --fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks" --gc_disk_headroom="0.1" --hadoop_home="" --help="false" --hostname_lookup="true" --http_authenticators="basic" --http_command_executor="false" --http_credentials="/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_xsfbvz/http_credentials" --image_provisioner_backend="copy" --initialize_driver_logging="true" --io_switchboard_enable_server="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" --qos_correction_interval_min="0ns" --quiet="false" --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/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_xsfbvz" --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/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_SE9Foc"
I1208 19:23:51.664422 26720 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_xsfbvz/credential'
I1208 19:23:51.664536 26720 slave.cpp:346] Agent using credential for: test-principal
I1208 19:23:51.664558 26720 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_xsfbvz/http_credentials'
I1208 19:23:51.664690 26720 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I1208 19:23:51.664738 26720 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I1208 19:23:51.665189 26720 slave.cpp:533] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I1208 19:23:51.665232 26720 slave.cpp:541] Agent attributes: [ ]
I1208 19:23:51.665246 26720 slave.cpp:546] Agent hostname: 03e5d86df58c
I1208 19:23:51.665724 26720 state.cpp:57] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_SE9Foc/meta'
I1208 19:23:51.665946 26718 status_update_manager.cpp:203] Recovering status update manager
I1208 19:23:51.666075 26725 containerizer.cpp:591] Recovering containerizer
I1208 19:23:51.666698 26717 provisioner.cpp:253] Provisioner recovery complete
I1208 19:23:51.666926 26719 slave.cpp:5414] Finished recovery
I1208 19:23:51.667443 26719 slave.cpp:5588] Querying resource estimator for oversubscribable resources
I1208 19:23:51.667603 26721 slave.cpp:5602] Received oversubscribable resources {} from the resource estimator
I1208 19:23:51.668473 26722 process.cpp:3679] Handling HTTP event for process 'slave(660)' with path: '/slave(660)/containers'
I1208 19:23:51.669039 26714 http.cpp:874] Authorizing principal 'test-principal' to GET the '/containers' endpoint
I1208 19:23:51.670989 26725 slave.cpp:790] Agent terminating
I1208 19:23:51.677253 26711 containerizer.cpp:217] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W1208 19:23:51.677618 26711 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges, but is running as user mesos
W1208 19:23:51.677707 26711 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I1208 19:23:51.678402 26711 cluster.cpp:446] Creating default 'local' authorizer
I1208 19:23:51.679039 26727 slave.cpp:208] Mesos agent started on (661)@172.17.0.2:40262
I1208 19:23:51.679142 26727 slave.cpp:209] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/mesos/store/appc" --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/Endpoint_SlaveEndpointTest_NoAuthorizer_0_BKuY33/credential" --default_role="*" --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/mesos/store/docker" --docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_0_BKuY33/fetch" --fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks" --gc_disk_headroom="0.1" --hadoop_home="" --help="false" --hostname_lookup="true" --http_authenticators="basic" --http_command_executor="false" --http_credentials="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_0_BKuY33/http_credentials" --image_provisioner_backend="copy" --initialize_driver_logging="true" --io_switchboard_enable_server="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" --qos_correction_interval_min="0ns" --quiet="false" --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/Endpoint_SlaveEndpointTest_NoAuthorizer_0_BKuY33" --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/Endpoint_SlaveEndpointTest_NoAuthorizer_0_OO9SUG"
I1208 19:23:51.679533 26727 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_0_BKuY33/credential'
I1208 19:23:51.679661 26727 slave.cpp:346] Agent using credential for: test-principal
I1208 19:23:51.679684 26727 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_0_BKuY33/http_credentials'
I1208 19:23:51.679824 26727 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I1208 19:23:51.679893 26727 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I1208 19:23:51.680356 26727 slave.cpp:533] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I1208 19:23:51.680402 26727 slave.cpp:541] Agent attributes: [ ]
I1208 19:23:51.680409 26727 slave.cpp:546] Agent hostname: 03e5d86df58c
I1208 19:23:51.680886 26721 state.cpp:57] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_0_OO9SUG/meta'
I1208 19:23:51.681136 26716 status_update_manager.cpp:203] Recovering status update manager
I1208 19:23:51.681263 26718 containerizer.cpp:591] Recovering containerizer
I1208 19:23:51.681978 26716 provisioner.cpp:253] Provisioner recovery complete
I1208 19:23:51.682175 26714 slave.cpp:5414] Finished recovery
I1208 19:23:51.682587 26714 slave.cpp:5588] Querying resource estimator for oversubscribable resources
I1208 19:23:51.682718 26716 slave.cpp:5602] Received oversubscribable resources {} from the resource estimator
I1208 19:23:51.683466 26725 process.cpp:3679] Handling HTTP event for process 'slave(661)' with path: '/slave(661)/monitor/statistics'
I1208 19:23:51.683909 26720 http.cpp:874] Authorizing principal 'test-principal' to GET the '/monitor/statistics' endpoint
I1208 19:23:51.685931 26711 slave.cpp:790] Agent terminating
I1208 19:23:51.691819 26711 containerizer.cpp:217] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W1208 19:23:51.692189 26711 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges, but is running as user mesos
W1208 19:23:51.692279 26711 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I1208 19:23:51.692929 26711 cluster.cpp:446] Creating default 'local' authorizer
I1208 19:23:51.693449 26717 slave.cpp:208] Mesos agent started on (662)@172.17.0.2:40262
I1208 19:23:51.693470 26717 slave.cpp:209] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/mesos/store/appc" --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/Endpoint_SlaveEndpointTest_NoAuthorizer_1_oySlMy/credential" --default_role="*" --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/mesos/store/docker" --docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_1_oySlMy/fetch" --fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks" --gc_disk_headroom="0.1" --hadoop_home="" --help="false" --hostname_lookup="true" --http_authenticators="basic" --http_command_executor="false" --http_credentials="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_1_oySlMy/http_credentials" --image_provisioner_backend="copy" --initialize_driver_logging="true" --io_switchboard_enable_server="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" --qos_correction_interval_min="0ns" --quiet="false" --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/Endpoint_SlaveEndpointTest_NoAuthorizer_1_oySlMy" --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/Endpoint_SlaveEndpointTest_NoAuthorizer_1_gmwTAb"
I1208 19:23:51.693889 26717 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_1_oySlMy/credential'
I1208 19:23:51.693979 26717 slave.cpp:346] Agent using credential for: test-principal
I1208 19:23:51.693994 26717 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_1_oySlMy/http_credentials'
I1208 19:23:51.694100 26717 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I1208 19:23:51.694147 26717 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I1208 19:23:51.694556 26717 slave.cpp:533] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I1208 19:23:51.694599 26717 slave.cpp:541] Agent attributes: [ ]
I1208 19:23:51.694610 26717 slave.cpp:546] Agent hostname: 03e5d86df58c
I1208 19:23:51.695098 26721 state.cpp:57] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_1_gmwTAb/meta'
I1208 19:23:51.695353 26723 status_update_manager.cpp:203] Recovering status update manager
I1208 19:23:51.695593 26712 containerizer.cpp:591] Recovering containerizer
I1208 19:23:51.696189 26714 provisioner.cpp:253] Provisioner recovery complete
I1208 19:23:51.696449 26714 slave.cpp:5414] Finished recovery
I1208 19:23:51.696863 26714 slave.cpp:5588] Querying resource estimator for oversubscribable resources
I1208 19:23:51.696961 26721 slave.cpp:5602] Received oversubscribable resources {} from the resource estimator
I1208 19:23:51.697691 26722 process.cpp:3679] Handling HTTP event for process 'slave(662)' with path: '/slave(662)/monitor/statistics.json'
I1208 19:23:51.698236 26720 http.cpp:874] Authorizing principal 'test-principal' to GET the '/monitor/statistics.json' endpoint
I1208 19:23:51.700055 26722 slave.cpp:790] Agent terminating
I1208 19:23:51.706653 26711 containerizer.cpp:217] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W1208 19:23:51.707053 26711 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges, but is running as user mesos
W1208 19:23:51.707145 26711 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I1208 19:23:51.707932 26711 cluster.cpp:446] Creating default 'local' authorizer
I1208 19:23:51.708591 26724 slave.cpp:208] Mesos agent started on (663)@172.17.0.2:40262
I1208 19:23:51.708647 26724 slave.cpp:209] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/mesos/store/appc" --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/Endpoint_SlaveEndpointTest_NoAuthorizer_2_u7RaE3/credential" --default_role="*" --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/mesos/store/docker" --docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_2_u7RaE3/fetch" --fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks" --gc_disk_headroom="0.1" --hadoop_home="" --help="false" --hostname_lookup="true" --http_authenticators="basic" --http_command_executor="false" --http_credentials="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_2_u7RaE3/http_credentials" --image_provisioner_backend="copy" --initialize_driver_logging="true" --io_switchboard_enable_server="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" --qos_correction_interval_min="0ns" --quiet="false" --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/Endpoint_SlaveEndpointTest_NoAuthorizer_2_u7RaE3" --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/Endpoint_SlaveEndpointTest_NoAuthorizer_2_VAykqG"
I1208 19:23:51.709161 26724 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_2_u7RaE3/credential'
I1208 19:23:51.709292 26724 slave.cpp:346] Agent using credential for: test-principal
I1208 19:23:51.709314 26724 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_2_u7RaE3/http_credentials'
I1208 19:23:51.709432 26724 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I1208 19:23:51.709499 26724 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I1208 19:23:51.710043 26724 slave.cpp:533] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I1208 19:23:51.710089 26724 slave.cpp:541] Agent attributes: [ ]
I1208 19:23:51.710098 26724 slave.cpp:546] Agent hostname: 03e5d86df58c
I1208 19:23:51.710513 26715 state.cpp:57] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_2_VAykqG/meta'
I1208 19:23:51.710712 26724 status_update_manager.cpp:203] Recovering status update manager
I1208 19:23:51.710865 26714 containerizer.cpp:591] Recovering containerizer
I1208 19:23:51.711551 26715 provisioner.cpp:253] Provisioner recovery complete
I1208 19:23:51.711818 26723 slave.cpp:5414] Finished recovery
I1208 19:23:51.712350 26723 slave.cpp:5588] Querying resource estimator for oversubscribable resources
I1208 19:23:51.712507 26714 slave.cpp:5602] Received oversubscribable resources {} from the resource estimator
I1208 19:23:51.713405 26717 process.cpp:3679] Handling HTTP event for process 'slave(663)' with path: '/slave(663)/containers'
I1208 19:23:51.714025 26712 http.cpp:874] Authorizing principal 'test-principal' to GET the '/containers' endpoint
I1208 19:23:51.715605 26717 slave.cpp:790] Agent terminating
make[3]: *** [CMakeFiles/check] Error 1
make[2]: *** [CMakeFiles/check.dir/all] Error 2
make[1]: *** [CMakeFiles/check.dir/rule] Error 2
make: *** [check] Error 2
+ docker rmi mesos-1481222313-27928
Untagged: mesos-1481222313-27928:latest
Deleted: sha256:1c3442467daf2723a9f4cab97cbc604d5a9202d8069686db9e4511b954aad395
Deleted: sha256:11104a02283e14fc9d729514b12f2388a9e6b13c06369fd5748a4c2d119701d1
Deleted: sha256:7847753bcec88553e01c24d74524f919b9d1e05f9e8fd596cf5dddecd84ed5d6
Deleted: sha256:368b114dcfa9c48365dfbd3752a36a33085656d4a1ef199cf579d70c45bef8e7
Deleted: sha256:68ee4e17a0fffa90773699487831320983d556bda8011c619d616c30359a8269
Deleted: sha256:035adbe5d6b8c8326c2c91dc584680fb54c9775a64522821fed5aa3789f21bc2
Deleted: sha256:e105dbb475e05534c7bc161175ca423c2c3579d3fa1faa96c62ad6696f78d6c0
Deleted: sha256:32a761ca60c45c3609af5514470e45ec52563440c3882c4b24b5796ddc567a07
Deleted: sha256:b1f46d716788e46a160664034a862e1b62a47e46786396bd477c46581bc838c6
Deleted: sha256:d65371397f23e2827f8b270195c71c5bcf67b9ab76ba479a62e4434fe37e4c6a
Deleted: sha256:9ca0c8c540d03b5a6f352d755a256eb7d1a7f097e8ca9aee5ed078e3ea488a2c
Deleted: sha256:39a456b62e0ba41c871a3a10d9cbc0db8c338a4317ab9a765cf075dd4ed8850d
Deleted: sha256:db1e37d37c5e7b25e1574e090d2e83e4dfe0fa81216cdaea5b5f62cd6c8642f1
Deleted: sha256:b5445a4d8a234ffb10a2e14305d4d4d7417e832813dd627bc757ab658fcde1cd
Deleted: sha256:ca3f138e98c9e05998a1ca211b4fa17f09ce03972b032ce5733132f417a9391e
Deleted: sha256:8f3ba1a7ac86e99d1a7e66e351d4892ae040600fc005c8480758c7339bc71aea
Deleted: sha256:85ab7ba74c1bba428840c92d75840ab627f84d6011971ccdee133bb4da1e708d
Deleted: sha256:41500275c49d5c72c1a1db3f7cad22cdb54cf1894460c48ecf730dff95da5c6b
Deleted: sha256:96241f8f7bb05fc649a1d91a12420167eec806ce880d01564636e95887755561
Deleted: sha256:b606ec304d57236a7ec8ec1b8d051ded6786116373fa6b333be83564021868c9
Deleted: sha256:3c2cb0cca5277c8aa88e95a168baf6cbe73d52634be2ab3eb98490a1c41af2b3
Deleted: sha256:dd62981c22964b6e4dc33e4814596311d121fc298888a894e626774f9daaa4cd
Deleted: sha256:6987b6bf7e56ce62acc3e856df599cc91efc83560e87091e8dba2a83aeb5b453
Deleted: sha256:819c83def5b5539066587518f60444288b9332cb7b87b284115fcbb8640319e0
Deleted: sha256:8a01d50928e2d2575112972a24347c1b009177a73b358f5d8313e7389196dbcd
Build step 'Execute shell' marked build as failure
Jenkins build is back to normal : Mesos » cmake,gcc,--verbose --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,centos:7,(docker||Hadoop)&&(!ubuntu-us1)&&(!ubuntu-6)&&(!ubuntu-eu2) #3025
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos/BUILDTOOL=cmake,COMPILER=gcc,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-6)&&(!ubuntu-eu2)/3025/changes>
Build failed in Jenkins: Mesos » cmake,gcc,--verbose --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,centos:7,(docker||Hadoop)&&(!ubuntu-us1)&&(!ubuntu-6)&&(!ubuntu-eu2) #3023
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos/BUILDTOOL=cmake,COMPILER=gcc,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-6)&&(!ubuntu-eu2)/3023/changes>
Changes:
[bmahler] Added implicit role tests for multi-role frameworks.
[toenshoff] Improved equality check in SlaveTest.StateEndpoint.
------------------------------------------
[...truncated 173761 lines...]
[ RUN ] Endpoint/SlaveEndpointTest.AuthorizedRequest/1
I1209 14:43:13.823158 26718 containerizer.cpp:217] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W1209 14:43:13.823559 26718 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges, but is running as user mesos
W1209 14:43:13.823734 26718 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I1209 14:43:13.825920 26732 slave.cpp:208] Mesos agent started on (656)@172.17.0.2:40532
I1209 14:43:13.825956 26732 slave.cpp:209] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/mesos/store/appc" --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/Endpoint_SlaveEndpointTest_AuthorizedRequest_1_uIjben/credential" --default_role="*" --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/mesos/store/docker" --docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/Endpoint_SlaveEndpointTest_AuthorizedRequest_1_uIjben/fetch" --fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks" --gc_disk_headroom="0.1" --hadoop_home="" --help="false" --hostname_lookup="true" --http_authenticators="basic" --http_command_executor="false" --http_credentials="/tmp/Endpoint_SlaveEndpointTest_AuthorizedRequest_1_uIjben/http_credentials" --image_provisioner_backend="copy" --initialize_driver_logging="true" --io_switchboard_enable_server="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" --qos_correction_interval_min="0ns" --quiet="false" --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/Endpoint_SlaveEndpointTest_AuthorizedRequest_1_uIjben" --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/Endpoint_SlaveEndpointTest_AuthorizedRequest_1_aFUi0F"
I1209 14:43:13.826547 26732 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_AuthorizedRequest_1_uIjben/credential'
I1209 14:43:13.826692 26732 slave.cpp:346] Agent using credential for: test-principal
I1209 14:43:13.826711 26732 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_AuthorizedRequest_1_uIjben/http_credentials'
I1209 14:43:13.826870 26732 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I1209 14:43:13.826947 26732 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I1209 14:43:13.827397 26732 slave.cpp:533] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I1209 14:43:13.827445 26732 slave.cpp:541] Agent attributes: [ ]
I1209 14:43:13.827451 26732 slave.cpp:546] Agent hostname: 6cca4c9eb3c3
I1209 14:43:13.828044 26732 state.cpp:57] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_AuthorizedRequest_1_aFUi0F/meta'
I1209 14:43:13.828488 26733 status_update_manager.cpp:203] Recovering status update manager
I1209 14:43:13.828706 26733 containerizer.cpp:591] Recovering containerizer
I1209 14:43:13.829557 26731 provisioner.cpp:253] Provisioner recovery complete
I1209 14:43:13.829885 26726 slave.cpp:5414] Finished recovery
I1209 14:43:13.830399 26726 slave.cpp:5588] Querying resource estimator for oversubscribable resources
I1209 14:43:13.830727 26727 slave.cpp:5602] Received oversubscribable resources {} from the resource estimator
I1209 14:43:13.831991 26723 process.cpp:3679] Handling HTTP event for process 'slave(656)' with path: '/slave(656)/monitor/statistics.json'
I1209 14:43:13.832859 26730 http.cpp:874] Authorizing principal 'test-principal' to GET the '/monitor/statistics.json' endpoint
I1209 14:43:13.835113 26718 slave.cpp:790] Agent terminating
I1209 14:43:13.840890 26718 containerizer.cpp:217] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W1209 14:43:13.841307 26718 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges, but is running as user mesos
W1209 14:43:13.841398 26718 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I1209 14:43:13.842655 26732 slave.cpp:208] Mesos agent started on (657)@172.17.0.2:40532
I1209 14:43:13.842682 26732 slave.cpp:209] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/mesos/store/appc" --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/Endpoint_SlaveEndpointTest_AuthorizedRequest_2_w0JSha/credential" --default_role="*" --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/mesos/store/docker" --docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/Endpoint_SlaveEndpointTest_AuthorizedRequest_2_w0JSha/fetch" --fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks" --gc_disk_headroom="0.1" --hadoop_home="" --help="false" --hostname_lookup="true" --http_authenticators="basic" --http_command_executor="false" --http_credentials="/tmp/Endpoint_SlaveEndpointTest_AuthorizedRequest_2_w0JSha/http_credentials" --image_provisioner_backend="copy" --initialize_driver_logging="true" --io_switchboard_enable_server="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" --qos_correction_interval_min="0ns" --quiet="false" --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/Endpoint_SlaveEndpointTest_AuthorizedRequest_2_w0JSha" --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/Endpoint_SlaveEndpointTest_AuthorizedRequest_2_0IW10s"
I1209 14:43:13.843135 26732 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_AuthorizedRequest_2_w0JSha/credential'
I1209 14:43:13.843245 26732 slave.cpp:346] Agent using credential for: test-principal
I1209 14:43:13.843264 26732 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_AuthorizedRequest_2_w0JSha/http_credentials'
I1209 14:43:13.843394 26732 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I1209 14:43:13.843454 26732 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I1209 14:43:13.843964 26732 slave.cpp:533] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I1209 14:43:13.844012 26732 slave.cpp:541] Agent attributes: [ ]
I1209 14:43:13.844024 26732 slave.cpp:546] Agent hostname: 6cca4c9eb3c3
I1209 14:43:13.844404 26732 state.cpp:57] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_AuthorizedRequest_2_0IW10s/meta'
I1209 14:43:13.844787 26724 status_update_manager.cpp:203] Recovering status update manager
I1209 14:43:13.844965 26724 containerizer.cpp:591] Recovering containerizer
I1209 14:43:13.845708 26723 provisioner.cpp:253] Provisioner recovery complete
I1209 14:43:13.846026 26729 slave.cpp:5414] Finished recovery
I1209 14:43:13.846482 26729 slave.cpp:5588] Querying resource estimator for oversubscribable resources
I1209 14:43:13.846621 26727 slave.cpp:5602] Received oversubscribable resources {} from the resource estimator
I1209 14:43:13.847466 26726 process.cpp:3679] Handling HTTP event for process 'slave(657)' with path: '/slave(657)/containers'
I1209 14:43:13.848107 26729 http.cpp:874] Authorizing principal 'test-principal' to GET the '/containers' endpoint
I1209 14:43:13.850517 26722 slave.cpp:790] Agent terminating
I1209 14:43:13.856564 26718 containerizer.cpp:217] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W1209 14:43:13.856932 26718 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges, but is running as user mesos
W1209 14:43:13.857028 26718 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I1209 14:43:13.858363 26729 slave.cpp:208] Mesos agent started on (658)@172.17.0.2:40532
I1209 14:43:13.858384 26729 slave.cpp:209] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/mesos/store/appc" --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/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_IlROvX/credential" --default_role="*" --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/mesos/store/docker" --docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_IlROvX/fetch" --fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks" --gc_disk_headroom="0.1" --hadoop_home="" --help="false" --hostname_lookup="true" --http_authenticators="basic" --http_command_executor="false" --http_credentials="/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_IlROvX/http_credentials" --image_provisioner_backend="copy" --initialize_driver_logging="true" --io_switchboard_enable_server="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" --qos_correction_interval_min="0ns" --quiet="false" --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/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_IlROvX" --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/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_Ursqcg"
I1209 14:43:13.858841 26729 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_IlROvX/credential'
I1209 14:43:13.858965 26729 slave.cpp:346] Agent using credential for: test-principal
I1209 14:43:13.858981 26729 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_IlROvX/http_credentials'
I1209 14:43:13.859089 26729 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I1209 14:43:13.859138 26729 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I1209 14:43:13.859577 26729 slave.cpp:533] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I1209 14:43:13.859657 26729 slave.cpp:541] Agent attributes: [ ]
I1209 14:43:13.859663 26729 slave.cpp:546] Agent hostname: 6cca4c9eb3c3
I1209 14:43:13.860260 26731 state.cpp:57] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_Ursqcg/meta'
I1209 14:43:13.860486 26731 status_update_manager.cpp:203] Recovering status update manager
I1209 14:43:13.860797 26734 containerizer.cpp:591] Recovering containerizer
I1209 14:43:13.861769 26726 provisioner.cpp:253] Provisioner recovery complete
I1209 14:43:13.862159 26732 slave.cpp:5414] Finished recovery
I1209 14:43:13.862696 26732 slave.cpp:5588] Querying resource estimator for oversubscribable resources
I1209 14:43:13.862951 26719 slave.cpp:5602] Received oversubscribable resources {} from the resource estimator
I1209 14:43:13.863770 26732 process.cpp:3679] Handling HTTP event for process 'slave(658)' with path: '/slave(658)/monitor/statistics'
I1209 14:43:13.864318 26734 http.cpp:874] Authorizing principal 'test-principal' to GET the '/monitor/statistics' endpoint
I1209 14:43:13.865766 26719 slave.cpp:790] Agent terminating
I1209 14:43:13.871812 26718 containerizer.cpp:217] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W1209 14:43:13.872169 26718 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges, but is running as user mesos
W1209 14:43:13.872259 26718 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I1209 14:43:13.873813 26724 slave.cpp:208] Mesos agent started on (659)@172.17.0.2:40532
I1209 14:43:13.873836 26724 slave.cpp:209] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/mesos/store/appc" --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/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_IcLQTK/credential" --default_role="*" --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/mesos/store/docker" --docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_IcLQTK/fetch" --fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks" --gc_disk_headroom="0.1" --hadoop_home="" --help="false" --hostname_lookup="true" --http_authenticators="basic" --http_command_executor="false" --http_credentials="/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_IcLQTK/http_credentials" --image_provisioner_backend="copy" --initialize_driver_logging="true" --io_switchboard_enable_server="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" --qos_correction_interval_min="0ns" --quiet="false" --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/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_IcLQTK" --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/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_OVjYx3"
I1209 14:43:13.874408 26724 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_IcLQTK/credential'
I1209 14:43:13.874532 26724 slave.cpp:346] Agent using credential for: test-principal
I1209 14:43:13.874549 26724 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_IcLQTK/http_credentials'
I1209 14:43:13.874665 26724 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I1209 14:43:13.874748 26724 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I1209 14:43:13.875226 26724 slave.cpp:533] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I1209 14:43:13.875269 26724 slave.cpp:541] Agent attributes: [ ]
I1209 14:43:13.875279 26724 slave.cpp:546] Agent hostname: 6cca4c9eb3c3
I1209 14:43:13.875977 26728 state.cpp:57] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_OVjYx3/meta'
I1209 14:43:13.876291 26729 status_update_manager.cpp:203] Recovering status update manager
I1209 14:43:13.876430 26724 containerizer.cpp:591] Recovering containerizer
I1209 14:43:13.877116 26729 provisioner.cpp:253] Provisioner recovery complete
I1209 14:43:13.877372 26719 slave.cpp:5414] Finished recovery
I1209 14:43:13.877821 26719 slave.cpp:5588] Querying resource estimator for oversubscribable resources
I1209 14:43:13.877992 26725 slave.cpp:5602] Received oversubscribable resources {} from the resource estimator
I1209 14:43:13.878728 26724 process.cpp:3679] Handling HTTP event for process 'slave(659)' with path: '/slave(659)/monitor/statistics.json'
I1209 14:43:13.879201 26730 http.cpp:874] Authorizing principal 'test-principal' to GET the '/monitor/statistics.json' endpoint
I1209 14:43:13.880692 26719 slave.cpp:790] Agent terminating
I1209 14:43:13.886095 26718 containerizer.cpp:217] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W1209 14:43:13.886451 26718 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges, but is running as user mesos
W1209 14:43:13.886597 26718 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I1209 14:43:13.887538 26730 slave.cpp:208] Mesos agent started on (660)@172.17.0.2:40532
I1209 14:43:13.887557 26730 slave.cpp:209] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/mesos/store/appc" --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/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_0L2iry/credential" --default_role="*" --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/mesos/store/docker" --docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_0L2iry/fetch" --fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks" --gc_disk_headroom="0.1" --hadoop_home="" --help="false" --hostname_lookup="true" --http_authenticators="basic" --http_command_executor="false" --http_credentials="/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_0L2iry/http_credentials" --image_provisioner_backend="copy" --initialize_driver_logging="true" --io_switchboard_enable_server="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" --qos_correction_interval_min="0ns" --quiet="false" --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/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_0L2iry" --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/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_imR62Q"
I1209 14:43:13.887835 26730 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_0L2iry/credential'
I1209 14:43:13.887948 26730 slave.cpp:346] Agent using credential for: test-principal
I1209 14:43:13.887961 26730 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_0L2iry/http_credentials'
I1209 14:43:13.888051 26730 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I1209 14:43:13.888098 26730 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I1209 14:43:13.888603 26730 slave.cpp:533] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I1209 14:43:13.888649 26730 slave.cpp:541] Agent attributes: [ ]
I1209 14:43:13.888666 26730 slave.cpp:546] Agent hostname: 6cca4c9eb3c3
I1209 14:43:13.889281 26731 state.cpp:57] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_imR62Q/meta'
I1209 14:43:13.889444 26729 status_update_manager.cpp:203] Recovering status update manager
I1209 14:43:13.889674 26734 containerizer.cpp:591] Recovering containerizer
I1209 14:43:13.890300 26723 provisioner.cpp:253] Provisioner recovery complete
I1209 14:43:13.890472 26733 slave.cpp:5414] Finished recovery
I1209 14:43:13.890877 26733 slave.cpp:5588] Querying resource estimator for oversubscribable resources
I1209 14:43:13.890983 26731 slave.cpp:5602] Received oversubscribable resources {} from the resource estimator
I1209 14:43:13.891706 26721 process.cpp:3679] Handling HTTP event for process 'slave(660)' with path: '/slave(660)/containers'
I1209 14:43:13.892160 26725 http.cpp:874] Authorizing principal 'test-principal' to GET the '/containers' endpoint
I1209 14:43:13.893108 26718 slave.cpp:790] Agent terminating
I1209 14:43:13.898948 26718 containerizer.cpp:217] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W1209 14:43:13.899206 26718 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges, but is running as user mesos
W1209 14:43:13.899271 26718 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I1209 14:43:13.899745 26718 cluster.cpp:446] Creating default 'local' authorizer
I1209 14:43:13.900185 26722 slave.cpp:208] Mesos agent started on (661)@172.17.0.2:40532
I1209 14:43:13.900204 26722 slave.cpp:209] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/mesos/store/appc" --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/Endpoint_SlaveEndpointTest_NoAuthorizer_0_Qi646l/credential" --default_role="*" --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/mesos/store/docker" --docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_0_Qi646l/fetch" --fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks" --gc_disk_headroom="0.1" --hadoop_home="" --help="false" --hostname_lookup="true" --http_authenticators="basic" --http_command_executor="false" --http_credentials="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_0_Qi646l/http_credentials" --image_provisioner_backend="copy" --initialize_driver_logging="true" --io_switchboard_enable_server="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" --qos_correction_interval_min="0ns" --quiet="false" --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/Endpoint_SlaveEndpointTest_NoAuthorizer_0_Qi646l" --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/Endpoint_SlaveEndpointTest_NoAuthorizer_0_2wQOGE"
I1209 14:43:13.900594 26722 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_0_Qi646l/credential'
I1209 14:43:13.900715 26722 slave.cpp:346] Agent using credential for: test-principal
I1209 14:43:13.900727 26722 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_0_Qi646l/http_credentials'
I1209 14:43:13.900806 26722 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I1209 14:43:13.900841 26722 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I1209 14:43:13.901157 26722 slave.cpp:533] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I1209 14:43:13.901201 26722 slave.cpp:541] Agent attributes: [ ]
I1209 14:43:13.901208 26722 slave.cpp:546] Agent hostname: 6cca4c9eb3c3
I1209 14:43:13.901764 26731 state.cpp:57] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_0_2wQOGE/meta'
I1209 14:43:13.902040 26727 status_update_manager.cpp:203] Recovering status update manager
I1209 14:43:13.902242 26731 containerizer.cpp:591] Recovering containerizer
I1209 14:43:13.902663 26721 provisioner.cpp:253] Provisioner recovery complete
I1209 14:43:13.902827 26721 slave.cpp:5414] Finished recovery
I1209 14:43:13.903134 26721 slave.cpp:5588] Querying resource estimator for oversubscribable resources
I1209 14:43:13.903226 26719 slave.cpp:5602] Received oversubscribable resources {} from the resource estimator
I1209 14:43:13.903892 26724 process.cpp:3679] Handling HTTP event for process 'slave(661)' with path: '/slave(661)/monitor/statistics'
I1209 14:43:13.904314 26726 http.cpp:874] Authorizing principal 'test-principal' to GET the '/monitor/statistics' endpoint
I1209 14:43:13.905505 26734 slave.cpp:790] Agent terminating
I1209 14:43:13.910574 26718 containerizer.cpp:217] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W1209 14:43:13.910958 26718 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges, but is running as user mesos
W1209 14:43:13.911049 26718 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I1209 14:43:13.911684 26718 cluster.cpp:446] Creating default 'local' authorizer
I1209 14:43:13.912084 26730 slave.cpp:208] Mesos agent started on (662)@172.17.0.2:40532
I1209 14:43:13.912124 26730 slave.cpp:209] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/mesos/store/appc" --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/Endpoint_SlaveEndpointTest_NoAuthorizer_1_AFBxU9/credential" --default_role="*" --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/mesos/store/docker" --docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_1_AFBxU9/fetch" --fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks" --gc_disk_headroom="0.1" --hadoop_home="" --help="false" --hostname_lookup="true" --http_authenticators="basic" --http_command_executor="false" --http_credentials="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_1_AFBxU9/http_credentials" --image_provisioner_backend="copy" --initialize_driver_logging="true" --io_switchboard_enable_server="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" --qos_correction_interval_min="0ns" --quiet="false" --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/Endpoint_SlaveEndpointTest_NoAuthorizer_1_AFBxU9" --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/Endpoint_SlaveEndpointTest_NoAuthorizer_1_4R5nss"
I1209 14:43:13.912400 26730 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_1_AFBxU9/credential'
I1209 14:43:13.912467 26730 slave.cpp:346] Agent using credential for: test-principal
I1209 14:43:13.912478 26730 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_1_AFBxU9/http_credentials'
I1209 14:43:13.912555 26730 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I1209 14:43:13.912595 26730 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I1209 14:43:13.912894 26730 slave.cpp:533] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I1209 14:43:13.912925 26730 slave.cpp:541] Agent attributes: [ ]
I1209 14:43:13.912930 26730 slave.cpp:546] Agent hostname: 6cca4c9eb3c3
I1209 14:43:13.913238 26733 state.cpp:57] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_1_4R5nss/meta'
I1209 14:43:13.913404 26734 status_update_manager.cpp:203] Recovering status update manager
I1209 14:43:13.913486 26729 containerizer.cpp:591] Recovering containerizer
I1209 14:43:13.913923 26721 provisioner.cpp:253] Provisioner recovery complete
I1209 14:43:13.914047 26726 slave.cpp:5414] Finished recovery
I1209 14:43:13.914371 26726 slave.cpp:5588] Querying resource estimator for oversubscribable resources
I1209 14:43:13.914459 26734 slave.cpp:5602] Received oversubscribable resources {} from the resource estimator
I1209 14:43:13.915134 26727 process.cpp:3679] Handling HTTP event for process 'slave(662)' with path: '/slave(662)/monitor/statistics.json'
I1209 14:43:13.915591 26723 http.cpp:874] Authorizing principal 'test-principal' to GET the '/monitor/statistics.json' endpoint
I1209 14:43:13.916867 26727 slave.cpp:790] Agent terminating
I1209 14:43:13.922299 26718 containerizer.cpp:217] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W1209 14:43:13.922664 26718 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges, but is running as user mesos
W1209 14:43:13.922755 26718 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I1209 14:43:13.923447 26718 cluster.cpp:446] Creating default 'local' authorizer
I1209 14:43:13.924070 26734 slave.cpp:208] Mesos agent started on (663)@172.17.0.2:40532
I1209 14:43:13.924088 26734 slave.cpp:209] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/mesos/store/appc" --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/Endpoint_SlaveEndpointTest_NoAuthorizer_2_Ic6wPX/credential" --default_role="*" --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/mesos/store/docker" --docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_2_Ic6wPX/fetch" --fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks" --gc_disk_headroom="0.1" --hadoop_home="" --help="false" --hostname_lookup="true" --http_authenticators="basic" --http_command_executor="false" --http_credentials="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_2_Ic6wPX/http_credentials" --image_provisioner_backend="copy" --initialize_driver_logging="true" --io_switchboard_enable_server="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" --qos_correction_interval_min="0ns" --quiet="false" --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/Endpoint_SlaveEndpointTest_NoAuthorizer_2_Ic6wPX" --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/Endpoint_SlaveEndpointTest_NoAuthorizer_2_MNvtlg"
I1209 14:43:13.924542 26734 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_2_Ic6wPX/credential'
I1209 14:43:13.924638 26734 slave.cpp:346] Agent using credential for: test-principal
I1209 14:43:13.924651 26734 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_2_Ic6wPX/http_credentials'
I1209 14:43:13.924729 26734 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I1209 14:43:13.924764 26734 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I1209 14:43:13.925055 26734 slave.cpp:533] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I1209 14:43:13.925084 26734 slave.cpp:541] Agent attributes: [ ]
I1209 14:43:13.925089 26734 slave.cpp:546] Agent hostname: 6cca4c9eb3c3
I1209 14:43:13.925413 26720 state.cpp:57] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_2_MNvtlg/meta'
I1209 14:43:13.925529 26732 status_update_manager.cpp:203] Recovering status update manager
I1209 14:43:13.925686 26725 containerizer.cpp:591] Recovering containerizer
I1209 14:43:13.926102 26723 provisioner.cpp:253] Provisioner recovery complete
I1209 14:43:13.926254 26720 slave.cpp:5414] Finished recovery
I1209 14:43:13.926548 26720 slave.cpp:5588] Querying resource estimator for oversubscribable resources
I1209 14:43:13.926656 26726 slave.cpp:5602] Received oversubscribable resources {} from the resource estimator
I1209 14:43:13.927145 26719 process.cpp:3679] Handling HTTP event for process 'slave(663)' with path: '/slave(663)/containers'
I1209 14:43:13.927466 26733 http.cpp:874] Authorizing principal 'test-principal' to GET the '/containers' endpoint
I1209 14:43:13.928529 26719 slave.cpp:790] Agent terminating
make[3]: *** [CMakeFiles/check] Error 1
make[2]: *** [CMakeFiles/check.dir/all] Error 2
make[1]: *** [CMakeFiles/check.dir/rule] Error 2
make: *** [check] Error 2
[ OK ] Endpoint/SlaveEndpointTest.AuthorizedRequest/1 (18 ms)
[ RUN ] Endpoint/SlaveEndpointTest.AuthorizedRequest/2
[ OK ] Endpoint/SlaveEndpointTest.AuthorizedRequest/2 (15 ms)
[ RUN ] Endpoint/SlaveEndpointTest.UnauthorizedRequest/0
[ OK ] Endpoint/SlaveEndpointTest.UnauthorizedRequest/0 (15 ms)
[ RUN ] Endpoint/SlaveEndpointTest.UnauthorizedRequest/1
[ OK ] Endpoint/SlaveEndpointTest.UnauthorizedRequest/1 (14 ms)
[ RUN ] Endpoint/SlaveEndpointTest.UnauthorizedRequest/2
[ OK ] Endpoint/SlaveEndpointTest.UnauthorizedRequest/2 (12 ms)
[ RUN ] Endpoint/SlaveEndpointTest.NoAuthorizer/0
[ OK ] Endpoint/SlaveEndpointTest.NoAuthorizer/0 (11 ms)
[ RUN ] Endpoint/SlaveEndpointTest.NoAuthorizer/1
[ OK ] Endpoint/SlaveEndpointTest.NoAuthorizer/1 (11 ms)
[ RUN ] Endpoint/SlaveEndpointTest.NoAuthorizer/2
[ OK ] Endpoint/SlaveEndpointTest.NoAuthorizer/2 (11 ms)
[----------] 9 tests from Endpoint/SlaveEndpointTest (15556 ms total)
[----------] Global test environment tear-down
[==========] 1369 tests from 156 test cases ran. (236209 ms total)
[ PASSED ] 1368 tests.
[ FAILED ] 1 test, listed below:
[ FAILED ] Endpoint/SlaveEndpointTest.AuthorizedRequest/0, where GetParam() = "monitor/statistics"
1 FAILED TEST
YOU HAVE 9 DISABLED TESTS
make[3]: Leaving directory `/mesos/build'
make[2]: Leaving directory `/mesos/build'
make[1]: Leaving directory `/mesos/build'
+ docker rmi mesos-1481291958-27310
Untagged: mesos-1481291958-27310:latest
Deleted: sha256:b9e95b6291f5e0a540b4f689ca3406544e01ab6a6fa22b0726a1ff435dfb70d1
Deleted: sha256:7db71b3bcd27550e8f3f99f9bf4a67ae75905df4d9ddb5524630e2627b18f2d2
Deleted: sha256:793c19853993438c1ce99676ee3d26974762560e3ea1e72d95f9e58f503b868a
Deleted: sha256:c724f5ac951a5b73253c043930a21e2b7484170ee2d4910a07dc891a0a551f51
Deleted: sha256:ac532f611bd6fe369bd8f62f77f1d678eb101c1e3e2e6d5951df6ef5c037e1c2
Deleted: sha256:aa943a07db439ce9e2ef940d1f7070b51e9e15482ec1630644d660b441dfb66d
Deleted: sha256:964bd39056c8dfb3d51c697ac8e6130fd689a90531e8a52e4d1b37c1afde6804
Deleted: sha256:d95786ef94fb49afb0a856ad9bd7b4ab9e05708631174906224bc2e96927c424
Deleted: sha256:f93db179245bc87b15d06dbe33b271ec06821fe913789efdf059ac8960233475
Deleted: sha256:672ff103ac6187978d89d926fee0818cf45109d2c55a05024e72aa734b1792c8
Deleted: sha256:71fdf984bfdbf1617be624bdcd78917c933bb5fd83358fa182481a04e85ed2c1
Deleted: sha256:12fa5e6d28eac0683f3d9b15e16653252463d56631d31214e074a2d833b0f734
Deleted: sha256:b3cfdbb5814f982df1816c33987618c338ea740d126137034d9344c7909a8e09
Deleted: sha256:af5bdbc587caca367e6cb98e5988de2fb7a1ef6d48323050721d245960b99086
Deleted: sha256:c11d169abe7033ba58e69d42a623628e936ddb21e2d17c0aca590e5003e8056c
Deleted: sha256:8f0a630af69263de476a6f85d56068764df141bb2ebe102d280845976c2359ab
Deleted: sha256:acfb586ec850247276ca529a8f1ccc155c134d44f31612ea09be37dd16da3e0b
Deleted: sha256:6ff4bd28d7c5b9e1ca7372f4705de50dc112f838cd26b562adceb5841582c525
Deleted: sha256:37478af1f571d718766e856f711f311ddcd1a86a0e8f9ff7af835104c7ed96df
Deleted: sha256:d2d498549d32668d2488e5828baa85ba313455cd2e002459839889bcb1e143f1
Deleted: sha256:cee6f4c987602295c5833d0fe09da957399770952725f7c23c6a485854df018a
Deleted: sha256:e906e8e95da2b690cafef12795c224e6e8cf12679ec812655bb3e8f89362b70f
Deleted: sha256:202bc683b5424a7327c6f3c395abb89e4f8fd70a224a394a8cd27e653308c387
Deleted: sha256:8da7b010fb92f2ad3a3a992d92a8df6dc24142e9e3057bc6e10fd7d9aaa4e474
Deleted: sha256:4010bf90636b57416124dfae6ff92aa394af72ce5a934077d7422324fbf4d4f4
Build step 'Execute shell' marked build as failure
Build failed in Jenkins: Mesos » cmake,gcc,--verbose --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,centos:7,(docker||Hadoop)&&(!ubuntu-us1)&&(!ubuntu-6)&&(!ubuntu-eu2) #3022
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos/BUILDTOOL=cmake,COMPILER=gcc,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-6)&&(!ubuntu-eu2)/3022/changes>
Changes:
[bmahler] Added a test for the `getRoles` helper in protobuf utils.
[bmahler] Removed "*" role from default response of `getRoles` API.
[yujie.jay] Added a grace period for I/O switchboard server termination.
------------------------------------------
[...truncated 18231 lines...]
I1209 02:39:25.106621 27239 master.cpp:4240] Launching task 2 of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000 (Event Call Scheduler using libprocess (C++)) with resources cpus(*):1; mem(*):128 on agent 5425aebd-5548-4224-8a77-1ee54e089233-S1 at slave(3)@172.17.0.3:34589 (0e1fb29f5fa3)
I1209 02:39:25.106842 27242 slave.cpp:1550] Got assigned task '2' for framework 5425aebd-5548-4224-8a77-1ee54e089233-0000
W1209 02:39:25.106917 27239 validation.cpp:1001] Executor 'default' for task '3' uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases.
W1209 02:39:25.106940 27239 validation.cpp:1013] Executor 'default' for task '3' uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases.
I1209 02:39:25.107054 27239 master.cpp:8501] Adding task 3 with resources cpus(*):1; mem(*):128 on agent 5425aebd-5548-4224-8a77-1ee54e089233-S1 (0e1fb29f5fa3)
I1209 02:39:25.107102 27239 master.cpp:4240] Launching task 3 of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000 (Event Call Scheduler using libprocess (C++)) with resources cpus(*):1; mem(*):128 on agent 5425aebd-5548-4224-8a77-1ee54e089233-S1 at slave(3)@172.17.0.3:34589 (0e1fb29f5fa3)
I1209 02:39:25.107280 27242 slave.cpp:1550] Got assigned task '3' for framework 5425aebd-5548-4224-8a77-1ee54e089233-0000
I1209 02:39:25.107497 27242 slave.cpp:1712] Launching task '2' for framework 5425aebd-5548-4224-8a77-1ee54e089233-0000
I1209 02:39:25.107492 27239 hierarchical.cpp:1023] Recovered ports(*):[31000-32000]; mem(*):9984; disk(*):3701220 (total: cpus(*):2; mem(*):10240; disk(*):3701220; ports(*):[31000-32000], allocated: cpus(*):2; mem(*):256) on agent 5425aebd-5548-4224-8a77-1ee54e089233-S1 from framework 5425aebd-5548-4224-8a77-1ee54e089233-0000
I1209 02:39:25.107519 27239 hierarchical.cpp:1060] Framework 5425aebd-5548-4224-8a77-1ee54e089233-0000 filtered agent 5425aebd-5548-4224-8a77-1ee54e089233-S1 for 5secs
I1209 02:39:25.108772 27242 paths.cpp:530] Trying to chown '/tmp/mesos-ABAnaL/2/slaves/5425aebd-5548-4224-8a77-1ee54e089233-S1/frameworks/5425aebd-5548-4224-8a77-1ee54e089233-0000/executors/default/runs/8ba3b178-de9c-489c-a68e-7e842f7896d6' to user 'mesos'
I1209 02:39:25.114014 27242 slave.cpp:6341] Launching executor 'default' of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000 with resources {} in work directory '/tmp/mesos-ABAnaL/2/slaves/5425aebd-5548-4224-8a77-1ee54e089233-S1/frameworks/5425aebd-5548-4224-8a77-1ee54e089233-0000/executors/default/runs/8ba3b178-de9c-489c-a68e-7e842f7896d6'
I1209 02:39:25.114310 27243 containerizer.cpp:983] Starting container 8ba3b178-de9c-489c-a68e-7e842f7896d6 for executor 'default' of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000
I1209 02:39:25.114331 27242 slave.cpp:2034] Queued task '2' for executor 'default' of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000
I1209 02:39:25.114382 27242 slave.cpp:1712] Launching task '3' for framework 5425aebd-5548-4224-8a77-1ee54e089233-0000
I1209 02:39:25.114445 27242 slave.cpp:2034] Queued task '3' for executor 'default' of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000
I1209 02:39:25.114485 27242 slave.cpp:871] Successfully attached file '/tmp/mesos-ABAnaL/2/slaves/5425aebd-5548-4224-8a77-1ee54e089233-S1/frameworks/5425aebd-5548-4224-8a77-1ee54e089233-0000/executors/default/runs/8ba3b178-de9c-489c-a68e-7e842f7896d6'
I1209 02:39:25.115571 27250 containerizer.cpp:1519] Launching 'mesos-containerizer' with flags '--help="false" --launch_info="{"command":{"shell":true,"value":"\/mesos\/build\/src\/test-http-executor"},"environment":{"variables":[{"name":"LIBPROCESS_PORT","value":"0"},{"name":"MESOS_AGENT_ENDPOINT","value":"172.17.0.3:34589"},{"name":"MESOS_CHECKPOINT","value":"0"},{"name":"MESOS_DIRECTORY","value":"\/tmp\/mesos-ABAnaL\/2\/slaves\/5425aebd-5548-4224-8a77-1ee54e089233-S1\/frameworks\/5425aebd-5548-4224-8a77-1ee54e089233-0000\/executors\/default\/runs\/8ba3b178-de9c-489c-a68e-7e842f7896d6"},{"name":"MESOS_EXECUTOR_ID","value":"default"},{"name":"MESOS_EXECUTOR_SHUTDOWN_GRACE_PERIOD","value":"5secs"},{"name":"MESOS_FRAMEWORK_ID","value":"5425aebd-5548-4224-8a77-1ee54e089233-0000"},{"name":"MESOS_HTTP_COMMAND_EXECUTOR","value":"0"},{"name":"MESOS_SLAVE_ID","value":"5425aebd-5548-4224-8a77-1ee54e089233-S1"},{"name":"MESOS_SLAVE_PID","value":"slave(3)@172.17.0.3:34589"},{"name":"MESOS_SANDBOX","value":"\/tmp\/mesos-ABAnaL\/2\/slaves\/5425aebd-5548-4224-8a77-1ee54e089233-S1\/frameworks\/5425aebd-5548-4224-8a77-1ee54e089233-0000\/executors\/default\/runs\/8ba3b178-de9c-489c-a68e-7e842f7896d6"}]},"user":"mesos","working_directory":"\/tmp\/mesos-ABAnaL\/2\/slaves\/5425aebd-5548-4224-8a77-1ee54e089233-S1\/frameworks\/5425aebd-5548-4224-8a77-1ee54e089233-0000\/executors\/default\/runs\/8ba3b178-de9c-489c-a68e-7e842f7896d6"}" --pipe_read="17" --pipe_write="18" --runtime_directory="/tmp/mesos-9tTaBQ/containers/8ba3b178-de9c-489c-a68e-7e842f7896d6" --unshare_namespace_mnt="false"'
I1209 02:39:25.116590 27250 launcher.cpp:133] Forked child with pid '27277' for container '8ba3b178-de9c-489c-a68e-7e842f7896d6'
I1209 02:39:25.117660 27238 fetcher.cpp:345] Starting to fetch URIs for container: 8ba3b178-de9c-489c-a68e-7e842f7896d6, directory: /tmp/mesos-ABAnaL/2/slaves/5425aebd-5548-4224-8a77-1ee54e089233-S1/frameworks/5425aebd-5548-4224-8a77-1ee54e089233-0000/executors/default/runs/8ba3b178-de9c-489c-a68e-7e842f7896d6
I1209 02:39:25.214936 27287 executor.cpp:189] Version: 1.2.0
I1209 02:39:25.217381 27242 process.cpp:3679] Handling HTTP event for process 'slave(3)' with path: '/slave(3)/api/v1/executor'
I1209 02:39:25.217836 27247 http.cpp:288] HTTP POST for /slave(3)/api/v1/executor from 172.17.0.3:33465
I1209 02:39:25.217960 27247 slave.cpp:3089] Received Subscribe request for HTTP executor 'default' of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000
I1209 02:39:25.218631 27239 slave.cpp:2250] Sending queued task '2' to executor 'default' of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000 (via HTTP)
I1209 02:39:25.218782 27239 slave.cpp:2250] Sending queued task '3' to executor 'default' of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000 (via HTTP)
Received a SUBSCRIBED event
Starting task 2
Finishing task 2
Starting task 3
Finishing task 3
I1209 02:39:25.221830 27251 process.cpp:3679] Handling HTTP event for process 'slave(3)' with path: '/slave(3)/api/v1/executor'
I1209 02:39:25.222020 27251 process.cpp:3679] Handling HTTP event for process 'slave(3)' with path: '/slave(3)/api/v1/executor'
I1209 02:39:25.222074 27251 process.cpp:3679] Handling HTTP event for process 'slave(3)' with path: '/slave(3)/api/v1/executor'
I1209 02:39:25.222122 27251 process.cpp:3679] Handling HTTP event for process 'slave(3)' with path: '/slave(3)/api/v1/executor'
I1209 02:39:25.222314 27251 http.cpp:288] HTTP POST for /slave(3)/api/v1/executor from 172.17.0.3:33466
I1209 02:39:25.222373 27251 slave.cpp:3743] Handling status update TASK_RUNNING (UUID: 7abada5d-148c-4b7d-bb17-4ed5861c3e39) for task 2 of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000
I1209 02:39:25.222509 27251 http.cpp:288] HTTP POST for /slave(3)/api/v1/executor from 172.17.0.3:33466
I1209 02:39:25.222551 27251 slave.cpp:3743] Handling status update TASK_FINISHED (UUID: 60e95572-ce7d-43d5-8174-f561f57c53dc) for task 2 of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000
I1209 02:39:25.222664 27251 http.cpp:288] HTTP POST for /slave(3)/api/v1/executor from 172.17.0.3:33466
I1209 02:39:25.222707 27251 slave.cpp:3743] Handling status update TASK_RUNNING (UUID: 8980d525-57c0-475e-92da-953e83e56fb6) for task 3 of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000
I1209 02:39:25.222818 27251 http.cpp:288] HTTP POST for /slave(3)/api/v1/executor from 172.17.0.3:33466
I1209 02:39:25.222861 27251 slave.cpp:3743] Handling status update TASK_FINISHED (UUID: 44bcf5ff-fbb3-4a59-ae40-d8c2686cad28) for task 3 of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000
I1209 02:39:25.223042 27245 status_update_manager.cpp:323] Received status update TASK_RUNNING (UUID: 7abada5d-148c-4b7d-bb17-4ed5861c3e39) for task 2 of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000
I1209 02:39:25.223070 27245 status_update_manager.cpp:500] Creating StatusUpdate stream for task 2 of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000
I1209 02:39:25.223318 27245 status_update_manager.cpp:377] Forwarding update TASK_RUNNING (UUID: 7abada5d-148c-4b7d-bb17-4ed5861c3e39) for task 2 of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000 to the agent
I1209 02:39:25.223451 27245 status_update_manager.cpp:323] Received status update TASK_RUNNING (UUID: 8980d525-57c0-475e-92da-953e83e56fb6) for task 3 of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000
I1209 02:39:25.223482 27245 status_update_manager.cpp:500] Creating StatusUpdate stream for task 3 of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000
I1209 02:39:25.223448 27251 slave.cpp:4184] Forwarding the update TASK_RUNNING (UUID: 7abada5d-148c-4b7d-bb17-4ed5861c3e39) for task 2 of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000 to master@172.17.0.3:34589
I1209 02:39:25.223577 27251 slave.cpp:4078] Status update manager successfully handled status update TASK_RUNNING (UUID: 7abada5d-148c-4b7d-bb17-4ed5861c3e39) for task 2 of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000
I1209 02:39:25.223598 27245 status_update_manager.cpp:377] Forwarding update TASK_RUNNING (UUID: 8980d525-57c0-475e-92da-953e83e56fb6) for task 3 of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000 to the agent
I1209 02:39:25.223639 27253 master.cpp:5769] Status update TASK_RUNNING (UUID: 7abada5d-148c-4b7d-bb17-4ed5861c3e39) for task 2 of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000 from agent 5425aebd-5548-4224-8a77-1ee54e089233-S1 at slave(3)@172.17.0.3:34589 (0e1fb29f5fa3)
I1209 02:39:25.223669 27253 master.cpp:5831] Forwarding status update TASK_RUNNING (UUID: 7abada5d-148c-4b7d-bb17-4ed5861c3e39) for task 2 of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000
I1209 02:39:25.223706 27251 slave.cpp:4184] Forwarding the update TASK_RUNNING (UUID: 8980d525-57c0-475e-92da-953e83e56fb6) for task 3 of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000 to master@172.17.0.3:34589
I1209 02:39:25.223726 27245 status_update_manager.cpp:323] Received status update TASK_FINISHED (UUID: 60e95572-ce7d-43d5-8174-f561f57c53dc) for task 2 of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000
I1209 02:39:25.223758 27251 slave.cpp:4078] Status update manager successfully handled status update TASK_RUNNING (UUID: 8980d525-57c0-475e-92da-953e83e56fb6) for task 3 of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000
I1209 02:39:25.223793 27253 master.cpp:7867] Updating the state of task 2 of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000 (latest state: TASK_FINISHED, status update state: TASK_RUNNING)
I1209 02:39:25.223806 27245 status_update_manager.cpp:323] Received status update TASK_FINISHED (UUID: 44bcf5ff-fbb3-4a59-ae40-d8c2686cad28) for task 3 of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000
I1209 02:39:25.223896 27253 master.cpp:5769] Status update TASK_RUNNING (UUID: 8980d525-57c0-475e-92da-953e83e56fb6) for task 3 of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000 from agent 5425aebd-5548-4224-8a77-1ee54e089233-S1 at slave(3)@172.17.0.3:34589 (0e1fb29f5fa3)
I1209 02:39:25.223922 27253 master.cpp:5831] Forwarding status update TASK_RUNNING (UUID: 8980d525-57c0-475e-92da-953e83e56fb6) for task 3 of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000
I1209 02:39:25.223950 27242 hierarchical.cpp:1023] Recovered cpus(*):1; mem(*):128 (total: cpus(*):2; mem(*):10240; disk(*):3701220; ports(*):[31000-32000], allocated: cpus(*):1; mem(*):128) on agent 5425aebd-5548-4224-8a77-1ee54e089233-S1 from framework 5425aebd-5548-4224-8a77-1ee54e089233-0000
I1209 02:39:25.224021 27253 master.cpp:7867] Updating the state of task 3 of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000 (latest state: TASK_FINISHED, status update state: TASK_RUNNING)
I1209 02:39:25.224056 27251 slave.cpp:4078] Status update manager successfully handled status update TASK_FINISHED (UUID: 60e95572-ce7d-43d5-8174-f561f57c53dc) for task 2 of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000
I1209 02:39:25.224109 27251 slave.cpp:4078] Status update manager successfully handled status update TASK_FINISHED (UUID: 44bcf5ff-fbb3-4a59-ae40-d8c2686cad28) for task 3 of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000
I1209 02:39:25.224196 27245 hierarchical.cpp:1023] Recovered cpus(*):1; mem(*):128 (total: cpus(*):2; mem(*):10240; disk(*):3701220; ports(*):[31000-32000], allocated: {}) on agent 5425aebd-5548-4224-8a77-1ee54e089233-S1 from framework 5425aebd-5548-4224-8a77-1ee54e089233-0000
Received an ACKNOWLEDGED event
Received an ACKNOWLEDGED event
Received an ACKNOWLEDGED event
Received an ACKNOWLEDGED event
I1209 02:39:25.225292 27239 scheduler.cpp:681] Enqueuing event UPDATE received from http://172.17.0.3:34589/master/api/v1/scheduler
I1209 02:39:25.225443 27239 scheduler.cpp:681] Enqueuing event UPDATE received from http://172.17.0.3:34589/master/api/v1/scheduler
Received an UPDATE event
Task 2 is in state TASK_RUNNING
I1209 02:39:25.225632 27239 scheduler.cpp:241] Sending ACKNOWLEDGE call to http://172.17.0.3:34589/master/api/v1/scheduler
I1209 02:39:25.225735 27239 scheduler.cpp:241] Sending ACKNOWLEDGE call to http://172.17.0.3:34589/master/api/v1/scheduler
Received an UPDATE event
Task 3 is in state TASK_RUNNING
I1209 02:39:25.226059 27250 process.cpp:3679] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1209 02:39:25.263834 27238 process.cpp:3679] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1209 02:39:25.264061 27238 http.cpp:393] HTTP POST for /master/api/v1/scheduler from 172.17.0.3:33461
I1209 02:39:25.264158 27238 master.cpp:4877] Processing ACKNOWLEDGE call 7abada5d-148c-4b7d-bb17-4ed5861c3e39 for task 2 of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000 (Event Call Scheduler using libprocess (C++)) on agent 5425aebd-5548-4224-8a77-1ee54e089233-S1
I1209 02:39:25.264389 27238 status_update_manager.cpp:395] Received status update acknowledgement (UUID: 7abada5d-148c-4b7d-bb17-4ed5861c3e39) for task 2 of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000
I1209 02:39:25.264502 27238 status_update_manager.cpp:377] Forwarding update TASK_FINISHED (UUID: 60e95572-ce7d-43d5-8174-f561f57c53dc) for task 2 of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000 to the agent
I1209 02:39:25.264601 27238 http.cpp:393] HTTP POST for /master/api/v1/scheduler from 172.17.0.3:33461
I1209 02:39:25.264636 27238 master.cpp:4877] Processing ACKNOWLEDGE call 8980d525-57c0-475e-92da-953e83e56fb6 for task 3 of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000 (Event Call Scheduler using libprocess (C++)) on agent 5425aebd-5548-4224-8a77-1ee54e089233-S1
I1209 02:39:25.264839 27238 slave.cpp:4184] Forwarding the update TASK_FINISHED (UUID: 60e95572-ce7d-43d5-8174-f561f57c53dc) for task 2 of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000 to master@172.17.0.3:34589
I1209 02:39:25.264911 27238 slave.cpp:3025] Status update manager successfully handled status update acknowledgement (UUID: 7abada5d-148c-4b7d-bb17-4ed5861c3e39) for task 2 of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000
I1209 02:39:25.265056 27238 master.cpp:5769] Status update TASK_FINISHED (UUID: 60e95572-ce7d-43d5-8174-f561f57c53dc) for task 2 of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000 from agent 5425aebd-5548-4224-8a77-1ee54e089233-S1 at slave(3)@172.17.0.3:34589 (0e1fb29f5fa3)
I1209 02:39:25.265084 27238 master.cpp:5831] Forwarding status update TASK_FINISHED (UUID: 60e95572-ce7d-43d5-8174-f561f57c53dc) for task 2 of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000
I1209 02:39:25.265219 27238 master.cpp:7867] Updating the state of task 2 of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000 (latest state: TASK_FINISHED, status update state: TASK_FINISHED)
I1209 02:39:25.265249 27238 status_update_manager.cpp:395] Received status update acknowledgement (UUID: 8980d525-57c0-475e-92da-953e83e56fb6) for task 3 of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000
I1209 02:39:25.265313 27238 status_update_manager.cpp:377] Forwarding update TASK_FINISHED (UUID: 44bcf5ff-fbb3-4a59-ae40-d8c2686cad28) for task 3 of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000 to the agent
I1209 02:39:25.265420 27238 slave.cpp:4184] Forwarding the update TASK_FINISHED (UUID: 44bcf5ff-fbb3-4a59-ae40-d8c2686cad28) for task 3 of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000 to master@172.17.0.3:34589
I1209 02:39:25.265476 27238 slave.cpp:3025] Status update manager successfully handled status update acknowledgement (UUID: 8980d525-57c0-475e-92da-953e83e56fb6) for task 3 of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000
I1209 02:39:25.265527 27238 master.cpp:5769] Status update TASK_FINISHED (UUID: 44bcf5ff-fbb3-4a59-ae40-d8c2686cad28) for task 3 of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000 from agent 5425aebd-5548-4224-8a77-1ee54e089233-S1 at slave(3)@172.17.0.3:34589 (0e1fb29f5fa3)
I1209 02:39:25.265549 27238 master.cpp:5831] Forwarding status update TASK_FINISHED (UUID: 44bcf5ff-fbb3-4a59-ae40-d8c2686cad28) for task 3 of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000
I1209 02:39:25.265635 27238 master.cpp:7867] Updating the state of task 3 of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000 (latest state: TASK_FINISHED, status update state: TASK_FINISHED)
I1209 02:39:25.266278 27238 scheduler.cpp:681] Enqueuing event UPDATE received from http://172.17.0.3:34589/master/api/v1/scheduler
I1209 02:39:25.266531 27238 scheduler.cpp:681] Enqueuing event UPDATE received from http://172.17.0.3:34589/master/api/v1/scheduler
I1209 02:39:25.266708 27238 scheduler.cpp:241] Sending ACKNOWLEDGE call to http://172.17.0.3:34589/master/api/v1/scheduler
Received an UPDATE event
Task 2 is in state TASK_FINISHED
Received an UPDATE event
Task 3 is in state TASK_FINISHED
I1209 02:39:25.266927 27238 scheduler.cpp:241] Sending ACKNOWLEDGE call to http://172.17.0.3:34589/master/api/v1/scheduler
I1209 02:39:25.267068 27238 process.cpp:3679] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1209 02:39:25.303630 27238 process.cpp:3679] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1209 02:39:25.303781 27238 http.cpp:393] HTTP POST for /master/api/v1/scheduler from 172.17.0.3:33461
I1209 02:39:25.303880 27238 master.cpp:4877] Processing ACKNOWLEDGE call 60e95572-ce7d-43d5-8174-f561f57c53dc for task 2 of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000 (Event Call Scheduler using libprocess (C++)) on agent 5425aebd-5548-4224-8a77-1ee54e089233-S1
I1209 02:39:25.303916 27238 master.cpp:7963] Removing task 2 with resources cpus(*):1; mem(*):128 of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000 on agent 5425aebd-5548-4224-8a77-1ee54e089233-S1 at slave(3)@172.17.0.3:34589 (0e1fb29f5fa3)
I1209 02:39:25.304136 27238 status_update_manager.cpp:395] Received status update acknowledgement (UUID: 60e95572-ce7d-43d5-8174-f561f57c53dc) for task 2 of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000
I1209 02:39:25.304206 27238 status_update_manager.cpp:531] Cleaning up status update stream for task 2 of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000
I1209 02:39:25.304217 27242 http.cpp:393] HTTP POST for /master/api/v1/scheduler from 172.17.0.3:33461
I1209 02:39:25.304294 27242 master.cpp:4877] Processing ACKNOWLEDGE call 44bcf5ff-fbb3-4a59-ae40-d8c2686cad28 for task 3 of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000 (Event Call Scheduler using libprocess (C++)) on agent 5425aebd-5548-4224-8a77-1ee54e089233-S1
I1209 02:39:25.304330 27242 master.cpp:7963] Removing task 3 with resources cpus(*):1; mem(*):128 of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000 on agent 5425aebd-5548-4224-8a77-1ee54e089233-S1 at slave(3)@172.17.0.3:34589 (0e1fb29f5fa3)
I1209 02:39:25.304363 27238 slave.cpp:3025] Status update manager successfully handled status update acknowledgement (UUID: 60e95572-ce7d-43d5-8174-f561f57c53dc) for task 2 of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000
I1209 02:39:25.304381 27238 slave.cpp:6713] Completing task 2
I1209 02:39:25.304479 27238 status_update_manager.cpp:395] Received status update acknowledgement (UUID: 44bcf5ff-fbb3-4a59-ae40-d8c2686cad28) for task 3 of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000
I1209 02:39:25.304535 27238 status_update_manager.cpp:531] Cleaning up status update stream for task 3 of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000
I1209 02:39:25.304630 27238 slave.cpp:3025] Status update manager successfully handled status update acknowledgement (UUID: 44bcf5ff-fbb3-4a59-ae40-d8c2686cad28) for task 3 of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000
I1209 02:39:25.304648 27238 slave.cpp:6713] Completing task 3
I1209 02:39:25.385330 27242 slave.cpp:1486] Will retry registration in 1.658029015secs if necessary
I1209 02:39:25.385406 27251 master.cpp:5161] Registering agent at slave(1)@172.17.0.3:34589 (0e1fb29f5fa3) with id 5425aebd-5548-4224-8a77-1ee54e089233-S2
I1209 02:39:25.385633 27247 registrar.cpp:461] Applied 1 operations in 63604ns; attempting to update the registry
I1209 02:39:25.386682 27253 log.cpp:577] Attempting to append 669 bytes to the log
I1209 02:39:25.386737 27240 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 7
I1209 02:39:25.387056 27248 replica.cpp:539] Replica received write request for position 7 from __req_res__(12)@172.17.0.3:34589
I1209 02:39:25.387356 27248 leveldb.cpp:341] Persisting action (688 bytes) to leveldb took 262533ns
I1209 02:39:25.387375 27248 replica.cpp:710] Persisted action APPEND at position 7
I1209 02:39:25.387610 27238 replica.cpp:693] Replica received learned notice for position 7 from @0.0.0.0:0
I1209 02:39:25.388350 27238 leveldb.cpp:341] Persisting action (690 bytes) to leveldb took 704642ns
I1209 02:39:25.388366 27238 replica.cpp:710] Persisted action APPEND at position 7
I1209 02:39:25.388859 27239 registrar.cpp:506] Successfully updated the registry in 3.080192ms
I1209 02:39:25.388885 27250 log.cpp:596] Attempting to truncate the log to 7
I1209 02:39:25.388921 27250 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 8
I1209 02:39:25.389124 27239 slave.cpp:4266] Received ping from slave-observer(3)@172.17.0.3:34589
I1209 02:39:25.389137 27253 master.cpp:5232] Registered agent 5425aebd-5548-4224-8a77-1ee54e089233-S2 at slave(1)@172.17.0.3:34589 (0e1fb29f5fa3) with cpus(*):2; mem(*):10240; disk(*):3701220; ports(*):[31000-32000]
I1209 02:39:25.389256 27239 slave.cpp:1118] Registered with master master@172.17.0.3:34589; given agent ID 5425aebd-5548-4224-8a77-1ee54e089233-S2
I1209 02:39:25.389253 27253 hierarchical.cpp:490] Added agent 5425aebd-5548-4224-8a77-1ee54e089233-S2 (0e1fb29f5fa3) with cpus(*):2; mem(*):10240; disk(*):3701220; ports(*):[31000-32000] (allocated: {})
I1209 02:39:25.389277 27239 fetcher.cpp:86] Clearing fetcher cache
I1209 02:39:25.389453 27245 status_update_manager.cpp:184] Resuming sending status updates
I1209 02:39:25.389497 27253 hierarchical.cpp:1784] No inverse offers to send out!
I1209 02:39:25.389520 27253 hierarchical.cpp:1314] Performed allocation for agent 5425aebd-5548-4224-8a77-1ee54e089233-S2 in 239890ns
I1209 02:39:25.389631 27245 master.cpp:6577] Sending 1 offers to framework 5425aebd-5548-4224-8a77-1ee54e089233-0000 (Event Call Scheduler using libprocess (C++))
I1209 02:39:25.389785 27241 replica.cpp:539] Replica received write request for position 8 from __req_res__(13)@172.17.0.3:34589
I1209 02:39:25.389855 27239 slave.cpp:1141] Checkpointing SlaveInfo to '/tmp/mesos-ABAnaL/0/meta/slaves/5425aebd-5548-4224-8a77-1ee54e089233-S2/slave.info'
I1209 02:39:25.390064 27241 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 192266ns
I1209 02:39:25.390118 27241 replica.cpp:710] Persisted action TRUNCATE at position 8
I1209 02:39:25.390184 27239 slave.cpp:1178] Forwarding total oversubscribed resources {}
I1209 02:39:25.390240 27239 master.cpp:5633] Received update of agent 5425aebd-5548-4224-8a77-1ee54e089233-S2 at slave(1)@172.17.0.3:34589 (0e1fb29f5fa3) with total oversubscribed resources {}
I1209 02:39:25.390342 27241 hierarchical.cpp:560] Agent 5425aebd-5548-4224-8a77-1ee54e089233-S2 (0e1fb29f5fa3) updated with oversubscribed resources {} (total: cpus(*):2; mem(*):10240; disk(*):3701220; ports(*):[31000-32000], allocated: cpus(*):2; mem(*):10240; disk(*):3701220; ports(*):[31000-32000])
I1209 02:39:25.390419 27241 hierarchical.cpp:1689] No allocations performed
I1209 02:39:25.390430 27241 hierarchical.cpp:1784] No inverse offers to send out!
I1209 02:39:25.390442 27241 hierarchical.cpp:1314] Performed allocation for agent 5425aebd-5548-4224-8a77-1ee54e089233-S2 in 64588ns
I1209 02:39:25.390573 27247 replica.cpp:693] Replica received learned notice for position 8 from @0.0.0.0:0
I1209 02:39:25.390750 27247 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 154792ns
I1209 02:39:25.390779 27247 leveldb.cpp:399] Deleting ~2 keys from leveldb took 14533ns
I1209 02:39:25.390789 27247 replica.cpp:710] Persisted action TRUNCATE at position 8
I1209 02:39:25.391047 27247 scheduler.cpp:681] Enqueuing event OFFERS received from http://172.17.0.3:34589/master/api/v1/scheduler
Received an OFFERS event
Received offer 5425aebd-5548-4224-8a77-1ee54e089233-O2 with cpus(*):2; mem(*):10240; disk(*):3701220; ports(*):[31000-32000]
Launching task 4 using offer 5425aebd-5548-4224-8a77-1ee54e089233-O2
I1209 02:39:25.391634 27246 scheduler.cpp:241] Sending ACCEPT call to http://172.17.0.3:34589/master/api/v1/scheduler
I1209 02:39:25.391978 27250 process.cpp:3679] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1209 02:39:25.431886 27248 http.cpp:393] HTTP POST for /master/api/v1/scheduler from 172.17.0.3:33461
I1209 02:39:25.432201 27248 master.cpp:3588] Processing ACCEPT call for offers: [ 5425aebd-5548-4224-8a77-1ee54e089233-O2 ] on agent 5425aebd-5548-4224-8a77-1ee54e089233-S2 at slave(1)@172.17.0.3:34589 (0e1fb29f5fa3) for framework 5425aebd-5548-4224-8a77-1ee54e089233-0000 (Event Call Scheduler using libprocess (C++))
I1209 02:39:25.432243 27248 master.cpp:3175] Authorizing framework principal 'test-principal' to launch task 4
W1209 02:39:25.432755 27242 validation.cpp:1001] Executor 'default' for task '4' uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases.
W1209 02:39:25.432785 27242 validation.cpp:1013] Executor 'default' for task '4' uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases.
I1209 02:39:25.432852 27242 master.cpp:8501] Adding task 4 with resources cpus(*):1; mem(*):128 on agent 5425aebd-5548-4224-8a77-1ee54e089233-S2 (0e1fb29f5fa3)
I1209 02:39:25.432886 27242 master.cpp:4240] Launching task 4 of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000 (Event Call Scheduler using libprocess (C++)) with resources cpus(*):1; mem(*):128 on agent 5425aebd-5548-4224-8a77-1ee54e089233-S2 at slave(1)@172.17.0.3:34589 (0e1fb29f5fa3)
I1209 02:39:25.433075 27242 slave.cpp:1550] Got assigned task '4' for framework 5425aebd-5548-4224-8a77-1ee54e089233-0000
I1209 02:39:25.433282 27242 slave.cpp:1712] Launching task '4' for framework 5425aebd-5548-4224-8a77-1ee54e089233-0000
I1209 02:39:25.433670 27245 hierarchical.cpp:1023] Recovered cpus(*):1; mem(*):10112; disk(*):3701220; ports(*):[31000-32000] (total: cpus(*):2; mem(*):10240; disk(*):3701220; ports(*):[31000-32000], allocated: cpus(*):1; mem(*):128) on agent 5425aebd-5548-4224-8a77-1ee54e089233-S2 from framework 5425aebd-5548-4224-8a77-1ee54e089233-0000
I1209 02:39:25.433707 27245 hierarchical.cpp:1060] Framework 5425aebd-5548-4224-8a77-1ee54e089233-0000 filtered agent 5425aebd-5548-4224-8a77-1ee54e089233-S2 for 5secs
I1209 02:39:25.433748 27242 paths.cpp:530] Trying to chown '/tmp/mesos-ABAnaL/0/slaves/5425aebd-5548-4224-8a77-1ee54e089233-S2/frameworks/5425aebd-5548-4224-8a77-1ee54e089233-0000/executors/default/runs/51ce1d0a-b170-45e4-8090-b0e5b33ba35e' to user 'mesos'
I1209 02:39:25.438900 27242 slave.cpp:6341] Launching executor 'default' of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000 with resources {} in work directory '/tmp/mesos-ABAnaL/0/slaves/5425aebd-5548-4224-8a77-1ee54e089233-S2/frameworks/5425aebd-5548-4224-8a77-1ee54e089233-0000/executors/default/runs/51ce1d0a-b170-45e4-8090-b0e5b33ba35e'
I1209 02:39:25.439172 27242 slave.cpp:2034] Queued task '4' for executor 'default' of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000
I1209 02:39:25.439213 27242 slave.cpp:871] Successfully attached file '/tmp/mesos-ABAnaL/0/slaves/5425aebd-5548-4224-8a77-1ee54e089233-S2/frameworks/5425aebd-5548-4224-8a77-1ee54e089233-0000/executors/default/runs/51ce1d0a-b170-45e4-8090-b0e5b33ba35e'
I1209 02:39:25.439224 27239 containerizer.cpp:983] Starting container 51ce1d0a-b170-45e4-8090-b0e5b33ba35e for executor 'default' of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000
I1209 02:39:25.440331 27244 containerizer.cpp:1519] Launching 'mesos-containerizer' with flags '--help="false" --launch_info="{"command":{"shell":true,"value":"\/mesos\/build\/src\/test-http-executor"},"environment":{"variables":[{"name":"LIBPROCESS_PORT","value":"0"},{"name":"MESOS_AGENT_ENDPOINT","value":"172.17.0.3:34589"},{"name":"MESOS_CHECKPOINT","value":"0"},{"name":"MESOS_DIRECTORY","value":"\/tmp\/mesos-ABAnaL\/0\/slaves\/5425aebd-5548-4224-8a77-1ee54e089233-S2\/frameworks\/5425aebd-5548-4224-8a77-1ee54e089233-0000\/executors\/default\/runs\/51ce1d0a-b170-45e4-8090-b0e5b33ba35e"},{"name":"MESOS_EXECUTOR_ID","value":"default"},{"name":"MESOS_EXECUTOR_SHUTDOWN_GRACE_PERIOD","value":"5secs"},{"name":"MESOS_FRAMEWORK_ID","value":"5425aebd-5548-4224-8a77-1ee54e089233-0000"},{"name":"MESOS_HTTP_COMMAND_EXECUTOR","value":"0"},{"name":"MESOS_SLAVE_ID","value":"5425aebd-5548-4224-8a77-1ee54e089233-S2"},{"name":"MESOS_SLAVE_PID","value":"slave(1)@172.17.0.3:34589"},{"name":"MESOS_SANDBOX","value":"\/tmp\/mesos-ABAnaL\/0\/slaves\/5425aebd-5548-4224-8a77-1ee54e089233-S2\/frameworks\/5425aebd-5548-4224-8a77-1ee54e089233-0000\/executors\/default\/runs\/51ce1d0a-b170-45e4-8090-b0e5b33ba35e"}]},"user":"mesos","working_directory":"\/tmp\/mesos-ABAnaL\/0\/slaves\/5425aebd-5548-4224-8a77-1ee54e089233-S2\/frameworks\/5425aebd-5548-4224-8a77-1ee54e089233-0000\/executors\/default\/runs\/51ce1d0a-b170-45e4-8090-b0e5b33ba35e"}" --pipe_read="19" --pipe_write="20" --runtime_directory="/tmp/mesos-9tTaBQ/containers/51ce1d0a-b170-45e4-8090-b0e5b33ba35e" --unshare_namespace_mnt="false"'
I1209 02:39:25.441324 27244 launcher.cpp:133] Forked child with pid '27297' for container '51ce1d0a-b170-45e4-8090-b0e5b33ba35e'
I1209 02:39:25.442400 27253 fetcher.cpp:345] Starting to fetch URIs for container: 51ce1d0a-b170-45e4-8090-b0e5b33ba35e, directory: /tmp/mesos-ABAnaL/0/slaves/5425aebd-5548-4224-8a77-1ee54e089233-S2/frameworks/5425aebd-5548-4224-8a77-1ee54e089233-0000/executors/default/runs/51ce1d0a-b170-45e4-8090-b0e5b33ba35e
I1209 02:39:25.544412 27305 executor.cpp:189] Version: 1.2.0
I1209 02:39:25.546732 27253 process.cpp:3679] Handling HTTP event for process 'slave(1)' with path: '/slave(1)/api/v1/executor'
I1209 02:39:25.547057 27253 http.cpp:288] HTTP POST for /slave(1)/api/v1/executor from 172.17.0.3:33467
I1209 02:39:25.547164 27253 slave.cpp:3089] Received Subscribe request for HTTP executor 'default' of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000
I1209 02:39:25.547794 27253 slave.cpp:2250] Sending queued task '4' to executor 'default' of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000 (via HTTP)
Received a SUBSCRIBED event
Starting task 4
Finishing task 4
I1209 02:39:25.550200 27253 process.cpp:3679] Handling HTTP event for process 'slave(1)' with path: '/slave(1)/api/v1/executor'
I1209 02:39:25.550357 27253 process.cpp:3679] Handling HTTP event for process 'slave(1)' with path: '/slave(1)/api/v1/executor'
I1209 02:39:25.550442 27253 http.cpp:288] HTTP POST for /slave(1)/api/v1/executor from 172.17.0.3:33468
I1209 02:39:25.550504 27253 slave.cpp:3743] Handling status update TASK_RUNNING (UUID: 38c066e1-4617-482c-8dd9-c5f69cf780bd) for task 4 of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000
I1209 02:39:25.550731 27252 http.cpp:288] HTTP POST for /slave(1)/api/v1/executor from 172.17.0.3:33468
I1209 02:39:25.550846 27252 slave.cpp:3743] Handling status update TASK_FINISHED (UUID: b8bb9e6c-9e6f-4372-8fc9-c14e633f5e00) for task 4 of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000
I1209 02:39:25.551154 27251 status_update_manager.cpp:323] Received status update TASK_RUNNING (UUID: 38c066e1-4617-482c-8dd9-c5f69cf780bd) for task 4 of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000
I1209 02:39:25.551182 27251 status_update_manager.cpp:500] Creating StatusUpdate stream for task 4 of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000
I1209 02:39:25.551462 27251 status_update_manager.cpp:377] Forwarding update TASK_RUNNING (UUID: 38c066e1-4617-482c-8dd9-c5f69cf780bd) for task 4 of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000 to the agent
I1209 02:39:25.551609 27245 slave.cpp:4184] Forwarding the update TASK_RUNNING (UUID: 38c066e1-4617-482c-8dd9-c5f69cf780bd) for task 4 of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000 to master@172.17.0.3:34589
I1209 02:39:25.551784 27245 slave.cpp:4078] Status update manager successfully handled status update TASK_RUNNING (UUID: 38c066e1-4617-482c-8dd9-c5f69cf780bd) for task 4 of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000
I1209 02:39:25.551925 27239 master.cpp:5769] Status update TASK_RUNNING (UUID: 38c066e1-4617-482c-8dd9-c5f69cf780bd) for task 4 of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000 from agent 5425aebd-5548-4224-8a77-1ee54e089233-S2 at slave(1)@172.17.0.3:34589 (0e1fb29f5fa3)
I1209 02:39:25.551959 27239 master.cpp:5831] Forwarding status update TASK_RUNNING (UUID: 38c066e1-4617-482c-8dd9-c5f69cf780bd) for task 4 of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000
I1209 02:39:25.552105 27239 master.cpp:7867] Updating the state of task 4 of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000 (latest state: TASK_FINISHED, status update state: TASK_RUNNING)
I1209 02:39:25.552330 27239 hierarchical.cpp:1023] Recovered cpus(*):1; mem(*):128 (total: cpus(*):2; mem(*):10240; disk(*):3701220; ports(*):[31000-32000], allocated: {}) on agent 5425aebd-5548-4224-8a77-1ee54e089233-S2 from framework 5425aebd-5548-4224-8a77-1ee54e089233-0000
I1209 02:39:25.552553 27239 scheduler.cpp:681] Enqueuing event UPDATE received from http://172.17.0.3:34589/master/api/v1/scheduler
Received an ACKNOWLEDGED event
Received an UPDATE event
Task 4 is in state TASK_RUNNING
I1209 02:39:25.552770 27239 scheduler.cpp:241] Sending ACKNOWLEDGE call to http://172.17.0.3:34589/master/api/v1/scheduler
I1209 02:39:25.553052 27241 process.cpp:3679] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1209 02:39:25.553118 27249 status_update_manager.cpp:323] Received status update TASK_FINISHED (UUID: b8bb9e6c-9e6f-4372-8fc9-c14e633f5e00) for task 4 of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000
I1209 02:39:25.553210 27251 slave.cpp:4078] Status update manager successfully handled status update TASK_FINISHED (UUID: b8bb9e6c-9e6f-4372-8fc9-c14e633f5e00) for task 4 of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000
Received an ACKNOWLEDGED event
I1209 02:39:25.591909 27238 http.cpp:393] HTTP POST for /master/api/v1/scheduler from 172.17.0.3:33461
I1209 02:39:25.592018 27238 master.cpp:4877] Processing ACKNOWLEDGE call 38c066e1-4617-482c-8dd9-c5f69cf780bd for task 4 of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000 (Event Call Scheduler using libprocess (C++)) on agent 5425aebd-5548-4224-8a77-1ee54e089233-S2
I1209 02:39:25.592133 27250 status_update_manager.cpp:395] Received status update acknowledgement (UUID: 38c066e1-4617-482c-8dd9-c5f69cf780bd) for task 4 of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000
I1209 02:39:25.592375 27250 status_update_manager.cpp:377] Forwarding update TASK_FINISHED (UUID: b8bb9e6c-9e6f-4372-8fc9-c14e633f5e00) for task 4 of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000 to the agent
I1209 02:39:25.592504 27243 slave.cpp:4184] Forwarding the update TASK_FINISHED (UUID: b8bb9e6c-9e6f-4372-8fc9-c14e633f5e00) for task 4 of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000 to master@172.17.0.3:34589
I1209 02:39:25.592591 27243 slave.cpp:3025] Status update manager successfully handled status update acknowledgement (UUID: 38c066e1-4617-482c-8dd9-c5f69cf780bd) for task 4 of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000
I1209 02:39:25.592674 27243 master.cpp:5769] Status update TASK_FINISHED (UUID: b8bb9e6c-9e6f-4372-8fc9-c14e633f5e00) for task 4 of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000 from agent 5425aebd-5548-4224-8a77-1ee54e089233-S2 at slave(1)@172.17.0.3:34589 (0e1fb29f5fa3)
I1209 02:39:25.592700 27243 master.cpp:5831] Forwarding status update TASK_FINISHED (UUID: b8bb9e6c-9e6f-4372-8fc9-c14e633f5e00) for task 4 of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000
I1209 02:39:25.592919 27243 master.cpp:7867] Updating the state of task 4 of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000 (latest state: TASK_FINISHED, status update state: TASK_FINISHED)
I1209 02:39:25.593366 27241 scheduler.cpp:681] Enqueuing event UPDATE received from http://172.17.0.3:34589/master/api/v1/scheduler
Received an UPDATE event
Task 4 is in state TASK_FINISHED
I1209 02:39:25.593940 27238 scheduler.cpp:241] Sending ACKNOWLEDGE call to http://172.17.0.3:34589/master/api/v1/scheduler
I1209 02:39:25.594058 27238 scheduler.cpp:241] Sending TEARDOWN call to http://172.17.0.3:34589/master/api/v1/scheduler
I1209 02:39:25.594347 27231 master.cpp:1097] Master terminating
I1209 02:39:25.594504 27231 master.cpp:7963] Removing task 4 with resources cpus(*):1; mem(*):128 of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000 on agent 5425aebd-5548-4224-8a77-1ee54e089233-S2 at slave(1)@172.17.0.3:34589 (0e1fb29f5fa3)
I1209 02:39:25.594586 27250 hierarchical.cpp:522] Removed agent 5425aebd-5548-4224-8a77-1ee54e089233-S2
I1209 02:39:25.594651 27231 master.cpp:7992] Removing executor 'default' with resources {} of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000 on agent 5425aebd-5548-4224-8a77-1ee54e089233-S2 at slave(1)@172.17.0.3:34589 (0e1fb29f5fa3)
I1209 02:39:25.594806 27231 master.cpp:7992] Removing executor 'default' with resources {} of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000 on agent 5425aebd-5548-4224-8a77-1ee54e089233-S1 at slave(3)@172.17.0.3:34589 (0e1fb29f5fa3)
I1209 02:39:25.594913 27231 master.cpp:7992] Removing executor 'default' with resources {} of framework 5425aebd-5548-4224-8a77-1ee54e089233-0000 on agent 5425aebd-5548-4224-8a77-1ee54e089233-S0 at slave(2)@172.17.0.3:34589 (0e1fb29f5fa3)
I1209 02:39:25.594928 27238 hierarchical.cpp:522] Removed agent 5425aebd-5548-4224-8a77-1ee54e089233-S1
I1209 02:39:25.595078 27238 hierarchical.cpp:522] Removed agent 5425aebd-5548-4224-8a77-1ee54e089233-S0
I1209 02:39:25.595199 27238 hierarchical.cpp:342] Removed framework 5425aebd-5548-4224-8a77-1ee54e089233-0000
Build timed out (after 300 minutes). Marking the build as failed.
Build was aborted
++ docker rmi mesos-1481248669-40
Error response from daemon: conflict: unable to remove repository reference "mesos-1481248669-40" (must force) - container 0e1fb29f5fa3 is using its referenced image 371174a782cd