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 2017/03/09 23:02:38 UTC
Build failed in Jenkins: Mesos-Reviewbot #17377
See <https://builds.apache.org/job/Mesos-Reviewbot/17377/display/redirect?page=changes>
Changes:
[josephwu] CMake: Normalized some function capitalization.
[josephwu] CMake: Removed unused FindCurl.cmake file.
[josephwu] CMake: Changed usage of `get_filename_component` function.
[josephwu] Windows: Reworked error messages in subprocess.
[josephwu] Windows: Prevented crash if sandbox `stdout` file already exists.
[yujie.jay] Removed redundant 'root' user set in containerizer::launch().
[yujie.jay] Fixed command task with container image 'root' user issue.
[yujie.jay] Added unit test for verifying user in command task with image specified.
[mpark] Added `initializer_list` constructors for `JSON::(Object|Array)`.
[mpark] Updated `MultiRoleSchedulerUpgrade` to test framework updates.
[xujyan] Kill stray tasks when tearing down test cgroups.
------------------------------------------
[...truncated 25.71 MB...]
I0309 23:02:15.755486 30601 status_update_manager.cpp:203] Recovering status update manager
I0309 23:02:15.755753 30601 containerizer.cpp:600] Recovering containerizer
I0309 23:02:15.757532 30607 provisioner.cpp:410] Provisioner recovery complete
I0309 23:02:15.758041 30609 slave.cpp:5564] Finished recovery
I0309 23:02:15.758625 30609 slave.cpp:5746] Querying resource estimator for oversubscribable resources
I0309 23:02:15.758971 30612 slave.cpp:5760] Received oversubscribable resources {} from the resource estimator
I0309 23:02:15.777446 30595 process.cpp:3705] Handling HTTP event for process 'slave(708)' with path: '/slave(708)/monitor/statistics.json'
I0309 23:02:15.779196 30597 http.cpp:916] Authorizing principal 'test-principal' to GET the '/monitor/statistics.json' endpoint
I0309 23:02:15.785104 30571 slave.cpp:808] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.AuthorizedRequest/1 (56 ms)
[ RUN ] Endpoint/SlaveEndpointTest.AuthorizedRequest/2
I0309 23:02:15.798658 30571 containerizer.cpp:221] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W0309 23:02:15.799238 30571 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0309 23:02:15.799348 30571 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0309 23:02:15.799388 30571 provisioner.cpp:249] Using default backend 'copy'
I0309 23:02:15.802770 30601 slave.cpp:215] Mesos agent started on (709)@172.17.0.2:33335
I0309 23:02:15.802808 30601 slave.cpp:216] 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_zDgEj1/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_zDgEj1/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_zDgEj1/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.3.0/_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_zDgEj1" --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_iKsmnd"
I0309 23:02:15.803571 30601 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_AuthorizedRequest_2_zDgEj1/credential'
I0309 23:02:15.803787 30601 slave.cpp:358] Agent using credential for: test-principal
I0309 23:02:15.803810 30601 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_AuthorizedRequest_2_zDgEj1/http_credentials'
I0309 23:02:15.804093 30601 http.cpp:964] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0309 23:02:15.804258 30601 http.cpp:964] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0309 23:02:15.805871 30601 slave.cpp:545] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0309 23:02:15.805979 30601 slave.cpp:553] Agent attributes: [ ]
I0309 23:02:15.805995 30601 slave.cpp:558] Agent hostname: b360fb40f7ee
I0309 23:02:15.806258 30610 status_update_manager.cpp:177] Pausing sending status updates
I0309 23:02:15.807684 30607 state.cpp:62] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_AuthorizedRequest_2_iKsmnd/meta'
I0309 23:02:15.808269 30598 status_update_manager.cpp:203] Recovering status update manager
I0309 23:02:15.808511 30591 containerizer.cpp:600] Recovering containerizer
I0309 23:02:15.810478 30601 provisioner.cpp:410] Provisioner recovery complete
I0309 23:02:15.810822 30609 slave.cpp:5564] Finished recovery
I0309 23:02:15.811394 30609 slave.cpp:5746] Querying resource estimator for oversubscribable resources
I0309 23:02:15.811869 30607 slave.cpp:5760] Received oversubscribable resources {} from the resource estimator
I0309 23:02:15.815141 30598 process.cpp:3705] Handling HTTP event for process 'slave(709)' with path: '/slave(709)/containers'
I0309 23:02:15.816637 30606 http.cpp:916] Authorizing principal 'test-principal' to GET the '/containers' endpoint
I0309 23:02:15.821353 30601 slave.cpp:808] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.AuthorizedRequest/2 (32 ms)
[ RUN ] Endpoint/SlaveEndpointTest.UnauthorizedRequest/0
I0309 23:02:15.831521 30571 containerizer.cpp:221] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W0309 23:02:15.832114 30571 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0309 23:02:15.832233 30571 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0309 23:02:15.832273 30571 provisioner.cpp:249] Using default backend 'copy'
I0309 23:02:15.835041 30598 slave.cpp:215] Mesos agent started on (710)@172.17.0.2:33335
I0309 23:02:15.835065 30598 slave.cpp:216] 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_Dljnlf/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_Dljnlf/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_Dljnlf/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.3.0/_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_Dljnlf" --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_PI8akr"
I0309 23:02:15.835733 30598 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_Dljnlf/credential'
I0309 23:02:15.835916 30598 slave.cpp:358] Agent using credential for: test-principal
I0309 23:02:15.835942 30598 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_Dljnlf/http_credentials'
I0309 23:02:15.836233 30598 http.cpp:964] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0309 23:02:15.836382 30598 http.cpp:964] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0309 23:02:15.838359 30598 slave.cpp:545] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0309 23:02:15.838435 30598 slave.cpp:553] Agent attributes: [ ]
I0309 23:02:15.838449 30598 slave.cpp:558] Agent hostname: b360fb40f7ee
I0309 23:02:15.838644 30591 status_update_manager.cpp:177] Pausing sending status updates
I0309 23:02:15.840075 30613 state.cpp:62] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_PI8akr/meta'
I0309 23:02:15.840917 30613 status_update_manager.cpp:203] Recovering status update manager
I0309 23:02:15.841135 30613 containerizer.cpp:600] Recovering containerizer
I0309 23:02:15.843724 30613 provisioner.cpp:410] Provisioner recovery complete
I0309 23:02:15.844326 30613 slave.cpp:5564] Finished recovery
I0309 23:02:15.845003 30613 slave.cpp:5746] Querying resource estimator for oversubscribable resources
I0309 23:02:15.845754 30613 slave.cpp:5760] Received oversubscribable resources {} from the resource estimator
I0309 23:02:15.849328 30596 process.cpp:3705] Handling HTTP event for process 'slave(710)' with path: '/slave(710)/monitor/statistics'
I0309 23:02:15.850891 30601 http.cpp:916] Authorizing principal 'test-principal' to GET the '/monitor/statistics' endpoint
I0309 23:02:15.854787 30609 slave.cpp:808] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.UnauthorizedRequest/0 (37 ms)
[ RUN ] Endpoint/SlaveEndpointTest.UnauthorizedRequest/1
I0309 23:02:15.869221 30571 containerizer.cpp:221] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W0309 23:02:15.869735 30571 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0309 23:02:15.869865 30571 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0309 23:02:15.869905 30571 provisioner.cpp:249] Using default backend 'copy'
I0309 23:02:15.873309 30609 slave.cpp:215] Mesos agent started on (711)@172.17.0.2:33335
I0309 23:02:15.873350 30609 slave.cpp:216] 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_BfGSIt/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_BfGSIt/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_BfGSIt/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.3.0/_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_BfGSIt" --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_UI35BF"
I0309 23:02:15.874096 30609 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_BfGSIt/credential'
I0309 23:02:15.874316 30609 slave.cpp:358] Agent using credential for: test-principal
I0309 23:02:15.874341 30609 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_BfGSIt/http_credentials'
I0309 23:02:15.874625 30609 http.cpp:964] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0309 23:02:15.874835 30609 http.cpp:964] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0309 23:02:15.876677 30609 slave.cpp:545] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0309 23:02:15.876808 30609 slave.cpp:553] Agent attributes: [ ]
I0309 23:02:15.876827 30609 slave.cpp:558] Agent hostname: b360fb40f7ee
I0309 23:02:15.877076 30608 status_update_manager.cpp:177] Pausing sending status updates
I0309 23:02:15.879025 30599 state.cpp:62] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_UI35BF/meta'
I0309 23:02:15.879526 30594 status_update_manager.cpp:203] Recovering status update manager
I0309 23:02:15.879763 30613 containerizer.cpp:600] Recovering containerizer
I0309 23:02:15.882371 30598 provisioner.cpp:410] Provisioner recovery complete
I0309 23:02:15.882868 30595 slave.cpp:5564] Finished recovery
I0309 23:02:15.883602 30595 slave.cpp:5746] Querying resource estimator for oversubscribable resources
I0309 23:02:15.883889 30607 slave.cpp:5760] Received oversubscribable resources {} from the resource estimator
I0309 23:02:15.886878 30610 process.cpp:3705] Handling HTTP event for process 'slave(711)' with path: '/slave(711)/monitor/statistics.json'
I0309 23:02:15.888414 30592 http.cpp:916] Authorizing principal 'test-principal' to GET the '/monitor/statistics.json' endpoint
I0309 23:02:15.891808 30595 slave.cpp:808] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.UnauthorizedRequest/1 (32 ms)
[ RUN ] Endpoint/SlaveEndpointTest.UnauthorizedRequest/2
I0309 23:02:15.901593 30571 containerizer.cpp:221] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W0309 23:02:15.902065 30571 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0309 23:02:15.902185 30571 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0309 23:02:15.902232 30571 provisioner.cpp:249] Using default backend 'copy'
I0309 23:02:15.905710 30591 slave.cpp:215] Mesos agent started on (712)@172.17.0.2:33335
I0309 23:02:15.905730 30591 slave.cpp:216] 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_Q7SCqI/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_Q7SCqI/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_Q7SCqI/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.3.0/_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_Q7SCqI" --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_nGw0eU"
I0309 23:02:15.906190 30591 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_Q7SCqI/credential'
I0309 23:02:15.906347 30591 slave.cpp:358] Agent using credential for: test-principal
I0309 23:02:15.906364 30591 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_Q7SCqI/http_credentials'
I0309 23:02:15.906577 30591 http.cpp:964] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0309 23:02:15.906718 30591 http.cpp:964] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0309 23:02:15.907855 30591 slave.cpp:545] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0309 23:02:15.907932 30591 slave.cpp:553] Agent attributes: [ ]
I0309 23:02:15.907943 30591 slave.cpp:558] Agent hostname: b360fb40f7ee
I0309 23:02:15.908071 30601 status_update_manager.cpp:177] Pausing sending status updates
I0309 23:02:15.909474 30596 state.cpp:62] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_nGw0eU/meta'
I0309 23:02:15.909812 30591 status_update_manager.cpp:203] Recovering status update manager
I0309 23:02:15.910051 30609 containerizer.cpp:600] Recovering containerizer
I0309 23:02:15.911764 30590 provisioner.cpp:410] Provisioner recovery complete
I0309 23:02:15.912099 30613 slave.cpp:5564] Finished recovery
I0309 23:02:15.912688 30613 slave.cpp:5746] Querying resource estimator for oversubscribable resources
I0309 23:02:15.912997 30605 slave.cpp:5760] Received oversubscribable resources {} from the resource estimator
I0309 23:02:15.921438 30600 process.cpp:3705] Handling HTTP event for process 'slave(712)' with path: '/slave(712)/containers'
I0309 23:02:15.932760 30607 http.cpp:916] Authorizing principal 'test-principal' to GET the '/containers' endpoint
I0309 23:02:15.942049 30608 slave.cpp:808] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.UnauthorizedRequest/2 (50 ms)
[ RUN ] Endpoint/SlaveEndpointTest.NoAuthorizer/0
I0309 23:02:15.953294 30571 containerizer.cpp:221] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W0309 23:02:15.955795 30571 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0309 23:02:15.955925 30571 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0309 23:02:15.955974 30571 provisioner.cpp:249] Using default backend 'copy'
I0309 23:02:15.957523 30571 cluster.cpp:446] Creating default 'local' authorizer
I0309 23:02:15.959673 30610 slave.cpp:215] Mesos agent started on (713)@172.17.0.2:33335
I0309 23:02:15.959715 30610 slave.cpp:216] 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_ApxFzX/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_ApxFzX/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_ApxFzX/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.3.0/_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_ApxFzX" --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_Gmvng9"
I0309 23:02:15.960479 30610 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_0_ApxFzX/credential'
I0309 23:02:15.960703 30610 slave.cpp:358] Agent using credential for: test-principal
I0309 23:02:15.960727 30610 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_0_ApxFzX/http_credentials'
I0309 23:02:15.961014 30610 http.cpp:964] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0309 23:02:15.961232 30610 http.cpp:964] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0309 23:02:15.962903 30610 slave.cpp:545] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0309 23:02:15.963079 30610 slave.cpp:553] Agent attributes: [ ]
I0309 23:02:15.963099 30610 slave.cpp:558] Agent hostname: b360fb40f7ee
I0309 23:02:15.963268 30602 status_update_manager.cpp:177] Pausing sending status updates
I0309 23:02:15.964872 30611 state.cpp:62] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_0_Gmvng9/meta'
I0309 23:02:15.965486 30593 status_update_manager.cpp:203] Recovering status update manager
I0309 23:02:15.965688 30604 containerizer.cpp:600] Recovering containerizer
I0309 23:02:15.967280 30592 provisioner.cpp:410] Provisioner recovery complete
I0309 23:02:15.967692 30612 slave.cpp:5564] Finished recovery
I0309 23:02:15.968240 30612 slave.cpp:5746] Querying resource estimator for oversubscribable resources
I0309 23:02:15.968542 30612 slave.cpp:5760] Received oversubscribable resources {} from the resource estimator
I0309 23:02:15.971288 30604 process.cpp:3705] Handling HTTP event for process 'slave(713)' with path: '/slave(713)/monitor/statistics'
I0309 23:02:15.972692 30604 http.cpp:916] Authorizing principal 'test-principal' to GET the '/monitor/statistics' endpoint
I0309 23:02:15.976078 30595 slave.cpp:808] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.NoAuthorizer/0 (33 ms)
[ RUN ] Endpoint/SlaveEndpointTest.NoAuthorizer/1
I0309 23:02:15.987325 30571 containerizer.cpp:221] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W0309 23:02:15.987855 30571 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0309 23:02:15.987969 30571 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0309 23:02:15.988004 30571 provisioner.cpp:249] Using default backend 'copy'
I0309 23:02:15.989773 30571 cluster.cpp:446] Creating default 'local' authorizer
I0309 23:02:15.991490 30598 slave.cpp:215] Mesos agent started on (714)@172.17.0.2:33335
I0309 23:02:15.991530 30598 slave.cpp:216] 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_L7vW5c/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_L7vW5c/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_L7vW5c/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.3.0/_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_L7vW5c" --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_fXLGHo"
I0309 23:02:15.992151 30598 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_1_L7vW5c/credential'
I0309 23:02:15.992341 30598 slave.cpp:358] Agent using credential for: test-principal
I0309 23:02:15.992363 30598 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_1_L7vW5c/http_credentials'
I0309 23:02:15.992578 30598 http.cpp:964] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0309 23:02:15.992753 30598 http.cpp:964] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0309 23:02:15.993926 30598 slave.cpp:545] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0309 23:02:15.994005 30598 slave.cpp:553] Agent attributes: [ ]
I0309 23:02:15.994024 30598 slave.cpp:558] Agent hostname: b360fb40f7ee
I0309 23:02:15.994173 30592 status_update_manager.cpp:177] Pausing sending status updates
I0309 23:02:15.996189 30600 state.cpp:62] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_1_fXLGHo/meta'
I0309 23:02:15.996639 30605 status_update_manager.cpp:203] Recovering status update manager
I0309 23:02:15.996981 30611 containerizer.cpp:600] Recovering containerizer
I0309 23:02:15.998569 30597 provisioner.cpp:410] Provisioner recovery complete
I0309 23:02:15.999083 30601 slave.cpp:5564] Finished recovery
I0309 23:02:15.999861 30601 slave.cpp:5746] Querying resource estimator for oversubscribable resources
I0309 23:02:16.000246 30613 slave.cpp:5760] Received oversubscribable resources {} from the resource estimator
I0309 23:02:16.004333 30592 process.cpp:3705] Handling HTTP event for process 'slave(714)' with path: '/slave(714)/monitor/statistics.json'
I0309 23:02:16.006031 30590 http.cpp:916] Authorizing principal 'test-principal' to GET the '/monitor/statistics.json' endpoint
I0309 23:02:16.011427 30608 slave.cpp:808] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.NoAuthorizer/1 (35 ms)
[ RUN ] Endpoint/SlaveEndpointTest.NoAuthorizer/2
I0309 23:02:16.022359 30571 containerizer.cpp:221] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W0309 23:02:16.023222 30571 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0309 23:02:16.023349 30571 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0309 23:02:16.023391 30571 provisioner.cpp:249] Using default backend 'copy'
I0309 23:02:16.025138 30571 cluster.cpp:446] Creating default 'local' authorizer
I0309 23:02:16.026926 30599 slave.cpp:215] Mesos agent started on (715)@172.17.0.2:33335
I0309 23:02:16.026973 30599 slave.cpp:216] 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_R41PWs/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_R41PWs/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_R41PWs/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.3.0/_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_R41PWs" --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_WH1gtE"
I0309 23:02:16.027577 30599 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_2_R41PWs/credential'
I0309 23:02:16.027765 30599 slave.cpp:358] Agent using credential for: test-principal
I0309 23:02:16.027783 30599 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_2_R41PWs/http_credentials'
I0309 23:02:16.027990 30599 http.cpp:964] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0309 23:02:16.028168 30599 http.cpp:964] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0309 23:02:16.029474 30599 slave.cpp:545] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0309 23:02:16.029553 30599 slave.cpp:553] Agent attributes: [ ]
I0309 23:02:16.029567 30599 slave.cpp:558] Agent hostname: b360fb40f7ee
I0309 23:02:16.029706 30597 status_update_manager.cpp:177] Pausing sending status updates
I0309 23:02:16.031019 30597 state.cpp:62] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_2_WH1gtE/meta'
I0309 23:02:16.031494 30595 status_update_manager.cpp:203] Recovering status update manager
I0309 23:02:16.031754 30592 containerizer.cpp:600] Recovering containerizer
I0309 23:02:16.033210 30597 provisioner.cpp:410] Provisioner recovery complete
I0309 23:02:16.033846 30595 slave.cpp:5564] Finished recovery
I0309 23:02:16.034667 30595 slave.cpp:5746] Querying resource estimator for oversubscribable resources
I0309 23:02:16.035068 30597 slave.cpp:5760] Received oversubscribable resources {} from the resource estimator
I0309 23:02:16.037700 30600 process.cpp:3705] Handling HTTP event for process 'slave(715)' with path: '/slave(715)/containers'
I0309 23:02:16.039468 30595 http.cpp:916] Authorizing principal 'test-principal' to GET the '/containers' endpoint
I0309 23:02:16.046406 30612 slave.cpp:808] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.NoAuthorizer/2 (35 ms)
[----------] 9 tests from Endpoint/SlaveEndpointTest (346 ms total)
[----------] Global test environment tear-down
[==========] 1516 tests from 169 test cases ran. (533976 ms total)
[ PASSED ] 1515 tests.
[ FAILED ] 1 test, listed below:
[ FAILED ] OversubscriptionTest.QoSCorrectionKill
1 FAILED TEST
YOU HAVE 12 DISABLED TESTS
make[4]: *** [check-local] Error 1
make[4]: Leaving directory `/mesos/mesos-1.3.0/_build/src'
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/mesos/mesos-1.3.0/_build/src'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/mesos/mesos-1.3.0/_build/src'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-1.3.0/_build'
make: *** [distcheck] Error 1
+ docker rmi mesos-1489097759-26085
Untagged: mesos-1489097759-26085:latest
Deleted: sha256:3692a1924a84a6a92722af58eb62907736ec38b8b5af27a9097f43466ceb72f0
Deleted: sha256:7b95a04d937f0ea91e53f77d7ca310b9d979756cc36558d09acb6afaacf53d3c
Deleted: sha256:db0c2bda27fb324f41ead2dc0c9ed1208898a071600728859fa72a0da225aac7
Deleted: sha256:1e63721399e876f1d35538b2dfab72e769eebfc2e2a73da605f7ef5ce3be8ce6
Deleted: sha256:e52255d9d6728a31ca5a848677212d095d3f22e3f311d32eabb140609074c386
Deleted: sha256:0a4d135a64bc97bb823562d20964b0c14fdfbd0710ed2981554a2afd0a849525
Deleted: sha256:87edf664cc859a569175f1a46e794275c31b2595b56182a9dff1bff31b7739e2
Deleted: sha256:594cc540e7546fee49554fdac670354a24745b010b82918ada208e18137baaaa
Deleted: sha256:93a33fafcafa97ca76bebd5818248ffc09d9259085b5523cfef23222eee18659
Deleted: sha256:c0373554ec1a4e4b8de315b73abbadb2b852d4bf799a9ca3d01d2ccc78032982
Deleted: sha256:57d245a5cc5192794336967b0daddc1ad645dd6cfdaa59b57722bb5a7b3a916d
Deleted: sha256:04e105341c96d3840dccb3fe897d6d3037805c58e0711e38076045cba0bbbd21
Deleted: sha256:d6c9ddec501c076f7ae18e6f5641413689744fa55b5771f74ca353dc9cfe2f75
Deleted: sha256:03c8454e3053cb357dcbb2848450b4d754b94e541c20f16bdec92d11b42d7163
Deleted: sha256:08abd53da213818bacb7ffc5a7140c536629bf21129d2294401ab39c2816fffd
Deleted: sha256:7d31e2ac16b09420bdbd3758988e3f111d35a4a15865aa4d0a03a6aa03729c43
Deleted: sha256:5f1e184a1014df7ec08114ad7f88471a9a511932bf9392218ef4e4f50b265714
Deleted: sha256:49d3aaa8f3498b7d2ddb7e45caec2754953dbc829c11a119dac073e0575d4197
Deleted: sha256:f4d2b5dd59d73d1b3468984c0ea9011330242a2709657b78a3660a114e5e9cfb
Deleted: sha256:df1cf66191d558681b13ca9a917eb871ba549deb68a177eaaf1a715eee3c9948
Deleted: sha256:9e5008485d7168482f7e85e62987285ab0890b744eb2d2acecf9bb548c55246e
Deleted: sha256:94e199682e610d751b11a607d55f9e4da71ab811a472b410d3f290e7fcf0d49e
Deleted: sha256:03f53cfcf7225af6ee9fc348482f3eaa40d511c7409923f18070901237e1f788
Deleted: sha256:cf364d8c16cbc1d10964c6010c0f8a490e39525f16d81d56a52dc2970253522c
Build step 'Execute shell' marked build as failure
Jenkins build is back to normal : Mesos-Reviewbot #17381
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Reviewbot/17381/display/redirect>
Build failed in Jenkins: Mesos-Reviewbot #17380
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Reviewbot/17380/display/redirect>
------------------------------------------
[...truncated 25.70 MB...]
I0310 04:28:01.081341 30440 status_update_manager.cpp:203] Recovering status update manager
I0310 04:28:01.081657 30440 containerizer.cpp:600] Recovering containerizer
I0310 04:28:01.083371 30432 provisioner.cpp:410] Provisioner recovery complete
I0310 04:28:01.083966 30439 slave.cpp:5564] Finished recovery
I0310 04:28:01.084542 30439 slave.cpp:5746] Querying resource estimator for oversubscribable resources
I0310 04:28:01.084997 30434 slave.cpp:5760] Received oversubscribable resources {} from the resource estimator
I0310 04:28:01.087677 30438 process.cpp:3705] Handling HTTP event for process 'slave(708)' with path: '/slave(708)/monitor/statistics.json'
I0310 04:28:01.089084 30425 http.cpp:916] Authorizing principal 'test-principal' to GET the '/monitor/statistics.json' endpoint
I0310 04:28:01.094707 30425 slave.cpp:808] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.AuthorizedRequest/1 (32 ms)
[ RUN ] Endpoint/SlaveEndpointTest.AuthorizedRequest/2
I0310 04:28:01.104713 30406 containerizer.cpp:221] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W0310 04:28:01.105306 30406 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0310 04:28:01.105417 30406 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0310 04:28:01.105468 30406 provisioner.cpp:249] Using default backend 'copy'
I0310 04:28:01.109321 30432 slave.cpp:215] Mesos agent started on (709)@172.17.0.2:60551
I0310 04:28:01.109350 30432 slave.cpp:216] 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_Qq5aqg/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_Qq5aqg/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_Qq5aqg/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.3.0/_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_Qq5aqg" --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_4e06Fc"
I0310 04:28:01.110064 30432 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_AuthorizedRequest_2_Qq5aqg/credential'
I0310 04:28:01.110234 30432 slave.cpp:358] Agent using credential for: test-principal
I0310 04:28:01.110261 30432 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_AuthorizedRequest_2_Qq5aqg/http_credentials'
I0310 04:28:01.110594 30432 http.cpp:964] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0310 04:28:01.110908 30432 http.cpp:964] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0310 04:28:01.112566 30432 slave.cpp:545] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0310 04:28:01.112668 30432 slave.cpp:553] Agent attributes: [ ]
I0310 04:28:01.112682 30432 slave.cpp:558] Agent hostname: aacbe870d1c2
I0310 04:28:01.112870 30425 status_update_manager.cpp:177] Pausing sending status updates
I0310 04:28:01.114464 30427 state.cpp:62] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_AuthorizedRequest_2_4e06Fc/meta'
I0310 04:28:01.114768 30430 status_update_manager.cpp:203] Recovering status update manager
I0310 04:28:01.115236 30438 containerizer.cpp:600] Recovering containerizer
I0310 04:28:01.116855 30430 provisioner.cpp:410] Provisioner recovery complete
I0310 04:28:01.117650 30431 slave.cpp:5564] Finished recovery
I0310 04:28:01.118166 30431 slave.cpp:5746] Querying resource estimator for oversubscribable resources
I0310 04:28:01.118520 30427 slave.cpp:5760] Received oversubscribable resources {} from the resource estimator
I0310 04:28:01.120705 30433 process.cpp:3705] Handling HTTP event for process 'slave(709)' with path: '/slave(709)/containers'
I0310 04:28:01.122229 30428 http.cpp:916] Authorizing principal 'test-principal' to GET the '/containers' endpoint
I0310 04:28:01.126572 30435 slave.cpp:808] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.AuthorizedRequest/2 (32 ms)
[ RUN ] Endpoint/SlaveEndpointTest.UnauthorizedRequest/0
I0310 04:28:01.138384 30406 containerizer.cpp:221] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W0310 04:28:01.138962 30406 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0310 04:28:01.139071 30406 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0310 04:28:01.139117 30406 provisioner.cpp:249] Using default backend 'copy'
I0310 04:28:01.142904 30432 slave.cpp:215] Mesos agent started on (710)@172.17.0.2:60551
I0310 04:28:01.142935 30432 slave.cpp:216] 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_2h59Gv/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_2h59Gv/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_2h59Gv/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.3.0/_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_2h59Gv" --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_mwwBRr"
I0310 04:28:01.143606 30432 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_2h59Gv/credential'
I0310 04:28:01.143769 30432 slave.cpp:358] Agent using credential for: test-principal
I0310 04:28:01.143796 30432 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_2h59Gv/http_credentials'
I0310 04:28:01.144055 30432 http.cpp:964] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0310 04:28:01.144201 30432 http.cpp:964] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0310 04:28:01.146023 30432 slave.cpp:545] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0310 04:28:01.146117 30432 slave.cpp:553] Agent attributes: [ ]
I0310 04:28:01.146131 30432 slave.cpp:558] Agent hostname: aacbe870d1c2
I0310 04:28:01.146255 30434 status_update_manager.cpp:177] Pausing sending status updates
I0310 04:28:01.147487 30435 state.cpp:62] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_mwwBRr/meta'
I0310 04:28:01.147794 30437 status_update_manager.cpp:203] Recovering status update manager
I0310 04:28:01.148038 30435 containerizer.cpp:600] Recovering containerizer
I0310 04:28:01.149920 30436 provisioner.cpp:410] Provisioner recovery complete
I0310 04:28:01.150420 30428 slave.cpp:5564] Finished recovery
I0310 04:28:01.151020 30428 slave.cpp:5746] Querying resource estimator for oversubscribable resources
I0310 04:28:01.151307 30437 slave.cpp:5760] Received oversubscribable resources {} from the resource estimator
I0310 04:28:01.153807 30432 process.cpp:3705] Handling HTTP event for process 'slave(710)' with path: '/slave(710)/monitor/statistics'
I0310 04:28:01.155228 30429 http.cpp:916] Authorizing principal 'test-principal' to GET the '/monitor/statistics' endpoint
I0310 04:28:01.158481 30437 slave.cpp:808] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.UnauthorizedRequest/0 (32 ms)
[ RUN ] Endpoint/SlaveEndpointTest.UnauthorizedRequest/1
I0310 04:28:01.171016 30406 containerizer.cpp:221] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W0310 04:28:01.171568 30406 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0310 04:28:01.171677 30406 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0310 04:28:01.171717 30406 provisioner.cpp:249] Using default backend 'copy'
I0310 04:28:01.175263 30428 slave.cpp:215] Mesos agent started on (711)@172.17.0.2:60551
I0310 04:28:01.175293 30428 slave.cpp:216] 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_alBmjL/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_alBmjL/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_alBmjL/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.3.0/_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_alBmjL" --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_avyvoH"
I0310 04:28:01.175977 30428 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_alBmjL/credential'
I0310 04:28:01.176137 30428 slave.cpp:358] Agent using credential for: test-principal
I0310 04:28:01.176163 30428 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_alBmjL/http_credentials'
I0310 04:28:01.176470 30428 http.cpp:964] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0310 04:28:01.176625 30428 http.cpp:964] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0310 04:28:01.178233 30428 slave.cpp:545] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0310 04:28:01.178334 30428 slave.cpp:553] Agent attributes: [ ]
I0310 04:28:01.178351 30428 slave.cpp:558] Agent hostname: aacbe870d1c2
I0310 04:28:01.178545 30434 status_update_manager.cpp:177] Pausing sending status updates
I0310 04:28:01.179864 30438 state.cpp:62] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_avyvoH/meta'
I0310 04:28:01.180292 30434 status_update_manager.cpp:203] Recovering status update manager
I0310 04:28:01.180562 30432 containerizer.cpp:600] Recovering containerizer
I0310 04:28:01.182190 30433 provisioner.cpp:410] Provisioner recovery complete
I0310 04:28:01.182669 30430 slave.cpp:5564] Finished recovery
I0310 04:28:01.183362 30430 slave.cpp:5746] Querying resource estimator for oversubscribable resources
I0310 04:28:01.183667 30428 slave.cpp:5760] Received oversubscribable resources {} from the resource estimator
I0310 04:28:01.186317 30430 process.cpp:3705] Handling HTTP event for process 'slave(711)' with path: '/slave(711)/monitor/statistics.json'
I0310 04:28:01.187968 30425 http.cpp:916] Authorizing principal 'test-principal' to GET the '/monitor/statistics.json' endpoint
I0310 04:28:01.191402 30437 slave.cpp:808] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.UnauthorizedRequest/1 (30 ms)
[ RUN ] Endpoint/SlaveEndpointTest.UnauthorizedRequest/2
I0310 04:28:01.201273 30406 containerizer.cpp:221] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W0310 04:28:01.201820 30406 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0310 04:28:01.201930 30406 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0310 04:28:01.201977 30406 provisioner.cpp:249] Using default backend 'copy'
I0310 04:28:01.205811 30427 slave.cpp:215] Mesos agent started on (712)@172.17.0.2:60551
I0310 04:28:01.205843 30427 slave.cpp:216] 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_0xJNf1/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_0xJNf1/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_0xJNf1/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.3.0/_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_0xJNf1" --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_CrxXfX"
I0310 04:28:01.206495 30427 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_0xJNf1/credential'
I0310 04:28:01.206725 30427 slave.cpp:358] Agent using credential for: test-principal
I0310 04:28:01.206758 30427 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_0xJNf1/http_credentials'
I0310 04:28:01.207089 30427 http.cpp:964] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0310 04:28:01.207252 30427 http.cpp:964] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0310 04:28:01.208814 30427 slave.cpp:545] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0310 04:28:01.208920 30427 slave.cpp:553] Agent attributes: [ ]
I0310 04:28:01.208945 30427 slave.cpp:558] Agent hostname: aacbe870d1c2
I0310 04:28:01.209146 30440 status_update_manager.cpp:177] Pausing sending status updates
I0310 04:28:01.210695 30430 state.cpp:62] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_CrxXfX/meta'
I0310 04:28:01.211125 30440 status_update_manager.cpp:203] Recovering status update manager
I0310 04:28:01.211411 30430 containerizer.cpp:600] Recovering containerizer
I0310 04:28:01.213021 30436 provisioner.cpp:410] Provisioner recovery complete
I0310 04:28:01.213481 30437 slave.cpp:5564] Finished recovery
I0310 04:28:01.214022 30437 slave.cpp:5746] Querying resource estimator for oversubscribable resources
I0310 04:28:01.214320 30436 slave.cpp:5760] Received oversubscribable resources {} from the resource estimator
I0310 04:28:01.216594 30437 process.cpp:3705] Handling HTTP event for process 'slave(712)' with path: '/slave(712)/containers'
I0310 04:28:01.217875 30430 http.cpp:916] Authorizing principal 'test-principal' to GET the '/containers' endpoint
I0310 04:28:01.221022 30425 slave.cpp:808] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.UnauthorizedRequest/2 (30 ms)
[ RUN ] Endpoint/SlaveEndpointTest.NoAuthorizer/0
I0310 04:28:01.231935 30406 containerizer.cpp:221] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W0310 04:28:01.232393 30406 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0310 04:28:01.232481 30406 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0310 04:28:01.232511 30406 provisioner.cpp:249] Using default backend 'copy'
I0310 04:28:01.234167 30406 cluster.cpp:446] Creating default 'local' authorizer
I0310 04:28:01.235792 30430 slave.cpp:215] Mesos agent started on (713)@172.17.0.2:60551
I0310 04:28:01.235815 30430 slave.cpp:216] 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_SFa5vh/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_SFa5vh/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_SFa5vh/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.3.0/_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_SFa5vh" --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_0pEhrd"
I0310 04:28:01.236431 30430 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_0_SFa5vh/credential'
I0310 04:28:01.236660 30430 slave.cpp:358] Agent using credential for: test-principal
I0310 04:28:01.236697 30430 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_0_SFa5vh/http_credentials'
I0310 04:28:01.237061 30430 http.cpp:964] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0310 04:28:01.237241 30430 http.cpp:964] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0310 04:28:01.238711 30430 slave.cpp:545] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0310 04:28:01.238785 30430 slave.cpp:553] Agent attributes: [ ]
I0310 04:28:01.238811 30430 slave.cpp:558] Agent hostname: aacbe870d1c2
I0310 04:28:01.239025 30432 status_update_manager.cpp:177] Pausing sending status updates
I0310 04:28:01.240777 30440 state.cpp:62] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_0_0pEhrd/meta'
I0310 04:28:01.241469 30436 status_update_manager.cpp:203] Recovering status update manager
I0310 04:28:01.241755 30430 containerizer.cpp:600] Recovering containerizer
I0310 04:28:01.243423 30429 provisioner.cpp:410] Provisioner recovery complete
I0310 04:28:01.243888 30428 slave.cpp:5564] Finished recovery
I0310 04:28:01.244410 30428 slave.cpp:5746] Querying resource estimator for oversubscribable resources
I0310 04:28:01.244674 30426 slave.cpp:5760] Received oversubscribable resources {} from the resource estimator
I0310 04:28:01.246649 30439 process.cpp:3705] Handling HTTP event for process 'slave(713)' with path: '/slave(713)/monitor/statistics'
I0310 04:28:01.248379 30440 http.cpp:916] Authorizing principal 'test-principal' to GET the '/monitor/statistics' endpoint
I0310 04:28:01.253021 30438 slave.cpp:808] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.NoAuthorizer/0 (32 ms)
[ RUN ] Endpoint/SlaveEndpointTest.NoAuthorizer/1
I0310 04:28:01.265161 30406 containerizer.cpp:221] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W0310 04:28:01.265822 30406 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0310 04:28:01.265934 30406 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0310 04:28:01.265974 30406 provisioner.cpp:249] Using default backend 'copy'
I0310 04:28:01.267673 30406 cluster.cpp:446] Creating default 'local' authorizer
I0310 04:28:01.269690 30433 slave.cpp:215] Mesos agent started on (714)@172.17.0.2:60551
I0310 04:28:01.269719 30433 slave.cpp:216] 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_CZ076x/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_CZ076x/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_CZ076x/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.3.0/_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_CZ076x" --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_KSe3Wt"
I0310 04:28:01.270375 30433 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_1_CZ076x/credential'
I0310 04:28:01.270545 30433 slave.cpp:358] Agent using credential for: test-principal
I0310 04:28:01.270565 30433 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_1_CZ076x/http_credentials'
I0310 04:28:01.270824 30433 http.cpp:964] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0310 04:28:01.270962 30433 http.cpp:964] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0310 04:28:01.272080 30433 slave.cpp:545] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0310 04:28:01.272184 30433 slave.cpp:553] Agent attributes: [ ]
I0310 04:28:01.272212 30433 slave.cpp:558] Agent hostname: aacbe870d1c2
I0310 04:28:01.272406 30425 status_update_manager.cpp:177] Pausing sending status updates
I0310 04:28:01.273720 30435 state.cpp:62] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_1_KSe3Wt/meta'
I0310 04:28:01.274091 30428 status_update_manager.cpp:203] Recovering status update manager
I0310 04:28:01.274340 30426 containerizer.cpp:600] Recovering containerizer
I0310 04:28:01.276016 30437 provisioner.cpp:410] Provisioner recovery complete
I0310 04:28:01.276475 30432 slave.cpp:5564] Finished recovery
I0310 04:28:01.277050 30432 slave.cpp:5746] Querying resource estimator for oversubscribable resources
I0310 04:28:01.277346 30435 slave.cpp:5760] Received oversubscribable resources {} from the resource estimator
I0310 04:28:01.279669 30438 process.cpp:3705] Handling HTTP event for process 'slave(714)' with path: '/slave(714)/monitor/statistics.json'
I0310 04:28:01.281213 30433 http.cpp:916] Authorizing principal 'test-principal' to GET the '/monitor/statistics.json' endpoint
I0310 04:28:01.285359 30430 slave.cpp:808] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.NoAuthorizer/1 (32 ms)
[ RUN ] Endpoint/SlaveEndpointTest.NoAuthorizer/2
I0310 04:28:01.297996 30406 containerizer.cpp:221] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W0310 04:28:01.298619 30406 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0310 04:28:01.298760 30406 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0310 04:28:01.298815 30406 provisioner.cpp:249] Using default backend 'copy'
I0310 04:28:01.300622 30406 cluster.cpp:446] Creating default 'local' authorizer
I0310 04:28:01.302459 30426 slave.cpp:215] Mesos agent started on (715)@172.17.0.2:60551
I0310 04:28:01.302480 30426 slave.cpp:216] 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_UCku3O/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_UCku3O/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_UCku3O/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.3.0/_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_UCku3O" --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_00g4NK"
I0310 04:28:01.302902 30426 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_2_UCku3O/credential'
I0310 04:28:01.303040 30426 slave.cpp:358] Agent using credential for: test-principal
I0310 04:28:01.303061 30426 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_2_UCku3O/http_credentials'
I0310 04:28:01.303267 30426 http.cpp:964] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0310 04:28:01.303531 30426 http.cpp:964] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0310 04:28:01.304610 30426 slave.cpp:545] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0310 04:28:01.304685 30426 slave.cpp:553] Agent attributes: [ ]
I0310 04:28:01.304704 30426 slave.cpp:558] Agent hostname: aacbe870d1c2
I0310 04:28:01.304929 30438 status_update_manager.cpp:177] Pausing sending status updates
I0310 04:28:01.306154 30429 state.cpp:62] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_2_00g4NK/meta'
I0310 04:28:01.306661 30432 status_update_manager.cpp:203] Recovering status update manager
I0310 04:28:01.306937 30433 containerizer.cpp:600] Recovering containerizer
I0310 04:28:01.308496 30435 provisioner.cpp:410] Provisioner recovery complete
I0310 04:28:01.308940 30438 slave.cpp:5564] Finished recovery
I0310 04:28:01.309499 30438 slave.cpp:5746] Querying resource estimator for oversubscribable resources
I0310 04:28:01.309780 30432 slave.cpp:5760] Received oversubscribable resources {} from the resource estimator
I0310 04:28:01.312073 30428 process.cpp:3705] Handling HTTP event for process 'slave(715)' with path: '/slave(715)/containers'
I0310 04:28:01.313504 30425 http.cpp:916] Authorizing principal 'test-principal' to GET the '/containers' endpoint
I0310 04:28:01.317639 30433 slave.cpp:808] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.NoAuthorizer/2 (32 ms)
[----------] 9 tests from Endpoint/SlaveEndpointTest (289 ms total)
[----------] Global test environment tear-down
[==========] 1516 tests from 169 test cases ran. (475685 ms total)
[ PASSED ] 1515 tests.
[ FAILED ] 1 test, listed below:
[ FAILED ] SlaveRecoveryTest/0.RecoverSlaveState, where TypeParam = mesos::internal::slave::MesosContainerizer
1 FAILED TEST
YOU HAVE 12 DISABLED TESTS
make[4]: *** [check-local] Error 1
make[4]: Leaving directory `/mesos/mesos-1.3.0/_build/src'
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/mesos/mesos-1.3.0/_build/src'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/mesos/mesos-1.3.0/_build/src'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-1.3.0/_build'
make: *** [distcheck] Error 1
+ docker rmi mesos-1489117288-15558
Untagged: mesos-1489117288-15558:latest
Deleted: sha256:2ed0961761f56d75be1bd71867efa6728a94e9dbab4c2be52d4924bcc3713bfa
Deleted: sha256:3a34d879519d365cf4ee16576bcdca0cab9852de857e1d6ba775544bbfeff6dd
Deleted: sha256:29b143ef8fc92ca01532ab4453fc3b398eac6b7684db6c5111b7bf8c1ea709a6
Deleted: sha256:0c0ee514246f4fdaf123c9c042b22d7f25c86e320d60095cbdab2950e6d950e7
Deleted: sha256:64c7588919f42e2c800d44658a5edc61edbaf4d3c694cbd9ac88cbf82f1e027a
Deleted: sha256:02e3f50a7db34d70afe9e7d7964b0e20af6290031200ae17715d6029aa2aa2e4
Deleted: sha256:86454bdd0e3e73b0e3eca61ae5e4450365425e837dd142c95b1af30f628cb448
Deleted: sha256:c20be15f49599b73cd28c44d1bb57ea5ec2f7ca67177d2cabd532ed7c9b6d3c8
Deleted: sha256:1515641c25c95f93eaca8b3141241d017081da7f7b8541f391f5bde6085ddafc
Deleted: sha256:7a927079183a19eefa1c289067ee6d0c2a4f7e89def5e9312c6885d77293e7fb
Deleted: sha256:27c89ac50e82948ce1a5a4f509b95e67da7fc8a71c564c817005536dc115611c
Deleted: sha256:d5253e0f5b4f6f6fdf93831c9f32b43fa5c12af53cd0157c58489a062b0fdde8
Deleted: sha256:cf184c6680f794c5b66d01393c3e2d3143e42549c5ab58c126c2357431569157
Deleted: sha256:07e0aeac49c1980142bed41ba44d39f4a92b59ab7f4d81fa35d0c789825f00fd
Deleted: sha256:55d2661c659c128c212890c985ee185418582b9c081990c27a75fe1f995bd9f0
Deleted: sha256:3945af20defbe7befea0890d5a8250a8b7668dcf90e3ff51959c65bffb600288
Deleted: sha256:e3c5b18b99ca208361df8df42899e7b2698bf6353ce1550110da16b10ff85347
Deleted: sha256:912fa09eada29483436daf2da1d6928be4ece1d347cf4b2d290fc124e94fa7d4
Deleted: sha256:6a88c8549db9ad27883425b11cb324205e090635a3a533c259b3747c8606d285
Deleted: sha256:ec640eb0e44080adcfb7d01ca3f11d7219a41b4d1485805d663d5869ed23e200
Deleted: sha256:29402f76c898bd6d2970a021ec9a1e6977d6055df0f831a314641aeb7cec06eb
Deleted: sha256:5488315c676c258dc80142d1fc1522145d3533c907b396ccfdaeec46fc6d0b93
Deleted: sha256:9ca86165b16f6e90a1eecfc1519d16f21e8633f50792fcda9fe7ac0f36718465
Deleted: sha256:fce9c535580d635ad4687bd4220c3c1471397984bff6fc597bdad51ee5e2b569
Build step 'Execute shell' marked build as failure
Build failed in Jenkins: Mesos-Reviewbot #17379
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Reviewbot/17379/display/redirect?page=changes>
Changes:
[vinodkone] Clarified a comment for 'Principal' in libprocess.
[adam] Update website for Mesos 1.2.0 release.
------------------------------------------
[...truncated 49.28 MB...]
I0310 03:36:37.922358 30442 status_update_manager.cpp:177] Pausing sending status updates
I0310 03:36:37.924378 30438 state.cpp:62] Recovering state from '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionAttachFailure_1_V1D9PU/meta'
I0310 03:36:37.925050 30436 status_update_manager.cpp:203] Recovering status update manager
I0310 03:36:37.925717 30438 slave.cpp:5564] Finished recovery
I0310 03:36:37.947018 30438 slave.cpp:5746] Querying resource estimator for oversubscribable resources
I0310 03:36:37.947407 30430 slave.cpp:5760] Received oversubscribable resources {} from the resource estimator
I0310 03:36:37.948171 30434 slave.cpp:936] New master detected at master@172.17.0.2:41073
I0310 03:36:37.948339 30434 slave.cpp:971] Detecting new master
I0310 03:36:37.948722 30434 status_update_manager.cpp:177] Pausing sending status updates
I0310 03:36:37.949785 30432 slave.cpp:998] Authenticating with master master@172.17.0.2:41073
I0310 03:36:37.949872 30432 slave.cpp:1009] Using default CRAM-MD5 authenticatee
I0310 03:36:37.950340 30432 authenticatee.cpp:121] Creating new client SASL connection
I0310 03:36:37.950865 30432 master.cpp:7252] Authenticating slave(601)@172.17.0.2:41073
I0310 03:36:37.951015 30442 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1165)@172.17.0.2:41073
I0310 03:36:37.951479 30439 authenticator.cpp:98] Creating new server SASL connection
I0310 03:36:37.951750 30433 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0310 03:36:37.951876 30433 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0310 03:36:37.952006 30433 authenticator.cpp:204] Received SASL authentication start
I0310 03:36:37.952074 30433 authenticator.cpp:326] Authentication requires more steps
I0310 03:36:37.952221 30433 authenticatee.cpp:259] Received SASL authentication step
I0310 03:36:37.952369 30436 authenticator.cpp:232] Received SASL authentication step
I0310 03:36:37.952404 30436 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '033299879f31' server FQDN: '033299879f31' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0310 03:36:37.952424 30436 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0310 03:36:37.952463 30436 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0310 03:36:37.952482 30436 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '033299879f31' server FQDN: '033299879f31' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0310 03:36:37.952491 30436 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0310 03:36:37.952498 30436 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0310 03:36:37.952512 30436 authenticator.cpp:318] Authentication success
I0310 03:36:37.952594 30430 authenticatee.cpp:299] Authentication success
I0310 03:36:37.952649 30438 master.cpp:7282] Successfully authenticated principal 'test-principal' at slave(601)@172.17.0.2:41073
I0310 03:36:37.952915 30428 slave.cpp:1093] Successfully authenticated with master master@172.17.0.2:41073
I0310 03:36:37.952945 30441 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1165)@172.17.0.2:41073
I0310 03:36:37.953155 30428 slave.cpp:1521] Will retry registration in 5.811144ms if necessary
I0310 03:36:37.953351 30437 master.cpp:5437] Registering agent at slave(601)@172.17.0.2:41073 (033299879f31) with id a23cd7cf-56f3-47d9-a80f-ee317d06691a-S0
I0310 03:36:37.953819 30430 registrar.cpp:463] Applied 1 operations in 58466ns; attempting to update the registry
I0310 03:36:37.955404 30430 registrar.cpp:508] Successfully updated the registry in 0ns
I0310 03:36:37.956437 30434 slave.cpp:4347] Received ping from slave-observer(548)@172.17.0.2:41073
I0310 03:36:37.956645 30434 slave.cpp:1139] Registered with master master@172.17.0.2:41073; given agent ID a23cd7cf-56f3-47d9-a80f-ee317d06691a-S0
I0310 03:36:37.956670 30434 fetcher.cpp:94] Clearing fetcher cache
I0310 03:36:37.956578 30441 master.cpp:5511] Registered agent a23cd7cf-56f3-47d9-a80f-ee317d06691a-S0 at slave(601)@172.17.0.2:41073 (033299879f31) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0310 03:36:37.956852 30436 hierarchical.cpp:527] Added agent a23cd7cf-56f3-47d9-a80f-ee317d06691a-S0 (033299879f31) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
I0310 03:36:37.956913 30430 status_update_manager.cpp:184] Resuming sending status updates
I0310 03:36:37.958094 30436 hierarchical.cpp:1944] No inverse offers to send out!
I0310 03:36:37.958153 30436 hierarchical.cpp:1438] Performed allocation for 1 agents in 1.073773ms
I0310 03:36:37.958525 30433 master.cpp:7081] Sending 1 offers to framework a23cd7cf-56f3-47d9-a80f-ee317d06691a-0000 (default) at scheduler-57e5241e-a731-4a65-a9cb-a88f0a2566cf@172.17.0.2:41073
I0310 03:36:37.959928 30435 sched.cpp:933] Scheduler::resourceOffers took 1.017698ms
I0310 03:36:37.961141 30435 master.cpp:3839] Processing ACCEPT call for offers: [ a23cd7cf-56f3-47d9-a80f-ee317d06691a-O0 ] on agent a23cd7cf-56f3-47d9-a80f-ee317d06691a-S0 at slave(601)@172.17.0.2:41073 (033299879f31) for framework a23cd7cf-56f3-47d9-a80f-ee317d06691a-0000 (default) at scheduler-57e5241e-a731-4a65-a9cb-a88f0a2566cf@172.17.0.2:41073
I0310 03:36:37.961257 30435 master.cpp:3415] Authorizing framework principal 'test-principal' to launch task 0
W0310 03:36:37.963533 30433 validation.cpp:1113] Executor 'default' for task '0' uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases.
W0310 03:36:37.963572 30433 validation.cpp:1125] Executor 'default' for task '0' uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases.
I0310 03:36:37.964048 30433 master.cpp:9097] Adding task 0 with resources cpus(*)(allocated: *):0.1; mem(*)(allocated: *):32 on agent a23cd7cf-56f3-47d9-a80f-ee317d06691a-S0 at slave(601)@172.17.0.2:41073 (033299879f31)
I0310 03:36:37.964275 30433 master.cpp:4489] Launching task 0 of framework a23cd7cf-56f3-47d9-a80f-ee317d06691a-0000 (default) at scheduler-57e5241e-a731-4a65-a9cb-a88f0a2566cf@172.17.0.2:41073 with resources cpus(*)(allocated: *):0.1; mem(*)(allocated: *):32 on agent a23cd7cf-56f3-47d9-a80f-ee317d06691a-S0 at slave(601)@172.17.0.2:41073 (033299879f31)
I0310 03:36:37.966464 30438 hierarchical.cpp:844] Updated allocation of framework a23cd7cf-56f3-47d9-a80f-ee317d06691a-0000 on agent a23cd7cf-56f3-47d9-a80f-ee317d06691a-S0 from cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] to cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000]
I0310 03:36:37.967444 30438 hierarchical.cpp:1108] Recovered cpus(*)(allocated: *):1.9; mem(*)(allocated: *):992; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: cpus(*)(allocated: *):0.1; mem(*)(allocated: *):32) on agent a23cd7cf-56f3-47d9-a80f-ee317d06691a-S0 from framework a23cd7cf-56f3-47d9-a80f-ee317d06691a-0000
I0310 03:36:37.967525 30438 hierarchical.cpp:1145] Framework a23cd7cf-56f3-47d9-a80f-ee317d06691a-0000 filtered agent a23cd7cf-56f3-47d9-a80f-ee317d06691a-S0 for 5secs
I0310 03:36:37.980453 30434 slave.cpp:1167] Checkpointing SlaveInfo to '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionAttachFailure_1_V1D9PU/meta/slaves/a23cd7cf-56f3-47d9-a80f-ee317d06691a-S0/slave.info'
I0310 03:36:37.981030 30434 slave.cpp:1205] Forwarding total oversubscribed resources {}
I0310 03:36:37.981359 30439 master.cpp:6098] Received update of agent a23cd7cf-56f3-47d9-a80f-ee317d06691a-S0 at slave(601)@172.17.0.2:41073 (033299879f31) with total oversubscribed resources {}
I0310 03:36:37.981530 30434 slave.cpp:1631] Got assigned task '0' for framework a23cd7cf-56f3-47d9-a80f-ee317d06691a-0000
I0310 03:36:37.982360 30434 slave.cpp:1791] Launching task '0' for framework a23cd7cf-56f3-47d9-a80f-ee317d06691a-0000
I0310 03:36:37.983147 30434 paths.cpp:547] Trying to chown '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionAttachFailure_1_V1D9PU/slaves/a23cd7cf-56f3-47d9-a80f-ee317d06691a-S0/frameworks/a23cd7cf-56f3-47d9-a80f-ee317d06691a-0000/executors/default/runs/3b26cbf0-9b39-4b75-96c9-7782a10c739d' to user 'mesos'
I0310 03:36:37.983453 30434 slave.cpp:6491] Launching executor 'default' of framework a23cd7cf-56f3-47d9-a80f-ee317d06691a-0000 with resources {} in work directory '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionAttachFailure_1_V1D9PU/slaves/a23cd7cf-56f3-47d9-a80f-ee317d06691a-S0/frameworks/a23cd7cf-56f3-47d9-a80f-ee317d06691a-0000/executors/default/runs/3b26cbf0-9b39-4b75-96c9-7782a10c739d'
I0310 03:36:37.984221 30434 slave.cpp:2124] Queued task '0' for executor 'default' of framework a23cd7cf-56f3-47d9-a80f-ee317d06691a-0000
I0310 03:36:37.984300 30434 slave.cpp:889] Successfully attached file '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionAttachFailure_1_V1D9PU/slaves/a23cd7cf-56f3-47d9-a80f-ee317d06691a-S0/frameworks/a23cd7cf-56f3-47d9-a80f-ee317d06691a-0000/executors/default/runs/3b26cbf0-9b39-4b75-96c9-7782a10c739d'
I0310 03:36:37.987519 30442 exec.cpp:162] Version: 1.3.0
I0310 03:36:37.987987 30440 exec.cpp:212] Executor started at: executor(183)@172.17.0.2:41073 with pid 30409
I0310 03:36:37.988221 30440 slave.cpp:3386] Got registration for executor 'default' of framework a23cd7cf-56f3-47d9-a80f-ee317d06691a-0000 from executor(183)@172.17.0.2:41073
I0310 03:36:37.988680 30429 exec.cpp:237] Executor registered on agent a23cd7cf-56f3-47d9-a80f-ee317d06691a-S0
I0310 03:36:37.988739 30429 exec.cpp:249] Executor::registered took 35634ns
I0310 03:36:37.989549 30429 slave.cpp:2337] Sending queued task '0' to executor 'default' of framework a23cd7cf-56f3-47d9-a80f-ee317d06691a-0000 at executor(183)@172.17.0.2:41073
I0310 03:36:37.989907 30429 exec.cpp:325] Executor asked to run task '0'
I0310 03:36:37.989951 30429 exec.cpp:334] Executor::launchTask took 20500ns
I0310 03:36:37.993655 30443 process.cpp:3705] Handling HTTP event for process 'slave(601)' with path: '/slave(601)/api/v1'
I0310 03:36:37.995245 30438 http.cpp:331] HTTP POST for /slave(601)/api/v1 from 172.17.0.2:60978
I0310 03:36:37.995854 30438 http.cpp:545] Processing call LAUNCH_NESTED_CONTAINER_SESSION
I0310 03:36:37.997189 30429 process.cpp:3762] Failed to process request for '/slave(601)/api/v1': Unsupported
W0310 03:36:37.997318 30429 http.cpp:2732] Failed to attach to nested container 3b26cbf0-9b39-4b75-96c9-7782a10c739d.cf47a7a0-87e8-45a4-902c-07d852ab0478: Unsupported
I0310 03:36:37.997460 30434 process.cpp:1457] Returning '500 Internal Server Error' for '/slave(601)/api/v1' (Unsupported)
I0310 03:36:38.001288 30409 sched.cpp:2021] Asked to stop the driver
I0310 03:36:38.001417 30443 sched.cpp:1203] Stopping framework a23cd7cf-56f3-47d9-a80f-ee317d06691a-0000
I0310 03:36:38.001967 30428 master.cpp:7765] Processing TEARDOWN call for framework a23cd7cf-56f3-47d9-a80f-ee317d06691a-0000 (default) at scheduler-57e5241e-a731-4a65-a9cb-a88f0a2566cf@172.17.0.2:41073
I0310 03:36:38.002038 30428 master.cpp:7777] Removing framework a23cd7cf-56f3-47d9-a80f-ee317d06691a-0000 (default) at scheduler-57e5241e-a731-4a65-a9cb-a88f0a2566cf@172.17.0.2:41073
I0310 03:36:38.002064 30428 master.cpp:3148] Deactivating framework a23cd7cf-56f3-47d9-a80f-ee317d06691a-0000 (default) at scheduler-57e5241e-a731-4a65-a9cb-a88f0a2566cf@172.17.0.2:41073
I0310 03:36:38.002238 30432 hierarchical.cpp:376] Deactivated framework a23cd7cf-56f3-47d9-a80f-ee317d06691a-0000
I0310 03:36:38.002318 30432 slave.cpp:2664] Asked to shut down framework a23cd7cf-56f3-47d9-a80f-ee317d06691a-0000 by master@172.17.0.2:41073
I0310 03:36:38.002334 30428 master.cpp:8345] Updating the state of task 0 of framework a23cd7cf-56f3-47d9-a80f-ee317d06691a-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED)
I0310 03:36:38.002357 30432 slave.cpp:2689] Shutting down framework a23cd7cf-56f3-47d9-a80f-ee317d06691a-0000
I0310 03:36:38.002413 30432 slave.cpp:5076] Shutting down executor 'default' of framework a23cd7cf-56f3-47d9-a80f-ee317d06691a-0000 at executor(183)@172.17.0.2:41073
I0310 03:36:38.002573 30432 exec.cpp:415] Executor asked to shutdown
I0310 03:36:38.002615 30432 exec.cpp:430] Executor::shutdown took 22839ns
I0310 03:36:38.002940 30428 master.cpp:8439] Removing task 0 with resources cpus(*)(allocated: *):0.1; mem(*)(allocated: *):32 of framework a23cd7cf-56f3-47d9-a80f-ee317d06691a-0000 on agent a23cd7cf-56f3-47d9-a80f-ee317d06691a-S0 at slave(601)@172.17.0.2:41073 (033299879f31)
I0310 03:36:38.003305 30430 slave.cpp:4389] Got exited event for executor(183)@172.17.0.2:41073
I0310 03:36:38.003381 30432 hierarchical.cpp:1108] Recovered cpus(*)(allocated: *):0.1; mem(*)(allocated: *):32 (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: {}) on agent a23cd7cf-56f3-47d9-a80f-ee317d06691a-S0 from framework a23cd7cf-56f3-47d9-a80f-ee317d06691a-0000
I0310 03:36:38.003626 30428 master.cpp:8468] Removing executor 'default' with resources {} of framework a23cd7cf-56f3-47d9-a80f-ee317d06691a-0000 on agent a23cd7cf-56f3-47d9-a80f-ee317d06691a-S0 at slave(601)@172.17.0.2:41073 (033299879f31)
I0310 03:36:38.004516 30430 hierarchical.cpp:323] Removed framework a23cd7cf-56f3-47d9-a80f-ee317d06691a-0000
I0310 03:36:38.006028 30433 slave.cpp:4762] Executor 'default' of framework a23cd7cf-56f3-47d9-a80f-ee317d06691a-0000 exited with status 0
I0310 03:36:38.006217 30433 slave.cpp:4862] Cleaning up executor 'default' of framework a23cd7cf-56f3-47d9-a80f-ee317d06691a-0000 at executor(183)@172.17.0.2:41073
W0310 03:36:38.006489 30432 master.cpp:6367] Ignoring unknown exited executor 'default' of framework a23cd7cf-56f3-47d9-a80f-ee317d06691a-0000 on agent a23cd7cf-56f3-47d9-a80f-ee317d06691a-S0 at slave(601)@172.17.0.2:41073 (033299879f31)
I0310 03:36:38.034215 30433 slave.cpp:4950] Cleaning up framework a23cd7cf-56f3-47d9-a80f-ee317d06691a-0000
I0310 03:36:38.034497 30433 gc.cpp:55] Scheduling '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionAttachFailure_1_V1D9PU/slaves/a23cd7cf-56f3-47d9-a80f-ee317d06691a-S0/frameworks/a23cd7cf-56f3-47d9-a80f-ee317d06691a-0000/executors/default/runs/3b26cbf0-9b39-4b75-96c9-7782a10c739d' for gc 1.0000002101837weeks in the future
I0310 03:36:38.034664 30433 gc.cpp:55] Scheduling '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionAttachFailure_1_V1D9PU/slaves/a23cd7cf-56f3-47d9-a80f-ee317d06691a-S0/frameworks/a23cd7cf-56f3-47d9-a80f-ee317d06691a-0000/executors/default' for gc 1.0000002101837weeks in the future
I0310 03:36:38.034740 30433 gc.cpp:55] Scheduling '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSessionAttachFailure_1_V1D9PU/slaves/a23cd7cf-56f3-47d9-a80f-ee317d06691a-S0/frameworks/a23cd7cf-56f3-47d9-a80f-ee317d06691a-0000' for gc 1.0000002101837weeks in the future
I0310 03:36:38.034839 30433 status_update_manager.cpp:285] Closing status update streams for framework a23cd7cf-56f3-47d9-a80f-ee317d06691a-0000
I0310 03:36:38.035135 30435 slave.cpp:808] Agent terminating
I0310 03:36:38.035428 30440 master.cpp:1317] Agent a23cd7cf-56f3-47d9-a80f-ee317d06691a-S0 at slave(601)@172.17.0.2:41073 (033299879f31) disconnected
I0310 03:36:38.035454 30440 master.cpp:3185] Disconnecting agent a23cd7cf-56f3-47d9-a80f-ee317d06691a-S0 at slave(601)@172.17.0.2:41073 (033299879f31)
I0310 03:36:38.035517 30440 master.cpp:3204] Deactivating agent a23cd7cf-56f3-47d9-a80f-ee317d06691a-S0 at slave(601)@172.17.0.2:41073 (033299879f31)
I0310 03:36:38.035795 30431 hierarchical.cpp:655] Agent a23cd7cf-56f3-47d9-a80f-ee317d06691a-S0 deactivated
I0310 03:36:38.038785 30435 master.cpp:1156] Master terminating
I0310 03:36:38.039423 30430 hierarchical.cpp:560] Removed agent a23cd7cf-56f3-47d9-a80f-ee317d06691a-S0
[ OK ] ContentType/AgentAPITest.LaunchNestedContainerSessionAttachFailure/1 (170 ms)
[ RUN ] ContentType/AgentAPITest.LaunchNestedContainerSession/0
I0310 03:36:38.073582 30409 cluster.cpp:160] Creating default 'local' authorizer
I0310 03:36:38.076258 30440 master.cpp:437] Master 2655ff0e-56e8-4666-9a1f-8dfeda2d1f36 (033299879f31) started on 172.17.0.2:41073
I0310 03:36:38.076289 30440 master.cpp:439] Flags at startup: --acls="" --agent_ping_timeout="15secs" --agent_reregister_timeout="10mins" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate_agents="true" --authenticate_frameworks="true" --authenticate_http_frameworks="true" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/LLNM0L/credentials" --framework_sorter="drf" --help="false" --hostname_lookup="true" --http_authenticators="basic" --http_framework_authenticators="basic" --initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO" --max_agent_ping_timeouts="5" --max_completed_frameworks="50" --max_completed_tasks_per_framework="1000" --max_unreachable_tasks_per_framework="1000" --quiet="false" --recovery_agent_removal_limit="100%" --registry="in_memory" --registry_fetch_timeout="1mins" --registry_gc_interval="15mins" --registry_max_agent_age="2weeks" --registry_max_agent_count="102400" --registry_store_timeout="100secs" --registry_strict="false" --root_submissions="true" --user_sorter="drf" --version="false" --webui_dir="/mesos/mesos-1.3.0/_inst/share/mesos/webui" --work_dir="/tmp/LLNM0L/master" --zk_session_timeout="10secs"
I0310 03:36:38.076758 30440 master.cpp:489] Master only allowing authenticated frameworks to register
I0310 03:36:38.076776 30440 master.cpp:503] Master only allowing authenticated agents to register
I0310 03:36:38.076786 30440 master.cpp:516] Master only allowing authenticated HTTP frameworks to register
I0310 03:36:38.076839 30440 credentials.hpp:37] Loading credentials for authentication from '/tmp/LLNM0L/credentials'
I0310 03:36:38.077121 30440 master.cpp:561] Using default 'crammd5' authenticator
I0310 03:36:38.077266 30440 http.cpp:964] Using default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I0310 03:36:38.077447 30440 http.cpp:964] Using default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I0310 03:36:38.077662 30440 http.cpp:964] Using default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I0310 03:36:38.077769 30440 master.cpp:641] Authorization enabled
I0310 03:36:38.077950 30430 hierarchical.cpp:159] Initialized hierarchical allocator process
I0310 03:36:38.077987 30436 whitelist_watcher.cpp:77] No whitelist given
I0310 03:36:38.080762 30442 master.cpp:2165] Elected as the leading master!
I0310 03:36:38.080799 30442 master.cpp:1704] Recovering from registrar
I0310 03:36:38.081030 30438 registrar.cpp:331] Recovering registrar
I0310 03:36:38.081805 30438 registrar.cpp:364] Successfully fetched the registry (0B) in 730112ns
I0310 03:36:38.081912 30438 registrar.cpp:463] Applied 1 operations in 25519ns; attempting to update the registry
I0310 03:36:38.082912 30438 registrar.cpp:508] Successfully updated the registry in 937216ns
I0310 03:36:38.083022 30438 registrar.cpp:394] Successfully recovered registrar
I0310 03:36:38.083535 30429 hierarchical.cpp:186] Skipping recovery of hierarchical allocator: nothing to recover
I0310 03:36:38.083525 30439 master.cpp:1803] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
I0310 03:36:38.114039 30409 containerizer.cpp:221] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W0310 03:36:38.114768 30409 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0310 03:36:38.114895 30409 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0310 03:36:38.114938 30409 provisioner.cpp:249] Using default backend 'copy'
I0310 03:36:38.119388 30409 cluster.cpp:446] Creating default 'local' authorizer
I0310 03:36:38.121546 30428 slave.cpp:215] Mesos agent started on (602)@172.17.0.2:41073
I0310 03:36:38.121570 30428 slave.cpp:216] 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/ContentType_AgentAPITest_LaunchNestedContainerSession_0_bIQccI/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/ContentType_AgentAPITest_LaunchNestedContainerSession_0_bIQccI/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/ContentType_AgentAPITest_LaunchNestedContainerSession_0_bIQccI/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.3.0/_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/ContentType_AgentAPITest_LaunchNestedContainerSession_0_bIQccI" --sandbox_directory="/mnt/mesos/sandbox" --strict="true" --switch_user="true" --systemd_enable_support="true" --systemd_runtime_directory="/run/systemd/system" --version="false" --work_dir="/tmp/ContentType_AgentAPITest_LaunchNestedContainerSession_0_ktcu6J"
I0310 03:36:38.122191 30428 credentials.hpp:86] Loading credential for authentication from '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSession_0_bIQccI/credential'
I0310 03:36:38.124351 30409 sched.cpp:232] Version: 1.3.0
I0310 03:36:38.125062 30434 sched.cpp:336] New master detected at master@172.17.0.2:41073
I0310 03:36:38.125247 30434 sched.cpp:407] Authenticating with master master@172.17.0.2:41073
I0310 03:36:38.125313 30434 sched.cpp:414] Using default CRAM-MD5 authenticatee
I0310 03:36:38.125715 30442 authenticatee.cpp:121] Creating new client SASL connection
I0310 03:36:38.126035 30429 master.cpp:7252] Authenticating scheduler-96cd4c47-d3e9-4b52-9278-be2ab772ce25@172.17.0.2:41073
I0310 03:36:38.126334 30442 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1166)@172.17.0.2:41073
I0310 03:36:38.126612 30429 authenticator.cpp:98] Creating new server SASL connection
I0310 03:36:38.126989 30429 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0310 03:36:38.127071 30429 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0310 03:36:38.127291 30429 authenticator.cpp:204] Received SASL authentication start
I0310 03:36:38.127436 30429 authenticator.cpp:326] Authentication requires more steps
I0310 03:36:38.127619 30429 authenticatee.cpp:259] Received SASL authentication step
I0310 03:36:38.127817 30429 authenticator.cpp:232] Received SASL authentication step
I0310 03:36:38.127899 30429 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '033299879f31' server FQDN: '033299879f31' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0310 03:36:38.127998 30429 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0310 03:36:38.128120 30429 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0310 03:36:38.128232 30429 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '033299879f31' server FQDN: '033299879f31' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0310 03:36:38.128329 30429 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0310 03:36:38.128419 30429 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0310 03:36:38.128501 30429 authenticator.cpp:318] Authentication success
I0310 03:36:38.128674 30442 authenticatee.cpp:299] Authentication success
I0310 03:36:38.128782 30442 master.cpp:7282] Successfully authenticated principal 'test-principal' at scheduler-96cd4c47-d3e9-4b52-9278-be2ab772ce25@172.17.0.2:41073
I0310 03:36:38.128924 30429 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1166)@172.17.0.2:41073
I0310 03:36:38.129312 30442 sched.cpp:513] Successfully authenticated with master master@172.17.0.2:41073
I0310 03:36:38.129335 30442 sched.cpp:836] Sending SUBSCRIBE call to master@172.17.0.2:41073
I0310 03:36:38.129456 30442 sched.cpp:869] Will retry registration in 701.229729ms if necessary
I0310 03:36:38.129683 30439 master.cpp:2817] Received SUBSCRIBE call for framework 'default' at scheduler-96cd4c47-d3e9-4b52-9278-be2ab772ce25@172.17.0.2:41073
I0310 03:36:38.129855 30439 master.cpp:2201] Authorizing framework principal 'test-principal' to receive offers for roles '{ * }'
I0310 03:36:38.130432 30439 master.cpp:2894] Subscribing framework default with checkpointing disabled and capabilities [ ]
I0310 03:36:38.131340 30439 hierarchical.cpp:271] Added framework 2655ff0e-56e8-4666-9a1f-8dfeda2d1f36-0000
I0310 03:36:38.131531 30429 sched.cpp:759] Framework registered with 2655ff0e-56e8-4666-9a1f-8dfeda2d1f36-0000
I0310 03:36:38.131788 30429 sched.cpp:773] Scheduler::registered took 27279ns
I0310 03:36:38.131942 30439 hierarchical.cpp:1854] No allocations performed
I0310 03:36:38.132031 30439 hierarchical.cpp:1944] No inverse offers to send out!
I0310 03:36:38.132208 30439 hierarchical.cpp:1438] Performed allocation for 0 agents in 315569ns
I0310 03:36:38.146998 30428 slave.cpp:358] Agent using credential for: test-principal
I0310 03:36:38.147050 30428 credentials.hpp:37] Loading credentials for authentication from '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSession_0_bIQccI/http_credentials'
I0310 03:36:38.147379 30428 http.cpp:964] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0310 03:36:38.147583 30428 http.cpp:964] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0310 03:36:38.149056 30428 slave.cpp:545] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0310 03:36:38.149137 30428 slave.cpp:553] Agent attributes: [ ]
I0310 03:36:38.149152 30428 slave.cpp:558] Agent hostname: 033299879f31
I0310 03:36:38.149274 30443 status_update_manager.cpp:177] Pausing sending status updates
I0310 03:36:38.150673 30429 state.cpp:62] Recovering state from '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSession_0_ktcu6J/meta'
I0310 03:36:38.151000 30429 status_update_manager.cpp:203] Recovering status update manager
I0310 03:36:38.151667 30442 containerizer.cpp:600] Recovering containerizer
I0310 03:36:38.154161 30440 provisioner.cpp:410] Provisioner recovery complete
I0310 03:36:38.154695 30440 slave.cpp:5564] Finished recovery
I0310 03:36:38.155222 30440 slave.cpp:5746] Querying resource estimator for oversubscribable resources
I0310 03:36:38.155796 30440 slave.cpp:936] New master detected at master@172.17.0.2:41073
I0310 03:36:38.155907 30437 status_update_manager.cpp:177] Pausing sending status updates
I0310 03:36:38.156031 30440 slave.cpp:971] Detecting new master
I0310 03:36:38.156142 30440 slave.cpp:5760] Received oversubscribable resources {} from the resource estimator
I0310 03:36:38.158316 30431 slave.cpp:998] Authenticating with master master@172.17.0.2:41073
I0310 03:36:38.158396 30431 slave.cpp:1009] Using default CRAM-MD5 authenticatee
I0310 03:36:38.158643 30430 authenticatee.cpp:121] Creating new client SASL connection
I0310 03:36:38.158922 30433 master.cpp:7252] Authenticating slave(602)@172.17.0.2:41073
I0310 03:36:38.159030 30438 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1167)@172.17.0.2:41073
I0310 03:36:38.159250 30434 authenticator.cpp:98] Creating new server SASL connection
I0310 03:36:38.159456 30440 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0310 03:36:38.159493 30440 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0310 03:36:38.160028 30428 authenticator.cpp:204] Received SASL authentication start
I0310 03:36:38.160101 30428 authenticator.cpp:326] Authentication requires more steps
I0310 03:36:38.160444 30431 authenticatee.cpp:259] Received SASL authentication step
I0310 03:36:38.160580 30431 authenticator.cpp:232] Received SASL authentication step
I0310 03:36:38.160615 30431 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '033299879f31' server FQDN: '033299879f31' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0310 03:36:38.160634 30431 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0310 03:36:38.160683 30431 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0310 03:36:38.160713 30431 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '033299879f31' server FQDN: '033299879f31' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0310 03:36:38.160729 30431 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0310 03:36:38.160742 30431 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0310 03:36:38.160763 30431 authenticator.cpp:318] Authentication success
I0310 03:36:38.160887 30440 authenticatee.cpp:299] Authentication success
I0310 03:36:38.160975 30441 master.cpp:7282] Successfully authenticated principal 'test-principal' at slave(602)@172.17.0.2:41073
I0310 03:36:38.161097 30431 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1167)@172.17.0.2:41073
I0310 03:36:38.161373 30442 slave.cpp:1093] Successfully authenticated with master master@172.17.0.2:41073
I0310 03:36:38.161586 30442 slave.cpp:1521] Will retry registration in 11.854164ms if necessary
I0310 03:36:38.161784 30438 master.cpp:5437] Registering agent at slave(602)@172.17.0.2:41073 (033299879f31) with id 2655ff0e-56e8-4666-9a1f-8dfeda2d1f36-S0
I0310 03:36:38.162220 30441 registrar.cpp:463] Applied 1 operations in 63956ns; attempting to update the registry
I0310 03:36:38.163743 30441 registrar.cpp:508] Successfully updated the registry in 1.452288ms
I0310 03:36:38.164427 30437 slave.cpp:4347] Received ping from slave-observer(549)@172.17.0.2:41073
I0310 03:36:38.164647 30441 slave.cpp:1139] Registered with master master@172.17.0.2:41073; given agent ID 2655ff0e-56e8-4666-9a1f-8dfeda2d1f36-S0
I0310 03:36:38.164672 30441 fetcher.cpp:94] Clearing fetcher cache
I0310 03:36:38.164597 30430 master.cpp:5511] Registered agent 2655ff0e-56e8-4666-9a1f-8dfeda2d1f36-S0 at slave(602)@172.17.0.2:41073 (033299879f31) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0310 03:36:38.164873 30438 status_update_manager.cpp:184] Resuming sending status updates
I0310 03:36:38.164870 30440 hierarchical.cpp:527] Added agent 2655ff0e-56e8-4666-9a1f-8dfeda2d1f36-S0 (033299879f31) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
I0310 03:36:38.165068 30441 slave.cpp:1167] Checkpointing SlaveInfo to '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSession_0_ktcu6J/meta/slaves/2655ff0e-56e8-4666-9a1f-8dfeda2d1f36-S0/slave.info'
I0310 03:36:38.165380 30441 slave.cpp:1205] Forwarding total oversubscribed resources {}
I0310 03:36:38.165513 30441 master.cpp:6098] Received update of agent 2655ff0e-56e8-4666-9a1f-8dfeda2d1f36-S0 at slave(602)@172.17.0.2:41073 (033299879f31) with total oversubscribed resources {}
I0310 03:36:38.166100 30440 hierarchical.cpp:1944] No inverse offers to send out!
I0310 03:36:38.166151 30440 hierarchical.cpp:1438] Performed allocation for 1 agents in 1.157816ms
I0310 03:36:38.166594 30439 master.cpp:7081] Sending 1 offers to framework 2655ff0e-56e8-4666-9a1f-8dfeda2d1f36-0000 (default) at scheduler-96cd4c47-d3e9-4b52-9278-be2ab772ce25@172.17.0.2:41073
I0310 03:36:38.167026 30437 sched.cpp:933] Scheduler::resourceOffers took 109715ns
I0310 03:36:38.170698 30437 master.cpp:3839] Processing ACCEPT call for offers: [ 2655ff0e-56e8-4666-9a1f-8dfeda2d1f36-O0 ] on agent 2655ff0e-56e8-4666-9a1f-8dfeda2d1f36-S0 at slave(602)@172.17.0.2:41073 (033299879f31) for framework 2655ff0e-56e8-4666-9a1f-8dfeda2d1f36-0000 (default) at scheduler-96cd4c47-d3e9-4b52-9278-be2ab772ce25@172.17.0.2:41073
I0310 03:36:38.170835 30437 master.cpp:3415] Authorizing framework principal 'test-principal' to launch task c36f66ec-13f7-4f2a-baba-5ea7efc020bb
I0310 03:36:38.173943 30437 master.cpp:9097] Adding task c36f66ec-13f7-4f2a-baba-5ea7efc020bb with resources cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] on agent 2655ff0e-56e8-4666-9a1f-8dfeda2d1f36-S0 at slave(602)@172.17.0.2:41073 (033299879f31)
I0310 03:36:38.174437 30437 master.cpp:4489] Launching task c36f66ec-13f7-4f2a-baba-5ea7efc020bb of framework 2655ff0e-56e8-4666-9a1f-8dfeda2d1f36-0000 (default) at scheduler-96cd4c47-d3e9-4b52-9278-be2ab772ce25@172.17.0.2:41073 with resources cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] on agent 2655ff0e-56e8-4666-9a1f-8dfeda2d1f36-S0 at slave(602)@172.17.0.2:41073 (033299879f31)
I0310 03:36:38.179314 30431 hierarchical.cpp:844] Updated allocation of framework 2655ff0e-56e8-4666-9a1f-8dfeda2d1f36-0000 on agent 2655ff0e-56e8-4666-9a1f-8dfeda2d1f36-S0 from cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] to cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000]
I0310 03:36:38.179868 30440 slave.cpp:1631] Got assigned task 'c36f66ec-13f7-4f2a-baba-5ea7efc020bb' for framework 2655ff0e-56e8-4666-9a1f-8dfeda2d1f36-0000
I0310 03:36:38.180958 30440 slave.cpp:1791] Launching task 'c36f66ec-13f7-4f2a-baba-5ea7efc020bb' for framework 2655ff0e-56e8-4666-9a1f-8dfeda2d1f36-0000
I0310 03:36:38.185183 30440 paths.cpp:547] Trying to chown '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSession_0_ktcu6J/slaves/2655ff0e-56e8-4666-9a1f-8dfeda2d1f36-S0/frameworks/2655ff0e-56e8-4666-9a1f-8dfeda2d1f36-0000/executors/c36f66ec-13f7-4f2a-baba-5ea7efc020bb/runs/705b6fad-6bac-4356-a225-3de2ea8ad386' to user 'mesos'
I0310 03:36:38.185612 30440 slave.cpp:6491] Launching executor 'c36f66ec-13f7-4f2a-baba-5ea7efc020bb' of framework 2655ff0e-56e8-4666-9a1f-8dfeda2d1f36-0000 with resources cpus(*)(allocated: *):0.1; mem(*)(allocated: *):32 in work directory '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSession_0_ktcu6J/slaves/2655ff0e-56e8-4666-9a1f-8dfeda2d1f36-S0/frameworks/2655ff0e-56e8-4666-9a1f-8dfeda2d1f36-0000/executors/c36f66ec-13f7-4f2a-baba-5ea7efc020bb/runs/705b6fad-6bac-4356-a225-3de2ea8ad386'
I0310 03:36:38.187024 30437 containerizer.cpp:993] Starting container 705b6fad-6bac-4356-a225-3de2ea8ad386 for executor 'c36f66ec-13f7-4f2a-baba-5ea7efc020bb' of framework 2655ff0e-56e8-4666-9a1f-8dfeda2d1f36-0000
I0310 03:36:38.187211 30440 slave.cpp:2124] Queued task 'c36f66ec-13f7-4f2a-baba-5ea7efc020bb' for executor 'c36f66ec-13f7-4f2a-baba-5ea7efc020bb' of framework 2655ff0e-56e8-4666-9a1f-8dfeda2d1f36-0000
I0310 03:36:38.187436 30440 slave.cpp:889] Successfully attached file '/tmp/ContentType_AgentAPITest_LaunchNestedContainerSession_0_ktcu6J/slaves/2655ff0e-56e8-4666-9a1f-8dfeda2d1f36-S0/frameworks/2655ff0e-56e8-4666-9a1f-8dfeda2d1f36-0000/executors/c36f66ec-13f7-4f2a-baba-5ea7efc020bb/runs/705b6fad-6bac-4356-a225-3de2ea8ad386'
Build failed in Jenkins: Mesos-Reviewbot #17378
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Reviewbot/17378/display/redirect?page=changes>
Changes:
[yujie.jay] Added placeholder in CHANGELOG for 1.2.1.
[yujie.jay] Added MESOS-7208 to 1.2.1 CHANGELOG.
------------------------------------------
[...truncated 25.62 MB...]
I0310 00:35:13.608206 30442 status_update_manager.cpp:203] Recovering status update manager
I0310 00:35:13.608393 30435 containerizer.cpp:600] Recovering containerizer
I0310 00:35:13.609848 30431 provisioner.cpp:410] Provisioner recovery complete
I0310 00:35:13.610328 30440 slave.cpp:5564] Finished recovery
I0310 00:35:13.610949 30440 slave.cpp:5746] Querying resource estimator for oversubscribable resources
I0310 00:35:13.611197 30435 slave.cpp:5760] Received oversubscribable resources {} from the resource estimator
I0310 00:35:13.612920 30434 process.cpp:3705] Handling HTTP event for process 'slave(707)' with path: '/slave(707)/monitor/statistics.json'
I0310 00:35:13.614358 30432 http.cpp:916] Authorizing principal 'test-principal' to GET the '/monitor/statistics.json' endpoint
I0310 00:35:13.617167 30442 slave.cpp:808] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.AuthorizedRequest/1 (26 ms)
[ RUN ] Endpoint/SlaveEndpointTest.AuthorizedRequest/2
I0310 00:35:13.968334 30412 containerizer.cpp:221] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W0310 00:35:13.968791 30412 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0310 00:35:13.968878 30412 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0310 00:35:13.968919 30412 provisioner.cpp:249] Using default backend 'copy'
I0310 00:35:13.971560 30445 slave.cpp:215] Mesos agent started on (708)@172.17.0.3:38901
I0310 00:35:13.971614 30445 slave.cpp:216] 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_Y7wQHy/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_Y7wQHy/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_Y7wQHy/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.3.0/_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_Y7wQHy" --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_02ujx3"
I0310 00:35:13.972232 30445 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_AuthorizedRequest_2_Y7wQHy/credential'
I0310 00:35:13.972437 30445 slave.cpp:358] Agent using credential for: test-principal
I0310 00:35:13.972466 30445 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_AuthorizedRequest_2_Y7wQHy/http_credentials'
I0310 00:35:13.972791 30445 http.cpp:964] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0310 00:35:13.972965 30445 http.cpp:964] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0310 00:35:13.974303 30445 slave.cpp:545] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0310 00:35:13.974383 30445 slave.cpp:553] Agent attributes: [ ]
I0310 00:35:13.974405 30445 slave.cpp:558] Agent hostname: 1fcdd0f5d33c
I0310 00:35:13.974540 30431 status_update_manager.cpp:177] Pausing sending status updates
I0310 00:35:13.975766 30446 state.cpp:62] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_AuthorizedRequest_2_02ujx3/meta'
I0310 00:35:13.976058 30438 status_update_manager.cpp:203] Recovering status update manager
I0310 00:35:13.976269 30445 containerizer.cpp:600] Recovering containerizer
I0310 00:35:13.977538 30446 provisioner.cpp:410] Provisioner recovery complete
I0310 00:35:13.977921 30441 slave.cpp:5564] Finished recovery
I0310 00:35:13.982424 30441 slave.cpp:5746] Querying resource estimator for oversubscribable resources
I0310 00:35:13.982669 30435 slave.cpp:5760] Received oversubscribable resources {} from the resource estimator
I0310 00:35:13.985178 30446 process.cpp:3705] Handling HTTP event for process 'slave(708)' with path: '/slave(708)/containers'
I0310 00:35:13.986476 30436 http.cpp:916] Authorizing principal 'test-principal' to GET the '/containers' endpoint
I0310 00:35:13.989838 30438 slave.cpp:808] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.AuthorizedRequest/2 (30 ms)
[ RUN ] Endpoint/SlaveEndpointTest.UnauthorizedRequest/0
I0310 00:35:14.006861 30412 containerizer.cpp:221] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W0310 00:35:14.007323 30412 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0310 00:35:14.007429 30412 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0310 00:35:14.007465 30412 provisioner.cpp:249] Using default backend 'copy'
I0310 00:35:14.010347 30445 slave.cpp:215] Mesos agent started on (709)@172.17.0.3:38901
I0310 00:35:14.010370 30445 slave.cpp:216] 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_y4EpIz/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_y4EpIz/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_y4EpIz/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.3.0/_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_y4EpIz" --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_ESPBr4"
I0310 00:35:14.010869 30445 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_y4EpIz/credential'
I0310 00:35:14.011041 30445 slave.cpp:358] Agent using credential for: test-principal
I0310 00:35:14.011061 30445 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_y4EpIz/http_credentials'
I0310 00:35:14.011268 30445 http.cpp:964] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0310 00:35:14.011407 30445 http.cpp:964] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0310 00:35:14.012564 30445 slave.cpp:545] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0310 00:35:14.012642 30445 slave.cpp:553] Agent attributes: [ ]
I0310 00:35:14.012655 30445 slave.cpp:558] Agent hostname: 1fcdd0f5d33c
I0310 00:35:14.012819 30439 status_update_manager.cpp:177] Pausing sending status updates
I0310 00:35:14.014039 30434 state.cpp:62] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_ESPBr4/meta'
I0310 00:35:14.014394 30439 status_update_manager.cpp:203] Recovering status update manager
I0310 00:35:14.014564 30433 containerizer.cpp:600] Recovering containerizer
I0310 00:35:14.015872 30435 provisioner.cpp:410] Provisioner recovery complete
I0310 00:35:14.016260 30443 slave.cpp:5564] Finished recovery
I0310 00:35:14.016786 30443 slave.cpp:5746] Querying resource estimator for oversubscribable resources
I0310 00:35:14.017035 30442 slave.cpp:5760] Received oversubscribable resources {} from the resource estimator
I0310 00:35:14.019611 30432 process.cpp:3705] Handling HTTP event for process 'slave(709)' with path: '/slave(709)/monitor/statistics'
I0310 00:35:14.020870 30437 http.cpp:916] Authorizing principal 'test-principal' to GET the '/monitor/statistics' endpoint
I0310 00:35:14.022986 30431 slave.cpp:808] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.UnauthorizedRequest/0 (25 ms)
[ RUN ] Endpoint/SlaveEndpointTest.UnauthorizedRequest/1
I0310 00:35:14.033738 30412 containerizer.cpp:221] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W0310 00:35:14.034342 30412 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0310 00:35:14.034437 30412 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0310 00:35:14.034471 30412 provisioner.cpp:249] Using default backend 'copy'
I0310 00:35:14.037073 30434 slave.cpp:215] Mesos agent started on (710)@172.17.0.3:38901
I0310 00:35:14.037093 30434 slave.cpp:216] 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_cvET1A/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_cvET1A/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_cvET1A/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.3.0/_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_cvET1A" --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_ka4PG5"
I0310 00:35:14.037752 30434 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_cvET1A/credential'
I0310 00:35:14.037945 30434 slave.cpp:358] Agent using credential for: test-principal
I0310 00:35:14.037966 30434 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_cvET1A/http_credentials'
I0310 00:35:14.038173 30434 http.cpp:964] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0310 00:35:14.038383 30434 http.cpp:964] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0310 00:35:14.039932 30434 slave.cpp:545] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0310 00:35:14.040032 30434 slave.cpp:553] Agent attributes: [ ]
I0310 00:35:14.040048 30434 slave.cpp:558] Agent hostname: 1fcdd0f5d33c
I0310 00:35:14.040218 30444 status_update_manager.cpp:177] Pausing sending status updates
I0310 00:35:14.041882 30442 state.cpp:62] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_ka4PG5/meta'
I0310 00:35:14.042238 30439 status_update_manager.cpp:203] Recovering status update manager
I0310 00:35:14.042497 30445 containerizer.cpp:600] Recovering containerizer
I0310 00:35:14.044118 30437 provisioner.cpp:410] Provisioner recovery complete
I0310 00:35:14.044553 30434 slave.cpp:5564] Finished recovery
I0310 00:35:14.045179 30434 slave.cpp:5746] Querying resource estimator for oversubscribable resources
I0310 00:35:14.045493 30431 slave.cpp:5760] Received oversubscribable resources {} from the resource estimator
I0310 00:35:14.048445 30433 process.cpp:3705] Handling HTTP event for process 'slave(710)' with path: '/slave(710)/monitor/statistics.json'
I0310 00:35:14.050024 30445 http.cpp:916] Authorizing principal 'test-principal' to GET the '/monitor/statistics.json' endpoint
I0310 00:35:14.052258 30445 slave.cpp:808] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.UnauthorizedRequest/1 (28 ms)
[ RUN ] Endpoint/SlaveEndpointTest.UnauthorizedRequest/2
I0310 00:35:14.060982 30412 containerizer.cpp:221] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W0310 00:35:14.061432 30412 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0310 00:35:14.061535 30412 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0310 00:35:14.061573 30412 provisioner.cpp:249] Using default backend 'copy'
I0310 00:35:14.064393 30437 slave.cpp:215] Mesos agent started on (711)@172.17.0.3:38901
I0310 00:35:14.064417 30437 slave.cpp:216] 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_82oJDC/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_82oJDC/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_82oJDC/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.3.0/_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_82oJDC" --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_CCZ7d7"
I0310 00:35:14.065114 30437 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_82oJDC/credential'
I0310 00:35:14.065320 30437 slave.cpp:358] Agent using credential for: test-principal
I0310 00:35:14.065346 30437 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_82oJDC/http_credentials'
I0310 00:35:14.065601 30437 http.cpp:964] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0310 00:35:14.065737 30437 http.cpp:964] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0310 00:35:14.066912 30437 slave.cpp:545] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0310 00:35:14.066990 30437 slave.cpp:553] Agent attributes: [ ]
I0310 00:35:14.067003 30437 slave.cpp:558] Agent hostname: 1fcdd0f5d33c
I0310 00:35:14.067134 30433 status_update_manager.cpp:177] Pausing sending status updates
I0310 00:35:14.068418 30444 state.cpp:62] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_CCZ7d7/meta'
I0310 00:35:14.068840 30443 status_update_manager.cpp:203] Recovering status update manager
I0310 00:35:14.069015 30434 containerizer.cpp:600] Recovering containerizer
I0310 00:35:14.070503 30437 provisioner.cpp:410] Provisioner recovery complete
I0310 00:35:14.070860 30440 slave.cpp:5564] Finished recovery
I0310 00:35:14.071382 30440 slave.cpp:5746] Querying resource estimator for oversubscribable resources
I0310 00:35:14.071709 30432 slave.cpp:5760] Received oversubscribable resources {} from the resource estimator
I0310 00:35:14.074300 30434 process.cpp:3705] Handling HTTP event for process 'slave(711)' with path: '/slave(711)/containers'
I0310 00:35:14.075562 30433 http.cpp:916] Authorizing principal 'test-principal' to GET the '/containers' endpoint
I0310 00:35:14.077550 30432 slave.cpp:808] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.UnauthorizedRequest/2 (24 ms)
[ RUN ] Endpoint/SlaveEndpointTest.NoAuthorizer/0
I0310 00:35:14.086712 30412 containerizer.cpp:221] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W0310 00:35:14.087182 30412 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0310 00:35:14.087285 30412 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0310 00:35:14.087328 30412 provisioner.cpp:249] Using default backend 'copy'
I0310 00:35:14.088743 30412 cluster.cpp:446] Creating default 'local' authorizer
I0310 00:35:14.090226 30444 slave.cpp:215] Mesos agent started on (712)@172.17.0.3:38901
I0310 00:35:14.090252 30444 slave.cpp:216] 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_u27dwE/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_u27dwE/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_u27dwE/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.3.0/_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_u27dwE" --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_Qfou28"
I0310 00:35:14.090976 30444 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_0_u27dwE/credential'
I0310 00:35:14.091204 30444 slave.cpp:358] Agent using credential for: test-principal
I0310 00:35:14.091229 30444 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_0_u27dwE/http_credentials'
I0310 00:35:14.091466 30444 http.cpp:964] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0310 00:35:14.091594 30444 http.cpp:964] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0310 00:35:14.092818 30444 slave.cpp:545] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0310 00:35:14.092895 30444 slave.cpp:553] Agent attributes: [ ]
I0310 00:35:14.092907 30444 slave.cpp:558] Agent hostname: 1fcdd0f5d33c
I0310 00:35:14.093039 30439 status_update_manager.cpp:177] Pausing sending status updates
I0310 00:35:14.094399 30434 state.cpp:62] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_0_Qfou28/meta'
I0310 00:35:14.094751 30432 status_update_manager.cpp:203] Recovering status update manager
I0310 00:35:14.094947 30433 containerizer.cpp:600] Recovering containerizer
I0310 00:35:14.096300 30438 provisioner.cpp:410] Provisioner recovery complete
I0310 00:35:14.096662 30435 slave.cpp:5564] Finished recovery
I0310 00:35:14.097201 30435 slave.cpp:5746] Querying resource estimator for oversubscribable resources
I0310 00:35:14.097443 30432 slave.cpp:5760] Received oversubscribable resources {} from the resource estimator
I0310 00:35:14.100028 30445 process.cpp:3705] Handling HTTP event for process 'slave(712)' with path: '/slave(712)/monitor/statistics'
I0310 00:35:14.101464 30440 http.cpp:916] Authorizing principal 'test-principal' to GET the '/monitor/statistics' endpoint
I0310 00:35:14.104619 30441 slave.cpp:808] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.NoAuthorizer/0 (27 ms)
[ RUN ] Endpoint/SlaveEndpointTest.NoAuthorizer/1
I0310 00:35:14.113991 30412 containerizer.cpp:221] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W0310 00:35:14.114475 30412 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0310 00:35:14.114581 30412 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0310 00:35:14.114622 30412 provisioner.cpp:249] Using default backend 'copy'
I0310 00:35:14.116073 30412 cluster.cpp:446] Creating default 'local' authorizer
I0310 00:35:14.117559 30439 slave.cpp:215] Mesos agent started on (713)@172.17.0.3:38901
I0310 00:35:14.117583 30439 slave.cpp:216] 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_A9ucGG/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_A9ucGG/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_A9ucGG/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.3.0/_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_A9ucGG" --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_O8a27a"
I0310 00:35:14.118121 30439 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_1_A9ucGG/credential'
I0310 00:35:14.118384 30439 slave.cpp:358] Agent using credential for: test-principal
I0310 00:35:14.118405 30439 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_1_A9ucGG/http_credentials'
I0310 00:35:14.118640 30439 http.cpp:964] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0310 00:35:14.118785 30439 http.cpp:964] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0310 00:35:14.120143 30439 slave.cpp:545] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0310 00:35:14.120221 30439 slave.cpp:553] Agent attributes: [ ]
I0310 00:35:14.120234 30439 slave.cpp:558] Agent hostname: 1fcdd0f5d33c
I0310 00:35:14.120367 30434 status_update_manager.cpp:177] Pausing sending status updates
I0310 00:35:14.121670 30445 state.cpp:62] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_1_O8a27a/meta'
I0310 00:35:14.121950 30441 status_update_manager.cpp:203] Recovering status update manager
I0310 00:35:14.122143 30442 containerizer.cpp:600] Recovering containerizer
I0310 00:35:14.123664 30446 provisioner.cpp:410] Provisioner recovery complete
I0310 00:35:14.124013 30438 slave.cpp:5564] Finished recovery
I0310 00:35:14.124533 30438 slave.cpp:5746] Querying resource estimator for oversubscribable resources
I0310 00:35:14.124789 30437 slave.cpp:5760] Received oversubscribable resources {} from the resource estimator
I0310 00:35:14.127441 30440 process.cpp:3705] Handling HTTP event for process 'slave(713)' with path: '/slave(713)/monitor/statistics.json'
I0310 00:35:14.128682 30437 http.cpp:916] Authorizing principal 'test-principal' to GET the '/monitor/statistics.json' endpoint
I0310 00:35:14.131664 30440 slave.cpp:808] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.NoAuthorizer/1 (27 ms)
[ RUN ] Endpoint/SlaveEndpointTest.NoAuthorizer/2
I0310 00:35:14.141121 30412 containerizer.cpp:221] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W0310 00:35:14.141611 30412 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0310 00:35:14.141726 30412 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0310 00:35:14.141765 30412 provisioner.cpp:249] Using default backend 'copy'
I0310 00:35:14.143326 30412 cluster.cpp:446] Creating default 'local' authorizer
I0310 00:35:14.144855 30443 slave.cpp:215] Mesos agent started on (714)@172.17.0.3:38901
I0310 00:35:14.144881 30443 slave.cpp:216] 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_snwR7I/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_snwR7I/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_snwR7I/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.3.0/_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_snwR7I" --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_YTvhvd"
I0310 00:35:14.145624 30443 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_2_snwR7I/credential'
I0310 00:35:14.145853 30443 slave.cpp:358] Agent using credential for: test-principal
I0310 00:35:14.145879 30443 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_2_snwR7I/http_credentials'
I0310 00:35:14.146113 30443 http.cpp:964] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0310 00:35:14.146349 30443 http.cpp:964] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0310 00:35:14.147559 30443 slave.cpp:545] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0310 00:35:14.147639 30443 slave.cpp:553] Agent attributes: [ ]
I0310 00:35:14.147657 30443 slave.cpp:558] Agent hostname: 1fcdd0f5d33c
I0310 00:35:14.147796 30445 status_update_manager.cpp:177] Pausing sending status updates
I0310 00:35:14.149058 30433 state.cpp:62] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_2_YTvhvd/meta'
I0310 00:35:14.149395 30438 status_update_manager.cpp:203] Recovering status update manager
I0310 00:35:14.149588 30431 containerizer.cpp:600] Recovering containerizer
I0310 00:35:14.150957 30432 provisioner.cpp:410] Provisioner recovery complete
I0310 00:35:14.151337 30434 slave.cpp:5564] Finished recovery
I0310 00:35:14.151897 30434 slave.cpp:5746] Querying resource estimator for oversubscribable resources
I0310 00:35:14.152148 30439 slave.cpp:5760] Received oversubscribable resources {} from the resource estimator
I0310 00:35:14.154652 30444 process.cpp:3705] Handling HTTP event for process 'slave(714)' with path: '/slave(714)/containers'
I0310 00:35:14.155936 30443 http.cpp:916] Authorizing principal 'test-principal' to GET the '/containers' endpoint
I0310 00:35:14.159044 30436 slave.cpp:808] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.NoAuthorizer/2 (27 ms)
[----------] 9 tests from Endpoint/SlaveEndpointTest (612 ms total)
[----------] Global test environment tear-down
[==========] 1516 tests from 169 test cases ran. (655001 ms total)
[ PASSED ] 1515 tests.
[ FAILED ] 1 test, listed below:
[ FAILED ] SlaveTest.StateEndpointUnavailableDuringRecovery
1 FAILED TEST
YOU HAVE 12 DISABLED TESTS
make[4]: *** [check-local] Error 1
make[4]: Leaving directory `/mesos/mesos-1.3.0/_build/src'
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/mesos/mesos-1.3.0/_build/src'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/mesos/mesos-1.3.0/_build/src'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-1.3.0/_build'
make: *** [distcheck] Error 1
+ docker rmi mesos-1489102221-19754
Untagged: mesos-1489102221-19754:latest
Deleted: sha256:ca2570c6260852038766b5399d9bcf5a5a50e36dab38223a2a23337caedea227
Deleted: sha256:604fbee6b737d17ba54ef01cd71cc1dd54e364c65dbb5bc556e14d251bedbd8a
Deleted: sha256:8785f20266446d11db4f0c7acb2827b9c979ec3e76b91ef74576f1b79431424f
Deleted: sha256:fe9d88f42df36f294a0da7f26587e74a989639c9c0740f87462eedaf96ae9d35
Deleted: sha256:e3a5b5be165c84faf3260c02b5e4af8579efb06bb7f8c623459ce6f5065bb7ef
Deleted: sha256:bd113e9f9e75f914f115f1051831328cc11bc78a69181448dc2a6439f6833667
Deleted: sha256:6510b5c81967683b122d0838d6802326629094d0226d5218d4d4728d98aa0093
Deleted: sha256:5be3b99f1078849f95bb7ff78aa0d8a2b8d572c56dbe813c7ff31c9ce15f7c16
Deleted: sha256:16a1ce73844316e9ebb30adffbcfa9c6258015de4cb37f14e675e6b7dfcbd293
Deleted: sha256:48ece8e55525cc6e1818b3de071396bda08153d783adb009abb0545ccd97426c
Deleted: sha256:feaf91c2625d4f7dd9439041329cc09735253ab8aa4078537872e7650ac0f998
Deleted: sha256:3f990229aa7e35817285ac9923e2808ce14155a42c1e13ae828136e113a8094c
Deleted: sha256:3182710f2382068837f7b4e0651267c7aa85ef5bb72db2b89d753346a5531653
Deleted: sha256:409e80988dbe6c5f000f57f58b20de79201bd9a19beb929113b0dc06f360261e
Deleted: sha256:ab6536c161614482dbf8d46d45a0d4c08f6d3fad48387144b826ec8cccec0d40
Deleted: sha256:bc9046155efee768eef3928f4133f1ce2f9f4d15f4e239c10fa61133aeec096e
Deleted: sha256:0740cad22489ff6d80a8ad24b4dda7c83fccb3b3e6a37d393804112417e075d2
Deleted: sha256:5604c80c1d68b0af28b36d9537ddd6bdf13332bcac3ede83f20a45546b54db54
Deleted: sha256:601ea51d2cd42c4405cf4dfaae35f73c0e8cc37e4a157efb9aa4cd92242782e3
Deleted: sha256:3040058bae462c559b42f18a2cfb8a098abfea094e992bed54e144faf7b4491e
Deleted: sha256:f3956ba32eae4de112d2f3e08fb6a906eabe951639ead65a9f561f59899d5098
Deleted: sha256:4bbe2eef03cd12550ab8e156be17951d2261bd72acf66fdbb71450b785cf2044
Deleted: sha256:87e38958fa127d25ac310033a83c036be912f115b9b2bf52a9732704a90a81fb
Deleted: sha256:1c247045c9e466b036f7aa03d37e8f2d35284750927b350df12b41a05deca200
Build step 'Execute shell' marked build as failure