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/27 00:59:00 UTC
Build failed in Jenkins: Mesos-Buildbot » autotools,gcc,--verbose,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(docker||Hadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2) #3733
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=autotools,COMPILER=gcc,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3733/display/redirect?page=changes>
Changes:
[bmahler] Don't crash when re-registering executor from an unknown framework.
[bmahler] Don't crash the agent when an unknown executor re-registers.
[bmahler] Removed a use of the 'default' switch case.
[bmahler] Added logging of executor re-registration messages.
[bmahler] Avoided use of [] operator for read only map access.
[bmahler] Minor logging cleanup to put open/close quotes on the same line.
------------------------------------------
[...truncated 15.15 MB...]
I0527 00:58:51.152112 4685 authenticatee.cpp:259] Received SASL authentication step
I0527 00:58:51.152295 4684 authenticator.cpp:232] Received SASL authentication step
I0527 00:58:51.152346 4684 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '31b7750c93d8' server FQDN: '31b7750c93d8' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0527 00:58:51.152377 4684 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0527 00:58:51.152441 4684 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0527 00:58:51.152475 4684 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '31b7750c93d8' server FQDN: '31b7750c93d8' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0527 00:58:51.152503 4684 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0527 00:58:51.152524 4684 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0527 00:58:51.152559 4684 authenticator.cpp:318] Authentication success
I0527 00:58:51.152698 4685 authenticatee.cpp:299] Authentication success
I0527 00:58:51.152926 4689 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(714)@172.17.0.3:58547
I0527 00:58:51.152933 4690 master.cpp:7517] Successfully authenticated principal 'test-principal' at slave(330)@172.17.0.3:58547
I0527 00:58:51.153224 4688 slave.cpp:1086] Successfully authenticated with master master@172.17.0.3:58547
I0527 00:58:51.153503 4688 slave.cpp:1506] Will retry registration in 3.92071ms if necessary
I0527 00:58:51.153750 4696 master.cpp:5429] Received register agent message from slave(330)@172.17.0.3:58547 (31b7750c93d8)
I0527 00:58:51.153952 4696 master.cpp:3659] Authorizing agent with principal 'test-principal'
I0527 00:58:51.154566 4695 master.cpp:5489] Authorized registration of agent at slave(330)@172.17.0.3:58547 (31b7750c93d8)
I0527 00:58:51.154722 4695 master.cpp:5568] Registering agent at slave(330)@172.17.0.3:58547 (31b7750c93d8) with id 52304b29-7a89-4f7c-83d2-f9b4d7125919-S0
I0527 00:58:51.155273 4697 registrar.cpp:493] Applied 1 operations in 75393ns; attempting to update the registry
I0527 00:58:51.156075 4697 registrar.cpp:550] Successfully updated the registry in 720128ns
I0527 00:58:51.156292 4686 master.cpp:5615] Admitted agent 52304b29-7a89-4f7c-83d2-f9b4d7125919-S0 at slave(330)@172.17.0.3:58547 (31b7750c93d8)
I0527 00:58:51.156954 4691 slave.cpp:4809] Received ping from slave-observer(336)@172.17.0.3:58547
I0527 00:58:51.157061 4686 master.cpp:5646] Registered agent 52304b29-7a89-4f7c-83d2-f9b4d7125919-S0 at slave(330)@172.17.0.3:58547 (31b7750c93d8) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0527 00:58:51.157189 4691 slave.cpp:1132] Registered with master master@172.17.0.3:58547; given agent ID 52304b29-7a89-4f7c-83d2-f9b4d7125919-S0
I0527 00:58:51.157340 4684 status_update_manager.cpp:184] Resuming sending status updates
I0527 00:58:51.157505 4694 hierarchical.cpp:525] Added agent 52304b29-7a89-4f7c-83d2-f9b4d7125919-S0 (31b7750c93d8) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
I0527 00:58:51.157661 4691 slave.cpp:1152] Checkpointing SlaveInfo to '/tmp/PartitionTest_FailHealthChecksTwice_gJl2l4/meta/slaves/52304b29-7a89-4f7c-83d2-f9b4d7125919-S0/slave.info'
I0527 00:58:51.158113 4691 slave.cpp:1190] Forwarding total oversubscribed resources {}
I0527 00:58:51.158350 4691 master.cpp:6336] Received update of agent 52304b29-7a89-4f7c-83d2-f9b4d7125919-S0 at slave(330)@172.17.0.3:58547 (31b7750c93d8) with total oversubscribed resources {}
I0527 00:58:51.158915 4694 hierarchical.cpp:1944] No inverse offers to send out!
I0527 00:58:51.158975 4694 hierarchical.cpp:1438] Performed allocation for 1 agents in 1.264224ms
I0527 00:58:51.159489 4683 master.cpp:7317] Sending 1 offers to framework 52304b29-7a89-4f7c-83d2-f9b4d7125919-0000 (default) at scheduler-232695a8-15d2-44f1-8cdd-3059eacf3c15@172.17.0.3:58547
I0527 00:58:51.160048 4683 sched.cpp:933] Scheduler::resourceOffers took 73113ns
I0527 00:58:51.162672 4690 slave.cpp:6265] Querying resource estimator for oversubscribable resources
I0527 00:58:51.162868 4689 hierarchical.cpp:1854] No allocations performed
I0527 00:58:51.162957 4689 hierarchical.cpp:1944] No inverse offers to send out!
I0527 00:58:51.163023 4689 hierarchical.cpp:1438] Performed allocation for 1 agents in 410864ns
I0527 00:58:51.163063 4690 slave.cpp:4809] Received ping from slave-observer(336)@172.17.0.3:58547
I0527 00:58:51.163307 4690 slave.cpp:6279] Received oversubscribable resources {} from the resource estimator
I0527 00:58:51.165103 4691 slave.cpp:6265] Querying resource estimator for oversubscribable resources
I0527 00:58:51.165148 4693 hierarchical.cpp:1854] No allocations performed
I0527 00:58:51.165207 4693 hierarchical.cpp:1944] No inverse offers to send out!
I0527 00:58:51.165273 4693 hierarchical.cpp:1438] Performed allocation for 1 agents in 249621ns
I0527 00:58:51.165307 4691 slave.cpp:4809] Received ping from slave-observer(336)@172.17.0.3:58547
I0527 00:58:51.165582 4691 slave.cpp:6279] Received oversubscribable resources {} from the resource estimator
I0527 00:58:51.167253 4686 slave.cpp:6265] Querying resource estimator for oversubscribable resources
I0527 00:58:51.167410 4686 slave.cpp:4809] Received ping from slave-observer(336)@172.17.0.3:58547
I0527 00:58:51.167423 4684 hierarchical.cpp:1854] No allocations performed
I0527 00:58:51.167482 4684 hierarchical.cpp:1944] No inverse offers to send out!
I0527 00:58:51.167564 4684 hierarchical.cpp:1438] Performed allocation for 1 agents in 238871ns
I0527 00:58:51.167592 4686 slave.cpp:6279] Received oversubscribable resources {} from the resource estimator
I0527 00:58:51.169383 4685 hierarchical.cpp:1854] No allocations performed
I0527 00:58:51.169433 4685 hierarchical.cpp:1944] No inverse offers to send out!
I0527 00:58:51.169481 4685 hierarchical.cpp:1438] Performed allocation for 1 agents in 192810ns
I0527 00:58:51.169754 4696 slave.cpp:6265] Querying resource estimator for oversubscribable resources
I0527 00:58:51.169939 4696 slave.cpp:4809] Received ping from slave-observer(336)@172.17.0.3:58547
I0527 00:58:51.170182 4696 slave.cpp:5730] Current disk usage 11.14%. Max allowed age: 5.520173695809595days
I0527 00:58:51.170397 4696 slave.cpp:6279] Received oversubscribable resources {} from the resource estimator
I0527 00:58:51.172302 4685 hierarchical.cpp:1854] No allocations performed
I0527 00:58:51.172320 4695 slave.cpp:6265] Querying resource estimator for oversubscribable resources
I0527 00:58:51.172363 4685 hierarchical.cpp:1944] No inverse offers to send out!
I0527 00:58:51.172428 4685 hierarchical.cpp:1438] Performed allocation for 1 agents in 230001ns
I0527 00:58:51.172462 4692 master.cpp:6708] Marking agent 52304b29-7a89-4f7c-83d2-f9b4d7125919-S0 at slave(330)@172.17.0.3:58547 (31b7750c93d8) unreachable: health check timed out
I0527 00:58:51.172543 4695 slave.cpp:4809] Received ping from slave-observer(336)@172.17.0.3:58547
I0527 00:58:51.172698 4695 slave.cpp:6279] Received oversubscribable resources {} from the resource estimator
I0527 00:58:51.174341 4684 slave.cpp:6265] Querying resource estimator for oversubscribable resources
I0527 00:58:51.174646 4695 slave.cpp:4809] Received ping from slave-observer(336)@172.17.0.3:58547
I0527 00:58:51.174688 4690 hierarchical.cpp:1854] No allocations performed
I0527 00:58:51.174782 4690 hierarchical.cpp:1944] No inverse offers to send out!
W0527 00:58:51.174830 4696 master.cpp:6696] Not marking agent 52304b29-7a89-4f7c-83d2-f9b4d7125919-S0 unreachable because another unreachable transition is already in progress
I0527 00:58:51.174834 4695 slave.cpp:6279] Received oversubscribable resources {} from the resource estimator
I0527 00:58:51.174868 4690 hierarchical.cpp:1438] Performed allocation for 1 agents in 297540ns
I0527 00:58:51.175456 4686 registrar.cpp:493] Applied 1 operations in 91317ns; attempting to update the registry
I0527 00:58:51.176338 4686 registrar.cpp:550] Successfully updated the registry in 0ns
I0527 00:58:51.176582 4692 master.cpp:6756] Marked agent 52304b29-7a89-4f7c-83d2-f9b4d7125919-S0 at slave(330)@172.17.0.3:58547 (31b7750c93d8) unreachable: health check timed out
I0527 00:58:51.177263 4691 hierarchical.cpp:558] Removed agent 52304b29-7a89-4f7c-83d2-f9b4d7125919-S0
I0527 00:58:51.177497 4687 sched.cpp:959] Rescinded offer 52304b29-7a89-4f7c-83d2-f9b4d7125919-O0
I0527 00:58:51.177587 4687 sched.cpp:970] Scheduler::offerRescinded took 29812ns
I0527 00:58:51.177767 4692 master.cpp:2078] Notifying framework 52304b29-7a89-4f7c-83d2-f9b4d7125919-0000 (default) at scheduler-232695a8-15d2-44f1-8cdd-3059eacf3c15@172.17.0.3:58547 of lost agent 52304b29-7a89-4f7c-83d2-f9b4d7125919-S0 (31b7750c93d8)
I0527 00:58:51.177979 4696 sched.cpp:1103] Lost agent 52304b29-7a89-4f7c-83d2-f9b4d7125919-S0
I0527 00:58:51.178040 4696 sched.cpp:1114] Scheduler::slaveLost took 28589ns
I0527 00:58:51.181736 4695 process.cpp:3730] Handling HTTP event for process 'metrics' with path: '/metrics/snapshot'
I0527 00:58:51.182970 4690 http.cpp:798] Authorizing principal 'ANY' to GET the endpoint '/metrics/snapshot'
I0527 00:58:51.211648 4664 sched.cpp:2021] Asked to stop the driver
I0527 00:58:51.211838 4696 sched.cpp:1203] Stopping framework 52304b29-7a89-4f7c-83d2-f9b4d7125919-0000
I0527 00:58:51.212178 4686 master.cpp:8000] Processing TEARDOWN call for framework 52304b29-7a89-4f7c-83d2-f9b4d7125919-0000 (default) at scheduler-232695a8-15d2-44f1-8cdd-3059eacf3c15@172.17.0.3:58547
I0527 00:58:51.212221 4686 master.cpp:8012] Removing framework 52304b29-7a89-4f7c-83d2-f9b4d7125919-0000 (default) at scheduler-232695a8-15d2-44f1-8cdd-3059eacf3c15@172.17.0.3:58547
I0527 00:58:51.212249 4686 master.cpp:3160] Deactivating framework 52304b29-7a89-4f7c-83d2-f9b4d7125919-0000 (default) at scheduler-232695a8-15d2-44f1-8cdd-3059eacf3c15@172.17.0.3:58547
I0527 00:58:51.212424 4695 hierarchical.cpp:374] Deactivated framework 52304b29-7a89-4f7c-83d2-f9b4d7125919-0000
I0527 00:58:51.213502 4688 hierarchical.cpp:325] Removed framework 52304b29-7a89-4f7c-83d2-f9b4d7125919-0000
I0527 00:58:51.214872 4693 slave.cpp:801] Agent terminating
I0527 00:58:51.220664 4695 master.cpp:1155] Master terminating
[ OK ] PartitionTest.FailHealthChecksTwice (119 ms)
[----------] 16 tests from PartitionTest (23217 ms total)
[----------] 2 tests from OneWayPartitionTest
[ RUN ] OneWayPartitionTest.MasterToSlave
I0527 00:58:51.228225 4664 cluster.cpp:162] Creating default 'local' authorizer
I0527 00:58:51.231214 4696 master.cpp:436] Master 35bab20d-2bf0-40b0-8b09-2474df0b8013 (31b7750c93d8) started on 172.17.0.3:58547
I0527 00:58:51.231240 4696 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/QWYbLV/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.4.0/_inst/share/mesos/webui" --work_dir="/tmp/QWYbLV/master" --zk_session_timeout="10secs"
I0527 00:58:51.231639 4696 master.cpp:488] Master only allowing authenticated frameworks to register
I0527 00:58:51.231658 4696 master.cpp:502] Master only allowing authenticated agents to register
I0527 00:58:51.231669 4696 master.cpp:515] Master only allowing authenticated HTTP frameworks to register
I0527 00:58:51.231683 4696 credentials.hpp:37] Loading credentials for authentication from '/tmp/QWYbLV/credentials'
I0527 00:58:51.232113 4696 master.cpp:560] Using default 'crammd5' authenticator
I0527 00:58:51.232292 4696 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I0527 00:58:51.232486 4696 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I0527 00:58:51.232659 4696 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I0527 00:58:51.232813 4696 master.cpp:640] Authorization enabled
I0527 00:58:51.233059 4691 hierarchical.cpp:158] Initialized hierarchical allocator process
I0527 00:58:51.233106 4684 whitelist_watcher.cpp:77] No whitelist given
I0527 00:58:51.235910 4688 master.cpp:2161] Elected as the leading master!
I0527 00:58:51.235944 4688 master.cpp:1700] Recovering from registrar
I0527 00:58:51.236090 4686 registrar.cpp:345] Recovering registrar
I0527 00:58:51.236887 4686 registrar.cpp:389] Successfully fetched the registry (0B) in 747776ns
I0527 00:58:51.236999 4686 registrar.cpp:493] Applied 1 operations in 26664ns; attempting to update the registry
I0527 00:58:51.237644 4686 registrar.cpp:550] Successfully updated the registry in 588032ns
I0527 00:58:51.237757 4686 registrar.cpp:422] Successfully recovered registrar
I0527 00:58:51.238283 4697 hierarchical.cpp:185] Skipping recovery of hierarchical allocator: nothing to recover
I0527 00:58:51.238273 4691 master.cpp:1799] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
I0527 00:58:51.244307 4664 containerizer.cpp:230] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni,environment_secret
W0527 00:58:51.244963 4664 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0527 00:58:51.245087 4664 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0527 00:58:51.245139 4664 provisioner.cpp:255] Using default backend 'copy'
I0527 00:58:51.247324 4664 cluster.cpp:448] Creating default 'local' authorizer
I0527 00:58:51.249347 4697 slave.cpp:232] Mesos agent started on (331)@172.17.0.3:58547
I0527 00:58:51.249408 4697 slave.cpp:233] 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/OneWayPartitionTest_MasterToSlave_SyYBSR/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/OneWayPartitionTest_MasterToSlave_SyYBSR/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_SyYBSR/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/OneWayPartitionTest_MasterToSlave_SyYBSR" --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_vVqTOT"
I0527 00:58:51.249945 4697 credentials.hpp:86] Loading credential for authentication from '/tmp/OneWayPartitionTest_MasterToSlave_SyYBSR/credential'
I0527 00:58:51.250131 4697 slave.cpp:265] Agent using credential for: test-principal
I0527 00:58:51.250156 4697 credentials.hpp:37] Loading credentials for authentication from '/tmp/OneWayPartitionTest_MasterToSlave_SyYBSR/http_credentials'
I0527 00:58:51.250473 4697 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0527 00:58:51.250643 4697 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0527 00:58:51.252465 4697 slave.cpp:536] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0527 00:58:51.252562 4697 slave.cpp:544] Agent attributes: [ ]
I0527 00:58:51.252578 4697 slave.cpp:549] Agent hostname: 31b7750c93d8
I0527 00:58:51.252791 4683 status_update_manager.cpp:177] Pausing sending status updates
I0527 00:58:51.254622 4695 state.cpp:62] Recovering state from '/tmp/OneWayPartitionTest_MasterToSlave_vVqTOT/meta'
I0527 00:58:51.255033 4694 status_update_manager.cpp:203] Recovering status update manager
I0527 00:58:51.255277 4691 containerizer.cpp:582] Recovering containerizer
I0527 00:58:51.257361 4686 provisioner.cpp:416] Provisioner recovery complete
I0527 00:58:51.257818 4685 slave.cpp:6083] Finished recovery
I0527 00:58:51.258426 4685 slave.cpp:6265] Querying resource estimator for oversubscribable resources
I0527 00:58:51.258760 4693 status_update_manager.cpp:177] Pausing sending status updates
I0527 00:58:51.258755 4685 slave.cpp:929] New master detected at master@172.17.0.3:58547
I0527 00:58:51.258880 4685 slave.cpp:964] Detecting new master
I0527 00:58:51.259089 4685 slave.cpp:6279] Received oversubscribable resources {} from the resource estimator
I0527 00:58:51.263151 4690 slave.cpp:991] Authenticating with master master@172.17.0.3:58547
I0527 00:58:51.263262 4690 slave.cpp:1002] Using default CRAM-MD5 authenticatee
I0527 00:58:51.263564 4697 authenticatee.cpp:121] Creating new client SASL connection
I0527 00:58:51.263945 4697 master.cpp:7487] Authenticating slave(331)@172.17.0.3:58547
I0527 00:58:51.264116 4689 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(715)@172.17.0.3:58547
I0527 00:58:51.264456 4698 authenticator.cpp:98] Creating new server SASL connection
I0527 00:58:51.264819 4694 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0527 00:58:51.264856 4694 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0527 00:58:51.265027 4689 authenticator.cpp:204] Received SASL authentication start
I0527 00:58:51.265120 4689 authenticator.cpp:326] Authentication requires more steps
I0527 00:58:51.265307 4695 authenticatee.cpp:259] Received SASL authentication step
I0527 00:58:51.265487 4693 authenticator.cpp:232] Received SASL authentication step
I0527 00:58:51.265528 4693 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '31b7750c93d8' server FQDN: '31b7750c93d8' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0527 00:58:51.265545 4693 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0527 00:58:51.265596 4693 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0527 00:58:51.265630 4693 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '31b7750c93d8' server FQDN: '31b7750c93d8' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0527 00:58:51.265647 4693 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0527 00:58:51.265658 4693 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0527 00:58:51.265681 4693 authenticator.cpp:318] Authentication success
I0527 00:58:51.265810 4685 authenticatee.cpp:299] Authentication success
I0527 00:58:51.265858 4695 master.cpp:7517] Successfully authenticated principal 'test-principal' at slave(331)@172.17.0.3:58547
I0527 00:58:51.266001 4696 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(715)@172.17.0.3:58547
I0527 00:58:51.266381 4689 slave.cpp:1086] Successfully authenticated with master master@172.17.0.3:58547
I0527 00:58:51.266633 4689 slave.cpp:1506] Will retry registration in 2.152243ms if necessary
I0527 00:58:51.266832 4694 master.cpp:5429] Received register agent message from slave(331)@172.17.0.3:58547 (31b7750c93d8)
I0527 00:58:51.266984 4694 master.cpp:3659] Authorizing agent with principal 'test-principal'
I0527 00:58:51.267449 4686 master.cpp:5489] Authorized registration of agent at slave(331)@172.17.0.3:58547 (31b7750c93d8)
I0527 00:58:51.267619 4686 master.cpp:5568] Registering agent at slave(331)@172.17.0.3:58547 (31b7750c93d8) with id 35bab20d-2bf0-40b0-8b09-2474df0b8013-S0
I0527 00:58:51.268244 4697 registrar.cpp:493] Applied 1 operations in 72680ns; attempting to update the registry
I0527 00:58:51.268993 4697 registrar.cpp:550] Successfully updated the registry in 662016ns
I0527 00:58:51.269376 4685 master.cpp:5615] Admitted agent 35bab20d-2bf0-40b0-8b09-2474df0b8013-S0 at slave(331)@172.17.0.3:58547 (31b7750c93d8)
I0527 00:58:51.269508 4692 slave.cpp:1506] Will retry registration in 32.220838ms if necessary
I0527 00:58:51.270164 4688 slave.cpp:4809] Received ping from slave-observer(337)@172.17.0.3:58547
I0527 00:58:51.270330 4685 master.cpp:5646] Registered agent 35bab20d-2bf0-40b0-8b09-2474df0b8013-S0 at slave(331)@172.17.0.3:58547 (31b7750c93d8) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0527 00:58:51.270619 4691 hierarchical.cpp:525] Added agent 35bab20d-2bf0-40b0-8b09-2474df0b8013-S0 (31b7750c93d8) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
I0527 00:58:51.270648 4688 slave.cpp:1132] Registered with master master@172.17.0.3:58547; given agent ID 35bab20d-2bf0-40b0-8b09-2474df0b8013-S0
I0527 00:58:51.270853 4685 master.cpp:5429] Received register agent message from slave(331)@172.17.0.3:58547 (31b7750c93d8)
I0527 00:58:51.271066 4685 master.cpp:3659] Authorizing agent with principal 'test-principal'
I0527 00:58:51.271103 4697 status_update_manager.cpp:184] Resuming sending status updates
I0527 00:58:51.271296 4691 hierarchical.cpp:1854] No allocations performed
I0527 00:58:51.271374 4691 hierarchical.cpp:1438] Performed allocation for 1 agents in 272872ns
I0527 00:58:51.271399 4688 slave.cpp:1152] Checkpointing SlaveInfo to '/tmp/OneWayPartitionTest_MasterToSlave_vVqTOT/meta/slaves/35bab20d-2bf0-40b0-8b09-2474df0b8013-S0/slave.info'
I0527 00:58:51.271798 4694 master.cpp:1313] Agent 35bab20d-2bf0-40b0-8b09-2474df0b8013-S0 at slave(331)@172.17.0.3:58547 (31b7750c93d8) disconnected
I0527 00:58:51.271826 4688 slave.cpp:1190] Forwarding total oversubscribed resources {}
I0527 00:58:51.271829 4694 master.cpp:3197] Disconnecting agent 35bab20d-2bf0-40b0-8b09-2474df0b8013-S0 at slave(331)@172.17.0.3:58547 (31b7750c93d8)
I0527 00:58:51.271970 4694 master.cpp:3216] Deactivating agent 35bab20d-2bf0-40b0-8b09-2474df0b8013-S0 at slave(331)@172.17.0.3:58547 (31b7750c93d8)
I0527 00:58:51.272148 4698 hierarchical.cpp:653] Agent 35bab20d-2bf0-40b0-8b09-2474df0b8013-S0 deactivated
I0527 00:58:51.272217 4694 master.cpp:5489] Authorized registration of agent at slave(331)@172.17.0.3:58547 (31b7750c93d8)
I0527 00:58:51.272330 4694 master.cpp:5537] Removing old disconnected agent 35bab20d-2bf0-40b0-8b09-2474df0b8013-S0 at slave(331)@172.17.0.3:58547 (31b7750c93d8) because a registration attempt occurred
I0527 00:58:51.272377 4694 master.cpp:8365] Removing agent 35bab20d-2bf0-40b0-8b09-2474df0b8013-S0 at slave(331)@172.17.0.3:58547 (31b7750c93d8): a new agent registered at the same address
I0527 00:58:51.272639 4694 master.cpp:5568] Registering agent at slave(331)@172.17.0.3:58547 (31b7750c93d8) with id 35bab20d-2bf0-40b0-8b09-2474df0b8013-S1
I0527 00:58:51.272900 4686 registrar.cpp:493] Applied 1 operations in 75888ns; attempting to update the registry
I0527 00:58:51.273020 4694 master.cpp:6336] Received update of agent 35bab20d-2bf0-40b0-8b09-2474df0b8013-S0 at slave(331)@172.17.0.3:58547 (31b7750c93d8) with total oversubscribed resources {}
I0527 00:58:51.274034 4686 registrar.cpp:550] Successfully updated the registry in 0ns
I0527 00:58:51.274226 4690 master.cpp:8407] Removed agent 35bab20d-2bf0-40b0-8b09-2474df0b8013-S0 at slave(331)@172.17.0.3:58547 (31b7750c93d8): a new agent registered at the same address
I0527 00:58:51.274312 4686 registrar.cpp:493] Applied 1 operations in 53164ns; attempting to update the registry
I0527 00:58:51.275066 4686 registrar.cpp:550] Successfully updated the registry in 0ns
I0527 00:58:51.275125 4685 hierarchical.cpp:558] Removed agent 35bab20d-2bf0-40b0-8b09-2474df0b8013-S0
I0527 00:58:51.275274 4693 master.cpp:5615] Admitted agent 35bab20d-2bf0-40b0-8b09-2474df0b8013-S1 at slave(331)@172.17.0.3:58547 (31b7750c93d8)
I0527 00:58:51.275838 4689 slave.cpp:4809] Received ping from slave-observer(338)@172.17.0.3:58547
I0527 00:58:51.275954 4693 master.cpp:5646] Registered agent 35bab20d-2bf0-40b0-8b09-2474df0b8013-S1 at slave(331)@172.17.0.3:58547 (31b7750c93d8) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
E0527 00:58:51.276099 4689 slave.cpp:1172] EXIT with status 1: Registered but got wrong id: 35bab20d-2bf0-40b0-8b09-2474df0b8013-S1 (expected: 35bab20d-2bf0-40b0-8b09-2474df0b8013-S0). Committing suicide
../../3rdparty/libprocess/include/process/gmock.hpp:209: ERROR: this mock object (used in test OneWayPartitionTest.MasterToSlave) should be deleted but never is. Its address is @0x2b69ba706a28.
../../src/tests/mock_registrar.cpp:54: ERROR: this mock object (used in test OneWayPartitionTest.MasterToSlave) should be deleted but never is. Its address is @0x2b69bae56cc0.
ERROR: 2 leaked mock objects found at program exit.
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-1495843383-13771
Untagged: mesos-1495843383-13771:latest
Deleted: sha256:01e9efab0939900ec5d4fc632f1e8eff4eec052a1e61a7d23ec02baa63ec8070
Deleted: sha256:fb45fb5ff9353397d89537e3ef6679c72135b6de1e83f40e714e2cbd4510bad8
Deleted: sha256:e82f45961992e3632b231619b85878528c1b7e940ea9ba3fb9c282d103f7ec35
Deleted: sha256:31a7f02d9bca63c8a7469834d261c0c53fb398eb631a2f3d7cac9b1aadbf368a
Deleted: sha256:8da00f16e582fea82651df7b9ed1726379ae18f52ab4503c799689ef2134497d
Deleted: sha256:f433aeafd94a2db779add7930286a31d8058e1ee2d915fe2f689328b78fd7b9d
Deleted: sha256:e1e0e7dcfc3656aa18adbe79387c5ab8c98a7651e5e4ea2dcb24824c58149d02
Deleted: sha256:2ffcdb4d10795615c17b8f495ce7b7ae043d572fe5c129ccd1381249158401c0
Deleted: sha256:7a1877741ce74baa250fdefcd6cc03032fa11eb1272c3268144316483d4f56f1
Deleted: sha256:a635ad8b029a86891f02e7544b17b168512cb9e9ff711628b8001285d4db2b9f
Deleted: sha256:270f3b32140266aa8c4ef50c812e8984fe4c59bf029316d1ef59ae331a03c350
Deleted: sha256:bffd8ee5545bbf17bffda4c16c50677efdc04952d6fbbaefa63b9265d485298a
Deleted: sha256:6eb755062e3dc8338e4d7fc4da94a5d8c373951ebc3fd4c3b47d5a757289cc6a
Deleted: sha256:4c9d1fe0945c7401422ca5553c0cb08fe8ebf12c929210f404652052c42a2b82
Deleted: sha256:077b7b55f2be500ff50e29676f9cae2059fb67a4fff79c00855e96375b2e1952
Deleted: sha256:0bd18dd024684d47368ac8b0b3db8ff789ccb1c3c043b6a4daf9645f380606eb
Deleted: sha256:eec1d41f3cf09ac5620640aabec8a7199da91f9d37fe75c20ff66c26ca2fb2fb
Deleted: sha256:0aa2c14d00e93fab267f89ee82d7163632c9a37cf7769e1877cbf12c0b2e1708
Deleted: sha256:388e8ab5b1459723021e3efa3c036d3d0e02ffe22ee60a78934d2b3166fed523
Deleted: sha256:e1bf25840c05c2a65a7bc74f85d4bf03085c0f8bf30855beecc34b84531ff850
Deleted: sha256:03ba9bea829c82c6222d584638f3cf30d78bf5127f593bb78147a298d043878e
Deleted: sha256:cd9e5a2948e20bdbf01d4f09d548c9a28edbc1edb3bf1a841a561b768422a919
Deleted: sha256:dc5295bab334a39a25e9b008fd4e6e783f38c15d9edc27659095ea8258474f8b
Deleted: sha256:dd97829c0eec9e12f43d70feff25bbf516c66426a624ea7f60e58068b050866e
Build step 'Execute shell' marked build as failure
Not sending mail to unregistered user neil.conway@gmail.com
Jenkins build is back to normal : Mesos-Buildbot » autotools,gcc,--verbose,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(docker||Hadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2) #3735
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=autotools,COMPILER=gcc,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3735/display/redirect?page=changes>
Build failed in Jenkins: Mesos-Buildbot » autotools,gcc,--verbose,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(docker||Hadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2) #3734
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=autotools,COMPILER=gcc,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3734/display/redirect?page=changes>
Changes:
[bmahler] Added a test for the optional executor reconnect retry in the agent.
[bmahler] Added a test for shutting down executors that re-register.
[bmahler] Added a test for ignoring executor re-registrations.
------------------------------------------
[...truncated 27.51 MB...]
[ RUN ] Endpoint/SlaveEndpointTest.AuthorizedRequest/2
I0527 03:16:28.971745 4670 containerizer.cpp:230] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni,environment_secret
W0527 03:16:28.972306 4670 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0527 03:16:28.972405 4670 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0527 03:16:28.972437 4670 provisioner.cpp:255] Using default backend 'copy'
I0527 03:16:28.976214 4700 slave.cpp:232] Mesos agent started on (751)@172.17.0.2:47759
I0527 03:16:28.976253 4700 slave.cpp:233] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/mesos/store/appc" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticatee="crammd5" --authentication_backoff_factor="1secs" --authorizer="local" --cgroups_cpu_enable_pids_and_tids_count="false" --cgroups_enable_cfs="false" --cgroups_hierarchy="/sys/fs/cgroup" --cgroups_limit_swap="false" --cgroups_root="mesos" --container_disk_watch_interval="15secs" --containerizers="mesos" --credential="/tmp/Endpoint_SlaveEndpointTest_AuthorizedRequest_2_9huL7i/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/Endpoint_SlaveEndpointTest_AuthorizedRequest_2_9huL7i/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_AuthorizedRequest_2_9huL7i/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_AuthorizedRequest_2_9huL7i" --sandbox_directory="/mnt/mesos/sandbox" --strict="true" --switch_user="true" --systemd_enable_support="true" --systemd_runtime_directory="/run/systemd/system" --version="false" --work_dir="/tmp/Endpoint_SlaveEndpointTest_AuthorizedRequest_2_VSbhmr"
I0527 03:16:28.976707 4700 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_AuthorizedRequest_2_9huL7i/credential'
I0527 03:16:28.976932 4700 slave.cpp:265] Agent using credential for: test-principal
I0527 03:16:28.976953 4700 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_AuthorizedRequest_2_9huL7i/http_credentials'
I0527 03:16:28.977219 4700 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0527 03:16:28.977474 4700 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0527 03:16:28.979188 4700 slave.cpp:536] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0527 03:16:28.979260 4700 slave.cpp:544] Agent attributes: [ ]
I0527 03:16:28.979274 4700 slave.cpp:549] Agent hostname: 25b1c1662401
I0527 03:16:28.979426 4692 status_update_manager.cpp:177] Pausing sending status updates
I0527 03:16:28.980773 4694 state.cpp:62] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_AuthorizedRequest_2_VSbhmr/meta'
I0527 03:16:28.981127 4702 status_update_manager.cpp:203] Recovering status update manager
I0527 03:16:28.981351 4699 containerizer.cpp:582] Recovering containerizer
I0527 03:16:28.983536 4693 provisioner.cpp:416] Provisioner recovery complete
I0527 03:16:28.983978 4694 slave.cpp:6083] Finished recovery
I0527 03:16:28.984525 4694 slave.cpp:6265] Querying resource estimator for oversubscribable resources
I0527 03:16:28.984797 4692 slave.cpp:6279] Received oversubscribable resources {} from the resource estimator
I0527 03:16:28.988348 4693 process.cpp:3730] Handling HTTP event for process 'slave(751)' with path: '/slave(751)/containers'
I0527 03:16:28.989905 4703 http.cpp:1115] HTTP GET for /slave(751)/containers from 172.17.0.2:45278
I0527 03:16:28.989984 4703 http.cpp:936] Authorizing principal 'test-principal' to GET the '/containers' endpoint
I0527 03:16:28.993700 4696 slave.cpp:801] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.AuthorizedRequest/2 (53 ms)
[ RUN ] Endpoint/SlaveEndpointTest.UnauthorizedRequest/0
I0527 03:16:29.023564 4670 containerizer.cpp:230] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni,environment_secret
W0527 03:16:29.024067 4670 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0527 03:16:29.024235 4670 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0527 03:16:29.024276 4670 provisioner.cpp:255] Using default backend 'copy'
I0527 03:16:29.029417 4698 slave.cpp:232] Mesos agent started on (752)@172.17.0.2:47759
I0527 03:16:29.029445 4698 slave.cpp:233] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/mesos/store/appc" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticatee="crammd5" --authentication_backoff_factor="1secs" --authorizer="local" --cgroups_cpu_enable_pids_and_tids_count="false" --cgroups_enable_cfs="false" --cgroups_hierarchy="/sys/fs/cgroup" --cgroups_limit_swap="false" --cgroups_root="mesos" --container_disk_watch_interval="15secs" --containerizers="mesos" --credential="/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_JBUFDM/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/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_JBUFDM/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_JBUFDM/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_JBUFDM" --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_hc1BJU"
I0527 03:16:29.029981 4698 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_JBUFDM/credential'
I0527 03:16:29.030197 4698 slave.cpp:265] Agent using credential for: test-principal
I0527 03:16:29.030223 4698 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_JBUFDM/http_credentials'
I0527 03:16:29.030560 4698 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0527 03:16:29.030772 4698 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0527 03:16:29.032503 4698 slave.cpp:536] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0527 03:16:29.032668 4698 slave.cpp:544] Agent attributes: [ ]
I0527 03:16:29.032694 4698 slave.cpp:549] Agent hostname: 25b1c1662401
I0527 03:16:29.032886 4701 status_update_manager.cpp:177] Pausing sending status updates
I0527 03:16:29.034504 4703 state.cpp:62] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_hc1BJU/meta'
I0527 03:16:29.034907 4689 status_update_manager.cpp:203] Recovering status update manager
I0527 03:16:29.035167 4692 containerizer.cpp:582] Recovering containerizer
I0527 03:16:29.037159 4702 provisioner.cpp:416] Provisioner recovery complete
I0527 03:16:29.037786 4703 slave.cpp:6083] Finished recovery
I0527 03:16:29.038440 4703 slave.cpp:6265] Querying resource estimator for oversubscribable resources
I0527 03:16:29.038811 4704 slave.cpp:6279] Received oversubscribable resources {} from the resource estimator
I0527 03:16:29.042052 4701 process.cpp:3730] Handling HTTP event for process 'slave(752)' with path: '/slave(752)/monitor/statistics'
I0527 03:16:29.043951 4698 http.cpp:1115] HTTP GET for /slave(752)/monitor/statistics from 172.17.0.2:45289
I0527 03:16:29.044067 4698 http.cpp:936] Authorizing principal 'test-principal' to GET the '/monitor/statistics' endpoint
I0527 03:16:29.047276 4694 slave.cpp:801] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.UnauthorizedRequest/0 (87 ms)
[ RUN ] Endpoint/SlaveEndpointTest.UnauthorizedRequest/1
I0527 03:16:29.111421 4670 containerizer.cpp:230] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni,environment_secret
W0527 03:16:29.111959 4670 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0527 03:16:29.112063 4670 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0527 03:16:29.112100 4670 provisioner.cpp:255] Using default backend 'copy'
I0527 03:16:29.115429 4696 slave.cpp:232] Mesos agent started on (753)@172.17.0.2:47759
I0527 03:16:29.115455 4696 slave.cpp:233] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/mesos/store/appc" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticatee="crammd5" --authentication_backoff_factor="1secs" --authorizer="local" --cgroups_cpu_enable_pids_and_tids_count="false" --cgroups_enable_cfs="false" --cgroups_hierarchy="/sys/fs/cgroup" --cgroups_limit_swap="false" --cgroups_root="mesos" --container_disk_watch_interval="15secs" --containerizers="mesos" --credential="/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_cNw1Yg/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/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_cNw1Yg/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_cNw1Yg/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_cNw1Yg" --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_WLCIQo"
I0527 03:16:29.115877 4696 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_cNw1Yg/credential'
I0527 03:16:29.116037 4696 slave.cpp:265] Agent using credential for: test-principal
I0527 03:16:29.116055 4696 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_cNw1Yg/http_credentials'
I0527 03:16:29.116281 4696 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0527 03:16:29.116430 4696 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0527 03:16:29.117662 4696 slave.cpp:536] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0527 03:16:29.117735 4696 slave.cpp:544] Agent attributes: [ ]
I0527 03:16:29.117754 4696 slave.cpp:549] Agent hostname: 25b1c1662401
I0527 03:16:29.117898 4704 status_update_manager.cpp:177] Pausing sending status updates
I0527 03:16:29.119472 4691 state.cpp:62] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_WLCIQo/meta'
I0527 03:16:29.119843 4698 status_update_manager.cpp:203] Recovering status update manager
I0527 03:16:29.120046 4693 containerizer.cpp:582] Recovering containerizer
I0527 03:16:29.121681 4691 provisioner.cpp:416] Provisioner recovery complete
I0527 03:16:29.122088 4700 slave.cpp:6083] Finished recovery
I0527 03:16:29.131625 4700 slave.cpp:6265] Querying resource estimator for oversubscribable resources
I0527 03:16:29.132097 4701 slave.cpp:6279] Received oversubscribable resources {} from the resource estimator
I0527 03:16:29.135574 4702 process.cpp:3730] Handling HTTP event for process 'slave(753)' with path: '/slave(753)/monitor/statistics.json'
I0527 03:16:29.137080 4700 http.cpp:1115] HTTP GET for /slave(753)/monitor/statistics.json from 172.17.0.2:45302
I0527 03:16:29.137182 4700 http.cpp:936] Authorizing principal 'test-principal' to GET the '/monitor/statistics.json' endpoint
I0527 03:16:29.139724 4693 slave.cpp:801] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.UnauthorizedRequest/1 (40 ms)
[ RUN ] Endpoint/SlaveEndpointTest.UnauthorizedRequest/2
I0527 03:16:29.153190 4670 containerizer.cpp:230] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni,environment_secret
W0527 03:16:29.153842 4670 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0527 03:16:29.153966 4670 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0527 03:16:29.154007 4670 provisioner.cpp:255] Using default backend 'copy'
I0527 03:16:29.158365 4701 slave.cpp:232] Mesos agent started on (754)@172.17.0.2:47759
I0527 03:16:29.158390 4701 slave.cpp:233] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/mesos/store/appc" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticatee="crammd5" --authentication_backoff_factor="1secs" --authorizer="local" --cgroups_cpu_enable_pids_and_tids_count="false" --cgroups_enable_cfs="false" --cgroups_hierarchy="/sys/fs/cgroup" --cgroups_limit_swap="false" --cgroups_root="mesos" --container_disk_watch_interval="15secs" --containerizers="mesos" --credential="/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_IEntTL/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/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_IEntTL/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_IEntTL/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_IEntTL" --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_SSFuET"
I0527 03:16:29.159035 4701 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_IEntTL/credential'
I0527 03:16:29.159278 4701 slave.cpp:265] Agent using credential for: test-principal
I0527 03:16:29.159303 4701 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_IEntTL/http_credentials'
I0527 03:16:29.159605 4701 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0527 03:16:29.159771 4701 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0527 03:16:29.161437 4701 slave.cpp:536] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0527 03:16:29.161526 4701 slave.cpp:544] Agent attributes: [ ]
I0527 03:16:29.161542 4701 slave.cpp:549] Agent hostname: 25b1c1662401
I0527 03:16:29.161780 4695 status_update_manager.cpp:177] Pausing sending status updates
I0527 03:16:29.163177 4694 state.cpp:62] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_SSFuET/meta'
I0527 03:16:29.163650 4693 status_update_manager.cpp:203] Recovering status update manager
I0527 03:16:29.163907 4698 containerizer.cpp:582] Recovering containerizer
I0527 03:16:29.165956 4692 provisioner.cpp:416] Provisioner recovery complete
I0527 03:16:29.166580 4696 slave.cpp:6083] Finished recovery
I0527 03:16:29.173774 4696 slave.cpp:6265] Querying resource estimator for oversubscribable resources
I0527 03:16:29.174113 4702 slave.cpp:6279] Received oversubscribable resources {} from the resource estimator
I0527 03:16:29.177119 4697 process.cpp:3730] Handling HTTP event for process 'slave(754)' with path: '/slave(754)/containers'
I0527 03:16:29.178570 4697 http.cpp:1115] HTTP GET for /slave(754)/containers from 172.17.0.2:45306
I0527 03:16:29.178663 4697 http.cpp:936] Authorizing principal 'test-principal' to GET the '/containers' endpoint
I0527 03:16:29.181097 4696 slave.cpp:801] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.UnauthorizedRequest/2 (39 ms)
[ RUN ] Endpoint/SlaveEndpointTest.NoAuthorizer/0
I0527 03:16:29.191687 4670 containerizer.cpp:230] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni,environment_secret
W0527 03:16:29.192214 4670 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0527 03:16:29.192332 4670 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0527 03:16:29.192395 4670 provisioner.cpp:255] Using default backend 'copy'
I0527 03:16:29.194872 4670 cluster.cpp:448] Creating default 'local' authorizer
I0527 03:16:29.196882 4691 slave.cpp:232] Mesos agent started on (755)@172.17.0.2:47759
I0527 03:16:29.196916 4691 slave.cpp:233] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/mesos/store/appc" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticatee="crammd5" --authentication_backoff_factor="1secs" --authorizer="local" --cgroups_cpu_enable_pids_and_tids_count="false" --cgroups_enable_cfs="false" --cgroups_hierarchy="/sys/fs/cgroup" --cgroups_limit_swap="false" --cgroups_root="mesos" --container_disk_watch_interval="15secs" --containerizers="mesos" --credential="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_0_uiT2dh/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/Endpoint_SlaveEndpointTest_NoAuthorizer_0_uiT2dh/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_uiT2dh/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_uiT2dh" --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_02CGSo"
I0527 03:16:29.197481 4691 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_0_uiT2dh/credential'
I0527 03:16:29.231503 4691 slave.cpp:265] Agent using credential for: test-principal
I0527 03:16:29.231549 4691 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_0_uiT2dh/http_credentials'
I0527 03:16:29.231889 4691 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0527 03:16:29.232141 4691 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0527 03:16:29.233422 4691 slave.cpp:536] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0527 03:16:29.233499 4691 slave.cpp:544] Agent attributes: [ ]
I0527 03:16:29.233510 4691 slave.cpp:549] Agent hostname: 25b1c1662401
I0527 03:16:29.233666 4700 status_update_manager.cpp:177] Pausing sending status updates
I0527 03:16:29.235332 4703 state.cpp:62] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_0_02CGSo/meta'
I0527 03:16:29.235772 4694 status_update_manager.cpp:203] Recovering status update manager
I0527 03:16:29.235993 4689 containerizer.cpp:582] Recovering containerizer
I0527 03:16:29.237431 4704 provisioner.cpp:416] Provisioner recovery complete
I0527 03:16:29.237792 4693 slave.cpp:6083] Finished recovery
I0527 03:16:29.238306 4693 slave.cpp:6265] Querying resource estimator for oversubscribable resources
I0527 03:16:29.238658 4697 slave.cpp:6279] Received oversubscribable resources {} from the resource estimator
I0527 03:16:29.242202 4692 process.cpp:3730] Handling HTTP event for process 'slave(755)' with path: '/slave(755)/monitor/statistics'
I0527 03:16:29.243692 4689 http.cpp:1115] HTTP GET for /slave(755)/monitor/statistics from 172.17.0.2:45320
I0527 03:16:29.243772 4689 http.cpp:936] Authorizing principal 'test-principal' to GET the '/monitor/statistics' endpoint
I0527 03:16:29.247316 4701 slave.cpp:801] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.NoAuthorizer/0 (66 ms)
[ RUN ] Endpoint/SlaveEndpointTest.NoAuthorizer/1
I0527 03:16:29.258905 4670 containerizer.cpp:230] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni,environment_secret
W0527 03:16:29.259402 4670 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0527 03:16:29.259503 4670 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0527 03:16:29.259538 4670 provisioner.cpp:255] Using default backend 'copy'
I0527 03:16:29.261273 4670 cluster.cpp:448] Creating default 'local' authorizer
I0527 03:16:29.262881 4693 slave.cpp:232] Mesos agent started on (756)@172.17.0.2:47759
I0527 03:16:29.262909 4693 slave.cpp:233] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/mesos/store/appc" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticatee="crammd5" --authentication_backoff_factor="1secs" --authorizer="local" --cgroups_cpu_enable_pids_and_tids_count="false" --cgroups_enable_cfs="false" --cgroups_hierarchy="/sys/fs/cgroup" --cgroups_limit_swap="false" --cgroups_root="mesos" --container_disk_watch_interval="15secs" --containerizers="mesos" --credential="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_1_ychKfN/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/Endpoint_SlaveEndpointTest_NoAuthorizer_1_ychKfN/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_ychKfN/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_ychKfN" --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_6s6tJU"
I0527 03:16:29.263460 4693 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_1_ychKfN/credential'
I0527 03:16:29.263664 4693 slave.cpp:265] Agent using credential for: test-principal
I0527 03:16:29.263689 4693 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_1_ychKfN/http_credentials'
I0527 03:16:29.263967 4693 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0527 03:16:29.264145 4693 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0527 03:16:29.265646 4693 slave.cpp:536] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0527 03:16:29.265733 4693 slave.cpp:544] Agent attributes: [ ]
I0527 03:16:29.265748 4693 slave.cpp:549] Agent hostname: 25b1c1662401
I0527 03:16:29.265904 4702 status_update_manager.cpp:177] Pausing sending status updates
I0527 03:16:29.267457 4692 state.cpp:62] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_1_6s6tJU/meta'
I0527 03:16:29.267822 4694 status_update_manager.cpp:203] Recovering status update manager
I0527 03:16:29.268041 4702 containerizer.cpp:582] Recovering containerizer
I0527 03:16:29.269928 4700 provisioner.cpp:416] Provisioner recovery complete
I0527 03:16:29.270406 4701 slave.cpp:6083] Finished recovery
I0527 03:16:29.270920 4701 slave.cpp:6265] Querying resource estimator for oversubscribable resources
I0527 03:16:29.271299 4694 slave.cpp:6279] Received oversubscribable resources {} from the resource estimator
I0527 03:16:29.274353 4700 process.cpp:3730] Handling HTTP event for process 'slave(756)' with path: '/slave(756)/monitor/statistics.json'
I0527 03:16:29.275825 4689 http.cpp:1115] HTTP GET for /slave(756)/monitor/statistics.json from 172.17.0.2:45325
I0527 03:16:29.275903 4689 http.cpp:936] Authorizing principal 'test-principal' to GET the '/monitor/statistics.json' endpoint
I0527 03:16:29.279180 4697 slave.cpp:801] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.NoAuthorizer/1 (59 ms)
[ RUN ] Endpoint/SlaveEndpointTest.NoAuthorizer/2
I0527 03:16:29.320464 4670 containerizer.cpp:230] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni,environment_secret
W0527 03:16:29.321116 4670 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0527 03:16:29.321247 4670 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0527 03:16:29.321296 4670 provisioner.cpp:255] Using default backend 'copy'
I0527 03:16:29.323559 4670 cluster.cpp:448] Creating default 'local' authorizer
I0527 03:16:29.325588 4699 slave.cpp:232] Mesos agent started on (757)@172.17.0.2:47759
I0527 03:16:29.325616 4699 slave.cpp:233] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/mesos/store/appc" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticatee="crammd5" --authentication_backoff_factor="1secs" --authorizer="local" --cgroups_cpu_enable_pids_and_tids_count="false" --cgroups_enable_cfs="false" --cgroups_hierarchy="/sys/fs/cgroup" --cgroups_limit_swap="false" --cgroups_root="mesos" --container_disk_watch_interval="15secs" --containerizers="mesos" --credential="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_2_yxyQPj/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/Endpoint_SlaveEndpointTest_NoAuthorizer_2_yxyQPj/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_yxyQPj/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_yxyQPj" --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_y7EQ9q"
I0527 03:16:29.326190 4699 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_2_yxyQPj/credential'
I0527 03:16:29.326474 4699 slave.cpp:265] Agent using credential for: test-principal
I0527 03:16:29.326503 4699 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_2_yxyQPj/http_credentials'
I0527 03:16:29.326802 4699 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0527 03:16:29.326975 4699 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0527 03:16:29.328260 4699 slave.cpp:536] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0527 03:16:29.328359 4699 slave.cpp:544] Agent attributes: [ ]
I0527 03:16:29.328374 4699 slave.cpp:549] Agent hostname: 25b1c1662401
I0527 03:16:29.328523 4700 status_update_manager.cpp:177] Pausing sending status updates
I0527 03:16:29.330003 4697 state.cpp:62] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_2_y7EQ9q/meta'
I0527 03:16:29.330423 4694 status_update_manager.cpp:203] Recovering status update manager
I0527 03:16:29.330658 4697 containerizer.cpp:582] Recovering containerizer
I0527 03:16:29.332350 4691 provisioner.cpp:416] Provisioner recovery complete
I0527 03:16:29.332815 4696 slave.cpp:6083] Finished recovery
I0527 03:16:29.333417 4696 slave.cpp:6265] Querying resource estimator for oversubscribable resources
I0527 03:16:29.333756 4692 slave.cpp:6279] Received oversubscribable resources {} from the resource estimator
I0527 03:16:29.336933 4704 process.cpp:3730] Handling HTTP event for process 'slave(757)' with path: '/slave(757)/containers'
I0527 03:16:29.339159 4704 http.cpp:1115] HTTP GET for /slave(757)/containers from 172.17.0.2:45338
I0527 03:16:29.339262 4704 http.cpp:936] Authorizing principal 'test-principal' to GET the '/containers' endpoint
I0527 03:16:29.344691 4670 slave.cpp:801] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.NoAuthorizer/2 (66 ms)
[----------] 9 tests from Endpoint/SlaveEndpointTest (501 ms total)
[----------] Global test environment tear-down
[==========] 1583 tests from 176 test cases ran. (713429 ms total)
[ PASSED ] 1581 tests.
[ FAILED ] 2 tests, listed below:
[ FAILED ] ExamplesTest.PythonFramework
[ FAILED ] IOSwitchboardTest.ContainerAttach
2 FAILED TESTS
YOU HAVE 22 DISABLED TESTS
E0527 03:16:29.469610 4705 process.cpp:950] 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-1495850562-20834
Untagged: mesos-1495850562-20834:latest
Deleted: sha256:c8e34fe6c601c3dc6dbfbc36e9b12206cd0e62c375d32779e8482453666c9d58
Deleted: sha256:ab574f0e431fa407d330e46e4976f1d3529b8f1bf922da73f98db135c3c35fde
Deleted: sha256:1ab3c83f8768ffae6bef8de9400019e58cb7be7d1c4d597ade36a4544f85f9f5
Deleted: sha256:03619a9b06654acf38d316aeaf2fca99b2b1e4009a9bff3c8dd30872f32aa84a
Deleted: sha256:2838425ce8e5df0e0258c523ff492b15aa636da98f21c639f914eb06824ca9db
Deleted: sha256:ade43ab9f8597dfb579550abe58893ee8072a9eff6a630ffe01e737ccd182bb2
Deleted: sha256:5c62343fee93cb0490610e9687ee1629182065ab92cbc32fa112d56359bff688
Deleted: sha256:fbd905f4f1358b3600b088d7d356fb240099b86845308ce0d1257a36be0d5e80
Deleted: sha256:e0945772fed9533398f202a9b3ecdc231d4127eff3651f9b1092c3407f87047b
Deleted: sha256:61a7e55d5a2de7c9aaf5d4472bb3a65c46b087521ccaa2c5bda1fbf7cef4ca60
Deleted: sha256:879b2bd7c64c75ffa029387e2018469d1cfb7073cb3347e6a12e424323337ee0
Deleted: sha256:81c01a9fdcb03ad59e4a002e71c6def7f0ae82760f82c302267e90b8dbe43b7c
Deleted: sha256:c90294da88ec5029ecd4eb67a525e3e278b68a93f5b95d408e5fc7f1c1319ef5
Deleted: sha256:821f766cb4ff539a38b9b2dc74a8f8a9b91b1e4a2dd823d2b70015d83f613ba9
Deleted: sha256:97c5d1de7147ee9f2efe22a8e3440d77b5dbd13acfe95ce4aa76166d0776e611
Deleted: sha256:c37c89c7490f0d5c1a2bdfc28d68ad537bff7e95b7b36192539c7b2787d134c3
Deleted: sha256:4f8e9e05e520a61b821e10beddd9a314ba8fe47fb6c82c628bdef3ccc143b7dd
Deleted: sha256:d42f040f8aeea8ed025e10a6889bcad92a38223300b94777c315b6e4824e0670
Deleted: sha256:71ae0de12324dd0cb2ad9dd4942b71923c3e224b25bf9055698fc4141ae8d824
Deleted: sha256:95b51524d8ab66dbb41926f44cea0c182439a8f6312d5017b9a2dd765d1ff5cb
Deleted: sha256:a38edcccb2c17ae9b91430d47d76147ba8c48422913cdba7faa25fb9c2ccacf6
Deleted: sha256:76020f76f610dc970efdf2343c2bb4db68febb4fc5b019f23eab8c529298a9d0
Deleted: sha256:4e4adce67c763d6bd4f49944f0964468ba34f614144e4390f7fa8476952db136
Deleted: sha256:5d2a54a585ee40d30ceb75851c6ebb8df14a11217dcbf3c1d13be0b10baecdeb
Build step 'Execute shell' marked build as failure
Not sending mail to unregistered user neil.conway@gmail.com