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/04 04:08:07 UTC
Build failed in Jenkins: Mesos-Buildbot » autotools,gcc,--verbose --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,centos:7,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2) #3859
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=autotools,COMPILER=gcc,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3859/display/redirect?page=changes>
Changes:
[toenshoff] Fixed interference of unbundled dependency include paths with Boost.
[toenshoff] Added MESOS-7581 to the 1.3.1 CHANGELOG.
------------------------------------------
[...truncated 28.07 MB...]
I0704 04:07:58.952105 21585 slave.cpp:796] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.AuthorizedRequest/2 (27 ms)
[ RUN ] Endpoint/SlaveEndpointTest.UnauthorizedRequest/0
I0704 04:07:58.961791 2251 containerizer.cpp:221] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W0704 04:07:58.962294 2251 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0704 04:07:58.962404 2251 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0704 04:07:58.962450 2251 provisioner.cpp:249] Using default backend 'copy'
I0704 04:07:58.965421 21572 slave.cpp:231] Mesos agent started on (744)@172.17.0.7:42921
I0704 04:07:58.965441 21572 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_xdUyO3/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_xdUyO3/executor_secret_key" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_xdUyO3/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_xdUyO3/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.3.1/_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_xdUyO3" --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_63QOFd"
I0704 04:07:58.965850 21572 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_xdUyO3/credential'
I0704 04:07:58.965996 21572 slave.cpp:264] Agent using credential for: test-principal
I0704 04:07:58.966017 21572 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_xdUyO3/http_credentials'
I0704 04:07:58.966317 21572 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-executor'
I0704 04:07:58.966424 21572 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-executor'
I0704 04:07:58.966779 21572 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0704 04:07:58.966891 21572 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readonly'
I0704 04:07:58.967078 21572 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0704 04:07:58.967258 21572 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readwrite'
I0704 04:07:58.968574 21572 slave.cpp:531] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0704 04:07:58.968658 21572 slave.cpp:539] Agent attributes: [ ]
I0704 04:07:58.968674 21572 slave.cpp:544] Agent hostname: c479a3a87b44
I0704 04:07:58.968789 21591 status_update_manager.cpp:177] Pausing sending status updates
I0704 04:07:58.970077 21591 state.cpp:62] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_63QOFd/meta'
I0704 04:07:58.970418 21571 status_update_manager.cpp:203] Recovering status update manager
I0704 04:07:58.970616 21574 containerizer.cpp:608] Recovering containerizer
I0704 04:07:58.971958 21581 provisioner.cpp:410] Provisioner recovery complete
I0704 04:07:58.972479 21569 slave.cpp:6075] Finished recovery
I0704 04:07:58.972970 21569 slave.cpp:6257] Querying resource estimator for oversubscribable resources
I0704 04:07:58.973222 21576 slave.cpp:6271] Received oversubscribable resources {} from the resource estimator
I0704 04:07:58.975045 21590 process.cpp:3731] Handling HTTP event for process 'slave(744)' with path: '/slave(744)/monitor/statistics'
I0704 04:07:58.976711 21585 http.cpp:1115] HTTP GET for /slave(744)/monitor/statistics from 172.17.0.7:55486
I0704 04:07:58.976799 21585 http.cpp:936] Authorizing principal 'test-principal' to GET the '/monitor/statistics' endpoint
I0704 04:07:58.978785 21591 slave.cpp:796] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.UnauthorizedRequest/0 (26 ms)
[ RUN ] Endpoint/SlaveEndpointTest.UnauthorizedRequest/1
I0704 04:07:58.988270 2251 containerizer.cpp:221] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W0704 04:07:58.988749 2251 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0704 04:07:58.988842 2251 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0704 04:07:58.988874 2251 provisioner.cpp:249] Using default backend 'copy'
I0704 04:07:58.991766 21575 slave.cpp:231] Mesos agent started on (745)@172.17.0.7:42921
I0704 04:07:58.991788 21575 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_vpk2Aq/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_vpk2Aq/executor_secret_key" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_vpk2Aq/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_vpk2Aq/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.3.1/_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_vpk2Aq" --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_wv7loA"
I0704 04:07:58.992161 21575 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_vpk2Aq/credential'
I0704 04:07:58.992300 21575 slave.cpp:264] Agent using credential for: test-principal
I0704 04:07:58.992323 21575 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_vpk2Aq/http_credentials'
I0704 04:07:58.992578 21575 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-executor'
I0704 04:07:58.992677 21575 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-executor'
I0704 04:07:58.993012 21575 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0704 04:07:58.993121 21575 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readonly'
I0704 04:07:58.993317 21575 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0704 04:07:58.993449 21575 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readwrite'
I0704 04:07:58.994730 21575 slave.cpp:531] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0704 04:07:58.994817 21575 slave.cpp:539] Agent attributes: [ ]
I0704 04:07:58.994830 21575 slave.cpp:544] Agent hostname: c479a3a87b44
I0704 04:07:58.994953 21577 status_update_manager.cpp:177] Pausing sending status updates
I0704 04:07:58.996495 21573 state.cpp:62] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_wv7loA/meta'
I0704 04:07:58.996794 21572 status_update_manager.cpp:203] Recovering status update manager
I0704 04:07:58.997062 21590 containerizer.cpp:608] Recovering containerizer
I0704 04:07:58.998443 21568 provisioner.cpp:410] Provisioner recovery complete
I0704 04:07:58.998848 21589 slave.cpp:6075] Finished recovery
I0704 04:07:58.999347 21589 slave.cpp:6257] Querying resource estimator for oversubscribable resources
I0704 04:07:58.999680 21586 slave.cpp:6271] Received oversubscribable resources {} from the resource estimator
I0704 04:07:59.001436 21582 process.cpp:3731] Handling HTTP event for process 'slave(745)' with path: '/slave(745)/monitor/statistics.json'
I0704 04:07:59.003007 21573 http.cpp:1115] HTTP GET for /slave(745)/monitor/statistics.json from 172.17.0.7:55488
I0704 04:07:59.003101 21573 http.cpp:936] Authorizing principal 'test-principal' to GET the '/monitor/statistics.json' endpoint
I0704 04:07:59.005064 21589 slave.cpp:796] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.UnauthorizedRequest/1 (26 ms)
[ RUN ] Endpoint/SlaveEndpointTest.UnauthorizedRequest/2
I0704 04:07:59.014638 2251 containerizer.cpp:221] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W0704 04:07:59.015154 2251 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0704 04:07:59.015267 2251 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0704 04:07:59.015312 2251 provisioner.cpp:249] Using default backend 'copy'
I0704 04:07:59.018046 21570 slave.cpp:231] Mesos agent started on (746)@172.17.0.7:42921
I0704 04:07:59.018074 21570 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_XLLdDN/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_XLLdDN/executor_secret_key" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_XLLdDN/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_XLLdDN/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.3.1/_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_XLLdDN" --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_cRQBmX"
I0704 04:07:59.018705 21570 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_XLLdDN/credential'
I0704 04:07:59.018903 21570 slave.cpp:264] Agent using credential for: test-principal
I0704 04:07:59.018934 21570 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_XLLdDN/http_credentials'
I0704 04:07:59.019325 21570 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-executor'
I0704 04:07:59.019441 21570 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-executor'
I0704 04:07:59.019654 21570 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0704 04:07:59.019812 21570 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readonly'
I0704 04:07:59.020114 21570 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0704 04:07:59.020221 21570 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readwrite'
I0704 04:07:59.021679 21570 slave.cpp:531] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0704 04:07:59.021765 21570 slave.cpp:539] Agent attributes: [ ]
I0704 04:07:59.021780 21570 slave.cpp:544] Agent hostname: c479a3a87b44
I0704 04:07:59.021903 21589 status_update_manager.cpp:177] Pausing sending status updates
I0704 04:07:59.023360 21575 state.cpp:62] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_cRQBmX/meta'
I0704 04:07:59.023617 21570 status_update_manager.cpp:203] Recovering status update manager
I0704 04:07:59.023807 21577 containerizer.cpp:608] Recovering containerizer
I0704 04:07:59.025141 21574 provisioner.cpp:410] Provisioner recovery complete
I0704 04:07:59.025552 21585 slave.cpp:6075] Finished recovery
I0704 04:07:59.025995 21585 slave.cpp:6257] Querying resource estimator for oversubscribable resources
I0704 04:07:59.026276 21580 slave.cpp:6271] Received oversubscribable resources {} from the resource estimator
I0704 04:07:59.027988 21585 process.cpp:3731] Handling HTTP event for process 'slave(746)' with path: '/slave(746)/containers'
I0704 04:07:59.029546 21590 http.cpp:1115] HTTP GET for /slave(746)/containers from 172.17.0.7:55490
I0704 04:07:59.029626 21590 http.cpp:936] Authorizing principal 'test-principal' to GET the '/containers' endpoint
I0704 04:07:59.031688 21581 slave.cpp:796] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.UnauthorizedRequest/2 (26 ms)
[ RUN ] Endpoint/SlaveEndpointTest.NoAuthorizer/0
I0704 04:07:59.041419 2251 containerizer.cpp:221] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W0704 04:07:59.041909 2251 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0704 04:07:59.042009 2251 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0704 04:07:59.042049 2251 provisioner.cpp:249] Using default backend 'copy'
I0704 04:07:59.043608 2251 cluster.cpp:448] Creating default 'local' authorizer
I0704 04:07:59.045208 21586 slave.cpp:231] Mesos agent started on (747)@172.17.0.7:42921
I0704 04:07:59.045228 21586 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_nffgVa/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_nffgVa/executor_secret_key" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_0_nffgVa/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_nffgVa/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.3.1/_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_nffgVa" --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_eA2GAk"
I0704 04:07:59.045630 21586 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_0_nffgVa/credential'
I0704 04:07:59.045764 21586 slave.cpp:264] Agent using credential for: test-principal
I0704 04:07:59.045785 21586 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_0_nffgVa/http_credentials'
I0704 04:07:59.046041 21586 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-executor'
I0704 04:07:59.046142 21586 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-executor'
I0704 04:07:59.046370 21586 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0704 04:07:59.046480 21586 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readonly'
I0704 04:07:59.046728 21586 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0704 04:07:59.046839 21586 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readwrite'
I0704 04:07:59.048177 21586 slave.cpp:531] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0704 04:07:59.048264 21586 slave.cpp:539] Agent attributes: [ ]
I0704 04:07:59.048277 21586 slave.cpp:544] Agent hostname: c479a3a87b44
I0704 04:07:59.048403 21573 status_update_manager.cpp:177] Pausing sending status updates
I0704 04:07:59.049665 21585 state.cpp:62] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_0_eA2GAk/meta'
I0704 04:07:59.050034 21571 status_update_manager.cpp:203] Recovering status update manager
I0704 04:07:59.050266 21584 containerizer.cpp:608] Recovering containerizer
I0704 04:07:59.051673 21571 provisioner.cpp:410] Provisioner recovery complete
I0704 04:07:59.052109 21573 slave.cpp:6075] Finished recovery
I0704 04:07:59.052573 21573 slave.cpp:6257] Querying resource estimator for oversubscribable resources
I0704 04:07:59.054438 21584 slave.cpp:6271] Received oversubscribable resources {} from the resource estimator
I0704 04:07:59.056149 21568 process.cpp:3731] Handling HTTP event for process 'slave(747)' with path: '/slave(747)/monitor/statistics'
I0704 04:07:59.057739 21588 http.cpp:1115] HTTP GET for /slave(747)/monitor/statistics from 172.17.0.7:55492
I0704 04:07:59.057814 21588 http.cpp:936] Authorizing principal 'test-principal' to GET the '/monitor/statistics' endpoint
I0704 04:07:59.060505 21568 slave.cpp:796] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.NoAuthorizer/0 (29 ms)
[ RUN ] Endpoint/SlaveEndpointTest.NoAuthorizer/1
I0704 04:07:59.070621 2251 containerizer.cpp:221] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W0704 04:07:59.071100 2251 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0704 04:07:59.071203 2251 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0704 04:07:59.071244 2251 provisioner.cpp:249] Using default backend 'copy'
I0704 04:07:59.072672 2251 cluster.cpp:448] Creating default 'local' authorizer
I0704 04:07:59.074255 21568 slave.cpp:231] Mesos agent started on (748)@172.17.0.7:42921
I0704 04:07:59.074285 21568 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_b1Qeuy/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_b1Qeuy/executor_secret_key" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_1_b1Qeuy/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_b1Qeuy/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.3.1/_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_b1Qeuy" --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_Ehel5H"
I0704 04:07:59.074690 21568 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_1_b1Qeuy/credential'
I0704 04:07:59.074826 21568 slave.cpp:264] Agent using credential for: test-principal
I0704 04:07:59.074846 21568 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_1_b1Qeuy/http_credentials'
I0704 04:07:59.075104 21568 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-executor'
I0704 04:07:59.075211 21568 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-executor'
I0704 04:07:59.075414 21568 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0704 04:07:59.075542 21568 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readonly'
I0704 04:07:59.075780 21568 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0704 04:07:59.075896 21568 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readwrite'
I0704 04:07:59.077172 21568 slave.cpp:531] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0704 04:07:59.077257 21568 slave.cpp:539] Agent attributes: [ ]
I0704 04:07:59.077271 21568 slave.cpp:544] Agent hostname: c479a3a87b44
I0704 04:07:59.077384 21569 status_update_manager.cpp:177] Pausing sending status updates
I0704 04:07:59.078712 21582 state.cpp:62] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_1_Ehel5H/meta'
I0704 04:07:59.079057 21591 status_update_manager.cpp:203] Recovering status update manager
I0704 04:07:59.079239 21589 containerizer.cpp:608] Recovering containerizer
I0704 04:07:59.080597 21576 provisioner.cpp:410] Provisioner recovery complete
I0704 04:07:59.081045 21589 slave.cpp:6075] Finished recovery
I0704 04:07:59.081517 21589 slave.cpp:6257] Querying resource estimator for oversubscribable resources
I0704 04:07:59.081742 21569 slave.cpp:6271] Received oversubscribable resources {} from the resource estimator
I0704 04:07:59.083534 21570 process.cpp:3731] Handling HTTP event for process 'slave(748)' with path: '/slave(748)/monitor/statistics.json'
I0704 04:07:59.085186 21578 http.cpp:1115] HTTP GET for /slave(748)/monitor/statistics.json from 172.17.0.7:55494
I0704 04:07:59.085260 21578 http.cpp:936] Authorizing principal 'test-principal' to GET the '/monitor/statistics.json' endpoint
I0704 04:07:59.088186 21571 slave.cpp:796] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.NoAuthorizer/1 (27 ms)
[ RUN ] Endpoint/SlaveEndpointTest.NoAuthorizer/2
I0704 04:07:59.097877 2251 containerizer.cpp:221] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W0704 04:07:59.098381 2251 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0704 04:07:59.098487 2251 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0704 04:07:59.098526 2251 provisioner.cpp:249] Using default backend 'copy'
I0704 04:07:59.100050 2251 cluster.cpp:448] Creating default 'local' authorizer
I0704 04:07:59.101519 21583 slave.cpp:231] Mesos agent started on (749)@172.17.0.7:42921
I0704 04:07:59.101547 21583 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_XpGJjW/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_XpGJjW/executor_secret_key" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_2_XpGJjW/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_XpGJjW/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.3.1/_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_XpGJjW" --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_CEOLQ5"
I0704 04:07:59.101958 21583 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_2_XpGJjW/credential'
I0704 04:07:59.102095 21583 slave.cpp:264] Agent using credential for: test-principal
I0704 04:07:59.102118 21583 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_2_XpGJjW/http_credentials'
I0704 04:07:59.102394 21583 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-executor'
I0704 04:07:59.102497 21583 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-executor'
I0704 04:07:59.102720 21583 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0704 04:07:59.102815 21583 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readonly'
I0704 04:07:59.102993 21583 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0704 04:07:59.103130 21583 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readwrite'
I0704 04:07:59.104497 21583 slave.cpp:531] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0704 04:07:59.104583 21583 slave.cpp:539] Agent attributes: [ ]
I0704 04:07:59.104595 21583 slave.cpp:544] Agent hostname: c479a3a87b44
I0704 04:07:59.104735 21588 status_update_manager.cpp:177] Pausing sending status updates
I0704 04:07:59.105918 21582 state.cpp:62] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_2_CEOLQ5/meta'
I0704 04:07:59.106199 21579 status_update_manager.cpp:203] Recovering status update manager
I0704 04:07:59.106454 21573 containerizer.cpp:608] Recovering containerizer
I0704 04:07:59.107728 21587 provisioner.cpp:410] Provisioner recovery complete
I0704 04:07:59.108131 21570 slave.cpp:6075] Finished recovery
I0704 04:07:59.108626 21570 slave.cpp:6257] Querying resource estimator for oversubscribable resources
I0704 04:07:59.108860 21570 slave.cpp:6271] Received oversubscribable resources {} from the resource estimator
I0704 04:07:59.110643 21578 process.cpp:3731] Handling HTTP event for process 'slave(749)' with path: '/slave(749)/containers'
I0704 04:07:59.112237 21582 http.cpp:1115] HTTP GET for /slave(749)/containers from 172.17.0.7:55496
I0704 04:07:59.112305 21582 http.cpp:936] Authorizing principal 'test-principal' to GET the '/containers' endpoint
I0704 04:07:59.115866 21576 slave.cpp:796] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.NoAuthorizer/2 (27 ms)
[----------] 9 tests from Endpoint/SlaveEndpointTest (250 ms total)
[----------] Global test environment tear-down
[==========] 1575 tests from 177 test cases ran. (571822 ms total)
[ PASSED ] 1572 tests.
[ FAILED ] 3 tests, listed below:
[ FAILED ] DefaultExecutorCheckTest.CommandCheckDeliveredAndReconciled
[ FAILED ] DefaultExecutorCheckTest.CommandCheckStatusChange
[ FAILED ] DefaultExecutorCheckTest.CommandCheckTimeout
3 FAILED TESTS
YOU HAVE 22 DISABLED TESTS
E0704 04:07:59.179541 21592 process.cpp:951] Failed to accept socket: future discarded
make[4]: *** [check-local] Error 1
make[4]: Leaving directory `/mesos/mesos-1.3.1/_build/src'
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/mesos/mesos-1.3.1/_build/src'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/mesos/mesos-1.3.1/_build/src'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-1.3.1/_build'
make: *** [distcheck] Error 1
+ docker rmi mesos-1499138788-1105
Untagged: mesos-1499138788-1105:latest
Deleted: sha256:42ad6dc06034541f57c3b2247dc4bffabf65817d5151e1d76058ded5420fb0c6
Deleted: sha256:e2eecef1f5c8a36293341d04f56fcc14915ec008a075986421f45ec67cb1a953
Deleted: sha256:17764d0b5ad2d849ebbf0e58a55708723d705ebfd4277fbdd227543a52f5304d
Deleted: sha256:0385ea2de89ac372e9a3cac16e7af050963a074825ed0d9dd0d12c6b511b903e
Deleted: sha256:fd81bb927e92e0da436c9e252788f4b9dbfbf3ecd0e60f0284ccc924aecfc3d7
Deleted: sha256:3c49d566e0e1171f6e03fdec8cc7292048737e3715ebca447ec99262920800f3
Deleted: sha256:aa591c9351b484444e5ad9103a7e763b56f68eaa0e2cc3d2bc0e40a92f82acf9
Deleted: sha256:56e83cc2e6bca90e12b3598db8d28fb66dbca9db1d31669e9cb56b6d16857788
Deleted: sha256:1bb6006d4c901e2a17a033e1805ebe14081d60f7636209b7ac12b4b226f5566c
Deleted: sha256:6712913c8711720274b83f1745a93ef9ebfe957b06bbc522a1274985fd75c9dc
Deleted: sha256:be28e21e66e176588f9074f993ed7051e838a6f53af64a06e98dddfdd42b551b
Deleted: sha256:1a5826e80eeeee6081360f167f8a17f0b087c1edae8bb2c3b249e77e374924bc
Deleted: sha256:9f5fd1f91be5555d6ccb06a7d98409b69036c6b0854287d44ea7613ba1b8f299
Deleted: sha256:b4f9305c7d06c33dc1f64ddaa248a46cfda3ea262a647d93889da7f771119a16
Deleted: sha256:6d4a286ac35e576fb4062ae9d0770b3d0c9eee00cd4f8d460dc2ab3a2c301691
Deleted: sha256:3d54744419e877323d161f6ca325c9b9285ac022b23e444add7863a088f5caf5
Deleted: sha256:479cd69a834d148993817e9b89747120e6b771949465735685d1a256be7ff3be
Deleted: sha256:f5ac5d0ff62ffd4593f8ca029aa518b46c951db6bde77e8a495cb0f5ab3b3415
Deleted: sha256:21892289b99d62ea6cbc5f4ccbe3583e880085e50c7a2a28b2d8f7e646c2c247
Deleted: sha256:e769619f95b3ed0eb63807da7f56660e73708ed71b14f4ec5f781a98e38e4d0a
Deleted: sha256:5feb158f026f28e9ec7b911a220b92e68743e73d7a94e0ae3b3a0546a06958a8
Deleted: sha256:2b1052757fb614a6e40cbbdbbf6acfe2f59a5b75cd01fe03d1f30c7f5cda74f4
Deleted: sha256:983bc6e043046259dadace2ed68de038a61eef881b0899a6878cb240e6f2c8da
Deleted: sha256:618f3736654b5f6d172fcbb8216917adf43f66d6b288ca0a048119907603414a
Deleted: sha256:d642106ca212b5f0a07735db0c0c3cee8f0cdb42619dd88a7f17074195aa6fa9
Build step 'Execute shell' marked build as failure
Not sending mail to unregistered user toenshoff@me.com
Jenkins build is back to normal : Mesos-Buildbot » autotools,gcc,--verbose --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,centos:7,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2) #3865
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=autotools,COMPILER=gcc,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3865/display/redirect?page=changes>
Build failed in Jenkins: Mesos-Buildbot » autotools,gcc,--verbose --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,centos:7,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2) #3864
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=autotools,COMPILER=gcc,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3864/display/redirect?page=changes>
Changes:
[mpark] Updated `accept` to perform operation adjustment in one place.
[mpark] Updated `validateAndNormalizeResources` to operate on `Operation`s.
[mpark] Performed validation/normalization of `Resource`s before authorization.
[mpark] Validated and normalized resources on the V1 operator API path.
------------------------------------------
[...truncated 2.69 MB...]
NOTE: Set LIBPROCESS_SSL_REQUIRE_CERT=1 to require peer certificate verification
I0705 11:20:21.021195 2972 libevent_ssl_socket.cpp:1170] Socket error: error:140760FC:SSL routines:SSL23_GET_CLIENT_HELLO:unknown protocol
I0705 11:20:21.108685 2947 ssl_tests.cpp:638] Testing server protocol 'LIBPROCESS_SSL_ENABLE_TLS_V1_1'
I0705 11:20:21.109408 2947 openssl.cpp:419] CA file path is unspecified! NOTE: Set CA file path with LIBPROCESS_SSL_CA_FILE=<filepath>
I0705 11:20:21.109428 2947 openssl.cpp:424] CA directory path unspecified! NOTE: Set CA directory path with LIBPROCESS_SSL_CA_DIR=<dirpath>
I0705 11:20:21.109436 2947 openssl.cpp:429] Will not verify peer certificate!
NOTE: Set LIBPROCESS_SSL_VERIFY_CERT=1 to enable peer certificate verification
I0705 11:20:21.109443 2947 openssl.cpp:435] Will only verify peer certificate if presented!
NOTE: Set LIBPROCESS_SSL_REQUIRE_CERT=1 to require peer certificate verification
I0705 11:20:21.129014 2972 libevent_ssl_socket.cpp:1170] Socket error: error:140760FC:SSL routines:SSL23_GET_CLIENT_HELLO:unknown protocol
I0705 11:20:21.209233 2947 ssl_tests.cpp:638] Testing server protocol 'LIBPROCESS_SSL_ENABLE_TLS_V1_2'
I0705 11:20:21.209983 2947 openssl.cpp:419] CA file path is unspecified! NOTE: Set CA file path with LIBPROCESS_SSL_CA_FILE=<filepath>
I0705 11:20:21.210026 2947 openssl.cpp:424] CA directory path unspecified! NOTE: Set CA directory path with LIBPROCESS_SSL_CA_DIR=<dirpath>
I0705 11:20:21.210034 2947 openssl.cpp:429] Will not verify peer certificate!
NOTE: Set LIBPROCESS_SSL_VERIFY_CERT=1 to enable peer certificate verification
I0705 11:20:21.210041 2947 openssl.cpp:435] Will only verify peer certificate if presented!
NOTE: Set LIBPROCESS_SSL_REQUIRE_CERT=1 to require peer certificate verification
I0705 11:20:21.223760 2972 libevent_ssl_socket.cpp:1170] Socket error: error:140760FC:SSL routines:SSL23_GET_CLIENT_HELLO:unknown protocol
[ OK ] SSLTest.NoValidDowngradeEachProtocol (604 ms)
[ RUN ] SSLTest.PeerAddress
I0705 11:20:21.806216 2947 openssl.cpp:419] CA file path is unspecified! NOTE: Set CA file path with LIBPROCESS_SSL_CA_FILE=<filepath>
I0705 11:20:21.806246 2947 openssl.cpp:424] CA directory path unspecified! NOTE: Set CA directory path with LIBPROCESS_SSL_CA_DIR=<dirpath>
I0705 11:20:21.806254 2947 openssl.cpp:429] Will not verify peer certificate!
NOTE: Set LIBPROCESS_SSL_VERIFY_CERT=1 to enable peer certificate verification
I0705 11:20:21.806262 2947 openssl.cpp:435] Will only verify peer certificate if presented!
NOTE: Set LIBPROCESS_SSL_REQUIRE_CERT=1 to require peer certificate verification
[ OK ] SSLTest.PeerAddress (503 ms)
[ RUN ] SSLTest.HTTPSGet
I0705 11:20:22.182507 2947 openssl.cpp:419] CA file path is unspecified! NOTE: Set CA file path with LIBPROCESS_SSL_CA_FILE=<filepath>
I0705 11:20:22.182548 2947 openssl.cpp:424] CA directory path unspecified! NOTE: Set CA directory path with LIBPROCESS_SSL_CA_DIR=<dirpath>
I0705 11:20:22.182557 2947 openssl.cpp:429] Will not verify peer certificate!
NOTE: Set LIBPROCESS_SSL_VERIFY_CERT=1 to enable peer certificate verification
I0705 11:20:22.182565 2947 openssl.cpp:435] Will only verify peer certificate if presented!
NOTE: Set LIBPROCESS_SSL_REQUIRE_CERT=1 to require peer certificate verification
[ OK ] SSLTest.HTTPSGet (376 ms)
[ RUN ] SSLTest.HTTPSPost
I0705 11:20:22.291604 2947 openssl.cpp:419] CA file path is unspecified! NOTE: Set CA file path with LIBPROCESS_SSL_CA_FILE=<filepath>
I0705 11:20:22.291671 2947 openssl.cpp:424] CA directory path unspecified! NOTE: Set CA directory path with LIBPROCESS_SSL_CA_DIR=<dirpath>
I0705 11:20:22.291681 2947 openssl.cpp:429] Will not verify peer certificate!
NOTE: Set LIBPROCESS_SSL_VERIFY_CERT=1 to enable peer certificate verification
I0705 11:20:22.291687 2947 openssl.cpp:435] Will only verify peer certificate if presented!
NOTE: Set LIBPROCESS_SSL_REQUIRE_CERT=1 to require peer certificate verification
[ OK ] SSLTest.HTTPSPost (112 ms)
[ RUN ] SSLTest.SilentSocket
I0705 11:20:22.418620 2947 openssl.cpp:419] CA file path is unspecified! NOTE: Set CA file path with LIBPROCESS_SSL_CA_FILE=<filepath>
I0705 11:20:22.418645 2947 openssl.cpp:424] CA directory path unspecified! NOTE: Set CA directory path with LIBPROCESS_SSL_CA_DIR=<dirpath>
I0705 11:20:22.418651 2947 openssl.cpp:429] Will not verify peer certificate!
NOTE: Set LIBPROCESS_SSL_VERIFY_CERT=1 to enable peer certificate verification
I0705 11:20:22.418656 2947 openssl.cpp:435] Will only verify peer certificate if presented!
NOTE: Set LIBPROCESS_SSL_REQUIRE_CERT=1 to require peer certificate verification
I0705 11:20:22.424218 2972 libevent_ssl_socket.cpp:1170] Socket error: error:00000000:lib(0):func(0):reason(0)
[ OK ] SSLTest.SilentSocket (123 ms)
[----------] 15 tests from SSLTest (12081 ms total)
[----------] 42 tests from Scheme/HTTPTest
[ RUN ] Scheme/HTTPTest.Endpoints/0
I0705 11:20:22.656047 2947 openssl.cpp:419] CA file path is unspecified! NOTE: Set CA file path with LIBPROCESS_SSL_CA_FILE=<filepath>
I0705 11:20:22.656075 2947 openssl.cpp:424] CA directory path unspecified! NOTE: Set CA directory path with LIBPROCESS_SSL_CA_DIR=<dirpath>
I0705 11:20:22.656080 2947 openssl.cpp:429] Will not verify peer certificate!
NOTE: Set LIBPROCESS_SSL_VERIFY_CERT=1 to enable peer certificate verification
I0705 11:20:22.656085 2947 openssl.cpp:435] Will only verify peer certificate if presented!
NOTE: Set LIBPROCESS_SSL_REQUIRE_CERT=1 to require peer certificate verification
I0705 11:20:22.656484 2972 process.cpp:968] Failed to accept socket: future discarded
I0705 11:20:22.660854 2947 process.cpp:1282] libprocess is initialized on 172.17.0.12:35869 with 24 worker threads
I0705 11:20:22.667027 4022 process.cpp:3779] Handling HTTP event for process '(74)' with path: '/(74)/body'
I0705 11:20:22.671514 4023 process.cpp:3779] Handling HTTP event for process '(74)' with path: '/(74)/pipe'
I0705 11:20:22.676558 4038 process.cpp:3779] Handling HTTP event for process '(74)' with path: '/(74)/body'
I0705 11:20:22.677114 4039 process.cpp:3836] Failed to process request for '/(74)/body': failure
I0705 11:20:22.677145 4031 process.cpp:1495] Returning '500 Internal Server Error' for '/(74)/body' (failure)
[ OK ] Scheme/HTTPTest.Endpoints/0 (254 ms)
[ RUN ] Scheme/HTTPTest.Endpoints/1
I0705 11:20:22.681447 2947 process.cpp:1282] libprocess is initialized on 172.17.0.12:45441 with 24 worker threads
I0705 11:20:22.682745 4051 process.cpp:3779] Handling HTTP event for process '(75)' with path: '/(75)/body'
I0705 11:20:22.684763 4054 process.cpp:3779] Handling HTTP event for process '(75)' with path: '/(75)/pipe'
I0705 11:20:22.687577 4058 process.cpp:3779] Handling HTTP event for process '(75)' with path: '/(75)/body'
I0705 11:20:22.688119 4055 process.cpp:3836] Failed to process request for '/(75)/body': failure
I0705 11:20:22.688148 4045 process.cpp:1495] Returning '500 Internal Server Error' for '/(75)/body' (failure)
I0705 11:20:22.688954 4066 process.cpp:855] Decode failure: Connection reset by peer
E0705 11:20:22.688997 4066 process.cpp:2469] Failed to shutdown socket with fd 9, address 172.17.0.12:45441: Transport endpoint is not connected
[ OK ] Scheme/HTTPTest.Endpoints/1 (11 ms)
[ RUN ] Scheme/HTTPTest.EndpointsHelp/0
I0705 11:20:23.138721 2947 openssl.cpp:419] CA file path is unspecified! NOTE: Set CA file path with LIBPROCESS_SSL_CA_FILE=<filepath>
I0705 11:20:23.138746 2947 openssl.cpp:424] CA directory path unspecified! NOTE: Set CA directory path with LIBPROCESS_SSL_CA_DIR=<dirpath>
I0705 11:20:23.138752 2947 openssl.cpp:429] Will not verify peer certificate!
NOTE: Set LIBPROCESS_SSL_VERIFY_CERT=1 to enable peer certificate verification
I0705 11:20:23.138756 2947 openssl.cpp:435] Will only verify peer certificate if presented!
NOTE: Set LIBPROCESS_SSL_REQUIRE_CERT=1 to require peer certificate verification
I0705 11:20:23.139199 4066 process.cpp:968] Failed to accept socket: future discarded
I0705 11:20:23.142405 2947 process.cpp:1282] libprocess is initialized on 172.17.0.12:35821 with 24 worker threads
I0705 11:20:23.150888 4080 process.cpp:3779] Handling HTTP event for process 'help' with path: '/help'
I0705 11:20:23.158167 4079 process.cpp:3779] Handling HTTP event for process 'help' with path: '/help'
I0705 11:20:23.164543 4077 process.cpp:3779] Handling HTTP event for process 'help' with path: '/help/(76)/body'
I0705 11:20:23.169515 4070 process.cpp:3779] Handling HTTP event for process 'help' with path: '/help/(76)/a/b/c'
[ OK ] Scheme/HTTPTest.EndpointsHelp/0 (482 ms)
[ RUN ] Scheme/HTTPTest.EndpointsHelp/1
I0705 11:20:23.174764 2947 process.cpp:1282] libprocess is initialized on 172.17.0.12:34664 with 24 worker threads
I0705 11:20:23.176110 4097 process.cpp:3779] Handling HTTP event for process 'help' with path: '/help'
I0705 11:20:23.178395 4112 process.cpp:3779] Handling HTTP event for process 'help' with path: '/help'
I0705 11:20:23.181529 4112 process.cpp:3779] Handling HTTP event for process 'help' with path: '/help/(77)/body'
I0705 11:20:23.183929 4100 process.cpp:3779] Handling HTTP event for process 'help' with path: '/help/(77)/a/b/c'
[ OK ] Scheme/HTTPTest.EndpointsHelp/1 (14 ms)
[ RUN ] Scheme/HTTPTest.EndpointsHelpRemoval/0
I0705 11:20:23.600616 2947 openssl.cpp:419] CA file path is unspecified! NOTE: Set CA file path with LIBPROCESS_SSL_CA_FILE=<filepath>
I0705 11:20:23.600644 2947 openssl.cpp:424] CA directory path unspecified! NOTE: Set CA directory path with LIBPROCESS_SSL_CA_DIR=<dirpath>
I0705 11:20:23.600652 2947 openssl.cpp:429] Will not verify peer certificate!
NOTE: Set LIBPROCESS_SSL_VERIFY_CERT=1 to enable peer certificate verification
I0705 11:20:23.600657 2947 openssl.cpp:435] Will only verify peer certificate if presented!
NOTE: Set LIBPROCESS_SSL_REQUIRE_CERT=1 to require peer certificate verification
I0705 11:20:23.601191 4116 process.cpp:968] Failed to accept socket: future discarded
I0705 11:20:23.604173 2947 process.cpp:1282] libprocess is initialized on 172.17.0.12:34590 with 24 worker threads
I0705 11:20:23.609839 4128 process.cpp:3779] Handling HTTP event for process 'help' with path: '/help/(78)/body'
I0705 11:20:23.616029 4135 process.cpp:3779] Handling HTTP event for process 'help' with path: '/help/(78)/bogus'
[ OK ] Scheme/HTTPTest.EndpointsHelpRemoval/0 (432 ms)
[ RUN ] Scheme/HTTPTest.EndpointsHelpRemoval/1
I0705 11:20:23.621642 2947 process.cpp:1282] libprocess is initialized on 172.17.0.12:34168 with 24 worker threads
I0705 11:20:23.623296 4142 process.cpp:3779] Handling HTTP event for process 'help' with path: '/help/(79)/body'
I0705 11:20:23.625905 4161 process.cpp:3779] Handling HTTP event for process 'help' with path: '/help/(79)/bogus'
[ OK ] Scheme/HTTPTest.EndpointsHelpRemoval/1 (10 ms)
[ RUN ] Scheme/HTTPTest.PipeEOF/0
I0705 11:20:23.901912 2947 openssl.cpp:419] CA file path is unspecified! NOTE: Set CA file path with LIBPROCESS_SSL_CA_FILE=<filepath>
I0705 11:20:23.901938 2947 openssl.cpp:424] CA directory path unspecified! NOTE: Set CA directory path with LIBPROCESS_SSL_CA_DIR=<dirpath>
I0705 11:20:23.901944 2947 openssl.cpp:429] Will not verify peer certificate!
NOTE: Set LIBPROCESS_SSL_VERIFY_CERT=1 to enable peer certificate verification
I0705 11:20:23.901948 2947 openssl.cpp:435] Will only verify peer certificate if presented!
NOTE: Set LIBPROCESS_SSL_REQUIRE_CERT=1 to require peer certificate verification
I0705 11:20:23.902508 4166 process.cpp:968] Failed to accept socket: future discarded
I0705 11:20:23.904866 2947 process.cpp:1282] libprocess is initialized on 172.17.0.12:42068 with 24 worker threads
[ OK ] Scheme/HTTPTest.PipeEOF/0 (278 ms)
[ RUN ] Scheme/HTTPTest.PipeEOF/1
I0705 11:20:23.908774 2947 process.cpp:1282] libprocess is initialized on 172.17.0.12:45260 with 24 worker threads
[ OK ] Scheme/HTTPTest.PipeEOF/1 (4 ms)
[ RUN ] Scheme/HTTPTest.PipeFailure/0
I0705 11:20:24.117470 2947 openssl.cpp:419] CA file path is unspecified! NOTE: Set CA file path with LIBPROCESS_SSL_CA_FILE=<filepath>
I0705 11:20:24.117496 2947 openssl.cpp:424] CA directory path unspecified! NOTE: Set CA directory path with LIBPROCESS_SSL_CA_DIR=<dirpath>
I0705 11:20:24.117501 2947 openssl.cpp:429] Will not verify peer certificate!
NOTE: Set LIBPROCESS_SSL_VERIFY_CERT=1 to enable peer certificate verification
I0705 11:20:24.117506 2947 openssl.cpp:435] Will only verify peer certificate if presented!
NOTE: Set LIBPROCESS_SSL_REQUIRE_CERT=1 to require peer certificate verification
I0705 11:20:24.117944 4216 process.cpp:968] Failed to accept socket: future discarded
I0705 11:20:24.120388 2947 process.cpp:1282] libprocess is initialized on 172.17.0.12:43007 with 24 worker threads
[ OK ] Scheme/HTTPTest.PipeFailure/0 (211 ms)
[ RUN ] Scheme/HTTPTest.PipeFailure/1
I0705 11:20:24.123726 2947 process.cpp:1282] libprocess is initialized on 172.17.0.12:36654 with 24 worker threads
[ OK ] Scheme/HTTPTest.PipeFailure/1 (4 ms)
[ RUN ] Scheme/HTTPTest.PipeReaderCloses/0
I0705 11:20:24.236085 2947 openssl.cpp:419] CA file path is unspecified! NOTE: Set CA file path with LIBPROCESS_SSL_CA_FILE=<filepath>
I0705 11:20:24.236109 2947 openssl.cpp:424] CA directory path unspecified! NOTE: Set CA directory path with LIBPROCESS_SSL_CA_DIR=<dirpath>
I0705 11:20:24.236115 2947 openssl.cpp:429] Will not verify peer certificate!
NOTE: Set LIBPROCESS_SSL_VERIFY_CERT=1 to enable peer certificate verification
I0705 11:20:24.236120 2947 openssl.cpp:435] Will only verify peer certificate if presented!
NOTE: Set LIBPROCESS_SSL_REQUIRE_CERT=1 to require peer certificate verification
I0705 11:20:24.236598 4266 process.cpp:968] Failed to accept socket: future discarded
I0705 11:20:24.239130 2947 process.cpp:1282] libprocess is initialized on 172.17.0.12:44209 with 24 worker threads
[ OK ] Scheme/HTTPTest.PipeReaderCloses/0 (115 ms)
[ RUN ] Scheme/HTTPTest.PipeReaderCloses/1
I0705 11:20:24.242797 2947 process.cpp:1282] libprocess is initialized on 172.17.0.12:32798 with 24 worker threads
[ OK ] Scheme/HTTPTest.PipeReaderCloses/1 (4 ms)
[ RUN ] Scheme/HTTPTest.Encode/0
I0705 11:20:24.500529 2947 openssl.cpp:419] CA file path is unspecified! NOTE: Set CA file path with LIBPROCESS_SSL_CA_FILE=<filepath>
I0705 11:20:24.500555 2947 openssl.cpp:424] CA directory path unspecified! NOTE: Set CA directory path with LIBPROCESS_SSL_CA_DIR=<dirpath>
I0705 11:20:24.500560 2947 openssl.cpp:429] Will not verify peer certificate!
NOTE: Set LIBPROCESS_SSL_VERIFY_CERT=1 to enable peer certificate verification
I0705 11:20:24.500564 2947 openssl.cpp:435] Will only verify peer certificate if presented!
NOTE: Set LIBPROCESS_SSL_REQUIRE_CERT=1 to require peer certificate verification
I0705 11:20:24.501149 4316 process.cpp:968] Failed to accept socket: future discarded
I0705 11:20:24.503741 2947 process.cpp:1282] libprocess is initialized on 172.17.0.12:35044 with 24 worker threads
[ OK ] Scheme/HTTPTest.Encode/0 (261 ms)
[ RUN ] Scheme/HTTPTest.Encode/1
I0705 11:20:24.507438 2947 process.cpp:1282] libprocess is initialized on 172.17.0.12:33497 with 24 worker threads
[ OK ] Scheme/HTTPTest.Encode/1 (3 ms)
[ RUN ] Scheme/HTTPTest.PathParse/0
I0705 11:20:24.738029 2947 openssl.cpp:419] CA file path is unspecified! NOTE: Set CA file path with LIBPROCESS_SSL_CA_FILE=<filepath>
I0705 11:20:24.738071 2947 openssl.cpp:424] CA directory path unspecified! NOTE: Set CA directory path with LIBPROCESS_SSL_CA_DIR=<dirpath>
I0705 11:20:24.738078 2947 openssl.cpp:429] Will not verify peer certificate!
NOTE: Set LIBPROCESS_SSL_VERIFY_CERT=1 to enable peer certificate verification
I0705 11:20:24.738083 2947 openssl.cpp:435] Will only verify peer certificate if presented!
NOTE: Set LIBPROCESS_SSL_REQUIRE_CERT=1 to require peer certificate verification
I0705 11:20:24.738646 4366 process.cpp:968] Failed to accept socket: future discarded
I0705 11:20:24.741395 2947 process.cpp:1282] libprocess is initialized on 172.17.0.12:34915 with 24 worker threads
[ OK ] Scheme/HTTPTest.PathParse/0 (234 ms)
[ RUN ] Scheme/HTTPTest.PathParse/1
*** Aborted at 1499253624 (unix time) try "date -d @1499253624" if you are using GNU date ***
PC: @ 0x7f87bf798912 (unknown)
*** SIGSEGV (@0x7f8771cf6eb8) received by PID 2947 (TID 0x7f87bb836700) from PID 1909419704; stack trace: ***
I0705 11:20:24.744930 2947 process.cpp:1282] libprocess is initialized on 172.17.0.12:40474 with 24 worker threads
[ OK ] Scheme/HTTPTest.PathParse/1 (4 ms)
[ RUN ] Scheme/HTTPTest.Get/0
@ 0x7f87c0014370 (unknown)
@ 0x7f87bf798912 (unknown)
@ 0x7f87bf798f8c (unknown)
@ 0x42b1a3 process::UPID::UPID()
@ 0x8fcdec process::DispatchEvent::DispatchEvent()
@ 0x8f5275 process::internal::dispatch()
@ 0x910002 process::dispatch<>()
@ 0x8f4184 process::ProcessBase::route()
@ 0x9e88b9 process::ProcessBase::route<>()
@ 0x9e4bb2 process::Help::initialize()
@ 0x8ed69a process::ProcessManager::resume()
@ 0x8e9a98 _ZZN7process14ProcessManager12init_threadsEvENKUt_clEv
@ 0x8fc38c _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEE9_M_invokeIIEEEvSt12_Index_tupleIIXspT_EEE
@ 0x8fc2d0 _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEEclEv
@ 0x8fc25a _ZNSt6thread5_ImplISt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEEE6_M_runEv
@ 0x7f87bf78f230 (unknown)
@ 0x7f87c000cdc5 start_thread
@ 0x7f87beef876d __clone
make[7]: *** [check-local] Segmentation fault (core dumped)
make[7]: Leaving directory `/mesos/mesos-1.4.0/_build/3rdparty/libprocess'
make[6]: *** [check-am] Error 2
make[6]: Leaving directory `/mesos/mesos-1.4.0/_build/3rdparty/libprocess'
make[5]: *** [check-recursive] Error 1
make[5]: Leaving directory `/mesos/mesos-1.4.0/_build/3rdparty/libprocess'
make[4]: *** [check] Error 2
make[4]: Leaving directory `/mesos/mesos-1.4.0/_build/3rdparty/libprocess'
make[3]: *** [check-recursive] Error 1
make[3]: Leaving directory `/mesos/mesos-1.4.0/_build/3rdparty'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/mesos/mesos-1.4.0/_build/3rdparty'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-1.4.0/_build'
make: *** [distcheck] Error 1
+ docker rmi mesos-1499252354-5989
Untagged: mesos-1499252354-5989:latest
Deleted: sha256:d6af4faef13a50b0db6b883453b99c3c90cabf0940c4d40780e35ff37ae67fd3
Deleted: sha256:9616e3f7c405d8eace271afbac88a09431685544ca022400521d28da7edb082e
Deleted: sha256:7bc93d49eddd1eb3bb1d6528fa9665dca1bf339166e5a57498d8f1afda6b71c3
Deleted: sha256:d6eaf8667cac7bc90e5dc00e4638db4a6cf354b84a65ff340215d084f3ddd41a
Deleted: sha256:ee8dde873bdc356dfc66affe95035e3db12aa1e94b885c1e4b26fc101628b004
Deleted: sha256:fb94bf38730267f850197f843ebaeabf88d63ddbb06b445a1b2835654227b013
Deleted: sha256:bd9cdec3a6f34ad1e640346b721a2933ddc184537b134a1bcec2118f17db21b1
Deleted: sha256:b4e74941b34456699fa007d48a5082ef1d8c59cc6ca35578db87aa5a3eabe790
Deleted: sha256:eabaef1315cca628630c4553096e0eb7f2179c8cc56ba4875eadd0692d3e2804
Deleted: sha256:58a218eaf89d899278f998533af46ee3636ef0857920fbabe652d3c9b7b0486d
Deleted: sha256:6e8aa0fcf8e4fdb7b93fe523f466378cb1e45f3f214b012f08927f103490e7a0
Deleted: sha256:97047c3ae048380dffd8ddf00c0ba394b8f4b902d8bde93e9a70235af89e111a
Deleted: sha256:31f3dabf8323b66be0d84c64f93c9fa1b938cbf0216a161b715dfdb2775d8c94
Deleted: sha256:828d96ea899f6d8b397cdda7030b0aeb56e1bd87af2c827aefb07092a4d81653
Deleted: sha256:a01c24500e5d484a4fcd15634516cb8a7270b0e87d49c5f53ae06690f071efa2
Deleted: sha256:d5bf5c08b61dc85b8d30030f035428ecbcadcbccc8b4122229786cc57550e3c2
Deleted: sha256:8f6cdc4a6c3818e009eaad28ce699b7af9372f3d0dd8e1d49cc88d781cc01d28
Deleted: sha256:98bbc09032b57319839c2ba5ebca772a129acbe6e2dee865de37c33f7f4dcc3f
Deleted: sha256:84166cf99e7890fe507df0d9e0304e5719150598a6406dbaac6f51d5a01116c5
Deleted: sha256:bbf10190b4f2155d8d8db1da2a15bb402bc3ced5a58fdb8d2b4b3481a33516d1
Deleted: sha256:31fcdcfede9ee2fcce146ae44ff65a897589f1907a54484f9cea27e7bc96a32a
Deleted: sha256:bbb16be2d407b992c4dedad1cb287219a863607281823f5ee90670b2368e6612
Deleted: sha256:9ecb85f47774e77f03e2aee5333e258a8ee513c88b3ab725b778474dca23144d
Deleted: sha256:50a5b1622f846d757a17cd0605304bf6f90d7d214576636c597f0b53b1c90fde
Deleted: sha256:47e15f92156201b72d4fafb70a5c791ddc1edb949eab3440e4147bfbedfb38a7
Build step 'Execute shell' marked build as failure
Not sending mail to unregistered user toenshoff@me.com
Build failed in Jenkins: Mesos-Buildbot » autotools,gcc,--verbose --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,centos:7,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2) #3863
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=autotools,COMPILER=gcc,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3863/display/redirect?page=changes>
Changes:
[alexr] Cleaned up logging around subscribers in master.
------------------------------------------
[...truncated 30.68 MB...]
[ RUN ] Endpoint/SlaveEndpointTest.UnauthorizedRequest/0
I0704 13:57:25.798975 9187 containerizer.cpp:230] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni,environment_secret
W0704 13:57:25.799741 9187 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0704 13:57:25.799917 9187 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0704 13:57:25.799995 9187 provisioner.cpp:255] Using default backend 'copy'
I0704 13:57:25.806398 29880 slave.cpp:250] Mesos agent started on (776)@172.17.0.6:37756
I0704 13:57:25.806421 29880 slave.cpp:251] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_gZtfJC/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_gZtfJC/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/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_gZtfJC/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_gZtfJC/executor_secret_key" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_gZtfJC/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_gZtfJC/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.4.0/_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_gZtfJC" --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_jOI50W"
I0704 13:57:25.806814 29880 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_gZtfJC/credential'
I0704 13:57:25.807037 29880 slave.cpp:283] Agent using credential for: test-principal
I0704 13:57:25.807061 29880 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_gZtfJC/http_credentials'
I0704 13:57:25.807391 29880 http.cpp:976] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-executor'
I0704 13:57:25.807502 29880 http.cpp:997] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-executor'
I0704 13:57:25.807729 29880 http.cpp:976] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0704 13:57:25.807823 29880 http.cpp:997] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readonly'
I0704 13:57:25.808138 29880 http.cpp:976] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0704 13:57:25.808297 29880 http.cpp:997] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readwrite'
I0704 13:57:25.810031 29880 slave.cpp:565] Agent resources: [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
I0704 13:57:25.810389 29880 slave.cpp:573] Agent attributes: [ ]
I0704 13:57:25.810405 29880 slave.cpp:578] Agent hostname: e3db83f9470c
I0704 13:57:25.810575 29889 status_update_manager.cpp:177] Pausing sending status updates
I0704 13:57:25.812430 29877 state.cpp:64] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_jOI50W/meta'
I0704 13:57:25.812937 29885 status_update_manager.cpp:203] Recovering status update manager
I0704 13:57:25.813163 29892 containerizer.cpp:582] Recovering containerizer
I0704 13:57:25.814903 29885 provisioner.cpp:416] Provisioner recovery complete
I0704 13:57:25.815456 29876 slave.cpp:6189] Finished recovery
I0704 13:57:25.816053 29876 slave.cpp:6371] Querying resource estimator for oversubscribable resources
I0704 13:57:25.816346 29879 slave.cpp:6385] Received oversubscribable resources {} from the resource estimator
I0704 13:57:25.818859 29891 process.cpp:3779] Handling HTTP event for process 'slave(776)' with path: '/slave(776)/monitor/statistics'
I0704 13:57:25.820797 29872 http.cpp:1116] HTTP GET for /slave(776)/monitor/statistics from 172.17.0.6:57620
I0704 13:57:25.820893 29872 http.cpp:937] Authorizing principal 'test-principal' to GET the '/monitor/statistics' endpoint
I0704 13:57:25.823536 29893 slave.cpp:839] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.UnauthorizedRequest/0 (39 ms)
[ RUN ] Endpoint/SlaveEndpointTest.UnauthorizedRequest/1
I0704 13:57:25.835858 9187 containerizer.cpp:230] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni,environment_secret
W0704 13:57:25.836654 9187 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0704 13:57:25.836819 9187 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0704 13:57:25.836858 9187 provisioner.cpp:255] Using default backend 'copy'
I0704 13:57:25.840900 29890 slave.cpp:250] Mesos agent started on (777)@172.17.0.6:37756
I0704 13:57:25.840926 29890 slave.cpp:251] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_0Wq0Tj/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_0Wq0Tj/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/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_0Wq0Tj/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_0Wq0Tj/executor_secret_key" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_0Wq0Tj/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_0Wq0Tj/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.4.0/_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_0Wq0Tj" --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_Vzx55D"
I0704 13:57:25.841343 29890 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_0Wq0Tj/credential'
I0704 13:57:25.841511 29890 slave.cpp:283] Agent using credential for: test-principal
I0704 13:57:25.841536 29890 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_0Wq0Tj/http_credentials'
I0704 13:57:25.841825 29890 http.cpp:976] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-executor'
I0704 13:57:25.841940 29890 http.cpp:997] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-executor'
I0704 13:57:25.842170 29890 http.cpp:976] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0704 13:57:25.842295 29890 http.cpp:997] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readonly'
I0704 13:57:25.842660 29890 http.cpp:976] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0704 13:57:25.842782 29890 http.cpp:997] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readwrite'
I0704 13:57:25.844442 29890 slave.cpp:565] Agent resources: [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
I0704 13:57:25.844719 29890 slave.cpp:573] Agent attributes: [ ]
I0704 13:57:25.844735 29890 slave.cpp:578] Agent hostname: e3db83f9470c
I0704 13:57:25.844892 29879 status_update_manager.cpp:177] Pausing sending status updates
I0704 13:57:25.846472 29882 state.cpp:64] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_Vzx55D/meta'
I0704 13:57:25.846993 29891 status_update_manager.cpp:203] Recovering status update manager
I0704 13:57:25.847379 29880 containerizer.cpp:582] Recovering containerizer
I0704 13:57:25.848951 29879 provisioner.cpp:416] Provisioner recovery complete
I0704 13:57:25.849292 29874 slave.cpp:6189] Finished recovery
I0704 13:57:25.849738 29874 slave.cpp:6371] Querying resource estimator for oversubscribable resources
I0704 13:57:25.850064 29875 slave.cpp:6385] Received oversubscribable resources {} from the resource estimator
I0704 13:57:25.852149 29874 process.cpp:3779] Handling HTTP event for process 'slave(777)' with path: '/slave(777)/monitor/statistics.json'
I0704 13:57:25.853781 29893 http.cpp:1116] HTTP GET for /slave(777)/monitor/statistics.json from 172.17.0.6:57622
I0704 13:57:25.853873 29893 http.cpp:937] Authorizing principal 'test-principal' to GET the '/monitor/statistics.json' endpoint
I0704 13:57:25.857033 29892 slave.cpp:839] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.UnauthorizedRequest/1 (33 ms)
[ RUN ] Endpoint/SlaveEndpointTest.UnauthorizedRequest/2
I0704 13:57:25.868460 9187 containerizer.cpp:230] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni,environment_secret
W0704 13:57:25.868938 9187 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0704 13:57:25.869036 9187 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0704 13:57:25.869068 9187 provisioner.cpp:255] Using default backend 'copy'
I0704 13:57:25.872390 29894 slave.cpp:250] Mesos agent started on (778)@172.17.0.6:37756
I0704 13:57:25.872414 29894 slave.cpp:251] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_eANuo1/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_eANuo1/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/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_eANuo1/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_eANuo1/executor_secret_key" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_eANuo1/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_eANuo1/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.4.0/_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_eANuo1" --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_hb6Gvl"
I0704 13:57:25.872814 29894 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_eANuo1/credential'
I0704 13:57:25.872957 29894 slave.cpp:283] Agent using credential for: test-principal
I0704 13:57:25.872982 29894 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_eANuo1/http_credentials'
I0704 13:57:25.873255 29894 http.cpp:976] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-executor'
I0704 13:57:25.873360 29894 http.cpp:997] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-executor'
I0704 13:57:25.873669 29894 http.cpp:976] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0704 13:57:25.873772 29894 http.cpp:997] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readonly'
I0704 13:57:25.873976 29894 http.cpp:976] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0704 13:57:25.874078 29894 http.cpp:997] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readwrite'
I0704 13:57:25.875802 29894 slave.cpp:565] Agent resources: [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
I0704 13:57:25.876044 29894 slave.cpp:573] Agent attributes: [ ]
I0704 13:57:25.876067 29894 slave.cpp:578] Agent hostname: e3db83f9470c
I0704 13:57:25.876207 29878 status_update_manager.cpp:177] Pausing sending status updates
I0704 13:57:25.877689 29880 state.cpp:64] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_hb6Gvl/meta'
I0704 13:57:25.877959 29879 status_update_manager.cpp:203] Recovering status update manager
I0704 13:57:25.878212 29880 containerizer.cpp:582] Recovering containerizer
I0704 13:57:25.879850 29871 provisioner.cpp:416] Provisioner recovery complete
I0704 13:57:25.880288 29877 slave.cpp:6189] Finished recovery
I0704 13:57:25.880769 29877 slave.cpp:6371] Querying resource estimator for oversubscribable resources
I0704 13:57:25.881140 29885 slave.cpp:6385] Received oversubscribable resources {} from the resource estimator
I0704 13:57:25.883831 29882 process.cpp:3779] Handling HTTP event for process 'slave(778)' with path: '/slave(778)/containers'
I0704 13:57:25.885679 29882 http.cpp:1116] HTTP GET for /slave(778)/containers from 172.17.0.6:57624
I0704 13:57:25.885759 29882 http.cpp:937] Authorizing principal 'test-principal' to GET the '/containers' endpoint
I0704 13:57:25.888310 29882 slave.cpp:839] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.UnauthorizedRequest/2 (31 ms)
[ RUN ] Endpoint/SlaveEndpointTest.NoAuthorizer/0
I0704 13:57:25.901640 9187 containerizer.cpp:230] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni,environment_secret
W0704 13:57:25.902132 9187 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0704 13:57:25.902248 9187 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0704 13:57:25.902284 9187 provisioner.cpp:255] Using default backend 'copy'
I0704 13:57:25.904197 9187 cluster.cpp:448] Creating default 'local' authorizer
I0704 13:57:25.905921 29881 slave.cpp:250] Mesos agent started on (779)@172.17.0.6:37756
I0704 13:57:25.905946 29881 slave.cpp:251] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_0_qYGacJ/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_qYGacJ/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/Endpoint_SlaveEndpointTest_NoAuthorizer_0_qYGacJ/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_qYGacJ/executor_secret_key" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_0_qYGacJ/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_qYGacJ/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.4.0/_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_qYGacJ" --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_bKfte3"
I0704 13:57:25.906358 29881 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_0_qYGacJ/credential'
I0704 13:57:25.906539 29881 slave.cpp:283] Agent using credential for: test-principal
I0704 13:57:25.906563 29881 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_0_qYGacJ/http_credentials'
I0704 13:57:25.906842 29881 http.cpp:976] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-executor'
I0704 13:57:25.906949 29881 http.cpp:997] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-executor'
I0704 13:57:25.907124 29881 http.cpp:976] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0704 13:57:25.907382 29881 http.cpp:997] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readonly'
I0704 13:57:25.907693 29881 http.cpp:976] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0704 13:57:25.907840 29881 http.cpp:997] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readwrite'
I0704 13:57:25.909729 29881 slave.cpp:565] Agent resources: [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
I0704 13:57:25.910007 29881 slave.cpp:573] Agent attributes: [ ]
I0704 13:57:25.910023 29881 slave.cpp:578] Agent hostname: e3db83f9470c
I0704 13:57:25.910199 29892 status_update_manager.cpp:177] Pausing sending status updates
I0704 13:57:25.911873 29882 state.cpp:64] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_0_bKfte3/meta'
I0704 13:57:25.912395 29887 status_update_manager.cpp:203] Recovering status update manager
I0704 13:57:25.912616 29892 containerizer.cpp:582] Recovering containerizer
I0704 13:57:25.914418 29878 provisioner.cpp:416] Provisioner recovery complete
I0704 13:57:25.915001 29884 slave.cpp:6189] Finished recovery
I0704 13:57:25.915423 29884 slave.cpp:6371] Querying resource estimator for oversubscribable resources
I0704 13:57:25.915824 29874 slave.cpp:6385] Received oversubscribable resources {} from the resource estimator
I0704 13:57:25.918082 29882 process.cpp:3779] Handling HTTP event for process 'slave(779)' with path: '/slave(779)/monitor/statistics'
I0704 13:57:25.920137 29877 http.cpp:1116] HTTP GET for /slave(779)/monitor/statistics from 172.17.0.6:57626
I0704 13:57:25.920207 29877 http.cpp:937] Authorizing principal 'test-principal' to GET the '/monitor/statistics' endpoint
I0704 13:57:25.924012 29871 slave.cpp:839] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.NoAuthorizer/0 (36 ms)
[ RUN ] Endpoint/SlaveEndpointTest.NoAuthorizer/1
I0704 13:57:25.936712 9187 containerizer.cpp:230] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni,environment_secret
W0704 13:57:25.937239 9187 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0704 13:57:25.937335 9187 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0704 13:57:25.937367 9187 provisioner.cpp:255] Using default backend 'copy'
I0704 13:57:25.939024 9187 cluster.cpp:448] Creating default 'local' authorizer
I0704 13:57:25.940636 29887 slave.cpp:250] Mesos agent started on (780)@172.17.0.6:37756
I0704 13:57:25.940659 29887 slave.cpp:251] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_1_KAiMkr/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_KAiMkr/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/Endpoint_SlaveEndpointTest_NoAuthorizer_1_KAiMkr/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_KAiMkr/executor_secret_key" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_1_KAiMkr/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_KAiMkr/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.4.0/_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_KAiMkr" --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_1gVRhL"
I0704 13:57:25.941045 29887 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_1_KAiMkr/credential'
I0704 13:57:25.941187 29887 slave.cpp:283] Agent using credential for: test-principal
I0704 13:57:25.941210 29887 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_1_KAiMkr/http_credentials'
I0704 13:57:25.941512 29887 http.cpp:976] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-executor'
I0704 13:57:25.941622 29887 http.cpp:997] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-executor'
I0704 13:57:25.941908 29887 http.cpp:976] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0704 13:57:25.942044 29887 http.cpp:997] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readonly'
I0704 13:57:25.942260 29887 http.cpp:976] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0704 13:57:25.942435 29887 http.cpp:997] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readwrite'
I0704 13:57:25.943981 29887 slave.cpp:565] Agent resources: [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
I0704 13:57:25.944221 29887 slave.cpp:573] Agent attributes: [ ]
I0704 13:57:25.944247 29887 slave.cpp:578] Agent hostname: e3db83f9470c
I0704 13:57:25.944403 29878 status_update_manager.cpp:177] Pausing sending status updates
I0704 13:57:25.946106 29881 state.cpp:64] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_1_1gVRhL/meta'
I0704 13:57:25.946815 29877 status_update_manager.cpp:203] Recovering status update manager
I0704 13:57:25.947152 29882 containerizer.cpp:582] Recovering containerizer
I0704 13:57:25.948770 29876 provisioner.cpp:416] Provisioner recovery complete
I0704 13:57:25.949232 29893 slave.cpp:6189] Finished recovery
I0704 13:57:25.949915 29893 slave.cpp:6371] Querying resource estimator for oversubscribable resources
I0704 13:57:25.950258 29893 slave.cpp:6385] Received oversubscribable resources {} from the resource estimator
I0704 13:57:25.952733 29892 process.cpp:3779] Handling HTTP event for process 'slave(780)' with path: '/slave(780)/monitor/statistics.json'
I0704 13:57:25.954684 29874 http.cpp:1116] HTTP GET for /slave(780)/monitor/statistics.json from 172.17.0.6:57628
I0704 13:57:25.954763 29874 http.cpp:937] Authorizing principal 'test-principal' to GET the '/monitor/statistics.json' endpoint
I0704 13:57:25.957998 29888 slave.cpp:839] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.NoAuthorizer/1 (33 ms)
[ RUN ] Endpoint/SlaveEndpointTest.NoAuthorizer/2
I0704 13:57:25.969863 9187 containerizer.cpp:230] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni,environment_secret
W0704 13:57:25.970360 9187 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0704 13:57:25.970458 9187 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0704 13:57:25.970492 9187 provisioner.cpp:255] Using default backend 'copy'
I0704 13:57:25.972728 9187 cluster.cpp:448] Creating default 'local' authorizer
I0704 13:57:25.974550 29877 slave.cpp:250] Mesos agent started on (781)@172.17.0.6:37756
I0704 13:57:25.974577 29877 slave.cpp:251] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_2_a3JlN9/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_a3JlN9/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/Endpoint_SlaveEndpointTest_NoAuthorizer_2_a3JlN9/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_a3JlN9/executor_secret_key" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_2_a3JlN9/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_a3JlN9/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.4.0/_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_a3JlN9" --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_dZfuFt"
I0704 13:57:25.975020 29877 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_2_a3JlN9/credential'
I0704 13:57:25.975167 29877 slave.cpp:283] Agent using credential for: test-principal
I0704 13:57:25.975194 29877 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_2_a3JlN9/http_credentials'
I0704 13:57:25.975487 29877 http.cpp:976] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-executor'
I0704 13:57:25.975592 29877 http.cpp:997] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-executor'
I0704 13:57:25.975775 29877 http.cpp:976] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0704 13:57:25.975927 29877 http.cpp:997] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readonly'
I0704 13:57:25.976214 29877 http.cpp:976] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0704 13:57:25.976331 29877 http.cpp:997] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readwrite'
I0704 13:57:25.977962 29877 slave.cpp:565] Agent resources: [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
I0704 13:57:25.978210 29877 slave.cpp:573] Agent attributes: [ ]
I0704 13:57:25.978227 29877 slave.cpp:578] Agent hostname: e3db83f9470c
I0704 13:57:25.978405 29887 status_update_manager.cpp:177] Pausing sending status updates
I0704 13:57:25.979746 29876 state.cpp:64] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_2_dZfuFt/meta'
I0704 13:57:25.980154 29891 status_update_manager.cpp:203] Recovering status update manager
I0704 13:57:25.980394 29871 containerizer.cpp:582] Recovering containerizer
I0704 13:57:25.982137 29889 provisioner.cpp:416] Provisioner recovery complete
I0704 13:57:25.982614 29879 slave.cpp:6189] Finished recovery
I0704 13:57:25.983062 29879 slave.cpp:6371] Querying resource estimator for oversubscribable resources
I0704 13:57:25.983409 29873 slave.cpp:6385] Received oversubscribable resources {} from the resource estimator
I0704 13:57:25.985735 29880 process.cpp:3779] Handling HTTP event for process 'slave(781)' with path: '/slave(781)/containers'
I0704 13:57:25.987577 29883 http.cpp:1116] HTTP GET for /slave(781)/containers from 172.17.0.6:57630
I0704 13:57:25.987671 29883 http.cpp:937] Authorizing principal 'test-principal' to GET the '/containers' endpoint
I0704 13:57:25.991379 29887 slave.cpp:839] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.NoAuthorizer/2 (33 ms)
[----------] 9 tests from Endpoint/SlaveEndpointTest (315 ms total)
[----------] Global test environment tear-down
[==========] 1625 tests from 181 test cases ran. (635205 ms total)
[ PASSED ] 1620 tests.
[ FAILED ] 5 tests, listed below:
[ FAILED ] CommandExecutorCheckTest.CommandCheckDeliveredAndReconciled
[ FAILED ] DefaultExecutorCheckTest.CommandCheckDeliveredAndReconciled
[ FAILED ] DefaultExecutorCheckTest.CommandCheckStatusChange
[ FAILED ] DefaultExecutorCheckTest.CommandCheckSeesParentsEnv
[ FAILED ] DefaultExecutorCheckTest.CommandCheckSharesWorkDirWithTask
5 FAILED TESTS
YOU HAVE 20 DISABLED TESTS
I0704 13:57:26.060756 29895 process.cpp:968] Failed to accept socket: future discarded
make[4]: *** [check-local] Error 1
make[4]: Leaving directory `/mesos/mesos-1.4.0/_build/src'
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/mesos/mesos-1.4.0/_build/src'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/mesos/mesos-1.4.0/_build/src'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-1.4.0/_build'
make: *** [distcheck] Error 1
+ docker rmi mesos-1499173934-27274
Untagged: mesos-1499173934-27274:latest
Deleted: sha256:308c434e069815e3a69f1530e8e77e314819a91de192008fee80917d5cf60822
Deleted: sha256:68a92609bd9ed7844bad5d5d46709d8adf26c469d4b6fb5ce49a272c0e019968
Deleted: sha256:99b684cbc325d6d7c6b802fd685ba34e5762598aaa4825263421211afa667b69
Deleted: sha256:7879f7ca03d1fe140b91cd05f7d67b3a53af6c2a8d34ca2f0a647204473f434c
Deleted: sha256:cd64fc85520f2cf9563b54fe7073d7e57201f46bd7fc34786d753353d35f77a4
Deleted: sha256:82bae648c7cb0f05cbe87239f4ed068b2ea9174675a69260511745f6c4153f46
Deleted: sha256:776d21953060132beb41deb5959b541ce0a04b648526020276dc250617939bb6
Deleted: sha256:b28c2004ec117801cbb9054bc46f542e5e345a96ed3d0e0aae52145b326eb564
Deleted: sha256:a57e07b2295e82af265c5fca4938eaf71a833873d41c230afcb5d34bba18ca52
Deleted: sha256:1dfba2c1f06ecc5dd584454a142f3aa06acad7da8c6ba9fc2f2e91285773abb9
Deleted: sha256:ff333c7476762438ee4614ee1d00f75330412b6f54f67ec77c03a132033d51b2
Deleted: sha256:61d642c72bb43a3f7b954b74065d3afdc188b2fa5e7375f5a96dcdef0f59be29
Deleted: sha256:d2adab793e50e15ce01a1ced4d03a7b319302509f867881fc142f8103451991f
Deleted: sha256:ea62cc969708d7f5cf9b0c35c18077c1b69d3bd61cb6419644de99284e31d573
Deleted: sha256:602d13f602f3ade733c35fba54d8ac0f76188145b6dae3c3148519100395308f
Deleted: sha256:f6aa535783d4053c8f58214077b6ccb19d9241da0a1ddf84ca5aa92652cf8d72
Deleted: sha256:a5dceb6319ed8f67d0eeb96cddaf6d03a653b31decb9c2efb508fb1e691f8976
Deleted: sha256:f96d6b88429e76bd068ad85586c0d4e3aa24af65e4feebc96002a47adbe56812
Deleted: sha256:c146b2065e703ae749daf648b503bd28a61742d9f44521fb5827c5866196a2a4
Deleted: sha256:3e204942f626caf5553bcfdb9a0f9ec66a66d4fd9e2f192078c4c6d4519e5217
Deleted: sha256:4ff355dfdf8f5266b83a46c74cc65cbea770bdaa2efa1f2741d86dfaba3bd933
Deleted: sha256:8ecd2e5abbf886ea9e7ac01bfca6c19fc5a65c0e0a17192c29f515928660b3ec
Deleted: sha256:8a880565a082f7e47bee4b810f03b74e2ea124e6c8ee4f5633e80624b227acea
Deleted: sha256:0073af38d71a86e6881c64b539cd53974df440c54b086257932ac2c6297cbc87
Deleted: sha256:b8c0cdbca8d7fe2fbd692f115204e171e57e542438e50fd7af3f392ed9a2ec20
Build step 'Execute shell' marked build as failure
Not sending mail to unregistered user toenshoff@me.com
Build failed in Jenkins: Mesos-Buildbot » autotools,gcc,--verbose --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,centos:7,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2) #3862
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=autotools,COMPILER=gcc,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3862/display/redirect>
------------------------------------------
[...truncated 3.18 MB...]
I0704 07:27:56.157881 32094 hierarchical.cpp:176] Skipping recovery of hierarchical allocator: nothing to recover
I0704 07:27:56.158442 32080 replica.cpp:537] Replica received write request for position 2 from __req_res__(174)@172.17.0.5:33842
I0704 07:27:56.158686 32080 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 207561ns
I0704 07:27:56.158709 32080 replica.cpp:708] Persisted action TRUNCATE at position 2
I0704 07:27:56.159276 32089 replica.cpp:691] Replica received learned notice for position 2 from @0.0.0.0:0
I0704 07:27:56.159528 32089 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 220082ns
I0704 07:27:56.159593 32089 leveldb.cpp:399] Deleting ~1 keys from leveldb took 38812ns
I0704 07:27:56.159617 32089 replica.cpp:708] Persisted action TRUNCATE at position 2
I0704 07:27:56.162654 32058 containerizer.cpp:200] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W0704 07:27:56.163221 32058 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges, but is running as user mesos
W0704 07:27:56.163352 32058 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0704 07:27:56.166780 32098 slave.cpp:208] Mesos agent started on 72.17.0.5:33842
I0704 07:27:56.166805 32098 slave.cpp:209] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/mesos/store/appc" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticatee="crammd5" --authentication_backoff_factor="1secs" --authorizer="local" --cgroups_cpu_enable_pids_and_tids_count="false" --cgroups_enable_cfs="false" --cgroups_hierarchy="/sys/fs/cgroup" --cgroups_limit_swap="false" --cgroups_root="mesos" --container_disk_watch_interval="15secs" --containerizers="mesos" --credential="/tmp/HTTPCommandExecutorTest_TerminateWithACK_pravej/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/HTTPCommandExecutorTest_TerminateWithACK_pravej/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="true" --http_credentials="/tmp/HTTPCommandExecutorTest_TerminateWithACK_pravej/http_credentials" --image_provisioner_backend="copy" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.1.3/_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/HTTPCommandExecutorTest_TerminateWithACK_pravej" --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/HTTPCommandExecutorTest_TerminateWithACK_PASuqq"
I0704 07:27:56.167300 32098 credentials.hpp:86] Loading credential for authentication from '/tmp/HTTPCommandExecutorTest_TerminateWithACK_pravej/credential'
I0704 07:27:56.167471 32098 slave.cpp:346] Agent using credential for: test-principal
I0704 07:27:56.167508 32098 credentials.hpp:37] Loading credentials for authentication from '/tmp/HTTPCommandExecutorTest_TerminateWithACK_pravej/http_credentials'
I0704 07:27:56.167716 32098 http.cpp:887] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0704 07:27:56.167816 32058 sched.cpp:226] Version: 1.1.3
I0704 07:27:56.167934 32098 http.cpp:887] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0704 07:27:56.168488 32080 sched.cpp:330] New master detected at master@172.17.0.5:33842
I0704 07:27:56.168566 32080 sched.cpp:396] Authenticating with master master@172.17.0.5:33842
I0704 07:27:56.168582 32080 sched.cpp:403] Using default CRAM-MD5 authenticatee
I0704 07:27:56.168781 32082 authenticatee.cpp:121] Creating new client SASL connection
I0704 07:27:56.169031 32077 master.cpp:6680] Authenticating scheduler-6f9122f6-a7d1-424f-b410-0ee874db5786@172.17.0.5:33842
I0704 07:27:56.169068 32098 slave.cpp:533] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0704 07:27:56.169138 32093 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(32)@172.17.0.5:33842
I0704 07:27:56.169145 32098 slave.cpp:541] Agent attributes: [ ]
I0704 07:27:56.169179 32098 slave.cpp:546] Agent hostname: 9b18639978b2
I0704 07:27:56.169348 32085 authenticator.cpp:98] Creating new server SASL connection
I0704 07:27:56.169566 32083 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0704 07:27:56.169598 32083 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0704 07:27:56.169708 32095 authenticator.cpp:204] Received SASL authentication start
I0704 07:27:56.169773 32095 authenticator.cpp:326] Authentication requires more steps
I0704 07:27:56.169878 32089 authenticatee.cpp:259] Received SASL authentication step
I0704 07:27:56.170075 32088 authenticator.cpp:232] Received SASL authentication step
I0704 07:27:56.170114 32088 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '9b18639978b2' server FQDN: '9b18639978b2' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0704 07:27:56.170135 32088 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0704 07:27:56.170183 32088 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0704 07:27:56.170214 32088 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '9b18639978b2' server FQDN: '9b18639978b2' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0704 07:27:56.170233 32088 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0704 07:27:56.170246 32088 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0704 07:27:56.170269 32088 authenticator.cpp:318] Authentication success
I0704 07:27:56.170366 32083 authenticatee.cpp:299] Authentication success
I0704 07:27:56.170420 32089 master.cpp:6710] Successfully authenticated principal 'test-principal' at scheduler-6f9122f6-a7d1-424f-b410-0ee874db5786@172.17.0.5:33842
I0704 07:27:56.170586 32080 state.cpp:57] Recovering state from '/tmp/HTTPCommandExecutorTest_TerminateWithACK_PASuqq/meta'
I0704 07:27:56.170635 32079 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(32)@172.17.0.5:33842
I0704 07:27:56.170758 32086 sched.cpp:502] Successfully authenticated with master master@172.17.0.5:33842
I0704 07:27:56.170778 32086 sched.cpp:820] Sending SUBSCRIBE call to master@172.17.0.5:33842
I0704 07:27:56.171041 32088 status_update_manager.cpp:203] Recovering status update manager
I0704 07:27:56.171049 32086 sched.cpp:853] Will retry registration in 236.794605ms if necessary
I0704 07:27:56.171213 32078 master.cpp:2596] Received SUBSCRIBE call for framework 'default' at scheduler-6f9122f6-a7d1-424f-b410-0ee874db5786@172.17.0.5:33842
I0704 07:27:56.171245 32091 containerizer.cpp:555] Recovering containerizer
I0704 07:27:56.171272 32078 master.cpp:2053] Authorizing framework principal 'test-principal' to receive offers for role '*'
I0704 07:27:56.171758 32078 master.cpp:2672] Subscribing framework default with checkpointing disabled and capabilities [ ]
I0704 07:27:56.172371 32082 sched.cpp:743] Framework registered with 6d791d4e-39d7-4052-a072-7c54264508ce-0000
I0704 07:27:56.172417 32082 sched.cpp:757] Scheduler::registered took 24099ns
I0704 07:27:56.172457 32092 hierarchical.cpp:275] Added framework 6d791d4e-39d7-4052-a072-7c54264508ce-0000
I0704 07:27:56.172536 32092 hierarchical.cpp:1694] No allocations performed
I0704 07:27:56.172555 32083 provisioner.cpp:253] Provisioner recovery complete
I0704 07:27:56.172567 32092 hierarchical.cpp:1789] No inverse offers to send out!
I0704 07:27:56.172627 32092 hierarchical.cpp:1286] Performed allocation for 0 agents in 144398ns
I0704 07:27:56.172824 32093 slave.cpp:5388] Finished recovery
I0704 07:27:56.173316 32093 slave.cpp:5562] Querying resource estimator for oversubscribable resources
I0704 07:27:56.173523 32093 slave.cpp:915] New master detected at master@172.17.0.5:33842
I0704 07:27:56.173543 32093 slave.cpp:974] Authenticating with master master@172.17.0.5:33842
I0704 07:27:56.173552 32096 status_update_manager.cpp:177] Pausing sending status updates
I0704 07:27:56.173599 32093 slave.cpp:985] Using default CRAM-MD5 authenticatee
I0704 07:27:56.173724 32093 slave.cpp:947] Detecting new master
I0704 07:27:56.173779 32091 authenticatee.cpp:121] Creating new client SASL connection
I0704 07:27:56.174015 32081 master.cpp:6680] Authenticating (1)@172.17.0.5:33842
I0704 07:27:56.174108 32085 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(33)@172.17.0.5:33842
I0704 07:27:56.174299 32086 authenticator.cpp:98] Creating new server SASL connection
I0704 07:27:56.174464 32093 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0704 07:27:56.174485 32093 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0704 07:27:56.174567 32096 authenticator.cpp:204] Received SASL authentication start
I0704 07:27:56.174618 32096 authenticator.cpp:326] Authentication requires more steps
I0704 07:27:56.174700 32084 authenticatee.cpp:259] Received SASL authentication step
I0704 07:27:56.174813 32093 authenticator.cpp:232] Received SASL authentication step
I0704 07:27:56.174846 32093 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '9b18639978b2' server FQDN: '9b18639978b2' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0704 07:27:56.174865 32093 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0704 07:27:56.174902 32093 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0704 07:27:56.174931 32093 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '9b18639978b2' server FQDN: '9b18639978b2' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0704 07:27:56.174947 32093 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0704 07:27:56.174960 32093 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0704 07:27:56.174983 32093 authenticator.cpp:318] Authentication success
I0704 07:27:56.175055 32084 authenticatee.cpp:299] Authentication success
I0704 07:27:56.175159 32096 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(33)@172.17.0.5:33842
I0704 07:27:56.175197 32088 master.cpp:6710] Successfully authenticated principal 'test-principal' at (1)@172.17.0.5:33842
I0704 07:27:56.175422 32082 slave.cpp:1069] Successfully authenticated with master master@172.17.0.5:33842
I0704 07:27:56.175590 32082 slave.cpp:1475] Will retry registration in 1.256262ms if necessary
I0704 07:27:56.175736 32089 master.cpp:5103] Registering agent at (1)@172.17.0.5:33842 (9b18639978b2) with id 6d791d4e-39d7-4052-a072-7c54264508ce-S0
I0704 07:27:56.176156 32087 registrar.cpp:461] Applied 1 operations in 53525ns; attempting to update the registry
I0704 07:27:56.176928 32090 log.cpp:577] Attempting to append 337 bytes to the log
I0704 07:27:56.177042 32088 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 3
I0704 07:27:56.177968 32098 slave.cpp:1475] Will retry registration in 10.122728ms if necessary
I0704 07:27:56.177980 32078 replica.cpp:537] Replica received write request for position 3 from __req_res__(175)@172.17.0.5:33842
I0704 07:27:56.178062 32088 master.cpp:5091] Ignoring register agent message from (1)@172.17.0.5:33842 (9b18639978b2) as admission is already in progress
I0704 07:27:56.182516 32078 leveldb.cpp:341] Persisting action (356 bytes) to leveldb took 4.495053ms
I0704 07:27:56.182559 32078 replica.cpp:708] Persisted action APPEND at position 3
I0704 07:27:56.183100 32079 replica.cpp:691] Replica received learned notice for position 3 from @0.0.0.0:0
I0704 07:27:56.183413 32079 leveldb.cpp:341] Persisting action (358 bytes) to leveldb took 285383ns
I0704 07:27:56.183434 32079 replica.cpp:708] Persisted action APPEND at position 3
I0704 07:27:56.184710 32091 registrar.cpp:506] Successfully updated the registry in 8.498944ms
I0704 07:27:56.184957 32098 log.cpp:596] Attempting to truncate the log to 3
I0704 07:27:56.185055 32084 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 4
I0704 07:27:56.185446 32084 slave.cpp:4176] Received ping from slave-observer(8)@172.17.0.5:33842
I0704 07:27:56.185508 32080 master.cpp:5174] Registered agent 6d791d4e-39d7-4052-a072-7c54264508ce-S0 at (1)@172.17.0.5:33842 (9b18639978b2) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0704 07:27:56.185650 32084 slave.cpp:1115] Registered with master master@172.17.0.5:33842; given agent ID 6d791d4e-39d7-4052-a072-7c54264508ce-S0
I0704 07:27:56.185684 32084 fetcher.cpp:90] Clearing fetcher cache
I0704 07:27:56.185745 32083 replica.cpp:537] Replica received write request for position 4 from __req_res__(176)@172.17.0.5:33842
I0704 07:27:56.185858 32100 hierarchical.cpp:485] Added agent 6d791d4e-39d7-4052-a072-7c54264508ce-S0 (9b18639978b2) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
I0704 07:27:56.185894 32080 status_update_manager.cpp:184] Resuming sending status updates
I0704 07:27:56.186008 32083 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 229547ns
I0704 07:27:56.186025 32083 replica.cpp:708] Persisted action TRUNCATE at position 4
I0704 07:27:56.186049 32084 slave.cpp:1138] Checkpointing SlaveInfo to '/tmp/HTTPCommandExecutorTest_TerminateWithACK_PASuqq/meta/slaves/6d791d4e-39d7-4052-a072-7c54264508ce-S0/slave.info'
I0704 07:27:56.186542 32079 replica.cpp:691] Replica received learned notice for position 4 from @0.0.0.0:0
I0704 07:27:56.187110 32100 hierarchical.cpp:1789] No inverse offers to send out!
I0704 07:27:56.187182 32100 hierarchical.cpp:1309] Performed allocation for agent 6d791d4e-39d7-4052-a072-7c54264508ce-S0 in 1.282152ms
I0704 07:27:56.187630 32093 master.cpp:6509] Sending 1 offers to framework 6d791d4e-39d7-4052-a072-7c54264508ce-0000 (default) at scheduler-6f9122f6-a7d1-424f-b410-0ee874db5786@172.17.0.5:33842
I0704 07:27:56.188221 32080 sched.cpp:917] Scheduler::resourceOffers took 97242ns
I0704 07:27:56.190575 32078 master.cpp:3554] Processing ACCEPT call for offers: [ 6d791d4e-39d7-4052-a072-7c54264508ce-O0 ] on agent 6d791d4e-39d7-4052-a072-7c54264508ce-S0 at (1)@172.17.0.5:33842 (9b18639978b2) for framework 6d791d4e-39d7-4052-a072-7c54264508ce-0000 (default) at scheduler-6f9122f6-a7d1-424f-b410-0ee874db5786@172.17.0.5:33842
I0704 07:27:56.190675 32078 master.cpp:3159] Authorizing framework principal 'test-principal' to launch task 2490f8c2-e633-498b-9a57-78693d0c0786
I0704 07:27:56.190814 32079 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 4.241141ms
I0704 07:27:56.190897 32079 leveldb.cpp:399] Deleting ~2 keys from leveldb took 55186ns
I0704 07:27:56.190922 32079 replica.cpp:708] Persisted action TRUNCATE at position 4
I0704 07:27:56.193218 32078 master.cpp:8262] Adding task 2490f8c2-e633-498b-9a57-78693d0c0786 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on agent 6d791d4e-39d7-4052-a072-7c54264508ce-S0 (9b18639978b2)
I0704 07:27:56.193470 32078 master.cpp:4192] Launching task 2490f8c2-e633-498b-9a57-78693d0c0786 of framework 6d791d4e-39d7-4052-a072-7c54264508ce-0000 (default) at scheduler-6f9122f6-a7d1-424f-b410-0ee874db5786@172.17.0.5:33842 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on agent 6d791d4e-39d7-4052-a072-7c54264508ce-S0 at (1)@172.17.0.5:33842 (9b18639978b2)
I0704 07:27:56.194241 32093 slave.cpp:1539] Got assigned task '2490f8c2-e633-498b-9a57-78693d0c0786' for framework 6d791d4e-39d7-4052-a072-7c54264508ce-0000
I0704 07:27:56.195170 32093 slave.cpp:1701] Launching task '2490f8c2-e633-498b-9a57-78693d0c0786' for framework 6d791d4e-39d7-4052-a072-7c54264508ce-0000
I0704 07:27:56.195817 32093 paths.cpp:536] Trying to chown '/tmp/HTTPCommandExecutorTest_TerminateWithACK_PASuqq/slaves/6d791d4e-39d7-4052-a072-7c54264508ce-S0/frameworks/6d791d4e-39d7-4052-a072-7c54264508ce-0000/executors/2490f8c2-e633-498b-9a57-78693d0c0786/runs/765e883a-cb63-4045-8704-8e17c781b5f8' to user 'mesos'
I0704 07:27:56.201314 32093 slave.cpp:6286] Launching executor '2490f8c2-e633-498b-9a57-78693d0c0786' of framework 6d791d4e-39d7-4052-a072-7c54264508ce-0000 with resources cpus(*):0.1; mem(*):32 in work directory '/tmp/HTTPCommandExecutorTest_TerminateWithACK_PASuqq/slaves/6d791d4e-39d7-4052-a072-7c54264508ce-S0/frameworks/6d791d4e-39d7-4052-a072-7c54264508ce-0000/executors/2490f8c2-e633-498b-9a57-78693d0c0786/runs/765e883a-cb63-4045-8704-8e17c781b5f8'
I0704 07:27:56.202368 32084 containerizer.cpp:938] Starting container 765e883a-cb63-4045-8704-8e17c781b5f8 for executor '2490f8c2-e633-498b-9a57-78693d0c0786' of framework 6d791d4e-39d7-4052-a072-7c54264508ce-0000
I0704 07:27:56.202841 32093 slave.cpp:1987] Queued task '2490f8c2-e633-498b-9a57-78693d0c0786' for executor '2490f8c2-e633-498b-9a57-78693d0c0786' of framework 6d791d4e-39d7-4052-a072-7c54264508ce-0000
I0704 07:27:56.202970 32093 slave.cpp:868] Successfully attached file '/tmp/HTTPCommandExecutorTest_TerminateWithACK_PASuqq/slaves/6d791d4e-39d7-4052-a072-7c54264508ce-S0/frameworks/6d791d4e-39d7-4052-a072-7c54264508ce-0000/executors/2490f8c2-e633-498b-9a57-78693d0c0786/runs/765e883a-cb63-4045-8704-8e17c781b5f8'
I0704 07:27:56.208575 32092 containerizer.cpp:1463] Launching 'mesos-containerizer' with flags '--command="{"arguments":["mesos-executor","--launcher_dir=\/mesos\/mesos-1.1.3\/_build\/src"],"shell":false,"value":"\/mesos\/mesos-1.1.3\/_build\/src\/mesos-executor"}" --help="false" --pipe_read="9" --pipe_write="12" --pre_exec_commands="[]" --runtime_directory="/tmp/HTTPCommandExecutorTest_TerminateWithACK_pravej/containers/765e883a-cb63-4045-8704-8e17c781b5f8" --unshare_namespace_mnt="false" --user="mesos" --working_directory="/tmp/HTTPCommandExecutorTest_TerminateWithACK_PASuqq/slaves/6d791d4e-39d7-4052-a072-7c54264508ce-S0/frameworks/6d791d4e-39d7-4052-a072-7c54264508ce-0000/executors/2490f8c2-e633-498b-9a57-78693d0c0786/runs/765e883a-cb63-4045-8704-8e17c781b5f8"'
I0704 07:27:56.210381 32092 launcher.cpp:127] Forked child with pid '32115' for container '765e883a-cb63-4045-8704-8e17c781b5f8'
I0704 07:27:57.139818 32093 hierarchical.cpp:1694] No allocations performed
I0704 07:27:57.139900 32093 hierarchical.cpp:1789] No inverse offers to send out!
I0704 07:27:57.139986 32093 hierarchical.cpp:1286] Performed allocation for 1 agents in 435266ns
I0704 07:27:58.140571 32083 hierarchical.cpp:1694] No allocations performed
I0704 07:27:58.140648 32083 hierarchical.cpp:1789] No inverse offers to send out!
I0704 07:27:58.140739 32083 hierarchical.cpp:1286] Performed allocation for 1 agents in 419966ns
I0704 07:27:59.141898 32089 hierarchical.cpp:1694] No allocations performed
I0704 07:27:59.141978 32089 hierarchical.cpp:1789] No inverse offers to send out!
I0704 07:27:59.142068 32089 hierarchical.cpp:1286] Performed allocation for 1 agents in 401222ns
I0704 07:28:00.143640 32098 hierarchical.cpp:1694] No allocations performed
I0704 07:28:00.143754 32098 hierarchical.cpp:1789] No inverse offers to send out!
I0704 07:28:00.143851 32098 hierarchical.cpp:1286] Performed allocation for 1 agents in 517320ns
I0704 07:28:01.145193 32099 hierarchical.cpp:1694] No allocations performed
I0704 07:28:01.145306 32099 hierarchical.cpp:1789] No inverse offers to send out!
I0704 07:28:01.145396 32099 hierarchical.cpp:1286] Performed allocation for 1 agents in 486141ns
I0704 07:28:02.146589 32084 hierarchical.cpp:1694] No allocations performed
I0704 07:28:02.146692 32084 hierarchical.cpp:1789] No inverse offers to send out!
I0704 07:28:02.146796 32084 hierarchical.cpp:1286] Performed allocation for 1 agents in 532411ns
I0704 07:28:03.148078 32083 hierarchical.cpp:1694] No allocations performed
I0704 07:28:03.148185 32083 hierarchical.cpp:1789] No inverse offers to send out!
I0704 07:28:03.148296 32083 hierarchical.cpp:1286] Performed allocation for 1 agents in 471537ns
I0704 07:28:04.148949 32086 hierarchical.cpp:1694] No allocations performed
I0704 07:28:04.149031 32086 hierarchical.cpp:1789] No inverse offers to send out!
I0704 07:28:04.149117 32086 hierarchical.cpp:1286] Performed allocation for 1 agents in 386492ns
I0704 07:28:05.150135 32100 hierarchical.cpp:1694] No allocations performed
I0704 07:28:05.150220 32100 hierarchical.cpp:1789] No inverse offers to send out!
I0704 07:28:05.150305 32100 hierarchical.cpp:1286] Performed allocation for 1 agents in 392771ns
I0704 07:28:06.151582 32077 hierarchical.cpp:1694] No allocations performed
I0704 07:28:06.151682 32077 hierarchical.cpp:1789] No inverse offers to send out!
I0704 07:28:06.151760 32077 hierarchical.cpp:1286] Performed allocation for 1 agents in 471986ns
I0704 07:28:07.152446 32097 hierarchical.cpp:1694] No allocations performed
I0704 07:28:07.152531 32097 hierarchical.cpp:1789] No inverse offers to send out!
I0704 07:28:07.152622 32097 hierarchical.cpp:1286] Performed allocation for 1 agents in 373205ns
I0704 07:28:08.154006 32080 hierarchical.cpp:1694] No allocations performed
I0704 07:28:08.154098 32080 hierarchical.cpp:1789] No inverse offers to send out!
I0704 07:28:08.154191 32080 hierarchical.cpp:1286] Performed allocation for 1 agents in 446651ns
I0704 07:28:09.155745 32096 hierarchical.cpp:1694] No allocations performed
I0704 07:28:09.155843 32096 hierarchical.cpp:1789] No inverse offers to send out!
I0704 07:28:09.155939 32096 hierarchical.cpp:1286] Performed allocation for 1 agents in 469684ns
I0704 07:28:10.157173 32094 hierarchical.cpp:1694] No allocations performed
I0704 07:28:10.157256 32094 hierarchical.cpp:1789] No inverse offers to send out!
I0704 07:28:10.157346 32094 hierarchical.cpp:1286] Performed allocation for 1 agents in 397009ns
I0704 07:28:11.158957 32088 hierarchical.cpp:1694] No allocations performed
I0704 07:28:11.159062 32088 hierarchical.cpp:1789] No inverse offers to send out!
I0704 07:28:11.159179 32088 hierarchical.cpp:1286] Performed allocation for 1 agents in 529641ns
I0704 07:28:11.186323 32079 slave.cpp:4176] Received ping from slave-observer(8)@172.17.0.5:33842
../../src/tests/command_executor_tests.cpp:280: Failure
Failed to wait 15secs for statusRunning
I0704 07:28:11.190170 32077 master.cpp:1297] Framework 6d791d4e-39d7-4052-a072-7c54264508ce-0000 (default) at scheduler-6f9122f6-a7d1-424f-b410-0ee874db5786@172.17.0.5:33842 disconnected
I0704 07:28:11.190217 32077 master.cpp:2904] Disconnecting framework 6d791d4e-39d7-4052-a072-7c54264508ce-0000 (default) at scheduler-6f9122f6-a7d1-424f-b410-0ee874db5786@172.17.0.5:33842
I0704 07:28:11.190254 32077 master.cpp:2928] Deactivating framework 6d791d4e-39d7-4052-a072-7c54264508ce-0000 (default) at scheduler-6f9122f6-a7d1-424f-b410-0ee874db5786@172.17.0.5:33842
I0704 07:28:11.190367 32077 master.cpp:1310] Giving framework 6d791d4e-39d7-4052-a072-7c54264508ce-0000 (default) at scheduler-6f9122f6-a7d1-424f-b410-0ee874db5786@172.17.0.5:33842 0ns to failover
I0704 07:28:11.190466 32095 hierarchical.cpp:386] Deactivated framework 6d791d4e-39d7-4052-a072-7c54264508ce-0000
../../src/tests/command_executor_tests.cpp:269: Failure
Actual function call count doesn't match EXPECT_CALL(sched, statusUpdate(_, _))...
Expected: to be called twice
Actual: never called - unsatisfied and active
I0704 07:28:11.190675 32086 master.cpp:6361] Framework failover timeout, removing framework 6d791d4e-39d7-4052-a072-7c54264508ce-0000 (default) at scheduler-6f9122f6-a7d1-424f-b410-0ee874db5786@172.17.0.5:33842
I0704 07:28:11.190713 32086 master.cpp:7095] Removing framework 6d791d4e-39d7-4052-a072-7c54264508ce-0000 (default) at scheduler-6f9122f6-a7d1-424f-b410-0ee874db5786@172.17.0.5:33842
I0704 07:28:11.191083 32092 slave.cpp:2511] Asked to shut down framework 6d791d4e-39d7-4052-a072-7c54264508ce-0000 by master@172.17.0.5:33842
I0704 07:28:11.191143 32092 slave.cpp:2536] Shutting down framework 6d791d4e-39d7-4052-a072-7c54264508ce-0000
I0704 07:28:11.191262 32086 master.cpp:7640] Updating the state of task 2490f8c2-e633-498b-9a57-78693d0c0786 of framework 6d791d4e-39d7-4052-a072-7c54264508ce-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED)
I0704 07:28:11.191349 32092 slave.cpp:4903] Shutting down executor '2490f8c2-e633-498b-9a57-78693d0c0786' of framework 6d791d4e-39d7-4052-a072-7c54264508ce-0000
W0704 07:28:11.191376 32092 slave.hpp:870] Unable to send event to executor '2490f8c2-e633-498b-9a57-78693d0c0786' of framework 6d791d4e-39d7-4052-a072-7c54264508ce-0000: unknown connection type
../../src/tests/command_executor_tests.cpp:274: Failure
Actual function call count doesn't match EXPECT_CALL(slave, executorTerminated(_, _, _))...
Expected: to be called once
Actual: never called - unsatisfied and active
I0704 07:28:11.192728 32087 hierarchical.cpp:1018] Recovered cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: {}) on agent 6d791d4e-39d7-4052-a072-7c54264508ce-S0 from framework 6d791d4e-39d7-4052-a072-7c54264508ce-0000
I0704 07:28:11.192760 32086 master.cpp:7736] Removing task 2490f8c2-e633-498b-9a57-78693d0c0786 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] of framework 6d791d4e-39d7-4052-a072-7c54264508ce-0000 on agent 6d791d4e-39d7-4052-a072-7c54264508ce-S0 at (1)@172.17.0.5:33842 (9b18639978b2)
*** Aborted at 1499153291 (unix time) try "date -d @1499153291" if you are using GNU date ***
I0704 07:28:11.193471 32092 hierarchical.cpp:337] Removed framework 6d791d4e-39d7-4052-a072-7c54264508ce-0000
PC: @ 0x0 (unknown)
*** SIGSEGV (@0x0) received by PID 32058 (TID 0x7fa9735ef700) from PID 0; stack trace: ***
@ 0x7fa97f3e7370 (unknown)
make[4]: *** [check-local] Segmentation fault (core dumped)
make[4]: Leaving directory `/mesos/mesos-1.1.3/_build/src'
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/mesos/mesos-1.1.3/_build/src'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/mesos/mesos-1.1.3/_build/src'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-1.1.3/_build'
make: *** [distcheck] Error 1
+ docker rmi mesos-1499151727-4918
Untagged: mesos-1499151727-4918:latest
Deleted: sha256:95ae829b1a358fe190e9d1b17fb7e950e6e6d090c41500a080f6be8641ac5c85
Deleted: sha256:721dc47f34c2077525c2f5983c2b52fcba99870780415b2e7bef66437eabd3b8
Deleted: sha256:f0e0a1c2aecc68372b01ecf66fb36aad2efeaaf74fcc7e57f3e3c189f5c7b743
Deleted: sha256:3402ae1e02eb76dcea81f4bc0c33ecee555ec673aa844290854240e17f277d7c
Deleted: sha256:4d1c2c56f8587ea44266fa6c08a777658aef164a9871342d8eea88ef96f2c0b4
Deleted: sha256:93d3fc5b13ce18bac9687da87713500bad46de60efbbe34ea5a18796dab6a11f
Deleted: sha256:7c806565801854dd3c0fe473031e35ad0895605d7be02eb6a129b76667b7527b
Deleted: sha256:ffa31924615f3350907caaac461c40ea4acfa6628c28940c2c9d05cf796453c1
Deleted: sha256:2cc36820e92225e957c816a6ad13dbc6dab2106518f8ab2bdc4420b43a197494
Deleted: sha256:6ab060a20a9b8262978611a77923104b399932e193c26baeb5c4987b0c06b77c
Deleted: sha256:b605c420cd834faa2804daa87733385df9d9612857a2d0926d901f433055370a
Deleted: sha256:294dd0ee86576519cbff98a509f0bb52d592a909fb65d04514b9d3a20447d8d7
Deleted: sha256:38a24dce19fd25d15fd18b883e1809631ff3bd213eb646f90f89272487871366
Deleted: sha256:40d12d0f71885f2442d9951f35fd355d498074af58cd12f946e03916faa523bf
Deleted: sha256:f37c9406df3ed6d6bc8b19935fb885bd1739c556c5e88b5f224191d18126595e
Deleted: sha256:0415c6535bc5de93127b176fe1ff118e8c894e9cb0502dc9d11d44484ec2f752
Deleted: sha256:d6d73c6eac613b3d918deab31aa4cc8b0af4f4dc40366eafe5ac8edf96966f16
Deleted: sha256:1843c16b950a416162f83e0206c0036827fae1885195cbb6f09d9feb7bf5dc57
Deleted: sha256:6cc5dc91c33e9ed8cc183eeca7d0507c2c793bdf9d290ec8b178a8e885c5cf37
Deleted: sha256:4f51205e46c902c967247dcafdcbbacc4741ebd7a8374aa0be7fcdea758db9d6
Deleted: sha256:3d841c2785306dc94e070604836479cce458f79232c55428dbd06995cd7b8690
Deleted: sha256:721b2d53a1294be51c9290548dfbd5746c7280b4e22dc2f624eb1313dd9b81fe
Deleted: sha256:8bc3f12ebe1e12e104b79cbc81777053dbca934a7b0dea2d91b17a8ab73ef58c
Deleted: sha256:fb52122868a8d9234be927616b3f14318e84e97cee1708cae2fb4836fad768f2
Deleted: sha256:bba8d4ae1145015dc838d2c1e44275b50da5277b40a15af15cfe510603977925
Build step 'Execute shell' marked build as failure
Not sending mail to unregistered user toenshoff@me.com
Build failed in Jenkins: Mesos-Buildbot » autotools,gcc,--verbose --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,centos:7,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2) #3861
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=autotools,COMPILER=gcc,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3861/display/redirect?page=changes>
Changes:
[toenshoff] Fixed interference of unbundled dependency include paths with Boost.
[toenshoff] Added MESOS-7690 to the 1.1.3 CHANGELOG.
[toenshoff] Added MESOS-7581 to the 1.1.3 CHANGELOG.
------------------------------------------
[...truncated 3.22 MB...]
I0704 06:32:29.580580 32110 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
I0704 06:32:29.580843 32114 master.cpp:1676] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
I0704 06:32:29.580884 32101 hierarchical.cpp:176] Skipping recovery of hierarchical allocator: nothing to recover
I0704 06:32:29.581567 32111 replica.cpp:537] Replica received write request for position 2 from __req_res__(174)@172.17.0.7:34172
I0704 06:32:29.581836 32111 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 233398ns
I0704 06:32:29.581861 32111 replica.cpp:708] Persisted action TRUNCATE at position 2
I0704 06:32:29.582773 32103 replica.cpp:691] Replica received learned notice for position 2 from @0.0.0.0:0
I0704 06:32:29.583041 32103 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 236254ns
I0704 06:32:29.583104 32103 leveldb.cpp:399] Deleting ~1 keys from leveldb took 33756ns
I0704 06:32:29.583129 32103 replica.cpp:708] Persisted action TRUNCATE at position 2
I0704 06:32:29.586521 32078 containerizer.cpp:200] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W0704 06:32:29.587159 32078 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges, but is running as user mesos
W0704 06:32:29.587322 32078 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0704 06:32:29.591982 32115 slave.cpp:208] Mesos agent started on 72.17.0.7:34172
I0704 06:32:29.592003 32115 slave.cpp:209] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/mesos/store/appc" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticatee="crammd5" --authentication_backoff_factor="1secs" --authorizer="local" --cgroups_cpu_enable_pids_and_tids_count="false" --cgroups_enable_cfs="false" --cgroups_hierarchy="/sys/fs/cgroup" --cgroups_limit_swap="false" --cgroups_root="mesos" --container_disk_watch_interval="15secs" --containerizers="mesos" --credential="/tmp/HTTPCommandExecutorTest_TerminateWithACK_0aBU6s/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/HTTPCommandExecutorTest_TerminateWithACK_0aBU6s/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="true" --http_credentials="/tmp/HTTPCommandExecutorTest_TerminateWithACK_0aBU6s/http_credentials" --image_provisioner_backend="copy" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.1.3/_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/HTTPCommandExecutorTest_TerminateWithACK_0aBU6s" --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/HTTPCommandExecutorTest_TerminateWithACK_aKyQpN"
I0704 06:32:29.592486 32115 credentials.hpp:86] Loading credential for authentication from '/tmp/HTTPCommandExecutorTest_TerminateWithACK_0aBU6s/credential'
I0704 06:32:29.592655 32115 slave.cpp:346] Agent using credential for: test-principal
I0704 06:32:29.592676 32115 credentials.hpp:37] Loading credentials for authentication from '/tmp/HTTPCommandExecutorTest_TerminateWithACK_0aBU6s/http_credentials'
I0704 06:32:29.592926 32115 http.cpp:887] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0704 06:32:29.593075 32115 http.cpp:887] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0704 06:32:29.593088 32078 sched.cpp:226] Version: 1.1.3
I0704 06:32:29.593842 32120 sched.cpp:330] New master detected at master@172.17.0.7:34172
I0704 06:32:29.593941 32120 sched.cpp:396] Authenticating with master master@172.17.0.7:34172
I0704 06:32:29.593960 32120 sched.cpp:403] Using default CRAM-MD5 authenticatee
I0704 06:32:29.594234 32105 authenticatee.cpp:121] Creating new client SASL connection
I0704 06:32:29.594545 32109 master.cpp:6680] Authenticating scheduler-f2db69d7-29e7-4171-a37f-9316423ff2b3@172.17.0.7:34172
I0704 06:32:29.594667 32112 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(32)@172.17.0.7:34172
I0704 06:32:29.594667 32115 slave.cpp:533] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0704 06:32:29.594759 32115 slave.cpp:541] Agent attributes: [ ]
I0704 06:32:29.594774 32115 slave.cpp:546] Agent hostname: ec15f6502380
I0704 06:32:29.594931 32097 authenticator.cpp:98] Creating new server SASL connection
I0704 06:32:29.595156 32105 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0704 06:32:29.595193 32105 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0704 06:32:29.595340 32098 authenticator.cpp:204] Received SASL authentication start
I0704 06:32:29.595412 32098 authenticator.cpp:326] Authentication requires more steps
I0704 06:32:29.595520 32110 authenticatee.cpp:259] Received SASL authentication step
I0704 06:32:29.595691 32111 authenticator.cpp:232] Received SASL authentication step
I0704 06:32:29.595726 32111 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'ec15f6502380' server FQDN: 'ec15f6502380' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0704 06:32:29.595738 32111 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0704 06:32:29.595783 32111 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0704 06:32:29.595808 32111 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'ec15f6502380' server FQDN: 'ec15f6502380' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0704 06:32:29.595819 32111 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0704 06:32:29.595829 32111 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0704 06:32:29.595847 32111 authenticator.cpp:318] Authentication success
I0704 06:32:29.595947 32097 authenticatee.cpp:299] Authentication success
I0704 06:32:29.596047 32101 master.cpp:6710] Successfully authenticated principal 'test-principal' at scheduler-f2db69d7-29e7-4171-a37f-9316423ff2b3@172.17.0.7:34172
I0704 06:32:29.596115 32119 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(32)@172.17.0.7:34172
I0704 06:32:29.596396 32105 sched.cpp:502] Successfully authenticated with master master@172.17.0.7:34172
I0704 06:32:29.596420 32105 sched.cpp:820] Sending SUBSCRIBE call to master@172.17.0.7:34172
I0704 06:32:29.596518 32105 sched.cpp:853] Will retry registration in 55.685919ms if necessary
I0704 06:32:29.596683 32099 master.cpp:2596] Received SUBSCRIBE call for framework 'default' at scheduler-f2db69d7-29e7-4171-a37f-9316423ff2b3@172.17.0.7:34172
I0704 06:32:29.596750 32099 master.cpp:2053] Authorizing framework principal 'test-principal' to receive offers for role '*'
I0704 06:32:29.597081 32097 state.cpp:57] Recovering state from '/tmp/HTTPCommandExecutorTest_TerminateWithACK_aKyQpN/meta'
I0704 06:32:29.597194 32109 master.cpp:2672] Subscribing framework default with checkpointing disabled and capabilities [ ]
I0704 06:32:29.597390 32099 status_update_manager.cpp:203] Recovering status update manager
I0704 06:32:29.597738 32101 containerizer.cpp:555] Recovering containerizer
I0704 06:32:29.597910 32105 sched.cpp:743] Framework registered with a590e91d-62a7-4e91-b865-76f0e1cbc775-0000
I0704 06:32:29.597965 32105 sched.cpp:757] Scheduler::registered took 27969ns
I0704 06:32:29.598122 32099 hierarchical.cpp:275] Added framework a590e91d-62a7-4e91-b865-76f0e1cbc775-0000
I0704 06:32:29.598232 32099 hierarchical.cpp:1694] No allocations performed
I0704 06:32:29.598275 32099 hierarchical.cpp:1789] No inverse offers to send out!
I0704 06:32:29.598361 32099 hierarchical.cpp:1286] Performed allocation for 0 agents in 211259ns
I0704 06:32:29.599311 32120 provisioner.cpp:253] Provisioner recovery complete
I0704 06:32:29.599668 32098 slave.cpp:5388] Finished recovery
I0704 06:32:29.600097 32098 slave.cpp:5562] Querying resource estimator for oversubscribable resources
I0704 06:32:29.600296 32118 status_update_manager.cpp:177] Pausing sending status updates
I0704 06:32:29.600298 32098 slave.cpp:915] New master detected at master@172.17.0.7:34172
I0704 06:32:29.600335 32098 slave.cpp:974] Authenticating with master master@172.17.0.7:34172
I0704 06:32:29.600390 32098 slave.cpp:985] Using default CRAM-MD5 authenticatee
I0704 06:32:29.600533 32098 slave.cpp:947] Detecting new master
I0704 06:32:29.600579 32119 authenticatee.cpp:121] Creating new client SASL connection
I0704 06:32:29.600808 32111 master.cpp:6680] Authenticating (1)@172.17.0.7:34172
I0704 06:32:29.600910 32106 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(33)@172.17.0.7:34172
I0704 06:32:29.601158 32115 authenticator.cpp:98] Creating new server SASL connection
I0704 06:32:29.601351 32118 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0704 06:32:29.601418 32118 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0704 06:32:29.601531 32100 authenticator.cpp:204] Received SASL authentication start
I0704 06:32:29.601583 32100 authenticator.cpp:326] Authentication requires more steps
I0704 06:32:29.601694 32110 authenticatee.cpp:259] Received SASL authentication step
I0704 06:32:29.601814 32117 authenticator.cpp:232] Received SASL authentication step
I0704 06:32:29.601843 32117 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'ec15f6502380' server FQDN: 'ec15f6502380' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0704 06:32:29.601856 32117 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0704 06:32:29.601888 32117 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0704 06:32:29.601915 32117 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'ec15f6502380' server FQDN: 'ec15f6502380' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0704 06:32:29.601933 32117 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0704 06:32:29.601944 32117 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0704 06:32:29.601963 32117 authenticator.cpp:318] Authentication success
I0704 06:32:29.602054 32118 authenticatee.cpp:299] Authentication success
I0704 06:32:29.602093 32100 master.cpp:6710] Successfully authenticated principal 'test-principal' at (1)@172.17.0.7:34172
I0704 06:32:29.602146 32106 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(33)@172.17.0.7:34172
I0704 06:32:29.602494 32117 slave.cpp:1069] Successfully authenticated with master master@172.17.0.7:34172
I0704 06:32:29.602658 32117 slave.cpp:1475] Will retry registration in 13.671823ms if necessary
I0704 06:32:29.602821 32106 master.cpp:5103] Registering agent at (1)@172.17.0.7:34172 (ec15f6502380) with id a590e91d-62a7-4e91-b865-76f0e1cbc775-S0
I0704 06:32:29.603281 32101 registrar.cpp:461] Applied 1 operations in 56611ns; attempting to update the registry
I0704 06:32:29.604045 32113 log.cpp:577] Attempting to append 337 bytes to the log
I0704 06:32:29.604202 32105 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 3
I0704 06:32:29.605094 32116 replica.cpp:537] Replica received write request for position 3 from __req_res__(175)@172.17.0.7:34172
I0704 06:32:29.609735 32116 leveldb.cpp:341] Persisting action (356 bytes) to leveldb took 4.598336ms
I0704 06:32:29.609766 32116 replica.cpp:708] Persisted action APPEND at position 3
I0704 06:32:29.610697 32102 replica.cpp:691] Replica received learned notice for position 3 from @0.0.0.0:0
I0704 06:32:29.610944 32102 leveldb.cpp:341] Persisting action (358 bytes) to leveldb took 216974ns
I0704 06:32:29.610968 32102 replica.cpp:708] Persisted action APPEND at position 3
I0704 06:32:29.612359 32109 registrar.cpp:506] Successfully updated the registry in 9.001984ms
I0704 06:32:29.612501 32107 log.cpp:596] Attempting to truncate the log to 3
I0704 06:32:29.612640 32114 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 4
I0704 06:32:29.613240 32116 slave.cpp:4176] Received ping from slave-observer(8)@172.17.0.7:34172
I0704 06:32:29.613415 32100 master.cpp:5174] Registered agent a590e91d-62a7-4e91-b865-76f0e1cbc775-S0 at (1)@172.17.0.7:34172 (ec15f6502380) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0704 06:32:29.613507 32103 slave.cpp:1115] Registered with master master@172.17.0.7:34172; given agent ID a590e91d-62a7-4e91-b865-76f0e1cbc775-S0
I0704 06:32:29.613534 32103 fetcher.cpp:90] Clearing fetcher cache
I0704 06:32:29.613667 32113 hierarchical.cpp:485] Added agent a590e91d-62a7-4e91-b865-76f0e1cbc775-S0 (ec15f6502380) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
I0704 06:32:29.613693 32115 status_update_manager.cpp:184] Resuming sending status updates
I0704 06:32:29.613903 32103 slave.cpp:1138] Checkpointing SlaveInfo to '/tmp/HTTPCommandExecutorTest_TerminateWithACK_aKyQpN/meta/slaves/a590e91d-62a7-4e91-b865-76f0e1cbc775-S0/slave.info'
I0704 06:32:29.613970 32105 replica.cpp:537] Replica received write request for position 4 from __req_res__(176)@172.17.0.7:34172
I0704 06:32:29.614235 32105 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 224911ns
I0704 06:32:29.614261 32105 replica.cpp:708] Persisted action TRUNCATE at position 4
I0704 06:32:29.615093 32113 hierarchical.cpp:1789] No inverse offers to send out!
I0704 06:32:29.615145 32099 replica.cpp:691] Replica received learned notice for position 4 from @0.0.0.0:0
I0704 06:32:29.615185 32113 hierarchical.cpp:1309] Performed allocation for agent a590e91d-62a7-4e91-b865-76f0e1cbc775-S0 in 1.475555ms
I0704 06:32:29.615428 32099 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 246715ns
I0704 06:32:29.615500 32099 leveldb.cpp:399] Deleting ~2 keys from leveldb took 41967ns
I0704 06:32:29.615525 32099 replica.cpp:708] Persisted action TRUNCATE at position 4
I0704 06:32:29.615685 32097 master.cpp:6509] Sending 1 offers to framework a590e91d-62a7-4e91-b865-76f0e1cbc775-0000 (default) at scheduler-f2db69d7-29e7-4171-a37f-9316423ff2b3@172.17.0.7:34172
I0704 06:32:29.616384 32115 sched.cpp:917] Scheduler::resourceOffers took 109994ns
I0704 06:32:29.619385 32101 master.cpp:3554] Processing ACCEPT call for offers: [ a590e91d-62a7-4e91-b865-76f0e1cbc775-O0 ] on agent a590e91d-62a7-4e91-b865-76f0e1cbc775-S0 at (1)@172.17.0.7:34172 (ec15f6502380) for framework a590e91d-62a7-4e91-b865-76f0e1cbc775-0000 (default) at scheduler-f2db69d7-29e7-4171-a37f-9316423ff2b3@172.17.0.7:34172
I0704 06:32:29.619496 32101 master.cpp:3159] Authorizing framework principal 'test-principal' to launch task 3268269b-706b-46b9-b59d-9da5e8c59f41
I0704 06:32:29.622709 32116 master.cpp:8262] Adding task 3268269b-706b-46b9-b59d-9da5e8c59f41 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on agent a590e91d-62a7-4e91-b865-76f0e1cbc775-S0 (ec15f6502380)
I0704 06:32:29.622992 32116 master.cpp:4192] Launching task 3268269b-706b-46b9-b59d-9da5e8c59f41 of framework a590e91d-62a7-4e91-b865-76f0e1cbc775-0000 (default) at scheduler-f2db69d7-29e7-4171-a37f-9316423ff2b3@172.17.0.7:34172 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on agent a590e91d-62a7-4e91-b865-76f0e1cbc775-S0 at (1)@172.17.0.7:34172 (ec15f6502380)
I0704 06:32:29.623756 32105 slave.cpp:1539] Got assigned task '3268269b-706b-46b9-b59d-9da5e8c59f41' for framework a590e91d-62a7-4e91-b865-76f0e1cbc775-0000
I0704 06:32:29.624912 32105 slave.cpp:1701] Launching task '3268269b-706b-46b9-b59d-9da5e8c59f41' for framework a590e91d-62a7-4e91-b865-76f0e1cbc775-0000
I0704 06:32:29.625597 32105 paths.cpp:536] Trying to chown '/tmp/HTTPCommandExecutorTest_TerminateWithACK_aKyQpN/slaves/a590e91d-62a7-4e91-b865-76f0e1cbc775-S0/frameworks/a590e91d-62a7-4e91-b865-76f0e1cbc775-0000/executors/3268269b-706b-46b9-b59d-9da5e8c59f41/runs/a18f691f-769d-4908-b05a-fa0c770a0955' to user 'mesos'
I0704 06:32:29.631089 32105 slave.cpp:6286] Launching executor '3268269b-706b-46b9-b59d-9da5e8c59f41' of framework a590e91d-62a7-4e91-b865-76f0e1cbc775-0000 with resources cpus(*):0.1; mem(*):32 in work directory '/tmp/HTTPCommandExecutorTest_TerminateWithACK_aKyQpN/slaves/a590e91d-62a7-4e91-b865-76f0e1cbc775-S0/frameworks/a590e91d-62a7-4e91-b865-76f0e1cbc775-0000/executors/3268269b-706b-46b9-b59d-9da5e8c59f41/runs/a18f691f-769d-4908-b05a-fa0c770a0955'
I0704 06:32:29.632021 32110 containerizer.cpp:938] Starting container a18f691f-769d-4908-b05a-fa0c770a0955 for executor '3268269b-706b-46b9-b59d-9da5e8c59f41' of framework a590e91d-62a7-4e91-b865-76f0e1cbc775-0000
I0704 06:32:29.632412 32105 slave.cpp:1987] Queued task '3268269b-706b-46b9-b59d-9da5e8c59f41' for executor '3268269b-706b-46b9-b59d-9da5e8c59f41' of framework a590e91d-62a7-4e91-b865-76f0e1cbc775-0000
I0704 06:32:29.632510 32105 slave.cpp:868] Successfully attached file '/tmp/HTTPCommandExecutorTest_TerminateWithACK_aKyQpN/slaves/a590e91d-62a7-4e91-b865-76f0e1cbc775-S0/frameworks/a590e91d-62a7-4e91-b865-76f0e1cbc775-0000/executors/3268269b-706b-46b9-b59d-9da5e8c59f41/runs/a18f691f-769d-4908-b05a-fa0c770a0955'
I0704 06:32:29.638075 32102 containerizer.cpp:1463] Launching 'mesos-containerizer' with flags '--command="{"arguments":["mesos-executor","--launcher_dir=\/mesos\/mesos-1.1.3\/_build\/src"],"shell":false,"value":"\/mesos\/mesos-1.1.3\/_build\/src\/mesos-executor"}" --help="false" --pipe_read="9" --pipe_write="12" --pre_exec_commands="[]" --runtime_directory="/tmp/HTTPCommandExecutorTest_TerminateWithACK_0aBU6s/containers/a18f691f-769d-4908-b05a-fa0c770a0955" --unshare_namespace_mnt="false" --user="mesos" --working_directory="/tmp/HTTPCommandExecutorTest_TerminateWithACK_aKyQpN/slaves/a590e91d-62a7-4e91-b865-76f0e1cbc775-S0/frameworks/a590e91d-62a7-4e91-b865-76f0e1cbc775-0000/executors/3268269b-706b-46b9-b59d-9da5e8c59f41/runs/a18f691f-769d-4908-b05a-fa0c770a0955"'
I0704 06:32:29.640249 32102 launcher.cpp:127] Forked child with pid '32135' for container 'a18f691f-769d-4908-b05a-fa0c770a0955'
I0704 06:32:30.533257 32105 hierarchical.cpp:1694] No allocations performed
I0704 06:32:30.533324 32105 hierarchical.cpp:1789] No inverse offers to send out!
I0704 06:32:30.533396 32105 hierarchical.cpp:1286] Performed allocation for 1 agents in 353601ns
I0704 06:32:31.534173 32120 hierarchical.cpp:1694] No allocations performed
I0704 06:32:31.534281 32120 hierarchical.cpp:1789] No inverse offers to send out!
I0704 06:32:31.534365 32120 hierarchical.cpp:1286] Performed allocation for 1 agents in 406796ns
I0704 06:32:32.535826 32106 hierarchical.cpp:1694] No allocations performed
I0704 06:32:32.535895 32106 hierarchical.cpp:1789] No inverse offers to send out!
I0704 06:32:32.535984 32106 hierarchical.cpp:1286] Performed allocation for 1 agents in 368162ns
I0704 06:32:33.537158 32117 hierarchical.cpp:1694] No allocations performed
I0704 06:32:33.537278 32117 hierarchical.cpp:1789] No inverse offers to send out!
I0704 06:32:33.537428 32117 hierarchical.cpp:1286] Performed allocation for 1 agents in 639455ns
I0704 06:32:34.538771 32113 hierarchical.cpp:1694] No allocations performed
I0704 06:32:34.538846 32113 hierarchical.cpp:1789] No inverse offers to send out!
I0704 06:32:34.538936 32113 hierarchical.cpp:1286] Performed allocation for 1 agents in 396178ns
I0704 06:32:35.540194 32111 hierarchical.cpp:1694] No allocations performed
I0704 06:32:35.540304 32111 hierarchical.cpp:1789] No inverse offers to send out!
I0704 06:32:35.540417 32111 hierarchical.cpp:1286] Performed allocation for 1 agents in 549757ns
I0704 06:32:36.541597 32117 hierarchical.cpp:1694] No allocations performed
I0704 06:32:36.541671 32117 hierarchical.cpp:1789] No inverse offers to send out!
I0704 06:32:36.541743 32117 hierarchical.cpp:1286] Performed allocation for 1 agents in 345695ns
I0704 06:32:37.542870 32105 hierarchical.cpp:1694] No allocations performed
I0704 06:32:37.542968 32105 hierarchical.cpp:1789] No inverse offers to send out!
I0704 06:32:37.543071 32105 hierarchical.cpp:1286] Performed allocation for 1 agents in 452689ns
I0704 06:32:38.544119 32104 hierarchical.cpp:1694] No allocations performed
I0704 06:32:38.544232 32104 hierarchical.cpp:1789] No inverse offers to send out!
I0704 06:32:38.544364 32104 hierarchical.cpp:1286] Performed allocation for 1 agents in 522695ns
I0704 06:32:39.545694 32103 hierarchical.cpp:1694] No allocations performed
I0704 06:32:39.545810 32103 hierarchical.cpp:1789] No inverse offers to send out!
I0704 06:32:39.545925 32103 hierarchical.cpp:1286] Performed allocation for 1 agents in 554595ns
I0704 06:32:40.546820 32111 hierarchical.cpp:1694] No allocations performed
I0704 06:32:40.546948 32111 hierarchical.cpp:1789] No inverse offers to send out!
I0704 06:32:40.547062 32111 hierarchical.cpp:1286] Performed allocation for 1 agents in 494544ns
I0704 06:32:41.548036 32109 hierarchical.cpp:1694] No allocations performed
I0704 06:32:41.548148 32109 hierarchical.cpp:1789] No inverse offers to send out!
I0704 06:32:41.548257 32109 hierarchical.cpp:1286] Performed allocation for 1 agents in 543069ns
I0704 06:32:42.549818 32098 hierarchical.cpp:1694] No allocations performed
I0704 06:32:42.549927 32098 hierarchical.cpp:1789] No inverse offers to send out!
I0704 06:32:42.550040 32098 hierarchical.cpp:1286] Performed allocation for 1 agents in 520910ns
I0704 06:32:43.551528 32119 hierarchical.cpp:1694] No allocations performed
I0704 06:32:43.551687 32119 hierarchical.cpp:1789] No inverse offers to send out!
I0704 06:32:43.551843 32119 hierarchical.cpp:1286] Performed allocation for 1 agents in 916473ns
I0704 06:32:44.552855 32108 hierarchical.cpp:1694] No allocations performed
I0704 06:32:44.552949 32108 hierarchical.cpp:1789] No inverse offers to send out!
I0704 06:32:44.553045 32108 hierarchical.cpp:1286] Performed allocation for 1 agents in 467526ns
I0704 06:32:44.614487 32110 slave.cpp:4176] Received ping from slave-observer(8)@172.17.0.7:34172
../../src/tests/command_executor_tests.cpp:280: Failure
Failed to wait 15secs for statusRunning
I0704 06:32:44.619179 32097 master.cpp:1297] Framework a590e91d-62a7-4e91-b865-76f0e1cbc775-0000 (default) at scheduler-f2db69d7-29e7-4171-a37f-9316423ff2b3@172.17.0.7:34172 disconnected
I0704 06:32:44.619230 32097 master.cpp:2904] Disconnecting framework a590e91d-62a7-4e91-b865-76f0e1cbc775-0000 (default) at scheduler-f2db69d7-29e7-4171-a37f-9316423ff2b3@172.17.0.7:34172
I0704 06:32:44.619283 32097 master.cpp:2928] Deactivating framework a590e91d-62a7-4e91-b865-76f0e1cbc775-0000 (default) at scheduler-f2db69d7-29e7-4171-a37f-9316423ff2b3@172.17.0.7:34172
I0704 06:32:44.619494 32097 master.cpp:1310] Giving framework a590e91d-62a7-4e91-b865-76f0e1cbc775-0000 (default) at scheduler-f2db69d7-29e7-4171-a37f-9316423ff2b3@172.17.0.7:34172 0ns to failover
../../src/tests/command_executor_tests.cpp:269: Failure
Actual function call count doesn't match EXPECT_CALL(sched, statusUpdate(_, _))...
Expected: to be called twice
Actual: never called - unsatisfied and active
I0704 06:32:44.619730 32109 hierarchical.cpp:386] Deactivated framework a590e91d-62a7-4e91-b865-76f0e1cbc775-0000
I0704 06:32:44.619801 32113 master.cpp:6361] Framework failover timeout, removing framework a590e91d-62a7-4e91-b865-76f0e1cbc775-0000 (default) at scheduler-f2db69d7-29e7-4171-a37f-9316423ff2b3@172.17.0.7:34172
I0704 06:32:44.619843 32113 master.cpp:7095] Removing framework a590e91d-62a7-4e91-b865-76f0e1cbc775-0000 (default) at scheduler-f2db69d7-29e7-4171-a37f-9316423ff2b3@172.17.0.7:34172
I0704 06:32:44.620281 32113 master.cpp:7640] Updating the state of task 3268269b-706b-46b9-b59d-9da5e8c59f41 of framework a590e91d-62a7-4e91-b865-76f0e1cbc775-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED)
I0704 06:32:44.620299 32100 slave.cpp:2511] Asked to shut down framework a590e91d-62a7-4e91-b865-76f0e1cbc775-0000 by master@172.17.0.7:34172
I0704 06:32:44.620348 32100 slave.cpp:2536] Shutting down framework a590e91d-62a7-4e91-b865-76f0e1cbc775-0000
I0704 06:32:44.620532 32100 slave.cpp:4903] Shutting down executor '3268269b-706b-46b9-b59d-9da5e8c59f41' of framework a590e91d-62a7-4e91-b865-76f0e1cbc775-0000
W0704 06:32:44.620558 32100 slave.hpp:870] Unable to send event to executor '3268269b-706b-46b9-b59d-9da5e8c59f41' of framework a590e91d-62a7-4e91-b865-76f0e1cbc775-0000: unknown connection type
../../src/tests/command_executor_tests.cpp:274: Failure
Actual function call count doesn't match EXPECT_CALL(slave, executorTerminated(_, _, _))...
Expected: to be called once
Actual: never called - unsatisfied and active
I0704 06:32:44.621457 32113 master.cpp:7736] Removing task 3268269b-706b-46b9-b59d-9da5e8c59f41 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] of framework a590e91d-62a7-4e91-b865-76f0e1cbc775-0000 on agent a590e91d-62a7-4e91-b865-76f0e1cbc775-S0 at (1)@172.17.0.7:34172 (ec15f6502380)
I0704 06:32:44.622043 32107 hierarchical.cpp:1018] Recovered cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: {}) on agent a590e91d-62a7-4e91-b865-76f0e1cbc775-S0 from framework a590e91d-62a7-4e91-b865-76f0e1cbc775-0000
I0704 06:32:44.622398 32107 hierarchical.cpp:337] Removed framework a590e91d-62a7-4e91-b865-76f0e1cbc775-0000
*** Aborted at 1499149964 (unix time) try "date -d @1499149964" if you are using GNU date ***
PC: @ 0x0 (unknown)
*** SIGSEGV (@0x0) received by PID 32078 (TID 0x7fa3b9519700) from PID 0; stack trace: ***
@ 0x7fa3c430f370 (unknown)
make[4]: *** [check-local] Segmentation fault (core dumped)
make[4]: Leaving directory `/mesos/mesos-1.1.3/_build/src'
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/mesos/mesos-1.1.3/_build/src'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/mesos/mesos-1.1.3/_build/src'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-1.1.3/_build'
make: *** [distcheck] Error 1
+ docker rmi mesos-1499148392-23691
Untagged: mesos-1499148392-23691:latest
Deleted: sha256:f7d46a4a22c1d3afb96631c0c38771ad864e6b209dbe4872cd2ff6e6e66fce50
Deleted: sha256:3552bc2c739c61202a470f08d0aeffc05ab4c88cd5566f06ee52f1f4403d4e33
Deleted: sha256:abd9b27098e6f817941c03e455cbb5f6a5bb3a8850996f7e95667ac9552e5552
Deleted: sha256:2f03b63fc8b885d2f3b95a692f78bb3277ac3394deea92cfb2253280b7aca712
Deleted: sha256:3452b9d79193135423ae367e7631e2f8c2f0194210f3519b5e8e55a18b012bcc
Deleted: sha256:0ac929ccbe7592abb1ab6411290e94f51a990b82a1a900370c73e2d04834a327
Deleted: sha256:203e38fe0258dbe1a2fbfe5d19ad176f4e9b52238691b0f58874b38f56e45fc6
Deleted: sha256:ddab4201e76d0b1fb83bd2bc573a698b7e5bcc657198a9432c2ce2d50a8f7177
Deleted: sha256:c80f277381ba6e22ed2ead3728654dafbb38f561082ccd588685c0db45a40c35
Deleted: sha256:e4e7ad22276f2e179402e4bc28e30d0ddbdd30eca665495342502aa49c83fbb0
Deleted: sha256:8d5b5c3e5d215cef846e1afbc9c7fe2e9e91f4a3d7055c725665a2574ae1b839
Deleted: sha256:63a5db4ee0d7311161c3379dd4d4bdf2b7c196030b5c4f1c9a9722261b8a7d19
Deleted: sha256:2b2395b8d7dd1f566bf137b8b5e58b4760e9fe1c4f549fa5a1be741c5b3ed938
Deleted: sha256:bb00562a7ebde3505b0a17b65a6c1802bcdeb1974ca95d40cd32c14c3138bcb9
Deleted: sha256:d1aab2927d90a99978c07a623e0ad14440bf46b0d9c759a1c92c687347a39149
Deleted: sha256:ac54c260c9d63f354caf7138af74bfafdd911d82dbe8cdfafe9b29b1c2773746
Deleted: sha256:5e18ecdeb9b11f01bbfa13045dc0cbf58ab24734953a8a3f0ee4ceb63630b2f8
Deleted: sha256:d2d57b377f50347913beefe3062300ddbb6edf1dd09fce01081815653096855d
Deleted: sha256:0c73c943f0e2f43816071a70e761cc9985ee8fbec60df2e0221310399180626c
Deleted: sha256:190dce08cb7b698ad1f2f00b5634427c7279178985784311e3f916eab104fcf1
Deleted: sha256:96b86b0db662037a8fd8214f3ea104f15bb2115b153ab29e8472e719fffce327
Deleted: sha256:a6ee5208102d19f77e7dbdae4aadc8f7687155b6a907dcf9f533ede809be3a03
Deleted: sha256:607049505f950fdc5b3e4b9f4e173e8b17fa638909a04617471717d803580b2d
Deleted: sha256:8a89e9fdf1ea0aa9b84e2336b1b160b91b11e4a8fda2e9d6d21b569a078d4bc8
Deleted: sha256:d66097ec1373710550d85812e8c987c5319be98545521c445143e3be18a8acbd
Build step 'Execute shell' marked build as failure
Not sending mail to unregistered user toenshoff@me.com
Build failed in Jenkins: Mesos-Buildbot » autotools,gcc,--verbose --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,centos:7,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2) #3860
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=autotools,COMPILER=gcc,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3860/display/redirect?page=changes>
Changes:
[toenshoff] Fixed interference of unbundled dependency include paths with Boost.
[toenshoff] Added MESOS-7581 to the 1.2.2 CHANGELOG.
------------------------------------------
[...truncated 3.32 MB...]
I0704 04:58:31.503592 1907 master.cpp:435] Master only allowing authenticated frameworks to register
I0704 04:58:31.503605 1907 master.cpp:449] Master only allowing authenticated agents to register
I0704 04:58:31.503614 1907 master.cpp:462] Master only allowing authenticated HTTP frameworks to register
I0704 04:58:31.503624 1907 credentials.hpp:37] Loading credentials for authentication from '/tmp/r4xlno/credentials'
I0704 04:58:31.503883 1907 master.cpp:507] Using default 'crammd5' authenticator
I0704 04:58:31.504029 1907 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I0704 04:58:31.504271 1907 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I0704 04:58:31.504443 1907 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I0704 04:58:31.504611 1907 master.cpp:587] Authorization enabled
I0704 04:58:31.504838 1902 hierarchical.cpp:161] Initialized hierarchical allocator process
I0704 04:58:31.504853 1892 whitelist_watcher.cpp:77] No whitelist given
I0704 04:58:31.507431 1894 master.cpp:2124] Elected as the leading master!
I0704 04:58:31.507462 1894 master.cpp:1646] Recovering from registrar
I0704 04:58:31.507618 1895 registrar.cpp:329] Recovering registrar
I0704 04:58:31.508450 1895 registrar.cpp:362] Successfully fetched the registry (0B) in 785152ns
I0704 04:58:31.508550 1895 registrar.cpp:461] Applied 1 operations in 19513ns; attempting to update the registry
I0704 04:58:31.509555 1895 registrar.cpp:506] Successfully updated the registry in 947968ns
I0704 04:58:31.509676 1895 registrar.cpp:392] Successfully recovered registrar
I0704 04:58:31.510138 1899 master.cpp:1762] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
I0704 04:58:31.510182 1891 hierarchical.cpp:188] Skipping recovery of hierarchical allocator: nothing to recover
I0704 04:58:31.512925 1868 containerizer.cpp:221] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W0704 04:58:31.513432 1868 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0704 04:58:31.513540 1868 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0704 04:58:31.513576 1868 provisioner.cpp:249] Using default backend 'copy'
I0704 04:58:31.517701 1910 slave.cpp:217] Mesos agent started on 172.17.0.5:35564
I0704 04:58:31.517729 1910 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/HTTPCommandExecutorTest_TerminateWithACK_ZVRsyI/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/HTTPCommandExecutorTest_TerminateWithACK_ZVRsyI/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="true" --http_credentials="/tmp/HTTPCommandExecutorTest_TerminateWithACK_ZVRsyI/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.2.2/_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/HTTPCommandExecutorTest_TerminateWithACK_ZVRsyI" --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/HTTPCommandExecutorTest_TerminateWithACK_r6KTs3"
I0704 04:58:31.518302 1910 credentials.hpp:86] Loading credential for authentication from '/tmp/HTTPCommandExecutorTest_TerminateWithACK_ZVRsyI/credential'
I0704 04:58:31.518476 1910 slave.cpp:360] Agent using credential for: test-principal
I0704 04:58:31.518499 1910 credentials.hpp:37] Loading credentials for authentication from '/tmp/HTTPCommandExecutorTest_TerminateWithACK_ZVRsyI/http_credentials'
I0704 04:58:31.518740 1910 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0704 04:58:31.518981 1910 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0704 04:58:31.518993 1868 sched.cpp:232] Version: 1.2.2
I0704 04:58:31.519680 1905 sched.cpp:336] New master detected at master@172.17.0.5:35564
I0704 04:58:31.519767 1905 sched.cpp:407] Authenticating with master master@172.17.0.5:35564
I0704 04:58:31.519785 1905 sched.cpp:414] Using default CRAM-MD5 authenticatee
I0704 04:58:31.519991 1894 authenticatee.cpp:121] Creating new client SASL connection
I0704 04:58:31.520262 1903 master.cpp:7199] Authenticating scheduler-e4dac63b-9ae9-44cc-ac6a-340178dcb2b4@172.17.0.5:35564
I0704 04:58:31.520396 1908 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(34)@172.17.0.5:35564
I0704 04:58:31.520438 1910 slave.cpp:547] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0704 04:58:31.520567 1910 slave.cpp:555] Agent attributes: [ ]
I0704 04:58:31.520583 1910 slave.cpp:560] Agent hostname: 9f9cb886e8d5
I0704 04:58:31.520638 1900 authenticator.cpp:98] Creating new server SASL connection
I0704 04:58:31.520715 1908 status_update_manager.cpp:177] Pausing sending status updates
I0704 04:58:31.520835 1905 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0704 04:58:31.520864 1905 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0704 04:58:31.520994 1889 authenticator.cpp:204] Received SASL authentication start
I0704 04:58:31.521055 1889 authenticator.cpp:326] Authentication requires more steps
I0704 04:58:31.521172 1905 authenticatee.cpp:259] Received SASL authentication step
I0704 04:58:31.521464 1896 authenticator.cpp:232] Received SASL authentication step
I0704 04:58:31.521534 1896 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '9f9cb886e8d5' server FQDN: '9f9cb886e8d5' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0704 04:58:31.521553 1896 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0704 04:58:31.521597 1896 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0704 04:58:31.521618 1896 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '9f9cb886e8d5' server FQDN: '9f9cb886e8d5' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0704 04:58:31.521630 1896 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0704 04:58:31.521639 1896 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0704 04:58:31.521661 1896 authenticator.cpp:318] Authentication success
I0704 04:58:31.521760 1906 authenticatee.cpp:299] Authentication success
I0704 04:58:31.521981 1902 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(34)@172.17.0.5:35564
I0704 04:58:31.522006 1905 master.cpp:7229] Successfully authenticated principal 'test-principal' at scheduler-e4dac63b-9ae9-44cc-ac6a-340178dcb2b4@172.17.0.5:35564
I0704 04:58:31.522195 1904 sched.cpp:513] Successfully authenticated with master master@172.17.0.5:35564
I0704 04:58:31.522222 1904 sched.cpp:836] Sending SUBSCRIBE call to master@172.17.0.5:35564
I0704 04:58:31.522347 1904 sched.cpp:869] Will retry registration in 306.308972ms if necessary
I0704 04:58:31.522495 1908 state.cpp:62] Recovering state from '/tmp/HTTPCommandExecutorTest_TerminateWithACK_r6KTs3/meta'
I0704 04:58:31.522505 1901 master.cpp:2737] Received SUBSCRIBE call for framework 'default' at scheduler-e4dac63b-9ae9-44cc-ac6a-340178dcb2b4@172.17.0.5:35564
I0704 04:58:31.522581 1901 master.cpp:2160] Authorizing framework principal 'test-principal' to receive offers for role '*'
I0704 04:58:31.522889 1910 status_update_manager.cpp:203] Recovering status update manager
I0704 04:58:31.523084 1892 master.cpp:2813] Subscribing framework default with checkpointing disabled and capabilities [ ]
I0704 04:58:31.523106 1903 containerizer.cpp:600] Recovering containerizer
I0704 04:58:31.523778 1904 sched.cpp:759] Framework registered with b3a7be1c-ec24-4cd5-aba0-51de09ae9609-0000
I0704 04:58:31.523847 1891 hierarchical.cpp:286] Added framework b3a7be1c-ec24-4cd5-aba0-51de09ae9609-0000
I0704 04:58:31.523859 1904 sched.cpp:773] Scheduler::registered took 45014ns
I0704 04:58:31.524001 1891 hierarchical.cpp:1810] No allocations performed
I0704 04:58:31.524052 1891 hierarchical.cpp:1900] No inverse offers to send out!
I0704 04:58:31.524106 1891 hierarchical.cpp:1394] Performed allocation for 0 agents in 160068ns
I0704 04:58:31.524983 1910 provisioner.cpp:410] Provisioner recovery complete
I0704 04:58:31.525396 1900 slave.cpp:5662] Finished recovery
I0704 04:58:31.525815 1900 slave.cpp:5836] Querying resource estimator for oversubscribable resources
I0704 04:58:31.526077 1900 slave.cpp:937] New master detected at master@172.17.0.5:35564
I0704 04:58:31.526082 1903 status_update_manager.cpp:177] Pausing sending status updates
I0704 04:58:31.526160 1900 slave.cpp:972] Detecting new master
I0704 04:58:31.535444 1893 slave.cpp:999] Authenticating with master master@172.17.0.5:35564
I0704 04:58:31.535550 1893 slave.cpp:1010] Using default CRAM-MD5 authenticatee
I0704 04:58:31.535780 1888 authenticatee.cpp:121] Creating new client SASL connection
I0704 04:58:31.536092 1900 master.cpp:7199] Authenticating (13)@172.17.0.5:35564
I0704 04:58:31.536213 1888 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(35)@172.17.0.5:35564
I0704 04:58:31.536448 1909 authenticator.cpp:98] Creating new server SASL connection
I0704 04:58:31.536712 1907 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0704 04:58:31.536742 1907 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0704 04:58:31.536855 1901 authenticator.cpp:204] Received SASL authentication start
I0704 04:58:31.536926 1901 authenticator.cpp:326] Authentication requires more steps
I0704 04:58:31.537132 1903 authenticatee.cpp:259] Received SASL authentication step
I0704 04:58:31.537250 1903 authenticator.cpp:232] Received SASL authentication step
I0704 04:58:31.537284 1903 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '9f9cb886e8d5' server FQDN: '9f9cb886e8d5' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0704 04:58:31.537300 1903 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0704 04:58:31.537343 1903 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0704 04:58:31.537376 1903 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '9f9cb886e8d5' server FQDN: '9f9cb886e8d5' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0704 04:58:31.537391 1903 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0704 04:58:31.537400 1903 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0704 04:58:31.537417 1903 authenticator.cpp:318] Authentication success
I0704 04:58:31.537531 1900 authenticatee.cpp:299] Authentication success
I0704 04:58:31.537566 1907 master.cpp:7229] Successfully authenticated principal 'test-principal' at (13)@172.17.0.5:35564
I0704 04:58:31.537647 1904 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(35)@172.17.0.5:35564
I0704 04:58:31.537909 1903 slave.cpp:1094] Successfully authenticated with master master@172.17.0.5:35564
I0704 04:58:31.538151 1903 slave.cpp:1521] Will retry registration in 2.078639ms if necessary
I0704 04:58:31.538383 1907 master.cpp:5396] Registering agent at (13)@172.17.0.5:35564 (9f9cb886e8d5) with id b3a7be1c-ec24-4cd5-aba0-51de09ae9609-S0
I0704 04:58:31.538902 1905 registrar.cpp:461] Applied 1 operations in 80507ns; attempting to update the registry
I0704 04:58:31.540655 1905 registrar.cpp:506] Successfully updated the registry in 1.675008ms
I0704 04:58:31.541451 1893 slave.cpp:4395] Received ping from slave-observer(9)@172.17.0.5:35564
I0704 04:58:31.541554 1906 master.cpp:5470] Registered agent b3a7be1c-ec24-4cd5-aba0-51de09ae9609-S0 at (13)@172.17.0.5:35564 (9f9cb886e8d5) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0704 04:58:31.541766 1893 slave.cpp:1521] Will retry registration in 22.187917ms if necessary
I0704 04:58:31.541862 1898 hierarchical.cpp:516] Added agent b3a7be1c-ec24-4cd5-aba0-51de09ae9609-S0 (9f9cb886e8d5) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
I0704 04:58:31.541918 1893 slave.cpp:1140] Registered with master master@172.17.0.5:35564; given agent ID b3a7be1c-ec24-4cd5-aba0-51de09ae9609-S0
I0704 04:58:31.541942 1893 fetcher.cpp:94] Clearing fetcher cache
I0704 04:58:31.541940 1891 master.cpp:5366] Agent b3a7be1c-ec24-4cd5-aba0-51de09ae9609-S0 at (13)@172.17.0.5:35564 (9f9cb886e8d5) already registered, resending acknowledgement
I0704 04:58:31.542104 1905 status_update_manager.cpp:184] Resuming sending status updates
I0704 04:58:31.542367 1893 slave.cpp:1168] Checkpointing SlaveInfo to '/tmp/HTTPCommandExecutorTest_TerminateWithACK_r6KTs3/meta/slaves/b3a7be1c-ec24-4cd5-aba0-51de09ae9609-S0/slave.info'
W0704 04:58:31.542799 1893 slave.cpp:1192] Already registered with master master@172.17.0.5:35564
I0704 04:58:31.543089 1898 hierarchical.cpp:1900] No inverse offers to send out!
I0704 04:58:31.543146 1898 hierarchical.cpp:1394] Performed allocation for 1 agents in 1.160976ms
I0704 04:58:31.543632 1897 master.cpp:7028] Sending 1 offers to framework b3a7be1c-ec24-4cd5-aba0-51de09ae9609-0000 (default) at scheduler-e4dac63b-9ae9-44cc-ac6a-340178dcb2b4@172.17.0.5:35564
I0704 04:58:31.544160 1888 sched.cpp:933] Scheduler::resourceOffers took 126302ns
I0704 04:58:31.546211 1900 master.cpp:3776] Processing ACCEPT call for offers: [ b3a7be1c-ec24-4cd5-aba0-51de09ae9609-O0 ] on agent b3a7be1c-ec24-4cd5-aba0-51de09ae9609-S0 at (13)@172.17.0.5:35564 (9f9cb886e8d5) for framework b3a7be1c-ec24-4cd5-aba0-51de09ae9609-0000 (default) at scheduler-e4dac63b-9ae9-44cc-ac6a-340178dcb2b4@172.17.0.5:35564
I0704 04:58:31.546313 1900 master.cpp:3352] Authorizing framework principal 'test-principal' to launch task 82f9519d-8d99-411b-9ef3-eaf997b63a00
I0704 04:58:31.548635 1900 master.cpp:9089] Adding task 82f9519d-8d99-411b-9ef3-eaf997b63a00 with resources cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] on agent b3a7be1c-ec24-4cd5-aba0-51de09ae9609-S0 at (13)@172.17.0.5:35564 (9f9cb886e8d5)
I0704 04:58:31.548967 1900 master.cpp:4426] Launching task 82f9519d-8d99-411b-9ef3-eaf997b63a00 of framework b3a7be1c-ec24-4cd5-aba0-51de09ae9609-0000 (default) at scheduler-e4dac63b-9ae9-44cc-ac6a-340178dcb2b4@172.17.0.5:35564 with resources cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] on agent b3a7be1c-ec24-4cd5-aba0-51de09ae9609-S0 at (13)@172.17.0.5:35564 (9f9cb886e8d5)
I0704 04:58:31.549767 1891 slave.cpp:1631] Got assigned task '82f9519d-8d99-411b-9ef3-eaf997b63a00' for framework b3a7be1c-ec24-4cd5-aba0-51de09ae9609-0000
I0704 04:58:31.550509 1891 slave.cpp:1791] Launching task '82f9519d-8d99-411b-9ef3-eaf997b63a00' for framework b3a7be1c-ec24-4cd5-aba0-51de09ae9609-0000
I0704 04:58:31.551158 1891 paths.cpp:547] Trying to chown '/tmp/HTTPCommandExecutorTest_TerminateWithACK_r6KTs3/slaves/b3a7be1c-ec24-4cd5-aba0-51de09ae9609-S0/frameworks/b3a7be1c-ec24-4cd5-aba0-51de09ae9609-0000/executors/82f9519d-8d99-411b-9ef3-eaf997b63a00/runs/3e390b61-98bd-4fa7-ac1f-9dd3b6573766' to user 'mesos'
I0704 04:58:31.551453 1891 slave.cpp:6584] Launching executor '82f9519d-8d99-411b-9ef3-eaf997b63a00' of framework b3a7be1c-ec24-4cd5-aba0-51de09ae9609-0000 with resources cpus(*)(allocated: *):0.1; mem(*)(allocated: *):32 in work directory '/tmp/HTTPCommandExecutorTest_TerminateWithACK_r6KTs3/slaves/b3a7be1c-ec24-4cd5-aba0-51de09ae9609-S0/frameworks/b3a7be1c-ec24-4cd5-aba0-51de09ae9609-0000/executors/82f9519d-8d99-411b-9ef3-eaf997b63a00/runs/3e390b61-98bd-4fa7-ac1f-9dd3b6573766'
I0704 04:58:31.551550 1908 hierarchical.cpp:807] Updated allocation of framework b3a7be1c-ec24-4cd5-aba0-51de09ae9609-0000 on agent b3a7be1c-ec24-4cd5-aba0-51de09ae9609-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]
I0704 04:58:31.552214 1888 containerizer.cpp:993] Starting container 3e390b61-98bd-4fa7-ac1f-9dd3b6573766 for executor '82f9519d-8d99-411b-9ef3-eaf997b63a00' of framework b3a7be1c-ec24-4cd5-aba0-51de09ae9609-0000
I0704 04:58:31.552294 1891 slave.cpp:2124] Queued task '82f9519d-8d99-411b-9ef3-eaf997b63a00' for executor '82f9519d-8d99-411b-9ef3-eaf997b63a00' of framework b3a7be1c-ec24-4cd5-aba0-51de09ae9609-0000
I0704 04:58:31.552366 1891 slave.cpp:890] Successfully attached file '/tmp/HTTPCommandExecutorTest_TerminateWithACK_r6KTs3/slaves/b3a7be1c-ec24-4cd5-aba0-51de09ae9609-S0/frameworks/b3a7be1c-ec24-4cd5-aba0-51de09ae9609-0000/executors/82f9519d-8d99-411b-9ef3-eaf997b63a00/runs/3e390b61-98bd-4fa7-ac1f-9dd3b6573766'
I0704 04:58:31.562039 1910 containerizer.cpp:1524] Launching 'mesos-containerizer' with flags '--help="false" --launch_info="{"command":{"arguments":["mesos-executor","--launcher_dir=\/mesos\/mesos-1.2.2\/_build\/src"],"shell":false,"value":"\/mesos\/mesos-1.2.2\/_build\/src\/mesos-executor"},"environment":{"variables":[{"name":"LIBPROCESS_PORT","value":"0"},{"name":"MESOS_AGENT_ENDPOINT","value":"172.17.0.5:35564"},{"name":"MESOS_CHECKPOINT","value":"0"},{"name":"MESOS_DIRECTORY","value":"\/tmp\/HTTPCommandExecutorTest_TerminateWithACK_r6KTs3\/slaves\/b3a7be1c-ec24-4cd5-aba0-51de09ae9609-S0\/frameworks\/b3a7be1c-ec24-4cd5-aba0-51de09ae9609-0000\/executors\/82f9519d-8d99-411b-9ef3-eaf997b63a00\/runs\/3e390b61-98bd-4fa7-ac1f-9dd3b6573766"},{"name":"MESOS_EXECUTOR_ID","value":"82f9519d-8d99-411b-9ef3-eaf997b63a00"},{"name":"MESOS_EXECUTOR_SHUTDOWN_GRACE_PERIOD","value":"5secs"},{"name":"MESOS_FRAMEWORK_ID","value":"b3a7be1c-ec24-4cd5-aba0-51de09ae9609-0000"},{"name":"MESOS_HTTP_COMMAND_EXECUTOR","value":"1"},{"name":"MESOS_SLAVE_ID","value":"b3a7be1c-ec24-4cd5-aba0-51de09ae9609-S0"},{"name":"MESOS_SLAVE_PID","value":"(13)@172.17.0.5:35564"},{"name":"MESOS_SANDBOX","value":"\/tmp\/HTTPCommandExecutorTest_TerminateWithACK_r6KTs3\/slaves\/b3a7be1c-ec24-4cd5-aba0-51de09ae9609-S0\/frameworks\/b3a7be1c-ec24-4cd5-aba0-51de09ae9609-0000\/executors\/82f9519d-8d99-411b-9ef3-eaf997b63a00\/runs\/3e390b61-98bd-4fa7-ac1f-9dd3b6573766"}]},"user":"mesos","working_directory":"\/tmp\/HTTPCommandExecutorTest_TerminateWithACK_r6KTs3\/slaves\/b3a7be1c-ec24-4cd5-aba0-51de09ae9609-S0\/frameworks\/b3a7be1c-ec24-4cd5-aba0-51de09ae9609-0000\/executors\/82f9519d-8d99-411b-9ef3-eaf997b63a00\/runs\/3e390b61-98bd-4fa7-ac1f-9dd3b6573766"}" --pipe_read="7" --pipe_write="8" --runtime_directory="/tmp/HTTPCommandExecutorTest_TerminateWithACK_ZVRsyI/containers/3e390b61-98bd-4fa7-ac1f-9dd3b6573766" --unshare_namespace_mnt="false"'
I0704 04:58:31.564972 1910 launcher.cpp:135] Forked child with pid '1923' for container '3e390b61-98bd-4fa7-ac1f-9dd3b6573766'
I0704 04:58:31.570111 1888 fetcher.cpp:353] Starting to fetch URIs for container: 3e390b61-98bd-4fa7-ac1f-9dd3b6573766, directory: /tmp/HTTPCommandExecutorTest_TerminateWithACK_r6KTs3/slaves/b3a7be1c-ec24-4cd5-aba0-51de09ae9609-S0/frameworks/b3a7be1c-ec24-4cd5-aba0-51de09ae9609-0000/executors/82f9519d-8d99-411b-9ef3-eaf997b63a00/runs/3e390b61-98bd-4fa7-ac1f-9dd3b6573766
I0704 04:58:32.505918 1887 hierarchical.cpp:1810] No allocations performed
I0704 04:58:32.506017 1887 hierarchical.cpp:1900] No inverse offers to send out!
I0704 04:58:32.506098 1887 hierarchical.cpp:1394] Performed allocation for 1 agents in 412074ns
I0704 04:58:33.507236 1908 hierarchical.cpp:1810] No allocations performed
I0704 04:58:33.507351 1908 hierarchical.cpp:1900] No inverse offers to send out!
I0704 04:58:33.507448 1908 hierarchical.cpp:1394] Performed allocation for 1 agents in 468080ns
I0704 04:58:34.508641 1905 hierarchical.cpp:1810] No allocations performed
I0704 04:58:34.508738 1905 hierarchical.cpp:1900] No inverse offers to send out!
I0704 04:58:34.508816 1905 hierarchical.cpp:1394] Performed allocation for 1 agents in 371579ns
I0704 04:58:35.510354 1903 hierarchical.cpp:1810] No allocations performed
I0704 04:58:35.510442 1903 hierarchical.cpp:1900] No inverse offers to send out!
I0704 04:58:35.510504 1903 hierarchical.cpp:1394] Performed allocation for 1 agents in 320560ns
I0704 04:58:36.511286 1888 hierarchical.cpp:1810] No allocations performed
I0704 04:58:36.511384 1888 hierarchical.cpp:1900] No inverse offers to send out!
I0704 04:58:36.511509 1888 hierarchical.cpp:1394] Performed allocation for 1 agents in 404326ns
I0704 04:58:37.512768 1903 hierarchical.cpp:1810] No allocations performed
I0704 04:58:37.512852 1903 hierarchical.cpp:1900] No inverse offers to send out!
I0704 04:58:37.512923 1903 hierarchical.cpp:1394] Performed allocation for 1 agents in 298588ns
I0704 04:58:38.513928 1906 hierarchical.cpp:1810] No allocations performed
I0704 04:58:38.514015 1906 hierarchical.cpp:1900] No inverse offers to send out!
I0704 04:58:38.514075 1906 hierarchical.cpp:1394] Performed allocation for 1 agents in 343193ns
I0704 04:58:39.515071 1908 hierarchical.cpp:1810] No allocations performed
I0704 04:58:39.515153 1908 hierarchical.cpp:1900] No inverse offers to send out!
I0704 04:58:39.515221 1908 hierarchical.cpp:1394] Performed allocation for 1 agents in 307802ns
I0704 04:58:40.516489 1907 hierarchical.cpp:1810] No allocations performed
I0704 04:58:40.516589 1907 hierarchical.cpp:1900] No inverse offers to send out!
I0704 04:58:40.516656 1907 hierarchical.cpp:1394] Performed allocation for 1 agents in 315285ns
I0704 04:58:41.518126 1909 hierarchical.cpp:1810] No allocations performed
I0704 04:58:41.518213 1909 hierarchical.cpp:1900] No inverse offers to send out!
I0704 04:58:41.518275 1909 hierarchical.cpp:1394] Performed allocation for 1 agents in 296283ns
I0704 04:58:42.518925 1900 hierarchical.cpp:1810] No allocations performed
I0704 04:58:42.519022 1900 hierarchical.cpp:1900] No inverse offers to send out!
I0704 04:58:42.519091 1900 hierarchical.cpp:1394] Performed allocation for 1 agents in 326691ns
I0704 04:58:43.519719 1894 hierarchical.cpp:1810] No allocations performed
I0704 04:58:43.519815 1894 hierarchical.cpp:1900] No inverse offers to send out!
I0704 04:58:43.519876 1894 hierarchical.cpp:1394] Performed allocation for 1 agents in 307588ns
I0704 04:58:44.520609 1891 hierarchical.cpp:1810] No allocations performed
I0704 04:58:44.520725 1891 hierarchical.cpp:1900] No inverse offers to send out!
I0704 04:58:44.520793 1891 hierarchical.cpp:1394] Performed allocation for 1 agents in 366118ns
I0704 04:58:45.521407 1894 hierarchical.cpp:1810] No allocations performed
I0704 04:58:45.521504 1894 hierarchical.cpp:1900] No inverse offers to send out!
I0704 04:58:45.521562 1894 hierarchical.cpp:1394] Performed allocation for 1 agents in 296169ns
I0704 04:58:46.522090 1894 hierarchical.cpp:1810] No allocations performed
I0704 04:58:46.522186 1894 hierarchical.cpp:1900] No inverse offers to send out!
I0704 04:58:46.522250 1894 hierarchical.cpp:1394] Performed allocation for 1 agents in 333562ns
I0704 04:58:46.542234 1896 slave.cpp:4395] Received ping from slave-observer(9)@172.17.0.5:35564
../../src/tests/command_executor_tests.cpp:402: Failure
Failed to wait 15secs for statusRunning
I0704 04:58:46.546883 1910 master.cpp:1381] Framework b3a7be1c-ec24-4cd5-aba0-51de09ae9609-0000 (default) at scheduler-e4dac63b-9ae9-44cc-ac6a-340178dcb2b4@172.17.0.5:35564 disconnected
I0704 04:58:46.546929 1910 master.cpp:3081] Deactivating framework b3a7be1c-ec24-4cd5-aba0-51de09ae9609-0000 (default) at scheduler-e4dac63b-9ae9-44cc-ac6a-340178dcb2b4@172.17.0.5:35564
I0704 04:58:46.547034 1910 master.cpp:3058] Disconnecting framework b3a7be1c-ec24-4cd5-aba0-51de09ae9609-0000 (default) at scheduler-e4dac63b-9ae9-44cc-ac6a-340178dcb2b4@172.17.0.5:35564
I0704 04:58:46.547083 1910 master.cpp:1396] Giving framework b3a7be1c-ec24-4cd5-aba0-51de09ae9609-0000 (default) at scheduler-e4dac63b-9ae9-44cc-ac6a-340178dcb2b4@172.17.0.5:35564 0ns to failover
I0704 04:58:46.547109 1895 hierarchical.cpp:415] Deactivated framework b3a7be1c-ec24-4cd5-aba0-51de09ae9609-0000
I0704 04:58:46.547389 1906 master.cpp:6868] Framework failover timeout, removing framework b3a7be1c-ec24-4cd5-aba0-51de09ae9609-0000 (default) at scheduler-e4dac63b-9ae9-44cc-ac6a-340178dcb2b4@172.17.0.5:35564
I0704 04:58:46.547417 1906 master.cpp:7753] Removing framework b3a7be1c-ec24-4cd5-aba0-51de09ae9609-0000 (default) at scheduler-e4dac63b-9ae9-44cc-ac6a-340178dcb2b4@172.17.0.5:35564
../../src/tests/command_executor_tests.cpp:391: Failure
Actual function call count doesn't match EXPECT_CALL(sched, statusUpdate(_, _))...
Expected: to be called twice
Actual: never called - unsatisfied and active
I0704 04:58:46.547603 1906 master.cpp:8338] Updating the state of task 82f9519d-8d99-411b-9ef3-eaf997b63a00 of framework b3a7be1c-ec24-4cd5-aba0-51de09ae9609-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED)
I0704 04:58:46.547616 1887 slave.cpp:2664] Asked to shut down framework b3a7be1c-ec24-4cd5-aba0-51de09ae9609-0000 by master@172.17.0.5:35564
I0704 04:58:46.547677 1887 slave.cpp:2689] Shutting down framework b3a7be1c-ec24-4cd5-aba0-51de09ae9609-0000
I0704 04:58:46.547744 1887 slave.cpp:5131] Shutting down executor '82f9519d-8d99-411b-9ef3-eaf997b63a00' of framework b3a7be1c-ec24-4cd5-aba0-51de09ae9609-0000
W0704 04:58:46.547767 1887 slave.hpp:915] Unable to send event to executor '82f9519d-8d99-411b-9ef3-eaf997b63a00' of framework b3a7be1c-ec24-4cd5-aba0-51de09ae9609-0000: unknown connection type
I0704 04:58:46.548454 1906 master.cpp:8432] Removing task 82f9519d-8d99-411b-9ef3-eaf997b63a00 with resources cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] of framework b3a7be1c-ec24-4cd5-aba0-51de09ae9609-0000 on agent b3a7be1c-ec24-4cd5-aba0-51de09ae9609-S0 at (13)@172.17.0.5:35564 (9f9cb886e8d5)
../../src/tests/command_executor_tests.cpp:396: Failure
Actual function call count doesn't match EXPECT_CALL(slave, executorTerminated(_, _, _))...
Expected: to be called once
Actual: never called - unsatisfied and active
I0704 04:58:46.548727 1890 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 b3a7be1c-ec24-4cd5-aba0-51de09ae9609-S0 from framework b3a7be1c-ec24-4cd5-aba0-51de09ae9609-0000
I0704 04:58:46.549023 1895 hierarchical.cpp:362] Removed framework b3a7be1c-ec24-4cd5-aba0-51de09ae9609-0000
*** Aborted at 1499144326 (unix time) try "date -d @1499144326" if you are using GNU date ***
PC: @ 0x0 (unknown)
*** SIGSEGV (@0x0) received by PID 1868 (TID 0x7f9d9fc05700) from PID 0; stack trace: ***
@ 0x7f9dade7b370 (unknown)
make[4]: *** [check-local] Segmentation fault (core dumped)
make[4]: Leaving directory `/mesos/mesos-1.2.2/_build/src'
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/mesos/mesos-1.2.2/_build/src'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/mesos/mesos-1.2.2/_build/src'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-1.2.2/_build'
make: *** [distcheck] Error 1
+ docker rmi mesos-1499142365-8578
Untagged: mesos-1499142365-8578:latest
Deleted: sha256:2cce93be5435add9a665864f2f2ed5271c11a4b4cc1e855fe0d80488ea66bfbe
Deleted: sha256:b39b207fde86bfc4a3bdd948cf25e256ff28003bf4ae80ebfeb8de08f2d073a2
Deleted: sha256:0aa4a81536877a42234b7f3904d9a0c5e586f16c3ccf6e8af5483d62b2fa5cf8
Deleted: sha256:dc8e57f294ecdd6e34c2321cc37874166e29ba05f3c5174c7f8c78180b436323
Deleted: sha256:43fa5b2b16b32e279461f590c74aaf35c3bb49a7a56189be7486d335130a7a9c
Deleted: sha256:e025d202fcb6e11079ad76b6ad10a19c657728333404341da0d7dc2737740304
Deleted: sha256:7bee33e3e453938afb54d4495c10c86bc7bfd14117c85697ec1bec61f38e802c
Deleted: sha256:6fc2f6b3e000f86abd395bb3b567fd975aa9d5be4a0028a46d90f3cb3fdb081f
Deleted: sha256:c3ace5b056ea49ea4e4c474b5a6457fd79959368b8ef4d55c4199b99e24b37c2
Deleted: sha256:df0f34ab02f2ff372adcc5f112937eedaa110a572a1632f372f5a539f26b71a4
Deleted: sha256:8c5b0a2e7003e7b53ad4bfd207f611d569c3e13d989ecd29d27debc2c650bf97
Deleted: sha256:fe898ed26862975beea4405dd4bdaad89f28655d222aabe64ba9a6b3a0756af2
Deleted: sha256:8ca751e4426fc5651e56adbe95826ec64f77ea9b538c935562cd6bfec6ab71eb
Deleted: sha256:525ea9c9995a1db0fb20e0d027514f0383bb93eeb302ef677d9d7ad932ccd87c
Deleted: sha256:fd96cf999b08d62688da53897c65d1910d31b9fe3ca7acac7582b8a685aae929
Deleted: sha256:7a348d326d454837cf3a879ce173ff09efef0a8f10c548ca1aad0da8b34d4b5b
Deleted: sha256:42adad682a350f094ced83f69c1d65fa2907f519a9ed5faf1d2b8cfdf928bda7
Deleted: sha256:f572b3d689de297b348845541b0d7d7ebc19ca4d3a840d59f640efbf4b6e32a2
Deleted: sha256:d5fff9b4ed32de2b65c06263e9701c6992e836d433c7df19c70c2e0fc92ebbe3
Deleted: sha256:a0beae1e4ad24f92cf2a46ea26ceeeece1c3cb0097f4cd27118d771222284a22
Deleted: sha256:d0aac294bd08aec0bb4c36cb17d4729459a7293b829c9c7405b251102d2ec3c8
Deleted: sha256:ced79a9ec242970ef0f25eb51adf6e0352d30425a5042077318bdd0184b10bbb
Deleted: sha256:d6d4185c9e8246962472166d9f026a2b6e59fec2d168bd41086b868ec5d67acc
Deleted: sha256:7a019a13a6012d89280ec182345ab3f0024f5a6efaa4aff8a1aced87d41fe920
Deleted: sha256:11549e1030de7775a1caedfdafef1ed319e4002d7b8eae2f753492f09c578d4a
Build step 'Execute shell' marked build as failure
Not sending mail to unregistered user toenshoff@me.com