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/05/01 18:06:03 UTC
Build failed in Jenkins: Mesos-Buildbot » autotools,gcc,--verbose --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(docker||Hadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2) #3586
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=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3586/display/redirect?page=changes>
Changes:
[yujie.jay] Bumped the default timeout value for docker volume detach operation.
------------------------------------------
[...truncated 15.44 MB...]
I0501 18:05:55.892278 470 authenticator.cpp:232] Received SASL authentication step
I0501 18:05:55.892323 470 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '6deba4beab5a' server FQDN: '6deba4beab5a' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0501 18:05:55.892350 470 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0501 18:05:55.892397 470 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0501 18:05:55.892441 470 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '6deba4beab5a' server FQDN: '6deba4beab5a' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0501 18:05:55.892464 470 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0501 18:05:55.892485 470 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0501 18:05:55.892515 470 authenticator.cpp:318] Authentication success
I0501 18:05:55.892628 466 authenticatee.cpp:299] Authentication success
I0501 18:05:55.892683 471 master.cpp:7469] Successfully authenticated principal 'test-principal' at slave(328)@172.17.0.2:51881
I0501 18:05:55.892810 472 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(719)@172.17.0.2:51881
I0501 18:05:55.893193 471 slave.cpp:1075] Successfully authenticated with master master@172.17.0.2:51881
I0501 18:05:55.893393 471 slave.cpp:1503] Will retry registration in 16.770327ms if necessary
I0501 18:05:55.893581 467 master.cpp:5429] Received register agent message from slave(328)@172.17.0.2:51881 (6deba4beab5a)
I0501 18:05:55.893757 467 master.cpp:3659] Authorizing agent with principal 'test-principal'
I0501 18:05:55.894356 464 master.cpp:5546] Registering agent at slave(328)@172.17.0.2:51881 (6deba4beab5a) with id 5155cdee-0e13-49e9-8afe-f510d9bba91b-S0
I0501 18:05:55.894847 476 registrar.cpp:493] Applied 1 operations in 65830ns; attempting to update the registry
I0501 18:05:55.895632 476 registrar.cpp:550] Successfully updated the registry in 716032ns
I0501 18:05:55.896409 462 slave.cpp:4745] Received ping from slave-observer(336)@172.17.0.2:51881
I0501 18:05:55.896539 467 master.cpp:5621] Registered agent 5155cdee-0e13-49e9-8afe-f510d9bba91b-S0 at slave(328)@172.17.0.2:51881 (6deba4beab5a) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0501 18:05:55.896680 462 slave.cpp:1121] Registered with master master@172.17.0.2:51881; given agent ID 5155cdee-0e13-49e9-8afe-f510d9bba91b-S0
I0501 18:05:55.896719 462 fetcher.cpp:94] Clearing fetcher cache
I0501 18:05:55.896927 475 status_update_manager.cpp:184] Resuming sending status updates
I0501 18:05:55.897217 471 hierarchical.cpp:525] Added agent 5155cdee-0e13-49e9-8afe-f510d9bba91b-S0 (6deba4beab5a) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
I0501 18:05:55.897267 462 slave.cpp:1149] Checkpointing SlaveInfo to '/tmp/PartitionTest_FailHealthChecksTwice_jTCtYm/meta/slaves/5155cdee-0e13-49e9-8afe-f510d9bba91b-S0/slave.info'
I0501 18:05:55.897778 462 slave.cpp:1187] Forwarding total oversubscribed resources {}
I0501 18:05:55.897990 474 master.cpp:6288] Received update of agent 5155cdee-0e13-49e9-8afe-f510d9bba91b-S0 at slave(328)@172.17.0.2:51881 (6deba4beab5a) with total oversubscribed resources {}
I0501 18:05:55.898998 471 hierarchical.cpp:1940] No inverse offers to send out!
I0501 18:05:55.899076 471 hierarchical.cpp:1434] Performed allocation for 1 agents in 1.675058ms
I0501 18:05:55.899874 462 master.cpp:7269] Sending 1 offers to framework 5155cdee-0e13-49e9-8afe-f510d9bba91b-0000 (default) at scheduler-5638f936-6dd2-49bc-a880-39cbdffbf840@172.17.0.2:51881
I0501 18:05:55.900401 475 sched.cpp:933] Scheduler::resourceOffers took 61593ns
I0501 18:05:55.901783 461 hierarchical.cpp:1850] No allocations performed
I0501 18:05:55.901847 461 hierarchical.cpp:1940] No inverse offers to send out!
I0501 18:05:55.901968 461 hierarchical.cpp:1434] Performed allocation for 1 agents in 328806ns
I0501 18:05:55.902073 473 slave.cpp:6145] Querying resource estimator for oversubscribable resources
I0501 18:05:55.902343 473 slave.cpp:4745] Received ping from slave-observer(336)@172.17.0.2:51881
I0501 18:05:55.902492 473 slave.cpp:6159] Received oversubscribable resources {} from the resource estimator
I0501 18:05:55.902997 474 slave.cpp:6145] Querying resource estimator for oversubscribable resources
I0501 18:05:55.903136 463 hierarchical.cpp:1850] No allocations performed
I0501 18:05:55.903182 474 slave.cpp:4745] Received ping from slave-observer(336)@172.17.0.2:51881
I0501 18:05:55.903193 463 hierarchical.cpp:1940] No inverse offers to send out!
I0501 18:05:55.903333 463 hierarchical.cpp:1434] Performed allocation for 1 agents in 313199ns
I0501 18:05:55.903424 474 slave.cpp:6159] Received oversubscribable resources {} from the resource estimator
I0501 18:05:55.904047 470 hierarchical.cpp:1850] No allocations performed
I0501 18:05:55.904067 475 slave.cpp:6145] Querying resource estimator for oversubscribable resources
I0501 18:05:55.904099 470 hierarchical.cpp:1940] No inverse offers to send out!
I0501 18:05:55.904162 470 hierarchical.cpp:1434] Performed allocation for 1 agents in 252655ns
I0501 18:05:55.904245 475 slave.cpp:4745] Received ping from slave-observer(336)@172.17.0.2:51881
I0501 18:05:55.904419 475 slave.cpp:6159] Received oversubscribable resources {} from the resource estimator
I0501 18:05:55.905004 469 slave.cpp:6145] Querying resource estimator for oversubscribable resources
I0501 18:05:55.905025 471 hierarchical.cpp:1850] No allocations performed
I0501 18:05:55.905072 471 hierarchical.cpp:1940] No inverse offers to send out!
I0501 18:05:55.905120 471 hierarchical.cpp:1434] Performed allocation for 1 agents in 193380ns
I0501 18:05:55.905189 469 slave.cpp:4745] Received ping from slave-observer(336)@172.17.0.2:51881
I0501 18:05:55.905382 469 slave.cpp:5659] Current disk usage 9.88%. Max allowed age: 5.608362095982651days
I0501 18:05:55.905560 469 slave.cpp:6159] Received oversubscribable resources {} from the resource estimator
I0501 18:05:55.906296 467 hierarchical.cpp:1850] No allocations performed
I0501 18:05:55.906313 474 slave.cpp:6145] Querying resource estimator for oversubscribable resources
I0501 18:05:55.906345 467 hierarchical.cpp:1940] No inverse offers to send out!
I0501 18:05:55.906428 467 hierarchical.cpp:1434] Performed allocation for 1 agents in 306023ns
I0501 18:05:55.906477 474 slave.cpp:4745] Received ping from slave-observer(336)@172.17.0.2:51881
I0501 18:05:55.906625 469 master.cpp:6660] Marking agent 5155cdee-0e13-49e9-8afe-f510d9bba91b-S0 at slave(328)@172.17.0.2:51881 (6deba4beab5a) unreachable: health check timed out
I0501 18:05:55.906750 474 slave.cpp:6159] Received oversubscribable resources {} from the resource estimator
I0501 18:05:55.907300 470 slave.cpp:6145] Querying resource estimator for oversubscribable resources
I0501 18:05:55.907529 461 hierarchical.cpp:1850] No allocations performed
I0501 18:05:55.907547 470 slave.cpp:4745] Received ping from slave-observer(336)@172.17.0.2:51881
I0501 18:05:55.907587 461 hierarchical.cpp:1940] No inverse offers to send out!
W0501 18:05:55.907613 462 master.cpp:6648] Not marking agent 5155cdee-0e13-49e9-8afe-f510d9bba91b-S0 unreachable because another unreachable transition is already in progress
I0501 18:05:55.907688 461 hierarchical.cpp:1434] Performed allocation for 1 agents in 258256ns
I0501 18:05:55.907771 470 slave.cpp:6159] Received oversubscribable resources {} from the resource estimator
I0501 18:05:55.908201 475 registrar.cpp:493] Applied 1 operations in 81463ns; attempting to update the registry
I0501 18:05:55.908967 475 registrar.cpp:550] Successfully updated the registry in 0ns
I0501 18:05:55.909181 473 master.cpp:6708] Marked agent 5155cdee-0e13-49e9-8afe-f510d9bba91b-S0 at slave(328)@172.17.0.2:51881 (6deba4beab5a) unreachable: health check timed out
I0501 18:05:55.909854 471 hierarchical.cpp:558] Removed agent 5155cdee-0e13-49e9-8afe-f510d9bba91b-S0
I0501 18:05:55.910313 462 sched.cpp:959] Rescinded offer 5155cdee-0e13-49e9-8afe-f510d9bba91b-O0
I0501 18:05:55.910516 473 master.cpp:2078] Notifying framework 5155cdee-0e13-49e9-8afe-f510d9bba91b-0000 (default) at scheduler-5638f936-6dd2-49bc-a880-39cbdffbf840@172.17.0.2:51881 of lost agent 5155cdee-0e13-49e9-8afe-f510d9bba91b-S0 (6deba4beab5a)
I0501 18:05:55.910573 462 sched.cpp:970] Scheduler::offerRescinded took 202719ns
I0501 18:05:55.914330 462 sched.cpp:1103] Lost agent 5155cdee-0e13-49e9-8afe-f510d9bba91b-S0
I0501 18:05:55.914396 462 sched.cpp:1114] Scheduler::slaveLost took 29684ns
I0501 18:05:55.916784 469 process.cpp:3731] Handling HTTP event for process 'metrics' with path: '/metrics/snapshot'
I0501 18:05:55.917898 470 http.cpp:798] Authorizing principal 'ANY' to GET the endpoint '/metrics/snapshot'
I0501 18:05:55.947155 442 sched.cpp:2021] Asked to stop the driver
I0501 18:05:55.947340 470 sched.cpp:1203] Stopping framework 5155cdee-0e13-49e9-8afe-f510d9bba91b-0000
I0501 18:05:55.947676 473 master.cpp:7952] Processing TEARDOWN call for framework 5155cdee-0e13-49e9-8afe-f510d9bba91b-0000 (default) at scheduler-5638f936-6dd2-49bc-a880-39cbdffbf840@172.17.0.2:51881
I0501 18:05:55.947715 473 master.cpp:7964] Removing framework 5155cdee-0e13-49e9-8afe-f510d9bba91b-0000 (default) at scheduler-5638f936-6dd2-49bc-a880-39cbdffbf840@172.17.0.2:51881
I0501 18:05:55.947747 473 master.cpp:3160] Deactivating framework 5155cdee-0e13-49e9-8afe-f510d9bba91b-0000 (default) at scheduler-5638f936-6dd2-49bc-a880-39cbdffbf840@172.17.0.2:51881
I0501 18:05:55.948124 465 hierarchical.cpp:374] Deactivated framework 5155cdee-0e13-49e9-8afe-f510d9bba91b-0000
I0501 18:05:55.948642 462 hierarchical.cpp:325] Removed framework 5155cdee-0e13-49e9-8afe-f510d9bba91b-0000
I0501 18:05:55.949443 462 slave.cpp:790] Agent terminating
I0501 18:05:55.955519 462 master.cpp:1155] Master terminating
[ OK ] PartitionTest.FailHealthChecksTwice (107 ms)
[----------] 16 tests from PartitionTest (18156 ms total)
[----------] 2 tests from OneWayPartitionTest
[ RUN ] OneWayPartitionTest.MasterToSlave
I0501 18:05:55.966081 442 cluster.cpp:162] Creating default 'local' authorizer
I0501 18:05:55.968338 471 master.cpp:436] Master 61981298-a2d5-4c14-ad7e-5999f2a32a10 (6deba4beab5a) started on 172.17.0.2:51881
I0501 18:05:55.968364 471 master.cpp:438] Flags at startup: --acls="" --agent_ping_timeout="15secs" --agent_reregister_timeout="10mins" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate_agents="true" --authenticate_frameworks="true" --authenticate_http_frameworks="true" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/6wR2xY/credentials" --framework_sorter="drf" --help="false" --hostname_lookup="true" --http_authenticators="basic" --http_framework_authenticators="basic" --initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO" --max_agent_ping_timeouts="5" --max_completed_frameworks="50" --max_completed_tasks_per_framework="1000" --max_unreachable_tasks_per_framework="1000" --port="5050" --quiet="false" --recovery_agent_removal_limit="100%" --registry="in_memory" --registry_fetch_timeout="1mins" --registry_gc_interval="15mins" --registry_max_agent_age="2weeks" --registry_max_agent_count="102400" --registry_store_timeout="100secs" --registry_strict="false" --root_submissions="true" --user_sorter="drf" --version="false" --webui_dir="/mesos/mesos-1.3.0/_inst/share/mesos/webui" --work_dir="/tmp/6wR2xY/master" --zk_session_timeout="10secs"
I0501 18:05:55.968616 471 master.cpp:488] Master only allowing authenticated frameworks to register
I0501 18:05:55.968628 471 master.cpp:502] Master only allowing authenticated agents to register
I0501 18:05:55.968634 471 master.cpp:515] Master only allowing authenticated HTTP frameworks to register
I0501 18:05:55.968641 471 credentials.hpp:37] Loading credentials for authentication from '/tmp/6wR2xY/credentials'
I0501 18:05:55.968871 471 master.cpp:560] Using default 'crammd5' authenticator
I0501 18:05:55.968987 471 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I0501 18:05:55.969118 471 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I0501 18:05:55.969272 471 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I0501 18:05:55.969384 471 master.cpp:640] Authorization enabled
I0501 18:05:55.969586 476 hierarchical.cpp:158] Initialized hierarchical allocator process
I0501 18:05:55.969595 475 whitelist_watcher.cpp:77] No whitelist given
I0501 18:05:55.972745 466 master.cpp:2161] Elected as the leading master!
I0501 18:05:55.972781 466 master.cpp:1700] Recovering from registrar
I0501 18:05:55.972961 462 registrar.cpp:345] Recovering registrar
I0501 18:05:55.973554 462 registrar.cpp:389] Successfully fetched the registry (0B) in 546048ns
I0501 18:05:55.973646 462 registrar.cpp:493] Applied 1 operations in 19426ns; attempting to update the registry
I0501 18:05:55.974117 462 registrar.cpp:550] Successfully updated the registry in 421888ns
I0501 18:05:55.974223 462 registrar.cpp:422] Successfully recovered registrar
I0501 18:05:55.974726 470 hierarchical.cpp:185] Skipping recovery of hierarchical allocator: nothing to recover
I0501 18:05:55.974750 473 master.cpp:1799] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
I0501 18:05:55.979377 442 containerizer.cpp:221] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W0501 18:05:55.979903 442 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0501 18:05:55.980008 442 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0501 18:05:55.980046 442 provisioner.cpp:249] Using default backend 'copy'
I0501 18:05:55.981729 442 cluster.cpp:448] Creating default 'local' authorizer
I0501 18:05:55.983386 469 slave.cpp:225] Mesos agent started on (329)@172.17.0.2:51881
I0501 18:05:55.983409 469 slave.cpp:226] 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/OneWayPartitionTest_MasterToSlave_SBYKIM/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_secret_key="/tmp/OneWayPartitionTest_MasterToSlave_SBYKIM/executor_secret_key" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/OneWayPartitionTest_MasterToSlave_SBYKIM/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/OneWayPartitionTest_MasterToSlave_SBYKIM/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.3.0/_build/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --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/OneWayPartitionTest_MasterToSlave_SBYKIM" --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/OneWayPartitionTest_MasterToSlave_4EwoDn"
I0501 18:05:55.983773 469 credentials.hpp:86] Loading credential for authentication from '/tmp/OneWayPartitionTest_MasterToSlave_SBYKIM/credential'
I0501 18:05:55.983922 469 slave.cpp:258] Agent using credential for: test-principal
I0501 18:05:55.983942 469 credentials.hpp:37] Loading credentials for authentication from '/tmp/OneWayPartitionTest_MasterToSlave_SBYKIM/http_credentials'
I0501 18:05:55.984184 469 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-executor'
I0501 18:05:55.984282 469 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-executor'
I0501 18:05:55.984508 469 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0501 18:05:55.984607 469 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readonly'
I0501 18:05:55.984796 469 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0501 18:05:55.984920 469 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readwrite'
I0501 18:05:55.986292 469 slave.cpp:525] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0501 18:05:55.986374 469 slave.cpp:533] Agent attributes: [ ]
I0501 18:05:55.986387 469 slave.cpp:538] Agent hostname: 6deba4beab5a
I0501 18:05:55.986541 465 status_update_manager.cpp:177] Pausing sending status updates
I0501 18:05:55.987835 463 state.cpp:62] Recovering state from '/tmp/OneWayPartitionTest_MasterToSlave_4EwoDn/meta'
I0501 18:05:55.988368 468 status_update_manager.cpp:203] Recovering status update manager
I0501 18:05:55.988564 474 containerizer.cpp:608] Recovering containerizer
I0501 18:05:55.989936 461 provisioner.cpp:410] Provisioner recovery complete
I0501 18:05:55.990267 473 slave.cpp:5963] Finished recovery
I0501 18:05:55.990756 473 slave.cpp:6145] Querying resource estimator for oversubscribable resources
I0501 18:05:55.991037 464 status_update_manager.cpp:177] Pausing sending status updates
I0501 18:05:55.991066 467 slave.cpp:918] New master detected at master@172.17.0.2:51881
I0501 18:05:55.991147 467 slave.cpp:953] Detecting new master
I0501 18:05:55.991363 467 slave.cpp:6159] Received oversubscribable resources {} from the resource estimator
I0501 18:05:56.002295 463 slave.cpp:980] Authenticating with master master@172.17.0.2:51881
I0501 18:05:56.002383 463 slave.cpp:991] Using default CRAM-MD5 authenticatee
I0501 18:05:56.002591 468 authenticatee.cpp:121] Creating new client SASL connection
I0501 18:05:56.002909 461 master.cpp:7439] Authenticating slave(329)@172.17.0.2:51881
I0501 18:05:56.003047 472 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(720)@172.17.0.2:51881
I0501 18:05:56.003341 471 authenticator.cpp:98] Creating new server SASL connection
I0501 18:05:56.003581 466 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0501 18:05:56.003612 466 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0501 18:05:56.003715 461 authenticator.cpp:204] Received SASL authentication start
I0501 18:05:56.003788 461 authenticator.cpp:326] Authentication requires more steps
I0501 18:05:56.003916 466 authenticatee.cpp:259] Received SASL authentication step
I0501 18:05:56.004048 466 authenticator.cpp:232] Received SASL authentication step
I0501 18:05:56.004091 466 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '6deba4beab5a' server FQDN: '6deba4beab5a' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0501 18:05:56.004115 466 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0501 18:05:56.004161 466 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0501 18:05:56.004190 466 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '6deba4beab5a' server FQDN: '6deba4beab5a' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0501 18:05:56.004205 466 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0501 18:05:56.004215 466 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0501 18:05:56.004235 466 authenticator.cpp:318] Authentication success
I0501 18:05:56.004339 467 authenticatee.cpp:299] Authentication success
I0501 18:05:56.004391 471 master.cpp:7469] Successfully authenticated principal 'test-principal' at slave(329)@172.17.0.2:51881
I0501 18:05:56.004506 474 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(720)@172.17.0.2:51881
I0501 18:05:56.004668 461 slave.cpp:1075] Successfully authenticated with master master@172.17.0.2:51881
I0501 18:05:56.004884 461 slave.cpp:1503] Will retry registration in 2.79418ms if necessary
I0501 18:05:56.005059 465 master.cpp:5429] Received register agent message from slave(329)@172.17.0.2:51881 (6deba4beab5a)
I0501 18:05:56.005206 465 master.cpp:3659] Authorizing agent with principal 'test-principal'
I0501 18:05:56.005702 472 master.cpp:5546] Registering agent at slave(329)@172.17.0.2:51881 (6deba4beab5a) with id 61981298-a2d5-4c14-ad7e-5999f2a32a10-S0
I0501 18:05:56.006245 469 registrar.cpp:493] Applied 1 operations in 115407ns; attempting to update the registry
I0501 18:05:56.007273 469 registrar.cpp:550] Successfully updated the registry in 958976ns
I0501 18:05:56.008204 475 slave.cpp:4745] Received ping from slave-observer(337)@172.17.0.2:51881
I0501 18:05:56.008287 472 master.cpp:5621] Registered agent 61981298-a2d5-4c14-ad7e-5999f2a32a10-S0 at slave(329)@172.17.0.2:51881 (6deba4beab5a) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0501 18:05:56.008565 475 slave.cpp:1503] Will retry registration in 16.312719ms if necessary
I0501 18:05:56.008754 473 hierarchical.cpp:525] Added agent 61981298-a2d5-4c14-ad7e-5999f2a32a10-S0 (6deba4beab5a) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
I0501 18:05:56.008798 469 master.cpp:5429] Received register agent message from slave(329)@172.17.0.2:51881 (6deba4beab5a)
I0501 18:05:56.008877 475 slave.cpp:1121] Registered with master master@172.17.0.2:51881; given agent ID 61981298-a2d5-4c14-ad7e-5999f2a32a10-S0
I0501 18:05:56.008908 475 fetcher.cpp:94] Clearing fetcher cache
I0501 18:05:56.008991 469 master.cpp:3659] Authorizing agent with principal 'test-principal'
I0501 18:05:56.009044 473 hierarchical.cpp:1850] No allocations performed
I0501 18:05:56.009109 473 hierarchical.cpp:1434] Performed allocation for 1 agents in 197057ns
I0501 18:05:56.009111 474 status_update_manager.cpp:184] Resuming sending status updates
I0501 18:05:56.009400 475 slave.cpp:1149] Checkpointing SlaveInfo to '/tmp/OneWayPartitionTest_MasterToSlave_4EwoDn/meta/slaves/61981298-a2d5-4c14-ad7e-5999f2a32a10-S0/slave.info'
I0501 18:05:56.009727 467 master.cpp:1313] Agent 61981298-a2d5-4c14-ad7e-5999f2a32a10-S0 at slave(329)@172.17.0.2:51881 (6deba4beab5a) disconnected
I0501 18:05:56.009762 467 master.cpp:3197] Disconnecting agent 61981298-a2d5-4c14-ad7e-5999f2a32a10-S0 at slave(329)@172.17.0.2:51881 (6deba4beab5a)
I0501 18:05:56.009862 467 master.cpp:3216] Deactivating agent 61981298-a2d5-4c14-ad7e-5999f2a32a10-S0 at slave(329)@172.17.0.2:51881 (6deba4beab5a)
I0501 18:05:56.009886 475 slave.cpp:1187] Forwarding total oversubscribed resources {}
I0501 18:05:56.010056 475 hierarchical.cpp:653] Agent 61981298-a2d5-4c14-ad7e-5999f2a32a10-S0 deactivated
I0501 18:05:56.010136 467 master.cpp:5515] Removing old disconnected agent 61981298-a2d5-4c14-ad7e-5999f2a32a10-S0 at slave(329)@172.17.0.2:51881 (6deba4beab5a) because a registration attempt occurred
I0501 18:05:56.010218 467 master.cpp:8317] Removing agent 61981298-a2d5-4c14-ad7e-5999f2a32a10-S0 at slave(329)@172.17.0.2:51881 (6deba4beab5a): a new agent registered at the same address
I0501 18:05:56.010466 467 master.cpp:5546] Registering agent at slave(329)@172.17.0.2:51881 (6deba4beab5a) with id 61981298-a2d5-4c14-ad7e-5999f2a32a10-S1
I0501 18:05:56.010699 470 registrar.cpp:493] Applied 1 operations in 79023ns; attempting to update the registry
I0501 18:05:56.010867 467 master.cpp:6288] Received update of agent 61981298-a2d5-4c14-ad7e-5999f2a32a10-S0 at slave(329)@172.17.0.2:51881 (6deba4beab5a) with total oversubscribed resources {}
I0501 18:05:56.011546 470 registrar.cpp:550] Successfully updated the registry in 0ns
I0501 18:05:56.011708 468 master.cpp:8359] Removed agent 61981298-a2d5-4c14-ad7e-5999f2a32a10-S0 at slave(329)@172.17.0.2:51881 (6deba4beab5a): a new agent registered at the same address
I0501 18:05:56.011812 470 registrar.cpp:493] Applied 1 operations in 56458ns; attempting to update the registry
I0501 18:05:56.012634 470 registrar.cpp:550] Successfully updated the registry in 0ns
I0501 18:05:56.012693 476 hierarchical.cpp:558] Removed agent 61981298-a2d5-4c14-ad7e-5999f2a32a10-S0
I0501 18:05:56.013484 472 slave.cpp:4745] Received ping from slave-observer(338)@172.17.0.2:51881
Registered but got wrong id: 61981298-a2d5-4c14-ad7e-5999f2a32a10-S1 (expected: 61981298-a2d5-4c14-ad7e-5999f2a32a10-S0). Committing suicide
I0501 18:05:56.013707 466 master.cpp:5621] Registered agent 61981298-a2d5-4c14-ad7e-5999f2a32a10-S1 at slave(329)@172.17.0.2:51881 (6deba4beab5a) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
../../3rdparty/libprocess/include/process/gmock.hpp:214: ERROR: this mock object (used in test OneWayPartitionTest.MasterToSlave) should be deleted but never is. Its address is @0x2adbb0ef8b68.
../../src/tests/mock_registrar.cpp:54: ERROR: this mock object (used in test OneWayPartitionTest.MasterToSlave) should be deleted but never is. Its address is @0x2adbb1a1bae0.
ERROR: 2 leaked mock objects found at program exit.
make[4]: *** [check-local] Error 1
make[4]: Leaving directory `/mesos/mesos-1.3.0/_build/src'
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/mesos/mesos-1.3.0/_build/src'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/mesos/mesos-1.3.0/_build/src'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-1.3.0/_build'
make: *** [distcheck] Error 1
+ docker rmi mesos-1493658560-10961
Untagged: mesos-1493658560-10961:latest
Deleted: sha256:1cf3e1d6403fea03456387eaeac3f3acc732dc973b8041f65dce82f8809b5866
Deleted: sha256:1663f52ac007cd0fbf7c3fe94e24c6a1dea45c7ae3ba77bd002eafbc5303c742
Deleted: sha256:ff48ba996498a531851bceac98145d2c3c9efb24f8f5a8c3c75e046eb81459f9
Deleted: sha256:d0b17a5d73a41305bfcdaf44e2e7896c2bf4454763383091dffc1958b4416b2a
Deleted: sha256:fd3142ea4f0fb2321809e1503c904877f29bc553ca4de4984aacaa93f3f895da
Deleted: sha256:05e1ea4a5e3eecf9dde56b6b6aa1e7ddc6212d325d65916ecbde01dce21569cb
Deleted: sha256:22da6b62bd4320aad082fd07ab22994bfcda880b9853984acdd4f7098bd1748c
Deleted: sha256:e460a8d521774e7e53b747b88f341d9979df9a8ed4fdd728bb2315734b28baf7
Deleted: sha256:04c5b4c06e50552179a2701909ae62d4d2c85c5d540b478bddbcc0b5c2f2d754
Deleted: sha256:4bad935df8463c0c3a82fbdcff0322ae9937dc022607587b982c575fd5d8adbe
Deleted: sha256:546663e1c0748bf4d1bb4bdd72447053cf73b345e4f8eed83b3707eee315e105
Deleted: sha256:e700fff193a588d97b229051c5e8be06da62c1a09736af71e536f7960c83a19e
Deleted: sha256:87520e5844865985a5d7951a614a38ec5b215d2e7e6317a2e99458bfe39dd924
Deleted: sha256:a73f4c63fe7f261aa4a47a6c03aa8ff7b1dcd1099a01efed820de352726dd231
Deleted: sha256:5dc32a4233801f84bc8bcc27ac243e4483aa1e624d5e8a77edd800d482737beb
Deleted: sha256:b75c9d38a98d5887cd4dd2b088406f655dbe1558e33250984f07655f9d9d8aa3
Deleted: sha256:a2e74039ae9f5caef8cea75bad0b9045e5a6bf68e4db8ce9e0837a5dee4b77b3
Deleted: sha256:dd10b2611d5afbade86924c75608d89668db6725194d544c015ef8bdf1bd0f3f
Deleted: sha256:f92fbf0c6e742486d57deea2dcf72d09e3ea8cee8014263756b281b9e00eb45e
Deleted: sha256:86a3f2909b1a8118109a39d27928a796a368eceed7e28240846a6a2d3dc7fe56
Deleted: sha256:193aead131c71bbe99772d5137466962812ad2bea11c850c38ac257746bcc834
Deleted: sha256:807ca3bd8b0a627522e2dedc1e8078030b91722c93b3e39fad6c1303e2e2d2ec
Deleted: sha256:9bbfd30110e7ef6076b741b6a319546b4964f69a0247a278ea333cb5bb3303bc
Deleted: sha256:498d4d688ae2162579fa93decc7adda7d61f57f001027b4b7e3de24d8bae2d1e
Build step 'Execute shell' marked build as failure
Not sending mail to unregistered user xujyan@apple.com
Not sending mail to unregistered user yujie.jay@gmail.com
Jenkins build is back to normal : Mesos-Buildbot » autotools,gcc,--verbose --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(docker||Hadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2) #3588
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=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3588/display/redirect?page=changes>
Build failed in Jenkins: Mesos-Buildbot » autotools,gcc,--verbose --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(docker||Hadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2) #3587
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=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3587/display/redirect?page=changes>
Changes:
[xujyan] Renamed containerizer.md.
------------------------------------------
[...truncated 28.36 MB...]
I0501 22:13:59.826889 18089 http.cpp:1115] HTTP GET for /slave(749)/containers from 172.17.0.2:50478
I0501 22:13:59.826948 18089 http.cpp:936] Authorizing principal 'test-principal' to GET the '/containers' endpoint
I0501 22:13:59.829687 18086 slave.cpp:790] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.AuthorizedRequest/2 (24 ms)
[ RUN ] Endpoint/SlaveEndpointTest.UnauthorizedRequest/0
I0501 22:13:59.838243 428 containerizer.cpp:221] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W0501 22:13:59.838665 428 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0501 22:13:59.838754 428 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0501 22:13:59.838790 428 provisioner.cpp:249] Using default backend 'copy'
I0501 22:13:59.841423 18074 slave.cpp:225] Mesos agent started on (750)@172.17.0.2:38755
I0501 22:13:59.841444 18074 slave.cpp:226] 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_rpozja/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_secret_key="/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_rpozja/executor_secret_key" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_rpozja/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_rpozja/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.3.0/_build/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --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_rpozja" --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_HGHwVg"
I0501 22:13:59.841835 18074 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_rpozja/credential'
I0501 22:13:59.841992 18074 slave.cpp:258] Agent using credential for: test-principal
I0501 22:13:59.842012 18074 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_rpozja/http_credentials'
I0501 22:13:59.842281 18074 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-executor'
I0501 22:13:59.842386 18074 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-executor'
I0501 22:13:59.842574 18074 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0501 22:13:59.842667 18074 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readonly'
I0501 22:13:59.842821 18074 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0501 22:13:59.842903 18074 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readwrite'
I0501 22:13:59.844012 18074 slave.cpp:525] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0501 22:13:59.844086 18074 slave.cpp:533] Agent attributes: [ ]
I0501 22:13:59.844099 18074 slave.cpp:538] Agent hostname: 8340a618aa9f
I0501 22:13:59.844228 18087 status_update_manager.cpp:177] Pausing sending status updates
I0501 22:13:59.845414 18081 state.cpp:62] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_HGHwVg/meta'
I0501 22:13:59.845744 18083 status_update_manager.cpp:203] Recovering status update manager
I0501 22:13:59.845912 18088 containerizer.cpp:608] Recovering containerizer
I0501 22:13:59.847069 18089 provisioner.cpp:410] Provisioner recovery complete
I0501 22:13:59.847443 18087 slave.cpp:5963] Finished recovery
I0501 22:13:59.847900 18087 slave.cpp:6145] Querying resource estimator for oversubscribable resources
I0501 22:13:59.848165 18074 slave.cpp:6159] Received oversubscribable resources {} from the resource estimator
I0501 22:13:59.849853 18077 process.cpp:3731] Handling HTTP event for process 'slave(750)' with path: '/slave(750)/monitor/statistics'
I0501 22:13:59.851294 18083 http.cpp:1115] HTTP GET for /slave(750)/monitor/statistics from 172.17.0.2:50479
I0501 22:13:59.851362 18083 http.cpp:936] Authorizing principal 'test-principal' to GET the '/monitor/statistics' endpoint
I0501 22:13:59.853278 18077 slave.cpp:790] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.UnauthorizedRequest/0 (23 ms)
[ RUN ] Endpoint/SlaveEndpointTest.UnauthorizedRequest/1
I0501 22:13:59.864761 428 containerizer.cpp:221] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W0501 22:13:59.865216 428 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0501 22:13:59.865298 428 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0501 22:13:59.865332 428 provisioner.cpp:249] Using default backend 'copy'
I0501 22:13:59.868098 18081 slave.cpp:225] Mesos agent started on (751)@172.17.0.2:38755
I0501 22:13:59.868121 18081 slave.cpp:226] 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_Tpeg6J/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_secret_key="/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_Tpeg6J/executor_secret_key" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_Tpeg6J/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_Tpeg6J/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.3.0/_build/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --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_Tpeg6J" --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_jAwUDQ"
I0501 22:13:59.868577 18081 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_Tpeg6J/credential'
I0501 22:13:59.868726 18081 slave.cpp:258] Agent using credential for: test-principal
I0501 22:13:59.868751 18081 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_Tpeg6J/http_credentials'
I0501 22:13:59.869163 18081 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-executor'
I0501 22:13:59.869271 18081 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-executor'
I0501 22:13:59.869521 18081 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0501 22:13:59.869619 18081 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readonly'
I0501 22:13:59.869796 18081 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0501 22:13:59.869886 18081 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readwrite'
I0501 22:13:59.871089 18081 slave.cpp:525] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0501 22:13:59.871163 18081 slave.cpp:533] Agent attributes: [ ]
I0501 22:13:59.871175 18081 slave.cpp:538] Agent hostname: 8340a618aa9f
I0501 22:13:59.871271 18086 status_update_manager.cpp:177] Pausing sending status updates
I0501 22:13:59.872400 18083 state.cpp:62] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_jAwUDQ/meta'
I0501 22:13:59.872653 18089 status_update_manager.cpp:203] Recovering status update manager
I0501 22:13:59.872825 18076 containerizer.cpp:608] Recovering containerizer
I0501 22:13:59.873992 18081 provisioner.cpp:410] Provisioner recovery complete
I0501 22:13:59.874327 18084 slave.cpp:5963] Finished recovery
I0501 22:13:59.874721 18084 slave.cpp:6145] Querying resource estimator for oversubscribable resources
I0501 22:13:59.874958 18087 slave.cpp:6159] Received oversubscribable resources {} from the resource estimator
I0501 22:13:59.876519 18080 process.cpp:3731] Handling HTTP event for process 'slave(751)' with path: '/slave(751)/monitor/statistics.json'
I0501 22:13:59.878018 18080 http.cpp:1115] HTTP GET for /slave(751)/monitor/statistics.json from 172.17.0.2:50480
I0501 22:13:59.878078 18080 http.cpp:936] Authorizing principal 'test-principal' to GET the '/monitor/statistics.json' endpoint
I0501 22:13:59.879730 18088 slave.cpp:790] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.UnauthorizedRequest/1 (23 ms)
[ RUN ] Endpoint/SlaveEndpointTest.UnauthorizedRequest/2
I0501 22:13:59.888526 428 containerizer.cpp:221] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W0501 22:13:59.888960 428 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0501 22:13:59.889045 428 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0501 22:13:59.889078 428 provisioner.cpp:249] Using default backend 'copy'
I0501 22:13:59.891577 18078 slave.cpp:225] Mesos agent started on (752)@172.17.0.2:38755
I0501 22:13:59.891594 18078 slave.cpp:226] 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_3WsS8j/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_secret_key="/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_3WsS8j/executor_secret_key" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_3WsS8j/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_3WsS8j/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.3.0/_build/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --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_3WsS8j" --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_PMFFCq"
I0501 22:13:59.891932 18078 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_3WsS8j/credential'
I0501 22:13:59.892051 18078 slave.cpp:258] Agent using credential for: test-principal
I0501 22:13:59.892071 18078 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_3WsS8j/http_credentials'
I0501 22:13:59.892313 18078 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-executor'
I0501 22:13:59.892405 18078 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-executor'
I0501 22:13:59.892590 18078 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0501 22:13:59.892676 18078 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readonly'
I0501 22:13:59.892853 18078 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0501 22:13:59.893061 18078 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readwrite'
I0501 22:13:59.894194 18078 slave.cpp:525] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0501 22:13:59.894268 18078 slave.cpp:533] Agent attributes: [ ]
I0501 22:13:59.894279 18078 slave.cpp:538] Agent hostname: 8340a618aa9f
I0501 22:13:59.894405 18075 status_update_manager.cpp:177] Pausing sending status updates
I0501 22:13:59.895390 18075 state.cpp:62] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_PMFFCq/meta'
I0501 22:13:59.895692 18084 status_update_manager.cpp:203] Recovering status update manager
I0501 22:13:59.895879 18081 containerizer.cpp:608] Recovering containerizer
I0501 22:13:59.897125 18081 provisioner.cpp:410] Provisioner recovery complete
I0501 22:13:59.897466 18075 slave.cpp:5963] Finished recovery
I0501 22:13:59.897953 18075 slave.cpp:6145] Querying resource estimator for oversubscribable resources
I0501 22:13:59.898298 18078 slave.cpp:6159] Received oversubscribable resources {} from the resource estimator
I0501 22:13:59.899884 18083 process.cpp:3731] Handling HTTP event for process 'slave(752)' with path: '/slave(752)/containers'
I0501 22:13:59.901345 18084 http.cpp:1115] HTTP GET for /slave(752)/containers from 172.17.0.2:50481
I0501 22:13:59.901418 18084 http.cpp:936] Authorizing principal 'test-principal' to GET the '/containers' endpoint
I0501 22:13:59.903261 18078 slave.cpp:790] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.UnauthorizedRequest/2 (23 ms)
[ RUN ] Endpoint/SlaveEndpointTest.NoAuthorizer/0
I0501 22:13:59.913272 428 containerizer.cpp:221] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W0501 22:13:59.913892 428 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0501 22:13:59.913986 428 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0501 22:13:59.914019 428 provisioner.cpp:249] Using default backend 'copy'
I0501 22:13:59.915556 428 cluster.cpp:448] Creating default 'local' authorizer
I0501 22:13:59.917305 18088 slave.cpp:225] Mesos agent started on (753)@172.17.0.2:38755
I0501 22:13:59.917326 18088 slave.cpp:226] 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_TM0KqU/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_secret_key="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_0_TM0KqU/executor_secret_key" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_0_TM0KqU/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_TM0KqU/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.3.0/_build/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --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_TM0KqU" --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_xh6GQ0"
I0501 22:13:59.917642 18088 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_0_TM0KqU/credential'
I0501 22:13:59.917789 18088 slave.cpp:258] Agent using credential for: test-principal
I0501 22:13:59.917810 18088 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_0_TM0KqU/http_credentials'
I0501 22:13:59.918043 18088 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-executor'
I0501 22:13:59.918135 18088 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-executor'
I0501 22:13:59.918323 18088 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0501 22:13:59.918419 18088 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readonly'
I0501 22:13:59.918607 18088 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0501 22:13:59.918702 18088 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readwrite'
I0501 22:13:59.919952 18088 slave.cpp:525] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0501 22:13:59.920029 18088 slave.cpp:533] Agent attributes: [ ]
I0501 22:13:59.920047 18088 slave.cpp:538] Agent hostname: 8340a618aa9f
I0501 22:13:59.920167 18081 status_update_manager.cpp:177] Pausing sending status updates
I0501 22:13:59.921372 18076 state.cpp:62] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_0_xh6GQ0/meta'
I0501 22:13:59.921627 18078 status_update_manager.cpp:203] Recovering status update manager
I0501 22:13:59.921830 18082 containerizer.cpp:608] Recovering containerizer
I0501 22:13:59.922940 18079 provisioner.cpp:410] Provisioner recovery complete
I0501 22:13:59.923269 18084 slave.cpp:5963] Finished recovery
I0501 22:13:59.923732 18084 slave.cpp:6145] Querying resource estimator for oversubscribable resources
I0501 22:13:59.924000 18088 slave.cpp:6159] Received oversubscribable resources {} from the resource estimator
I0501 22:13:59.925596 18087 process.cpp:3731] Handling HTTP event for process 'slave(753)' with path: '/slave(753)/monitor/statistics'
I0501 22:13:59.927032 18077 http.cpp:1115] HTTP GET for /slave(753)/monitor/statistics from 172.17.0.2:50482
I0501 22:13:59.927103 18077 http.cpp:936] Authorizing principal 'test-principal' to GET the '/monitor/statistics' endpoint
I0501 22:13:59.929627 18086 slave.cpp:790] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.NoAuthorizer/0 (26 ms)
[ RUN ] Endpoint/SlaveEndpointTest.NoAuthorizer/1
I0501 22:13:59.938572 428 containerizer.cpp:221] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W0501 22:13:59.939004 428 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0501 22:13:59.939095 428 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0501 22:13:59.939129 428 provisioner.cpp:249] Using default backend 'copy'
I0501 22:13:59.940444 428 cluster.cpp:448] Creating default 'local' authorizer
I0501 22:13:59.941778 18074 slave.cpp:225] Mesos agent started on (754)@172.17.0.2:38755
I0501 22:13:59.941802 18074 slave.cpp:226] 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_N3oCZu/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_secret_key="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_1_N3oCZu/executor_secret_key" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_1_N3oCZu/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_N3oCZu/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.3.0/_build/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --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_N3oCZu" --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_DdQjlB"
I0501 22:13:59.942251 18074 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_1_N3oCZu/credential'
I0501 22:13:59.942409 18074 slave.cpp:258] Agent using credential for: test-principal
I0501 22:13:59.942432 18074 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_1_N3oCZu/http_credentials'
I0501 22:13:59.942762 18074 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-executor'
I0501 22:13:59.942867 18074 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-executor'
I0501 22:13:59.943071 18074 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0501 22:13:59.943158 18074 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readonly'
I0501 22:13:59.943325 18074 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0501 22:13:59.943405 18074 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readwrite'
I0501 22:13:59.944514 18074 slave.cpp:525] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0501 22:13:59.944587 18074 slave.cpp:533] Agent attributes: [ ]
I0501 22:13:59.944598 18074 slave.cpp:538] Agent hostname: 8340a618aa9f
I0501 22:13:59.944708 18086 status_update_manager.cpp:177] Pausing sending status updates
I0501 22:13:59.945804 18082 state.cpp:62] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_1_DdQjlB/meta'
I0501 22:13:59.946051 18085 status_update_manager.cpp:203] Recovering status update manager
I0501 22:13:59.946276 18080 containerizer.cpp:608] Recovering containerizer
I0501 22:13:59.947382 18088 provisioner.cpp:410] Provisioner recovery complete
I0501 22:13:59.947687 18089 slave.cpp:5963] Finished recovery
I0501 22:13:59.948102 18089 slave.cpp:6145] Querying resource estimator for oversubscribable resources
I0501 22:13:59.948314 18086 slave.cpp:6159] Received oversubscribable resources {} from the resource estimator
I0501 22:13:59.949865 18080 process.cpp:3731] Handling HTTP event for process 'slave(754)' with path: '/slave(754)/monitor/statistics.json'
I0501 22:13:59.951268 18074 http.cpp:1115] HTTP GET for /slave(754)/monitor/statistics.json from 172.17.0.2:50483
I0501 22:13:59.951345 18074 http.cpp:936] Authorizing principal 'test-principal' to GET the '/monitor/statistics.json' endpoint
I0501 22:13:59.953933 18078 slave.cpp:790] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.NoAuthorizer/1 (24 ms)
[ RUN ] Endpoint/SlaveEndpointTest.NoAuthorizer/2
I0501 22:13:59.963433 428 containerizer.cpp:221] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni
W0501 22:13:59.963843 428 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0501 22:13:59.963929 428 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0501 22:13:59.963959 428 provisioner.cpp:249] Using default backend 'copy'
I0501 22:13:59.965214 428 cluster.cpp:448] Creating default 'local' authorizer
I0501 22:13:59.966538 18077 slave.cpp:225] Mesos agent started on (755)@172.17.0.2:38755
I0501 22:13:59.966562 18077 slave.cpp:226] 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_fMmwO5/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_secret_key="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_2_fMmwO5/executor_secret_key" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_2_fMmwO5/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_fMmwO5/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.3.0/_build/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --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_fMmwO5" --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_ZmZb6b"
I0501 22:13:59.966995 18077 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_2_fMmwO5/credential'
I0501 22:13:59.967108 18077 slave.cpp:258] Agent using credential for: test-principal
I0501 22:13:59.967128 18077 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_2_fMmwO5/http_credentials'
I0501 22:13:59.967356 18077 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-executor'
I0501 22:13:59.967448 18077 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-executor'
I0501 22:13:59.967629 18077 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0501 22:13:59.967733 18077 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readonly'
I0501 22:13:59.967897 18077 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0501 22:13:59.968008 18077 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readwrite'
I0501 22:13:59.969175 18077 slave.cpp:525] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0501 22:13:59.969249 18077 slave.cpp:533] Agent attributes: [ ]
I0501 22:13:59.969260 18077 slave.cpp:538] Agent hostname: 8340a618aa9f
I0501 22:13:59.969372 18087 status_update_manager.cpp:177] Pausing sending status updates
I0501 22:13:59.970459 18088 state.cpp:62] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_2_ZmZb6b/meta'
I0501 22:13:59.970746 18081 status_update_manager.cpp:203] Recovering status update manager
I0501 22:13:59.971037 18086 containerizer.cpp:608] Recovering containerizer
I0501 22:13:59.972177 18087 provisioner.cpp:410] Provisioner recovery complete
I0501 22:13:59.972530 18074 slave.cpp:5963] Finished recovery
I0501 22:13:59.972918 18074 slave.cpp:6145] Querying resource estimator for oversubscribable resources
I0501 22:13:59.973162 18083 slave.cpp:6159] Received oversubscribable resources {} from the resource estimator
I0501 22:13:59.974771 18084 process.cpp:3731] Handling HTTP event for process 'slave(755)' with path: '/slave(755)/containers'
I0501 22:13:59.976186 18076 http.cpp:1115] HTTP GET for /slave(755)/containers from 172.17.0.2:50484
I0501 22:13:59.976250 18076 http.cpp:936] Authorizing principal 'test-principal' to GET the '/containers' endpoint
I0501 22:13:59.978816 18081 slave.cpp:790] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.NoAuthorizer/2 (25 ms)
[----------] 9 tests from Endpoint/SlaveEndpointTest (231 ms total)
[----------] Global test environment tear-down
[==========] 1577 tests from 177 test cases ran. (593151 ms total)
[ PASSED ] 1576 tests.
[ FAILED ] 1 test, listed below:
[ FAILED ] RegisterSlaveValidationTest.DropInvalidReregistration
1 FAILED TEST
YOU HAVE 19 DISABLED TESTS
E0501 22:14:00.035070 18090 process.cpp:951] Failed to accept socket: future discarded
make[4]: *** [check-local] Error 1
make[4]: Leaving directory `/mesos/mesos-1.3.0/_build/src'
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/mesos/mesos-1.3.0/_build/src'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/mesos/mesos-1.3.0/_build/src'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-1.3.0/_build'
make: *** [distcheck] Error 1
+ docker rmi mesos-1493672736-9555
Untagged: mesos-1493672736-9555:latest
Deleted: sha256:8a26578133fbdfa18fb9e99b07751c2b77c1cf27c4ed4a19889f2217d3e904c6
Deleted: sha256:6f2ebaef8c03517a3a6dd7703eb82b744d199e2b65b5ef7432d3876c48448a1b
Deleted: sha256:3ef58eaced8c2f2a4a48403cf8f7eaf2fe4cc124c7bcce60ba86bc0fffc77d14
Deleted: sha256:9c4da49480e47d8da2406b9ef5ce1aa73705d2639d7ce6b01fceed680421082f
Deleted: sha256:6372aaebfe40f8f1929da62b334e8f858013d83abba06298d34fea3ab0bde26a
Deleted: sha256:287b5fb5fb7f66190a633bf41b36af1ada04b9af79cdbf9be92a234e492f5a6e
Deleted: sha256:50d2dace9454fd5617905ee3a0ef597b14237a66e13118b5b0eabb50ba57a3bb
Deleted: sha256:86f14adc9bce16997a8d696429e2afc6d0fd3d7f3ad70e1b80608e03f033d43e
Deleted: sha256:25f17d41dc5b0f1aa3476520c34d94851cc15bb39d84da37cf7adca6cfd33447
Deleted: sha256:619e5bab19526a3ff58a0aea36120f9d85b20f747cc20f70ca45c13ab262aff5
Deleted: sha256:1af80d246b60d1fa51bf19a8f66b9f1178b36ffe4fa14f48c84795901f774669
Deleted: sha256:a32f29306ce9e6029134ed473eeaa4bf066ebf8e812e4efea98ba1670bb80a0e
Deleted: sha256:841b23d90f273a336d7e6a4ba6813942d5f42953d5958eb7cde2f6cbb9a738b3
Deleted: sha256:5afb9b3d909a0d4b6c3c45216140de560c7a733a9ccaa500fd158dc422acfed5
Deleted: sha256:e60e4608b4c861055134954522408dd0903df3425c3a35b2c4eeea0362f117ae
Deleted: sha256:3dd00b124617184026b3fc2680808e484f63aa58ad97fafe56b673e017f000a7
Deleted: sha256:9a1f10e516aff8939dec1ea846d49c6d86848ac9c2bcdd7111a52c1efa5f74f2
Deleted: sha256:5257e696b1e2adfa34d672e6bf4351b18f03fe06d792918dd26b6b73ecd96864
Deleted: sha256:d957fb46237ccba217d14e0bbc5b8265933519f13e8361c19f6c0da73e06bf25
Deleted: sha256:7d78f7596d61d4d683254477abb5350bdb3c9a55cf4fb7ce42892311813fd64d
Deleted: sha256:8f9d7cee38610c907872cf65710e9fe221df860fe2837b65cdb9812fada00d82
Deleted: sha256:d8576c6cdac7987f90f408bb7d25e705be1761d63bae486cdb9104eab85ef75a
Deleted: sha256:eaabf40842205a32119832669585974b380b9f190210c449aa97b13b2fca4a2c
Deleted: sha256:aedbb1755a001b3322e7f46671ee949ddc41797af070c11c3d0f82fd382b1137
Build step 'Execute shell' marked build as failure
Not sending mail to unregistered user xujyan@apple.com
Not sending mail to unregistered user yujie.jay@gmail.com