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/07/20 00:20:12 UTC
Build failed in Jenkins: Mesos-Buildbot » cmake,clang,--verbose --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2) #3934
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=clang,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3934/display/redirect?page=changes>
Changes:
[yujie.jay] Set the `LIBPROCESS_IP` env variable before starting the fetcher.
[yujie.jay] Added MESOS-7796 to 1.3.1 CHANGELOG.
------------------------------------------
[...truncated 26.48 MB...]
I0720 00:20:05.617197 1738 slave.cpp:6257] Querying resource estimator for oversubscribable resources
I0720 00:20:05.617372 1738 slave.cpp:6271] Received oversubscribable resources {} from the resource estimator
I0720 00:20:05.618171 1741 process.cpp:3741] Handling HTTP event for process 'slave(743)' with path: '/slave(743)/containers'
I0720 00:20:05.618580 1741 http.cpp:1115] HTTP GET for /slave(743)/containers from 172.17.0.4:57482
I0720 00:20:05.618613 1741 http.cpp:936] Authorizing principal 'test-principal' to GET the '/containers' endpoint
I0720 00:20:05.619967 1742 slave.cpp:796] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.AuthorizedRequest/2 (13 ms)
[ RUN ] Endpoint/SlaveEndpointTest.UnauthorizedRequest/0
I0720 00:20:05.624541 26719 containerizer.cpp:221] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W0720 00:20:05.624788 26719 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0720 00:20:05.624822 26719 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0720 00:20:05.624835 26719 provisioner.cpp:249] Using default backend 'copy'
I0720 00:20:05.625905 1746 slave.cpp:231] Mesos agent started on (744)@172.17.0.4:34488
I0720 00:20:05.625967 1746 slave.cpp:232] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/mesos/store/appc" --authenticate_http_executors="true" --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_xDAn0w/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_reregistration_timeout="2secs" --executor_secret_key="/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_xDAn0w/executor_secret_key" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_xDAn0w/fetch" --fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks" --gc_disk_headroom="0.1" --hadoop_home="" --help="false" --hostname_lookup="true" --http_command_executor="false" --http_credentials="/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_xDAn0w/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/build/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --port="5051" --qos_correction_interval_min="0ns" --quiet="false" --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_xDAn0w" --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_RQ04Lx"
I0720 00:20:05.626296 1746 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_xDAn0w/credential'
I0720 00:20:05.626459 1746 slave.cpp:264] Agent using credential for: test-principal
I0720 00:20:05.626473 1746 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_xDAn0w/http_credentials'
I0720 00:20:05.626622 1746 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-executor'
I0720 00:20:05.626695 1746 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-executor'
I0720 00:20:05.626792 1746 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0720 00:20:05.626830 1746 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readonly'
I0720 00:20:05.626894 1746 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0720 00:20:05.626936 1746 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readwrite'
I0720 00:20:05.627590 1746 slave.cpp:531] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0720 00:20:05.627632 1746 slave.cpp:539] Agent attributes: [ ]
I0720 00:20:05.627640 1746 slave.cpp:544] Agent hostname: 291d5b00aa80
I0720 00:20:05.627702 1733 status_update_manager.cpp:177] Pausing sending status updates
I0720 00:20:05.628166 1755 state.cpp:62] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_RQ04Lx/meta'
I0720 00:20:05.628383 1755 status_update_manager.cpp:203] Recovering status update manager
I0720 00:20:05.628443 1755 containerizer.cpp:608] Recovering containerizer
I0720 00:20:05.628988 1755 provisioner.cpp:410] Provisioner recovery complete
I0720 00:20:05.629128 1755 slave.cpp:6075] Finished recovery
I0720 00:20:05.629633 1755 slave.cpp:6257] Querying resource estimator for oversubscribable resources
I0720 00:20:05.629820 1755 slave.cpp:6271] Received oversubscribable resources {} from the resource estimator
I0720 00:20:05.630589 1738 process.cpp:3741] Handling HTTP event for process 'slave(744)' with path: '/slave(744)/monitor/statistics'
I0720 00:20:05.631182 1738 http.cpp:1115] HTTP GET for /slave(744)/monitor/statistics from 172.17.0.4:57484
I0720 00:20:05.631220 1738 http.cpp:936] Authorizing principal 'test-principal' to GET the '/monitor/statistics' endpoint
I0720 00:20:05.632426 1734 slave.cpp:796] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.UnauthorizedRequest/0 (13 ms)
[ RUN ] Endpoint/SlaveEndpointTest.UnauthorizedRequest/1
I0720 00:20:05.639305 26719 containerizer.cpp:221] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W0720 00:20:05.639694 26719 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0720 00:20:05.639749 26719 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0720 00:20:05.639766 26719 provisioner.cpp:249] Using default backend 'copy'
I0720 00:20:05.641067 1736 slave.cpp:231] Mesos agent started on (745)@172.17.0.4:34488
I0720 00:20:05.641094 1736 slave.cpp:232] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/mesos/store/appc" --authenticate_http_executors="true" --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_Iwpy2t/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_reregistration_timeout="2secs" --executor_secret_key="/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_Iwpy2t/executor_secret_key" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_Iwpy2t/fetch" --fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks" --gc_disk_headroom="0.1" --hadoop_home="" --help="false" --hostname_lookup="true" --http_command_executor="false" --http_credentials="/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_Iwpy2t/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/build/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --port="5051" --qos_correction_interval_min="0ns" --quiet="false" --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_Iwpy2t" --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_AVF9Lu"
I0720 00:20:05.641310 1736 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_Iwpy2t/credential'
I0720 00:20:05.641386 1736 slave.cpp:264] Agent using credential for: test-principal
I0720 00:20:05.641398 1736 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_Iwpy2t/http_credentials'
I0720 00:20:05.641505 1736 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-executor'
I0720 00:20:05.641554 1736 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-executor'
I0720 00:20:05.641621 1736 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0720 00:20:05.641664 1736 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readonly'
I0720 00:20:05.641815 1736 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0720 00:20:05.641867 1736 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readwrite'
I0720 00:20:05.642376 1736 slave.cpp:531] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0720 00:20:05.642421 1736 slave.cpp:539] Agent attributes: [ ]
I0720 00:20:05.642427 1736 slave.cpp:544] Agent hostname: 291d5b00aa80
I0720 00:20:05.642858 1736 status_update_manager.cpp:177] Pausing sending status updates
I0720 00:20:05.643110 1736 state.cpp:62] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_AVF9Lu/meta'
I0720 00:20:05.643254 1736 status_update_manager.cpp:203] Recovering status update manager
I0720 00:20:05.643303 1736 containerizer.cpp:608] Recovering containerizer
I0720 00:20:05.643797 1736 provisioner.cpp:410] Provisioner recovery complete
I0720 00:20:05.643925 1736 slave.cpp:6075] Finished recovery
I0720 00:20:05.644361 1736 slave.cpp:6257] Querying resource estimator for oversubscribable resources
I0720 00:20:05.644551 1745 slave.cpp:6271] Received oversubscribable resources {} from the resource estimator
I0720 00:20:05.645615 1734 process.cpp:3741] Handling HTTP event for process 'slave(745)' with path: '/slave(745)/monitor/statistics.json'
I0720 00:20:05.646458 1748 http.cpp:1115] HTTP GET for /slave(745)/monitor/statistics.json from 172.17.0.4:57486
I0720 00:20:05.646493 1748 http.cpp:936] Authorizing principal 'test-principal' to GET the '/monitor/statistics.json' endpoint
I0720 00:20:05.647477 1734 slave.cpp:796] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.UnauthorizedRequest/1 (14 ms)
[ RUN ] Endpoint/SlaveEndpointTest.UnauthorizedRequest/2
I0720 00:20:05.654008 26719 containerizer.cpp:221] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W0720 00:20:05.654331 26719 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0720 00:20:05.654373 26719 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0720 00:20:05.654387 26719 provisioner.cpp:249] Using default backend 'copy'
I0720 00:20:05.655356 1738 slave.cpp:231] Mesos agent started on (746)@172.17.0.4:34488
I0720 00:20:05.655597 1738 slave.cpp:232] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/mesos/store/appc" --authenticate_http_executors="true" --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_8A3Gdr/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_reregistration_timeout="2secs" --executor_secret_key="/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_8A3Gdr/executor_secret_key" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_8A3Gdr/fetch" --fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks" --gc_disk_headroom="0.1" --hadoop_home="" --help="false" --hostname_lookup="true" --http_command_executor="false" --http_credentials="/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_8A3Gdr/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/build/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --port="5051" --qos_correction_interval_min="0ns" --quiet="false" --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_8A3Gdr" --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_3Eh3Ur"
I0720 00:20:05.656067 1738 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_8A3Gdr/credential'
I0720 00:20:05.656363 1738 slave.cpp:264] Agent using credential for: test-principal
I0720 00:20:05.656496 1738 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_8A3Gdr/http_credentials'
I0720 00:20:05.656791 1738 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-executor'
I0720 00:20:05.656981 1738 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-executor'
I0720 00:20:05.657223 1738 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0720 00:20:05.657389 1738 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readonly'
I0720 00:20:05.657887 1738 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0720 00:20:05.658098 1738 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readwrite'
I0720 00:20:05.658756 1738 slave.cpp:531] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0720 00:20:05.658956 1738 slave.cpp:539] Agent attributes: [ ]
I0720 00:20:05.659080 1738 slave.cpp:544] Agent hostname: 291d5b00aa80
I0720 00:20:05.659257 1755 status_update_manager.cpp:177] Pausing sending status updates
I0720 00:20:05.659690 1732 state.cpp:62] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_3Eh3Ur/meta'
I0720 00:20:05.659991 1750 status_update_manager.cpp:203] Recovering status update manager
I0720 00:20:05.660238 1742 containerizer.cpp:608] Recovering containerizer
I0720 00:20:05.660918 1742 provisioner.cpp:410] Provisioner recovery complete
I0720 00:20:05.661176 1742 slave.cpp:6075] Finished recovery
I0720 00:20:05.661466 1742 slave.cpp:6257] Querying resource estimator for oversubscribable resources
I0720 00:20:05.661576 1749 slave.cpp:6271] Received oversubscribable resources {} from the resource estimator
I0720 00:20:05.662314 1749 process.cpp:3741] Handling HTTP event for process 'slave(746)' with path: '/slave(746)/containers'
I0720 00:20:05.662866 1749 http.cpp:1115] HTTP GET for /slave(746)/containers from 172.17.0.4:57488
I0720 00:20:05.662914 1749 http.cpp:936] Authorizing principal 'test-principal' to GET the '/containers' endpoint
I0720 00:20:05.664649 1737 slave.cpp:796] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.UnauthorizedRequest/2 (16 ms)
[ RUN ] Endpoint/SlaveEndpointTest.NoAuthorizer/0
I0720 00:20:05.671586 26719 containerizer.cpp:221] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W0720 00:20:05.672430 26719 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0720 00:20:05.672636 26719 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0720 00:20:05.672778 26719 provisioner.cpp:249] Using default backend 'copy'
I0720 00:20:05.673738 26719 cluster.cpp:448] Creating default 'local' authorizer
I0720 00:20:05.674510 1754 slave.cpp:231] Mesos agent started on (747)@172.17.0.4:34488
I0720 00:20:05.674724 1754 slave.cpp:232] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/mesos/store/appc" --authenticate_http_executors="true" --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_Qry3yo/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_reregistration_timeout="2secs" --executor_secret_key="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_0_Qry3yo/executor_secret_key" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_0_Qry3yo/fetch" --fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks" --gc_disk_headroom="0.1" --hadoop_home="" --help="false" --hostname_lookup="true" --http_command_executor="false" --http_credentials="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_0_Qry3yo/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/build/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --port="5051" --qos_correction_interval_min="0ns" --quiet="false" --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_Qry3yo" --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_txJQdp"
I0720 00:20:05.675287 1754 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_0_Qry3yo/credential'
I0720 00:20:05.675539 1754 slave.cpp:264] Agent using credential for: test-principal
I0720 00:20:05.675683 1754 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_0_Qry3yo/http_credentials'
I0720 00:20:05.675981 1754 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-executor'
I0720 00:20:05.676177 1754 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-executor'
I0720 00:20:05.676407 1754 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0720 00:20:05.676631 1754 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readonly'
I0720 00:20:05.676861 1754 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0720 00:20:05.677037 1754 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readwrite'
I0720 00:20:05.677882 1754 slave.cpp:531] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0720 00:20:05.678056 1754 slave.cpp:539] Agent attributes: [ ]
I0720 00:20:05.678184 1754 slave.cpp:544] Agent hostname: 291d5b00aa80
I0720 00:20:05.678403 1739 status_update_manager.cpp:177] Pausing sending status updates
I0720 00:20:05.678905 1746 state.cpp:62] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_0_txJQdp/meta'
I0720 00:20:05.679219 1749 status_update_manager.cpp:203] Recovering status update manager
I0720 00:20:05.679281 1749 containerizer.cpp:608] Recovering containerizer
I0720 00:20:05.679944 1736 provisioner.cpp:410] Provisioner recovery complete
I0720 00:20:05.680349 1746 slave.cpp:6075] Finished recovery
I0720 00:20:05.680872 1746 slave.cpp:6257] Querying resource estimator for oversubscribable resources
I0720 00:20:05.681011 1746 slave.cpp:6271] Received oversubscribable resources {} from the resource estimator
I0720 00:20:05.681805 1740 process.cpp:3741] Handling HTTP event for process 'slave(747)' with path: '/slave(747)/monitor/statistics'
I0720 00:20:05.682577 1736 http.cpp:1115] HTTP GET for /slave(747)/monitor/statistics from 172.17.0.4:57490
I0720 00:20:05.682667 1736 http.cpp:936] Authorizing principal 'test-principal' to GET the '/monitor/statistics' endpoint
I0720 00:20:05.684576 1740 slave.cpp:796] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.NoAuthorizer/0 (20 ms)
[ RUN ] Endpoint/SlaveEndpointTest.NoAuthorizer/1
I0720 00:20:05.690963 26719 containerizer.cpp:221] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W0720 00:20:05.691190 26719 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0720 00:20:05.691239 26719 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0720 00:20:05.691253 26719 provisioner.cpp:249] Using default backend 'copy'
I0720 00:20:05.691884 26719 cluster.cpp:448] Creating default 'local' authorizer
I0720 00:20:05.692342 1741 slave.cpp:231] Mesos agent started on (748)@172.17.0.4:34488
I0720 00:20:05.692363 1741 slave.cpp:232] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/mesos/store/appc" --authenticate_http_executors="true" --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_Tjnh6l/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_reregistration_timeout="2secs" --executor_secret_key="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_1_Tjnh6l/executor_secret_key" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_1_Tjnh6l/fetch" --fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks" --gc_disk_headroom="0.1" --hadoop_home="" --help="false" --hostname_lookup="true" --http_command_executor="false" --http_credentials="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_1_Tjnh6l/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/build/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --port="5051" --qos_correction_interval_min="0ns" --quiet="false" --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_Tjnh6l" --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_9Lq6Hm"
I0720 00:20:05.692529 1741 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_1_Tjnh6l/credential'
I0720 00:20:05.692598 1741 slave.cpp:264] Agent using credential for: test-principal
I0720 00:20:05.692610 1741 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_1_Tjnh6l/http_credentials'
I0720 00:20:05.692714 1741 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-executor'
I0720 00:20:05.692752 1741 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-executor'
I0720 00:20:05.692816 1741 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0720 00:20:05.692888 1741 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readonly'
I0720 00:20:05.693048 1741 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0720 00:20:05.693092 1741 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readwrite'
I0720 00:20:05.693457 1741 slave.cpp:531] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0720 00:20:05.693486 1741 slave.cpp:539] Agent attributes: [ ]
I0720 00:20:05.693491 1741 slave.cpp:544] Agent hostname: 291d5b00aa80
I0720 00:20:05.693565 1748 status_update_manager.cpp:177] Pausing sending status updates
I0720 00:20:05.693858 1739 state.cpp:62] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_1_9Lq6Hm/meta'
I0720 00:20:05.694129 1747 status_update_manager.cpp:203] Recovering status update manager
I0720 00:20:05.694202 1747 containerizer.cpp:608] Recovering containerizer
I0720 00:20:05.694732 1747 provisioner.cpp:410] Provisioner recovery complete
I0720 00:20:05.694927 1746 slave.cpp:6075] Finished recovery
I0720 00:20:05.695416 1746 slave.cpp:6257] Querying resource estimator for oversubscribable resources
I0720 00:20:05.695503 1746 slave.cpp:6271] Received oversubscribable resources {} from the resource estimator
I0720 00:20:05.696276 1740 process.cpp:3741] Handling HTTP event for process 'slave(748)' with path: '/slave(748)/monitor/statistics.json'
I0720 00:20:05.696888 1750 http.cpp:1115] HTTP GET for /slave(748)/monitor/statistics.json from 172.17.0.4:57492
I0720 00:20:05.696924 1750 http.cpp:936] Authorizing principal 'test-principal' to GET the '/monitor/statistics.json' endpoint
I0720 00:20:05.698452 1735 slave.cpp:796] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.NoAuthorizer/1 (13 ms)
[ RUN ] Endpoint/SlaveEndpointTest.NoAuthorizer/2
I0720 00:20:05.704510 26719 containerizer.cpp:221] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W0720 00:20:05.704794 26719 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0720 00:20:05.704840 26719 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0720 00:20:05.704854 26719 provisioner.cpp:249] Using default backend 'copy'
I0720 00:20:05.705633 26719 cluster.cpp:448] Creating default 'local' authorizer
I0720 00:20:05.706516 1746 slave.cpp:231] Mesos agent started on (749)@172.17.0.4:34488
I0720 00:20:05.706538 1746 slave.cpp:232] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/mesos/store/appc" --authenticate_http_executors="true" --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_F3EBLj/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_reregistration_timeout="2secs" --executor_secret_key="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_2_F3EBLj/executor_secret_key" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_2_F3EBLj/fetch" --fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks" --gc_disk_headroom="0.1" --hadoop_home="" --help="false" --hostname_lookup="true" --http_command_executor="false" --http_credentials="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_2_F3EBLj/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/build/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --port="5051" --qos_correction_interval_min="0ns" --quiet="false" --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_F3EBLj" --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_bTjrlk"
I0720 00:20:05.706840 1746 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_2_F3EBLj/credential'
I0720 00:20:05.706926 1746 slave.cpp:264] Agent using credential for: test-principal
I0720 00:20:05.706938 1746 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_2_F3EBLj/http_credentials'
I0720 00:20:05.707069 1746 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-executor'
I0720 00:20:05.707111 1746 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-executor'
I0720 00:20:05.707207 1746 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0720 00:20:05.707314 1746 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readonly'
I0720 00:20:05.707478 1746 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0720 00:20:05.707556 1746 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readwrite'
I0720 00:20:05.707937 1746 slave.cpp:531] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0720 00:20:05.707964 1746 slave.cpp:539] Agent attributes: [ ]
I0720 00:20:05.707969 1746 slave.cpp:544] Agent hostname: 291d5b00aa80
I0720 00:20:05.708048 1733 status_update_manager.cpp:177] Pausing sending status updates
I0720 00:20:05.708312 1750 state.cpp:62] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_2_bTjrlk/meta'
I0720 00:20:05.708567 1741 status_update_manager.cpp:203] Recovering status update manager
I0720 00:20:05.708726 1748 containerizer.cpp:608] Recovering containerizer
I0720 00:20:05.709230 1737 provisioner.cpp:410] Provisioner recovery complete
I0720 00:20:05.709405 1755 slave.cpp:6075] Finished recovery
I0720 00:20:05.709802 1755 slave.cpp:6257] Querying resource estimator for oversubscribable resources
I0720 00:20:05.709988 1755 slave.cpp:6271] Received oversubscribable resources {} from the resource estimator
I0720 00:20:05.710775 1737 process.cpp:3741] Handling HTTP event for process 'slave(749)' with path: '/slave(749)/containers'
I0720 00:20:05.711398 1734 http.cpp:1115] HTTP GET for /slave(749)/containers from 172.17.0.4:57494
I0720 00:20:05.711427 1734 http.cpp:936] Authorizing principal 'test-principal' to GET the '/containers' endpoint
I0720 00:20:05.713389 1745 slave.cpp:796] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.NoAuthorizer/2 (14 ms)
[----------] 9 tests from Endpoint/SlaveEndpointTest (141 ms total)
[----------] Global test environment tear-down
[==========] 1528 tests from 169 test cases ran. (227304 ms total)
[ PASSED ] 1527 tests.
[ FAILED ] 1 test, listed below:
[ FAILED ] RegisterSlaveValidationTest.DropInvalidRegistration
1 FAILED TEST
YOU HAVE 22 DISABLED TESTS
E0720 00:20:05.763099 1756 process.cpp:956] Failed to accept socket: future discarded
make[3]: *** [CMakeFiles/check] Error 1
make[3]: Leaving directory `/mesos/build'
make[2]: Leaving directory `/mesos/build'
make[2]: *** [CMakeFiles/check.dir/all] Error 2
make[1]: *** [CMakeFiles/check.dir/rule] Error 2
make[1]: Leaving directory `/mesos/build'
make: *** [check] Error 2
+ docker rmi mesos-1500507738-1400
Untagged: mesos-1500507738-1400:latest
Deleted: sha256:e4bd475e2a30fecb9f351146b02c748a893655f76bd88edb94f3793f5d5d4d94
Deleted: sha256:e21e69b5dd13d093739d7c71cf22667bd35bea60758d579ae56ab5cef5c71909
Deleted: sha256:1e3f8d5911fafd39b77f91093835e345e13c84c9d8c29e54bc647ab77ba7201d
Deleted: sha256:4cd70fb0c94844e2525690771c2629c19ebbf9b9b03db474f5a2d4e1d500f9b2
Deleted: sha256:0704650411789d4a9c210eb139282b2394bfc767d643eb3c9eda0fb2c642eb0a
Deleted: sha256:84f53f4ca694d68dcf4f88a8a6de5625aceaff35f91319379ddef4f5340b2f89
Deleted: sha256:37e645390114075ff6ba7a6c96876ab83ec020838912e7ef78f665044a5508ed
Deleted: sha256:b91b8bb0601afaec2ea32a526a9ed52f97312822946c752f76c3fe8fed8aea14
Deleted: sha256:202b3b39f396eb1af4ec9ddaf14f51db3f13ca6b563cf9692895c19fac2b4452
Deleted: sha256:7489b639bd8fb0afdff15cc45144f718eb921f49d545acf96bce896e0fdc8a04
Deleted: sha256:74a4b789a5b3102725596c2677816b83a21ca27527c9e61784971ad6e9d57023
Deleted: sha256:3b12b28bfd90657082b2a39bffa747ba0d92ffe0b0599fc5226efaab2914bc90
Deleted: sha256:64da9c4996dd25e00542de3a70d8e0d0c6d3e7fba256ab8e42540afc0ad02d08
Deleted: sha256:4bf3b5257bbd3c82d23135d6d1f852f0286329cbf5e8f7e1fbcde1cf82b745d2
Deleted: sha256:aa11dc82d4c56c8f47c4a2459bbe7ce0263f5050a3d7309ef29bc8701c448187
Deleted: sha256:3ccc882a8d1e2e991f5865dc0c87f493f211497dc372f983f864a80e6e465df4
Deleted: sha256:69d7d08895f6f8e7909a796b7225430298b776c09644d2c0de1b145abab3de09
Deleted: sha256:3fabd2ff1f05989ebbcd93f8bcd1724bd121f2701ed1a51bf59b001b31c14a6b
Deleted: sha256:fc9a8ae6844f6aa5ba85f29eba7fec01133932bef4b2a4d3a836c88de0fc584c
Deleted: sha256:0e540f525cc1a4a1721dc196aa0439b050d80401ddee68b771001c79519836ff
Deleted: sha256:a19558416b87c78477d7a297706fe489cbcc44d966da6be736c2bd1ec25d9c78
Deleted: sha256:63a89cd7b2df1386a7e7c17488a666cd3ed36ea4faf7f98db9dab2fd27db1359
Deleted: sha256:c1eecbdc52a11ab36f8cffd8d62067877ef626ee3b4fd4accf317e6e35036bde
Deleted: sha256:2f09bfe56b9e093637b76a1d0c5a8d2e1c8e8d026b23ecbf7223c2316e083fc1
Build step 'Execute shell' marked build as failure
Not sending mail to unregistered user yujie.jay@gmail.com
Jenkins build is back to normal : Mesos-Buildbot » cmake,clang,--verbose --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2) #3936
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=clang,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3936/display/redirect?page=changes>
Build failed in Jenkins: Mesos-Buildbot » cmake,clang,--verbose --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2) #3935
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=clang,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3935/display/redirect?page=changes>
Changes:
[yujie.jay] Set the `LIBPROCESS_IP` env variable before starting the fetcher.
[yujie.jay] Added MESOS-7796 to 1.2.2 CHANGELOG.
------------------------------------------
[...truncated 11.93 MB...]
I0720 02:09:17.559423 25448 status_update_manager.cpp:500] Creating StatusUpdate stream for task 1 of framework 0c181e4c-4373-447f-9fda-731263d5f064-0000
I0720 02:09:17.559679 25448 status_update_manager.cpp:377] Forwarding update TASK_RUNNING (UUID: d491b036-2a8d-4e51-94c5-44f71a3d9cb1) for task 1 of framework 0c181e4c-4373-447f-9fda-731263d5f064-0000 to the agent
I0720 02:09:17.559783 25436 slave.cpp:4305] Forwarding the update TASK_RUNNING (UUID: d491b036-2a8d-4e51-94c5-44f71a3d9cb1) for task 1 of framework 0c181e4c-4373-447f-9fda-731263d5f064-0000 to master@172.17.0.5:41702
I0720 02:09:17.559870 25436 slave.cpp:4199] Status update manager successfully handled status update TASK_RUNNING (UUID: d491b036-2a8d-4e51-94c5-44f71a3d9cb1) for task 1 of framework 0c181e4c-4373-447f-9fda-731263d5f064-0000
I0720 02:09:17.559895 25436 slave.cpp:4215] Sending acknowledgement for status update TASK_RUNNING (UUID: d491b036-2a8d-4e51-94c5-44f71a3d9cb1) for task 1 of framework 0c181e4c-4373-447f-9fda-731263d5f064-0000 to executor(120)@172.17.0.5:41702
I0720 02:09:17.559955 25436 exec.cpp:388] Executor received status update acknowledgement d491b036-2a8d-4e51-94c5-44f71a3d9cb1 for task 1 of framework 0c181e4c-4373-447f-9fda-731263d5f064-0000
I0720 02:09:17.559959 25448 master.cpp:6190] Status update TASK_RUNNING (UUID: d491b036-2a8d-4e51-94c5-44f71a3d9cb1) for task 1 of framework 0c181e4c-4373-447f-9fda-731263d5f064-0000 from agent 0c181e4c-4373-447f-9fda-731263d5f064-S0 at slave(286)@172.17.0.5:41702 (76248a25c3f1)
I0720 02:09:17.559986 25448 master.cpp:6258] Forwarding status update TASK_RUNNING (UUID: d491b036-2a8d-4e51-94c5-44f71a3d9cb1) for task 1 of framework 0c181e4c-4373-447f-9fda-731263d5f064-0000
I0720 02:09:17.560050 25448 master.cpp:8338] Updating the state of task 1 of framework 0c181e4c-4373-447f-9fda-731263d5f064-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING)
I0720 02:09:17.560151 25436 sched.cpp:1041] Scheduler::statusUpdate took 59074ns
I0720 02:09:17.560258 25436 master.cpp:5092] Processing ACKNOWLEDGE call d491b036-2a8d-4e51-94c5-44f71a3d9cb1 for task 1 of framework 0c181e4c-4373-447f-9fda-731263d5f064-0000 (default) at scheduler-ecfdfcc7-3f48-4864-a534-4195e0c56553@172.17.0.5:41702 on agent 0c181e4c-4373-447f-9fda-731263d5f064-S0
I0720 02:09:17.563544 25439 status_update_manager.cpp:395] Received status update acknowledgement (UUID: d491b036-2a8d-4e51-94c5-44f71a3d9cb1) for task 1 of framework 0c181e4c-4373-447f-9fda-731263d5f064-0000
I0720 02:09:17.563680 25440 slave.cpp:3111] Status update manager successfully handled status update acknowledgement (UUID: d491b036-2a8d-4e51-94c5-44f71a3d9cb1) for task 1 of framework 0c181e4c-4373-447f-9fda-731263d5f064-0000
I0720 02:09:17.564168 25432 exec.cpp:416] Executor received framework message
I0720 02:09:17.564215 25432 exec.cpp:425] Executor::frameworkMessage took 34630ns
I0720 02:09:17.566720 25434 slave.cpp:4373] Sending message for framework 0c181e4c-4373-447f-9fda-731263d5f064-0000 to scheduler-ecfdfcc7-3f48-4864-a534-4195e0c56553@172.17.0.5:41702
I0720 02:09:17.566850 25452 sched.cpp:1177] Scheduler::frameworkMessage took 37300ns
I0720 02:09:17.567066 25428 sched.cpp:2021] Asked to stop the driver
I0720 02:09:17.567147 25451 sched.cpp:1203] Stopping framework 0c181e4c-4373-447f-9fda-731263d5f064-0000
I0720 02:09:17.567276 25439 master.cpp:7741] Processing TEARDOWN call for framework 0c181e4c-4373-447f-9fda-731263d5f064-0000 (default) at scheduler-ecfdfcc7-3f48-4864-a534-4195e0c56553@172.17.0.5:41702
I0720 02:09:17.567304 25439 master.cpp:7753] Removing framework 0c181e4c-4373-447f-9fda-731263d5f064-0000 (default) at scheduler-ecfdfcc7-3f48-4864-a534-4195e0c56553@172.17.0.5:41702
I0720 02:09:17.567351 25439 master.cpp:3081] Deactivating framework 0c181e4c-4373-447f-9fda-731263d5f064-0000 (default) at scheduler-ecfdfcc7-3f48-4864-a534-4195e0c56553@172.17.0.5:41702
I0720 02:09:17.567441 25439 master.cpp:8338] Updating the state of task 1 of framework 0c181e4c-4373-447f-9fda-731263d5f064-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED)
I0720 02:09:17.567456 25435 hierarchical.cpp:415] Deactivated framework 0c181e4c-4373-447f-9fda-731263d5f064-0000
I0720 02:09:17.567592 25443 slave.cpp:2664] Asked to shut down framework 0c181e4c-4373-447f-9fda-731263d5f064-0000 by master@172.17.0.5:41702
I0720 02:09:17.567621 25443 slave.cpp:2689] Shutting down framework 0c181e4c-4373-447f-9fda-731263d5f064-0000
I0720 02:09:17.567647 25443 slave.cpp:5131] Shutting down executor 'default' of framework 0c181e4c-4373-447f-9fda-731263d5f064-0000 at executor(120)@172.17.0.5:41702
I0720 02:09:17.567801 25443 exec.cpp:435] Executor asked to shutdown
I0720 02:09:17.567831 25443 exec.cpp:450] Executor::shutdown took 21852ns
I0720 02:09:17.567819 25439 master.cpp:8432] Removing task 1 with resources cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] of framework 0c181e4c-4373-447f-9fda-731263d5f064-0000 on agent 0c181e4c-4373-447f-9fda-731263d5f064-S0 at slave(286)@172.17.0.5:41702 (76248a25c3f1)
I0720 02:09:17.567910 25449 slave.cpp:4437] Got exited event for executor(120)@172.17.0.5:41702
I0720 02:09:17.567935 25439 master.cpp:8461] Removing executor 'default' with resources {} of framework 0c181e4c-4373-447f-9fda-731263d5f064-0000 on agent 0c181e4c-4373-447f-9fda-731263d5f064-S0 at slave(286)@172.17.0.5:41702 (76248a25c3f1)
I0720 02:09:17.568056 25434 hierarchical.cpp:1064] Recovered cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: {}) on agent 0c181e4c-4373-447f-9fda-731263d5f064-S0 from framework 0c181e4c-4373-447f-9fda-731263d5f064-0000
I0720 02:09:17.568188 25434 hierarchical.cpp:362] Removed framework 0c181e4c-4373-447f-9fda-731263d5f064-0000
I0720 02:09:17.569351 25445 slave.cpp:4817] Executor 'default' of framework 0c181e4c-4373-447f-9fda-731263d5f064-0000 exited with status 0
I0720 02:09:17.569409 25445 slave.cpp:4917] Cleaning up executor 'default' of framework 0c181e4c-4373-447f-9fda-731263d5f064-0000 at executor(120)@172.17.0.5:41702
I0720 02:09:17.569589 25443 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_kDKDtd/slaves/0c181e4c-4373-447f-9fda-731263d5f064-S0/frameworks/0c181e4c-4373-447f-9fda-731263d5f064-0000/executors/default/runs/dc080f4a-989a-4076-a2c9-24cddb2123ca' for gc 6.99999340806815days in the future
I0720 02:09:17.569638 25445 slave.cpp:5005] Cleaning up framework 0c181e4c-4373-447f-9fda-731263d5f064-0000
W0720 02:09:17.569689 25434 master.cpp:6314] Ignoring unknown exited executor 'default' of framework 0c181e4c-4373-447f-9fda-731263d5f064-0000 on agent 0c181e4c-4373-447f-9fda-731263d5f064-S0 at slave(286)@172.17.0.5:41702 (76248a25c3f1)
I0720 02:09:17.569705 25450 status_update_manager.cpp:285] Closing status update streams for framework 0c181e4c-4373-447f-9fda-731263d5f064-0000
I0720 02:09:17.569722 25450 status_update_manager.cpp:531] Cleaning up status update stream for task 1 of framework 0c181e4c-4373-447f-9fda-731263d5f064-0000
I0720 02:09:17.569828 25443 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_kDKDtd/slaves/0c181e4c-4373-447f-9fda-731263d5f064-S0/frameworks/0c181e4c-4373-447f-9fda-731263d5f064-0000/executors/default' for gc 6.99999340715259days in the future
I0720 02:09:17.569864 25443 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_kDKDtd/slaves/0c181e4c-4373-447f-9fda-731263d5f064-S0/frameworks/0c181e4c-4373-447f-9fda-731263d5f064-0000' for gc 6.99999340614518days in the future
I0720 02:09:17.570025 25428 slave.cpp:809] Agent terminating
I0720 02:09:17.570113 25451 master.cpp:1264] Agent 0c181e4c-4373-447f-9fda-731263d5f064-S0 at slave(286)@172.17.0.5:41702 (76248a25c3f1) disconnected
I0720 02:09:17.570140 25451 master.cpp:3118] Disconnecting agent 0c181e4c-4373-447f-9fda-731263d5f064-S0 at slave(286)@172.17.0.5:41702 (76248a25c3f1)
I0720 02:09:17.570166 25451 master.cpp:3137] Deactivating agent 0c181e4c-4373-447f-9fda-731263d5f064-S0 at slave(286)@172.17.0.5:41702 (76248a25c3f1)
I0720 02:09:17.570219 25435 hierarchical.cpp:618] Agent 0c181e4c-4373-447f-9fda-731263d5f064-S0 deactivated
I0720 02:09:17.572155 25442 master.cpp:1103] Master terminating
I0720 02:09:17.572710 25447 hierarchical.cpp:549] Removed agent 0c181e4c-4373-447f-9fda-731263d5f064-S0
[ OK ] MasterTest.FrameworkMessage (45 ms)
[ RUN ] MasterTest.MultipleExecutors
I0720 02:09:17.576166 25428 cluster.cpp:160] Creating default 'local' authorizer
I0720 02:09:17.577409 25442 master.cpp:383] Master 02d8fc1a-69af-40ed-884c-54a54267d429 (76248a25c3f1) started on 172.17.0.5:41702
I0720 02:09:17.577452 25442 master.cpp:385] 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/7vXFtf/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" --port="5050" --quiet="false" --recovery_agent_removal_limit="100%" --registry="in_memory" --registry_fetch_timeout="1mins" --registry_gc_interval="15mins" --registry_max_agent_age="2weeks" --registry_max_agent_count="102400" --registry_store_timeout="100secs" --registry_strict="false" --root_submissions="true" --user_sorter="drf" --version="false" --webui_dir="/usr/local/share/mesos/webui" --work_dir="/tmp/7vXFtf/master" --zk_session_timeout="10secs"
I0720 02:09:17.577735 25442 master.cpp:435] Master only allowing authenticated frameworks to register
I0720 02:09:17.577742 25442 master.cpp:449] Master only allowing authenticated agents to register
I0720 02:09:17.577747 25442 master.cpp:462] Master only allowing authenticated HTTP frameworks to register
I0720 02:09:17.577752 25442 credentials.hpp:37] Loading credentials for authentication from '/tmp/7vXFtf/credentials'
I0720 02:09:17.577947 25442 master.cpp:507] Using default 'crammd5' authenticator
I0720 02:09:17.578002 25442 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I0720 02:09:17.578068 25442 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I0720 02:09:17.578348 25442 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I0720 02:09:17.578482 25442 master.cpp:587] Authorization enabled
I0720 02:09:17.582473 25444 hierarchical.cpp:161] Initialized hierarchical allocator process
I0720 02:09:17.582517 25444 whitelist_watcher.cpp:77] No whitelist given
I0720 02:09:17.583349 25434 master.cpp:2124] Elected as the leading master!
I0720 02:09:17.583375 25434 master.cpp:1646] Recovering from registrar
I0720 02:09:17.583474 25435 registrar.cpp:329] Recovering registrar
I0720 02:09:17.583804 25435 registrar.cpp:362] Successfully fetched the registry (0B) in 306944ns
I0720 02:09:17.583847 25435 registrar.cpp:461] Applied 1 operations in 10971ns; attempting to update the registry
I0720 02:09:17.584079 25444 registrar.cpp:506] Successfully updated the registry in 208128ns
I0720 02:09:17.584190 25444 registrar.cpp:392] Successfully recovered registrar
I0720 02:09:17.584640 25442 hierarchical.cpp:188] Skipping recovery of hierarchical allocator: nothing to recover
I0720 02:09:17.584702 25446 master.cpp:1762] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
I0720 02:09:17.586899 25428 cluster.cpp:446] Creating default 'local' authorizer
I0720 02:09:17.587484 25441 slave.cpp:217] Mesos agent started on (287)@172.17.0.5:41702
I0720 02:09:17.587519 25441 slave.cpp:218] 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/MasterTest_MultipleExecutors_fEWdEL/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_reregistration_timeout="2secs" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/MasterTest_MultipleExecutors_fEWdEL/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/MasterTest_MultipleExecutors_fEWdEL/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/build/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --port="5051" --qos_correction_interval_min="0ns" --quiet="false" --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/MasterTest_MultipleExecutors_fEWdEL" --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/MasterTest_MultipleExecutors_N3pWy0"
I0720 02:09:17.587806 25441 credentials.hpp:86] Loading credential for authentication from '/tmp/MasterTest_MultipleExecutors_fEWdEL/credential'
I0720 02:09:17.587908 25441 slave.cpp:360] Agent using credential for: test-principal
I0720 02:09:17.587919 25441 credentials.hpp:37] Loading credentials for authentication from '/tmp/MasterTest_MultipleExecutors_fEWdEL/http_credentials'
I0720 02:09:17.588019 25441 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0720 02:09:17.588143 25441 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0720 02:09:17.588217 25428 sched.cpp:232] Version: 1.2.2
I0720 02:09:17.588482 25447 sched.cpp:336] New master detected at master@172.17.0.5:41702
I0720 02:09:17.588520 25447 sched.cpp:407] Authenticating with master master@172.17.0.5:41702
I0720 02:09:17.588531 25447 sched.cpp:414] Using default CRAM-MD5 authenticatee
I0720 02:09:17.588632 25434 authenticatee.cpp:121] Creating new client SASL connection
I0720 02:09:17.588718 25441 slave.cpp:547] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0720 02:09:17.588769 25441 slave.cpp:555] Agent attributes: [ ]
I0720 02:09:17.588778 25441 slave.cpp:560] Agent hostname: 76248a25c3f1
I0720 02:09:17.588834 25433 master.cpp:7199] Authenticating scheduler-cb89f8fa-8345-4832-ae94-5ba09a4d3512@172.17.0.5:41702
I0720 02:09:17.588893 25434 status_update_manager.cpp:177] Pausing sending status updates
I0720 02:09:17.588935 25439 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(635)@172.17.0.5:41702
I0720 02:09:17.589004 25452 authenticator.cpp:98] Creating new server SASL connection
I0720 02:09:17.589408 25445 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0720 02:09:17.589453 25445 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0720 02:09:17.589507 25445 authenticator.cpp:204] Received SASL authentication start
I0720 02:09:17.589582 25445 authenticator.cpp:326] Authentication requires more steps
I0720 02:09:17.589668 25443 state.cpp:62] Recovering state from '/tmp/MasterTest_MultipleExecutors_N3pWy0/meta'
I0720 02:09:17.589805 25441 status_update_manager.cpp:203] Recovering status update manager
I0720 02:09:17.589817 25446 authenticatee.cpp:259] Received SASL authentication step
I0720 02:09:17.589906 25436 authenticator.cpp:232] Received SASL authentication step
I0720 02:09:17.589928 25436 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '76248a25c3f1' server FQDN: '76248a25c3f1' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0720 02:09:17.589938 25436 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0720 02:09:17.589953 25436 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0720 02:09:17.589963 25436 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '76248a25c3f1' server FQDN: '76248a25c3f1' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0720 02:09:17.589970 25436 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0720 02:09:17.589977 25436 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0720 02:09:17.589992 25436 authenticator.cpp:318] Authentication success
I0720 02:09:17.590008 25446 slave.cpp:5662] Finished recovery
I0720 02:09:17.590044 25451 authenticatee.cpp:299] Authentication success
I0720 02:09:17.590109 25444 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(635)@172.17.0.5:41702
I0720 02:09:17.590133 25438 master.cpp:7229] Successfully authenticated principal 'test-principal' at scheduler-cb89f8fa-8345-4832-ae94-5ba09a4d3512@172.17.0.5:41702
I0720 02:09:17.590320 25434 sched.cpp:513] Successfully authenticated with master master@172.17.0.5:41702
I0720 02:09:17.590332 25434 sched.cpp:836] Sending SUBSCRIBE call to master@172.17.0.5:41702
I0720 02:09:17.598166 25446 slave.cpp:5836] Querying resource estimator for oversubscribable resources
I0720 02:09:17.598233 25438 master.cpp:2737] Received SUBSCRIBE call for framework 'default' at scheduler-cb89f8fa-8345-4832-ae94-5ba09a4d3512@172.17.0.5:41702
I0720 02:09:17.598265 25438 master.cpp:2160] Authorizing framework principal 'test-principal' to receive offers for role '*'
I0720 02:09:17.598162 25434 sched.cpp:869] Will retry registration in 1.309098315secs if necessary
I0720 02:09:17.598460 25433 master.cpp:2813] Subscribing framework default with checkpointing disabled and capabilities [ ]
I0720 02:09:17.598675 25433 sched.cpp:759] Framework registered with 02d8fc1a-69af-40ed-884c-54a54267d429-0000
I0720 02:09:17.598681 25440 hierarchical.cpp:286] Added framework 02d8fc1a-69af-40ed-884c-54a54267d429-0000
I0720 02:09:17.598709 25433 sched.cpp:773] Scheduler::registered took 22165ns
I0720 02:09:17.598733 25440 hierarchical.cpp:1810] No allocations performed
I0720 02:09:17.598752 25440 hierarchical.cpp:1900] No inverse offers to send out!
I0720 02:09:17.598767 25440 hierarchical.cpp:1394] Performed allocation for 0 agents in 49757ns
I0720 02:09:17.598773 25446 slave.cpp:937] New master detected at master@172.17.0.5:41702
I0720 02:09:17.598795 25439 status_update_manager.cpp:177] Pausing sending status updates
I0720 02:09:17.598817 25446 slave.cpp:972] Detecting new master
I0720 02:09:17.598878 25446 slave.cpp:5850] Received oversubscribable resources {} from the resource estimator
I0720 02:09:17.607023 25432 slave.cpp:999] Authenticating with master master@172.17.0.5:41702
I0720 02:09:17.607054 25432 slave.cpp:1010] Using default CRAM-MD5 authenticatee
I0720 02:09:17.607148 25432 authenticatee.cpp:121] Creating new client SASL connection
I0720 02:09:17.607322 25432 master.cpp:7199] Authenticating slave(287)@172.17.0.5:41702
I0720 02:09:17.607378 25439 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(636)@172.17.0.5:41702
I0720 02:09:17.607497 25442 authenticator.cpp:98] Creating new server SASL connection
I0720 02:09:17.607744 25442 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0720 02:09:17.607769 25442 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0720 02:09:17.607802 25442 authenticator.cpp:204] Received SASL authentication start
I0720 02:09:17.607851 25442 authenticator.cpp:326] Authentication requires more steps
I0720 02:09:17.607880 25442 authenticatee.cpp:259] Received SASL authentication step
I0720 02:09:17.607918 25442 authenticator.cpp:232] Received SASL authentication step
I0720 02:09:17.607934 25442 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '76248a25c3f1' server FQDN: '76248a25c3f1' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0720 02:09:17.607944 25442 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0720 02:09:17.607959 25442 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0720 02:09:17.607969 25442 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '76248a25c3f1' server FQDN: '76248a25c3f1' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0720 02:09:17.607975 25442 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0720 02:09:17.607981 25442 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0720 02:09:17.607993 25442 authenticator.cpp:318] Authentication success
I0720 02:09:17.608038 25442 authenticatee.cpp:299] Authentication success
I0720 02:09:17.608069 25442 master.cpp:7229] Successfully authenticated principal 'test-principal' at slave(287)@172.17.0.5:41702
I0720 02:09:17.608101 25442 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(636)@172.17.0.5:41702
I0720 02:09:17.608192 25442 slave.cpp:1094] Successfully authenticated with master master@172.17.0.5:41702
I0720 02:09:17.608255 25442 slave.cpp:1521] Will retry registration in 18.395999ms if necessary
I0720 02:09:17.608399 25442 master.cpp:5396] Registering agent at slave(287)@172.17.0.5:41702 (76248a25c3f1) with id 02d8fc1a-69af-40ed-884c-54a54267d429-S0
I0720 02:09:17.608551 25442 registrar.cpp:461] Applied 1 operations in 26126ns; attempting to update the registry
I0720 02:09:17.608968 25442 registrar.cpp:506] Successfully updated the registry in 393984ns
I0720 02:09:17.609203 25442 master.cpp:5470] Registered agent 02d8fc1a-69af-40ed-884c-54a54267d429-S0 at slave(287)@172.17.0.5:41702 (76248a25c3f1) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0720 02:09:17.609344 25442 hierarchical.cpp:516] Added agent 02d8fc1a-69af-40ed-884c-54a54267d429-S0 (76248a25c3f1) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
I0720 02:09:17.609643 25442 hierarchical.cpp:1900] No inverse offers to send out!
I0720 02:09:17.609683 25442 hierarchical.cpp:1394] Performed allocation for 1 agents in 298529ns
I0720 02:09:17.609732 25442 slave.cpp:1140] Registered with master master@172.17.0.5:41702; given agent ID 02d8fc1a-69af-40ed-884c-54a54267d429-S0
I0720 02:09:17.609855 25442 fetcher.cpp:94] Clearing fetcher cache
I0720 02:09:17.610047 25432 master.cpp:7028] Sending 1 offers to framework 02d8fc1a-69af-40ed-884c-54a54267d429-0000 (default) at scheduler-cb89f8fa-8345-4832-ae94-5ba09a4d3512@172.17.0.5:41702
I0720 02:09:17.610286 25442 slave.cpp:1168] Checkpointing SlaveInfo to '/tmp/MasterTest_MultipleExecutors_N3pWy0/meta/slaves/02d8fc1a-69af-40ed-884c-54a54267d429-S0/slave.info'
I0720 02:09:17.610416 25432 sched.cpp:933] Scheduler::resourceOffers took 54282ns
I0720 02:09:17.610155 25431 status_update_manager.cpp:184] Resuming sending status updates
I0720 02:09:17.610656 25442 slave.cpp:1206] Forwarding total oversubscribed resources {}
I0720 02:09:17.610855 25442 slave.cpp:4395] Received ping from slave-observer(280)@172.17.0.5:41702
I0720 02:09:17.610896 25439 master.cpp:6045] Received update of agent 02d8fc1a-69af-40ed-884c-54a54267d429-S0 at slave(287)@172.17.0.5:41702 (76248a25c3f1) with total oversubscribed resources {}
I0720 02:09:17.611249 25429 hierarchical.cpp:589] Agent 02d8fc1a-69af-40ed-884c-54a54267d429-S0 (76248a25c3f1) updated with oversubscribed resources {} (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000])
I0720 02:09:17.611347 25429 hierarchical.cpp:1810] No allocations performed
I0720 02:09:17.611361 25429 hierarchical.cpp:1900] No inverse offers to send out!
I0720 02:09:17.611373 25429 hierarchical.cpp:1394] Performed allocation for 1 agents in 54247ns
I0720 02:09:17.611572 25439 master.cpp:3776] Processing ACCEPT call for offers: [ 02d8fc1a-69af-40ed-884c-54a54267d429-O0 ] on agent 02d8fc1a-69af-40ed-884c-54a54267d429-S0 at slave(287)@172.17.0.5:41702 (76248a25c3f1) for framework 02d8fc1a-69af-40ed-884c-54a54267d429-0000 (default) at scheduler-cb89f8fa-8345-4832-ae94-5ba09a4d3512@172.17.0.5:41702
I0720 02:09:17.611624 25439 master.cpp:3352] Authorizing framework principal 'test-principal' to launch task 1
I0720 02:09:17.611776 25439 master.cpp:3352] Authorizing framework principal 'test-principal' to launch task 2
W0720 02:09:17.612421 25442 validation.cpp:1064] Executor 'executor-1' for task '1' uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases.
W0720 02:09:17.612444 25442 validation.cpp:1076] Executor 'executor-1' for task '1' uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases.
I0720 02:09:17.612555 25442 master.cpp:9089] Adding task 1 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent 02d8fc1a-69af-40ed-884c-54a54267d429-S0 at slave(287)@172.17.0.5:41702 (76248a25c3f1)
I0720 02:09:17.612622 25442 master.cpp:4426] Launching task 1 of framework 02d8fc1a-69af-40ed-884c-54a54267d429-0000 (default) at scheduler-cb89f8fa-8345-4832-ae94-5ba09a4d3512@172.17.0.5:41702 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent 02d8fc1a-69af-40ed-884c-54a54267d429-S0 at slave(287)@172.17.0.5:41702 (76248a25c3f1)
I0720 02:09:17.612824 25449 slave.cpp:1631] Got assigned task '1' for framework 02d8fc1a-69af-40ed-884c-54a54267d429-0000
W0720 02:09:17.612975 25442 validation.cpp:1064] Executor 'executor-2' for task '2' uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases.
W0720 02:09:17.612994 25442 validation.cpp:1076] Executor 'executor-2' for task '2' uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases.
I0720 02:09:17.613083 25442 master.cpp:9089] Adding task 2 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent 02d8fc1a-69af-40ed-884c-54a54267d429-S0 at slave(287)@172.17.0.5:41702 (76248a25c3f1)
I0720 02:09:17.613142 25442 master.cpp:4426] Launching task 2 of framework 02d8fc1a-69af-40ed-884c-54a54267d429-0000 (default) at scheduler-cb89f8fa-8345-4832-ae94-5ba09a4d3512@172.17.0.5:41702 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent 02d8fc1a-69af-40ed-884c-54a54267d429-S0 at slave(287)@172.17.0.5:41702 (76248a25c3f1)
I0720 02:09:17.613184 25449 slave.cpp:1791] Launching task '1' for framework 02d8fc1a-69af-40ed-884c-54a54267d429-0000
I0720 02:09:17.613854 25442 hierarchical.cpp:807] Updated allocation of framework 02d8fc1a-69af-40ed-884c-54a54267d429-0000 on agent 02d8fc1a-69af-40ed-884c-54a54267d429-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]
I0720 02:09:17.614004 25449 paths.cpp:547] Trying to chown '/tmp/MasterTest_MultipleExecutors_N3pWy0/slaves/02d8fc1a-69af-40ed-884c-54a54267d429-S0/frameworks/02d8fc1a-69af-40ed-884c-54a54267d429-0000/executors/executor-1/runs/7e4c403b-1deb-4a20-aac9-c95ad889e983' to user 'mesos'
I0720 02:09:17.614030 25442 hierarchical.cpp:1064] Recovered ports(*)(allocated: *):[31000-32000]; disk(*)(allocated: *):1024 (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: cpus(*)(allocated: *):2; mem(*)(allocated: *):1024) on agent 02d8fc1a-69af-40ed-884c-54a54267d429-S0 from framework 02d8fc1a-69af-40ed-884c-54a54267d429-0000
I0720 02:09:17.614068 25442 hierarchical.cpp:1101] Framework 02d8fc1a-69af-40ed-884c-54a54267d429-0000 filtered agent 02d8fc1a-69af-40ed-884c-54a54267d429-S0 for 5secs
I0720 02:09:17.614202 25449 slave.cpp:6584] Launching executor 'executor-1' of framework 02d8fc1a-69af-40ed-884c-54a54267d429-0000 with resources {} in work directory '/tmp/MasterTest_MultipleExecutors_N3pWy0/slaves/02d8fc1a-69af-40ed-884c-54a54267d429-S0/frameworks/02d8fc1a-69af-40ed-884c-54a54267d429-0000/executors/executor-1/runs/7e4c403b-1deb-4a20-aac9-c95ad889e983'
I0720 02:09:17.614411 25449 slave.cpp:2124] Queued task '1' for executor 'executor-1' of framework 02d8fc1a-69af-40ed-884c-54a54267d429-0000
I0720 02:09:17.614480 25449 slave.cpp:1631] Got assigned task '2' for framework 02d8fc1a-69af-40ed-884c-54a54267d429-0000
I0720 02:09:17.614665 25449 slave.cpp:890] Successfully attached file '/tmp/MasterTest_MultipleExecutors_N3pWy0/slaves/02d8fc1a-69af-40ed-884c-54a54267d429-S0/frameworks/02d8fc1a-69af-40ed-884c-54a54267d429-0000/executors/executor-1/runs/7e4c403b-1deb-4a20-aac9-c95ad889e983'
I0720 02:09:17.614696 25449 slave.cpp:1791] Launching task '2' for framework 02d8fc1a-69af-40ed-884c-54a54267d429-0000
I0720 02:09:17.614967 25449 paths.cpp:547] Trying to chown '/tmp/MasterTest_MultipleExecutors_N3pWy0/slaves/02d8fc1a-69af-40ed-884c-54a54267d429-S0/frameworks/02d8fc1a-69af-40ed-884c-54a54267d429-0000/executors/executor-2/runs/4837b2f8-5d6b-4b5f-a8b1-02820ce7751e' to user 'mesos'
I0720 02:09:17.615119 25449 slave.cpp:6584] Launching executor 'executor-2' of framework 02d8fc1a-69af-40ed-884c-54a54267d429-0000 with resources {} in work directory '/tmp/MasterTest_MultipleExecutors_N3pWy0/slaves/02d8fc1a-69af-40ed-884c-54a54267d429-S0/frameworks/02d8fc1a-69af-40ed-884c-54a54267d429-0000/executors/executor-2/runs/4837b2f8-5d6b-4b5f-a8b1-02820ce7751e'
*** Aborted at 1500516557 (unix time) try "date -d @1500516557" if you are using GNU date ***
I0720 02:09:17.615371 25445 exec.cpp:162] Version: 1.2.2
PC: @ 0x2b798134082d (unknown)
I*** SIGSEGV (@0xc0) received by PID 25428 (TID 0x2b798aada700) from PID 192; stack trace: ***
0720 02:09:17.615643 25445 exec.cpp:212] Executor started at: executor(121)@172.17.0.5:41702 with pid 25428
@ 0x2b79808d7330 (unknown)
@ 0x2b798134082d (unknown)
@ 0x2b797c4c13f2 mesos::internal::slave::executorEnvironment()
@ 0x2b797c49f726 mesos::internal::slave::Framework::launchExecutor()
@ 0x2b797c49bebf mesos::internal::slave::Slave::_run()
@ 0x2b797dd0e2c7 process::ProcessManager::resume()
@ 0x2b797dd22a6f std::thread::_Impl<>::_M_run()
@ 0x2b7980b96a60 (unknown)
@ 0x2b79808cf184 start_thread
@ 0x2b7981402ffd (unknown)
make[3]: *** [CMakeFiles/check] Segmentation fault (core dumped)
make[3]: Leaving directory `/mesos/build'
make[2]: *** [CMakeFiles/check.dir/all] Error 2
make[2]: Leaving directory `/mesos/build'
make[1]: *** [CMakeFiles/check.dir/rule] Error 2
make[1]: Leaving directory `/mesos/build'
make: *** [check] Error 2
+ docker rmi mesos-1500514460-13777
Untagged: mesos-1500514460-13777:latest
Deleted: sha256:4feb16b8357c901f86804d223094211f424d79b8059f2b1e692ee2858751396e
Deleted: sha256:e5b3e1f22dac280ce1ae8711a5a42526ce620ed12800bad895547aad5dd7bc84
Deleted: sha256:a6eefc304f7563af90816764f5232280a2a0b5ce80150816e696df5591a42c31
Deleted: sha256:ddbcfe16ea157bced393c3bb7a28323bcfeea0f10a0274d70bed2102529d40b1
Deleted: sha256:f697f116ebec36c67bc50302b2ce05a43d4ef85285c9bf308cc880daf3b4b4e3
Deleted: sha256:6539967b4ab00bb83dc4dc97875f09a7bf92c9c0e015169013ba86704a62642f
Deleted: sha256:376e0ed4c13b97ff0a265c9433ac4bd44a13b49e9017010f266177e4e969e75c
Deleted: sha256:a2a5f2dfe57ec96ac64ec42639999ade89a5e633d048780de9403e57b9f8d663
Deleted: sha256:fbff8134b5a7a58d53da6e12f53b6855f3aea6925504c1fbb81f924cb1c9627b
Deleted: sha256:0a081b79eadeee3a6d9d740d48c1408530440ddad24332d6045c14a89fe9b798
Deleted: sha256:e51d9442ba0c5af5e38858b2c9d1f4fe488092a0c8df2e3d0b63a293b80fab06
Deleted: sha256:4df57e5b11dcbb870a6cb6b6e8a11e2664d6e2fc12214c267eecffab52415e20
Deleted: sha256:5151d0e0b01f452d29f4f7c46844c05b8ca7b612fea7521c3ad2cbf6f5441c22
Deleted: sha256:b1602c975feac39ea62487ac8089e51d6550ea8314f21051b3df6c9710164b3e
Deleted: sha256:d0c403a1ec91e68be2b5538ce6d06fcb6e6eeee0f01de2597d7c945b71f06b19
Deleted: sha256:7322adaa7320ea8035dc474445929379e01982ec57a23bcd78c3c6733b2a6511
Deleted: sha256:033125ff0e38f071d8a0fe8ba07fa4887903eb1b4b0875a2de1f7a70aa211012
Deleted: sha256:3916c594d50b21201dc2121deb5a162c72d773d81ca5f1cec2834650c2ca3041
Deleted: sha256:1d7be0298dada7837661031662346556483adb6ff1b8060604423de627577119
Deleted: sha256:23752d2646886f8a7ba4a7462df6880296193d1c65de09bbc2a1ed93f2053369
Deleted: sha256:3114caa839e7510f41a96a21e0796a70dafcf2b5b7dbe32670995d2378a006fd
Deleted: sha256:cdc65a3a0cc907002b042e358e2466b1fb0409b19eb18537b7baf0dde0dd3103
Deleted: sha256:79ae62de389a7853851cbf9b396258a7209843c0310f58170e20d6d734a58e54
Deleted: sha256:6e1d1fccaac1b02ba58db7df25e954ca68a9977063cb1afaf62f14e9f1d8c0f5
Build step 'Execute shell' marked build as failure
Not sending mail to unregistered user yujie.jay@gmail.com