See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=gcc,CONFIGURATION=--verbose%20--disable-libtool-wrappers%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4589/display/redirect?page=changes>
Changes: [yujie.jay] Exposed resource provider information in master 'GET_AGENTS' response. [bmahler] Moved a comment in the allocator to a more relevant location. ------------------------------------------ [...truncated 19.19 MB...] 3: I1213 02:48:41.210907 20105 registrar.cpp:552] Successfully updated the registry in 539904ns 3: I1213 02:48:41.211098 20113 master.cpp:6288] Admitted agent 64cf8261-7c05-4e03-97cd-efcd9f24063a-S0 at slave(504)@172.17.0.2:42345 (d19c336a231a) 3: I1213 02:48:41.211946 20113 master.cpp:6337] Registered agent 64cf8261-7c05-4e03-97cd-efcd9f24063a-S0 at slave(504)@172.17.0.2:42345 (d19c336a231a) with cpus:1; mem:512; disk(reservations: [(STATIC,potato_head)]):1024; disk(reservations: [(STATIC,gumby)]):1024; ports:[31000-32000] 3: I1213 02:48:41.212137 20107 slave.cpp:1253] Registered with master [email protected]:42345; given agent ID 64cf8261-7c05-4e03-97cd-efcd9f24063a-S0 3: I1213 02:48:41.212306 20124 task_status_update_manager.cpp:188] Resuming sending task status updates 3: I1213 02:48:41.212503 20107 slave.cpp:1273] Checkpointing SlaveInfo to '/tmp/PersistentVolumeEndpointsTest_BadCreateACLMultipleRoles_8nWpkf/meta/slaves/64cf8261-7c05-4e03-97cd-efcd9f24063a-S0/slave.info' 3: I1213 02:48:41.212627 20121 hierarchical.cpp:574] Added agent 64cf8261-7c05-4e03-97cd-efcd9f24063a-S0 (d19c336a231a) with cpus:1; mem:512; disk(reservations: [(STATIC,potato_head)]):1024; disk(reservations: [(STATIC,gumby)]):1024; ports:[31000-32000] (allocated: {}) 3: I1213 02:48:41.213027 20121 hierarchical.cpp:1517] Performed allocation for 1 agents in 216554ns 3: I1213 02:48:41.213188 20107 slave.cpp:1330] Forwarding agent update {"offer_operations":{},"resource_categories":{"oversubscribed":true},"resource_version_uuid":"FCPossQWTnmY32B\/uCadNw==","slave_id":{"value":"64cf8261-7c05-4e03-97cd-efcd9f24063a-S0"}} 3: I1213 02:48:41.213407 20107 master.cpp:7270] Received update of agent 64cf8261-7c05-4e03-97cd-efcd9f24063a-S0 at slave(504)@172.17.0.2:42345 (d19c336a231a) with total oversubscribed resources {} 3: I1213 02:48:41.213711 20107 master.cpp:7368] Ignoring update on agent 64cf8261-7c05-4e03-97cd-efcd9f24063a-S0 at slave(504)@172.17.0.2:42345 (d19c336a231a) as it reports no changes 3: I1213 02:48:41.215950 20106 process.cpp:3503] Handling HTTP event for process 'master' with path: '/master/create-volumes' 3: I1213 02:48:41.217959 20119 http.cpp:1185] HTTP POST for /master/create-volumes from 172.17.0.2:44982 3: I1213 02:48:41.219434 20119 master.cpp:3786] Authorizing principal 'test-principal' to create volumes '[{"disk":{"persistence":{"id":"id1","principal":"test-principal"},"volume":{"container_path":"path1","mode":"RW"}},"name":"disk","reservations":[{"role":"potato_head","type":"STATIC"}],"scalar":{"value":64.0},"type":"SCALAR"},{"disk":{"persistence":{"id":"id2","principal":"test-principal"},"volume":{"container_path":"path2","mode":"RW"}},"name":"disk","reservations":[{"role":"gumby","type":"STATIC"}],"scalar":{"value":64.0},"type":"SCALAR"}]' 3: I1213 02:48:41.222328 20104 slave.cpp:909] Agent terminating 3: I1213 02:48:41.222574 20125 master.cpp:1305] Agent 64cf8261-7c05-4e03-97cd-efcd9f24063a-S0 at slave(504)@172.17.0.2:42345 (d19c336a231a) disconnected 3: I1213 02:48:41.222615 20125 master.cpp:3364] Disconnecting agent 64cf8261-7c05-4e03-97cd-efcd9f24063a-S0 at slave(504)@172.17.0.2:42345 (d19c336a231a) 3: I1213 02:48:41.222676 20125 master.cpp:3383] Deactivating agent 64cf8261-7c05-4e03-97cd-efcd9f24063a-S0 at slave(504)@172.17.0.2:42345 (d19c336a231a) 3: I1213 02:48:41.222834 20127 hierarchical.cpp:766] Agent 64cf8261-7c05-4e03-97cd-efcd9f24063a-S0 deactivated 3: I1213 02:48:41.230597 20104 master.cpp:1147] Master terminating 3: I1213 02:48:41.231499 20115 hierarchical.cpp:609] Removed agent 64cf8261-7c05-4e03-97cd-efcd9f24063a-S0 3: [ OK ] PersistentVolumeEndpointsTest.BadCreateACLMultipleRoles (74 ms) 3: [ RUN ] PersistentVolumeEndpointsTest.GoodCreateAndDestroyACLBadCredential 3: I1213 02:48:41.237818 20104 cluster.cpp:172] Creating default 'local' authorizer 3: I1213 02:48:41.242051 20110 master.cpp:456] Master 5a9d1021-8d9f-4203-aa9a-f09c07cc4fe3 (d19c336a231a) started on 172.17.0.2:42345 3: I1213 02:48:41.242072 20110 master.cpp:458] Flags at startup: --acls="create_volumes { 3: principals { 3: values: "awesome-principal" 3: } 3: roles { 3: type: ANY 3: } 3: } 3: create_volumes { 3: principals { 3: values: "test-principal" 3: } 3: roles { 3: type: ANY 3: } 3: } 3: destroy_volumes { 3: principals { 3: values: "awesome-principal" 3: } 3: creator_principals { 3: type: ANY 3: } 3: } 3: " --agent_ping_timeout="15secs" --agent_reregister_timeout="10mins" --allocation_interval="1000secs" --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/gTGkCn/credentials" --filter_gpu_resources="true" --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" --roles="role1" --root_submissions="true" --user_sorter="drf" --version="false" --webui_dir="/usr/local/share/mesos/webui" --work_dir="/tmp/gTGkCn/master" --zk_session_timeout="10secs" 3: I1213 02:48:41.242434 20110 master.cpp:507] Master only allowing authenticated frameworks to register 3: I1213 02:48:41.242441 20110 master.cpp:513] Master only allowing authenticated agents to register 3: I1213 02:48:41.242445 20110 master.cpp:519] Master only allowing authenticated HTTP frameworks to register 3: I1213 02:48:41.242450 20110 credentials.hpp:37] Loading credentials for authentication from '/tmp/gTGkCn/credentials' 3: I1213 02:48:41.242686 20110 master.cpp:563] Using default 'crammd5' authenticator 3: I1213 02:48:41.242816 20110 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly' 3: I1213 02:48:41.242947 20110 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite' 3: I1213 02:48:41.243062 20110 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler' 3: I1213 02:48:41.243176 20110 master.cpp:642] Authorization enabled 3: W1213 02:48:41.243190 20110 master.cpp:705] The '--roles' flag is deprecated. This flag will be removed in the future. See the Mesos 0.27 upgrade notes for more information 3: I1213 02:48:41.243355 20125 whitelist_watcher.cpp:77] No whitelist given 3: I1213 02:48:41.243381 20127 hierarchical.cpp:175] Initialized hierarchical allocator process 3: I1213 02:48:41.246242 20112 master.cpp:2209] Elected as the leading master! 3: I1213 02:48:41.246273 20112 master.cpp:1689] Recovering from registrar 3: I1213 02:48:41.246449 20106 registrar.cpp:347] Recovering registrar 3: I1213 02:48:41.247135 20106 registrar.cpp:391] Successfully fetched the registry (0B) in 648960ns 3: I1213 02:48:41.247247 20106 registrar.cpp:495] Applied 1 operations in 32021ns; attempting to update the registry 3: I1213 02:48:41.247886 20106 registrar.cpp:552] Successfully updated the registry in 589056ns 3: I1213 02:48:41.248014 20106 registrar.cpp:424] Successfully recovered registrar 3: I1213 02:48:41.248458 20128 hierarchical.cpp:213] Skipping recovery of hierarchical allocator: nothing to recover 3: I1213 02:48:41.248455 20105 master.cpp:1802] Recovered 0 agents from the registry (135B); allowing 10mins for agents to re-register 3: W1213 02:48:41.253233 20104 process.cpp:2756] Attempted to spawn already running process [email protected]:42345 3: I1213 02:48:41.254124 20104 containerizer.cpp:304] Using isolation { environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni } 3: W1213 02:48:41.254639 20104 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges 3: W1213 02:48:41.254746 20104 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges 3: I1213 02:48:41.254776 20104 provisioner.cpp:299] Using default backend 'copy' 3: I1213 02:48:41.256610 20104 cluster.cpp:460] Creating default 'local' authorizer 3: I1213 02:48:41.258834 20107 slave.cpp:258] Mesos agent started on (505)@172.17.0.2:42345 3: I1213 02:48:41.258854 20107 slave.cpp:259] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://"; --appc_store_dir="/tmp/PersistentVolumeEndpointsTest_GoodCreateAndDestroyACLBadCredential_YB2UYW/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/PersistentVolumeEndpointsTest_GoodCreateAndDestroyACLBadCredential_YB2UYW/credential" --default_role="*" --disallow_sharing_agent_pid_namespace="false" --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/PersistentVolumeEndpointsTest_GoodCreateAndDestroyACLBadCredential_YB2UYW/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/PersistentVolumeEndpointsTest_GoodCreateAndDestroyACLBadCredential_YB2UYW/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/PersistentVolumeEndpointsTest_GoodCreateAndDestroyACLBadCredential_YB2UYW/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --jwt_secret_key="/tmp/PersistentVolumeEndpointsTest_GoodCreateAndDestroyACLBadCredential_YB2UYW/jwt_secret_key" --launcher="posix" --launcher_dir="/mesos/build/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --port="5051" --qos_correction_interval_min="0ns" --quiet="false" --reconfiguration_policy="equal" --recover="reconnect" --recovery_timeout="15mins" --registration_backoff_factor="10ms" --resources="cpus:1;mem:512;disk(role1):1024" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/PersistentVolumeEndpointsTest_GoodCreateAndDestroyACLBadCredential_YB2UYW" --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/PersistentVolumeEndpointsTest_GoodCreateAndDestroyACLBadCredential_61sCia" --zk_session_timeout="10secs" 3: I1213 02:48:41.259316 20107 credentials.hpp:86] Loading credential for authentication from '/tmp/PersistentVolumeEndpointsTest_GoodCreateAndDestroyACLBadCredential_YB2UYW/credential' 3: I1213 02:48:41.259450 20107 slave.cpp:291] Agent using credential for: test-principal 3: I1213 02:48:41.259466 20107 credentials.hpp:37] Loading credentials for authentication from '/tmp/PersistentVolumeEndpointsTest_GoodCreateAndDestroyACLBadCredential_YB2UYW/http_credentials' 3: I1213 02:48:41.259713 20107 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-executor' 3: I1213 02:48:41.259827 20107 http.cpp:1066] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-executor' 3: I1213 02:48:41.260011 20107 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly' 3: I1213 02:48:41.260102 20107 http.cpp:1066] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readonly' 3: I1213 02:48:41.260279 20107 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite' 3: I1213 02:48:41.260367 20107 http.cpp:1066] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readwrite' 3: I1213 02:48:41.261754 20107 slave.cpp:590] Agent resources: [{"name":"cpus","scalar":{"value":1.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":512.0},"type":"SCALAR"},{"name":"disk","reservations":[{"role":"role1","type":"STATIC"}],"scalar":{"value":1024.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}] 3: I1213 02:48:41.262010 20107 slave.cpp:598] Agent attributes: [ ] 3: I1213 02:48:41.262019 20107 slave.cpp:607] Agent hostname: d19c336a231a 3: I1213 02:48:41.262177 20120 task_status_update_manager.cpp:181] Pausing sending task status updates 3: I1213 02:48:41.263764 20121 state.cpp:66] Recovering state from '/tmp/PersistentVolumeEndpointsTest_GoodCreateAndDestroyACLBadCredential_61sCia/meta' 3: I1213 02:48:41.264242 20128 task_status_update_manager.cpp:207] Recovering task status update manager 3: I1213 02:48:41.264488 20115 containerizer.cpp:674] Recovering containerizer 3: I1213 02:48:41.266968 20126 provisioner.cpp:495] Provisioner recovery complete 3: I1213 02:48:41.267397 20120 slave.cpp:6747] Finished recovery 3: I1213 02:48:41.268431 20107 task_status_update_manager.cpp:181] Pausing sending task status updates 3: I1213 02:48:41.268435 20105 slave.cpp:1033] New master detected at [email protected]:42345 3: I1213 02:48:41.268519 20105 slave.cpp:1088] Detecting new master 3: I1213 02:48:41.270901 20127 slave.cpp:1115] Authenticating with master [email protected]:42345 3: I1213 02:48:41.271023 20127 slave.cpp:1124] Using default CRAM-MD5 authenticatee 3: I1213 02:48:41.271412 20125 authenticatee.cpp:121] Creating new client SASL connection 3: I1213 02:48:41.271852 20128 master.cpp:8932] Authenticating slave(505)@172.17.0.2:42345 3: I1213 02:48:41.272078 20124 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(997)@172.17.0.2:42345 3: I1213 02:48:41.272477 20111 authenticator.cpp:98] Creating new server SASL connection 3: I1213 02:48:41.272886 20108 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5 3: I1213 02:48:41.272938 20108 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5' 3: I1213 02:48:41.273138 20116 authenticator.cpp:204] Received SASL authentication start 3: I1213 02:48:41.273193 20116 authenticator.cpp:326] Authentication requires more steps 3: I1213 02:48:41.273293 20116 authenticatee.cpp:259] Received SASL authentication step 3: I1213 02:48:41.273483 20118 authenticator.cpp:232] Received SASL authentication step 3: I1213 02:48:41.273535 20118 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'd19c336a231a' server FQDN: 'd19c336a231a' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 3: I1213 02:48:41.273546 20118 auxprop.cpp:181] Looking up auxiliary property '*userPassword' 3: I1213 02:48:41.273576 20118 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' 3: I1213 02:48:41.273608 20118 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'd19c336a231a' server FQDN: 'd19c336a231a' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 3: I1213 02:48:41.273617 20118 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true 3: I1213 02:48:41.273624 20118 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true 3: I1213 02:48:41.273656 20118 authenticator.cpp:318] Authentication success 3: I1213 02:48:41.273818 20115 authenticatee.cpp:299] Authentication success 3: I1213 02:48:41.273865 20112 master.cpp:8962] Successfully authenticated principal 'test-principal' at slave(505)@172.17.0.2:42345 3: I1213 02:48:41.273941 20118 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(997)@172.17.0.2:42345 3: I1213 02:48:41.274161 20117 slave.cpp:1207] Successfully authenticated with master [email protected]:42345 3: I1213 02:48:41.274400 20117 slave.cpp:1684] Will retry registration in 4.566941ms if necessary 3: I1213 02:48:41.274633 20126 master.cpp:6077] Received register agent message from slave(505)@172.17.0.2:42345 (d19c336a231a) 3: I1213 02:48:41.274755 20126 master.cpp:3866] Authorizing agent with principal 'test-principal' 3: I1213 02:48:41.275256 20120 master.cpp:6147] Authorized registration of agent at slave(505)@172.17.0.2:42345 (d19c336a231a) 3: I1213 02:48:41.275378 20120 master.cpp:6240] Registering agent at slave(505)@172.17.0.2:42345 (d19c336a231a) with id 5a9d1021-8d9f-4203-aa9a-f09c07cc4fe3-S0 3: I1213 02:48:41.276002 20119 registrar.cpp:495] Applied 1 operations in 87558ns; attempting to update the registry 3: I1213 02:48:41.276615 20119 registrar.cpp:552] Successfully updated the registry in 557056ns 3: I1213 02:48:41.276794 20127 master.cpp:6288] Admitted agent 5a9d1021-8d9f-4203-aa9a-f09c07cc4fe3-S0 at slave(505)@172.17.0.2:42345 (d19c336a231a) 3: I1213 02:48:41.277541 20127 master.cpp:6337] Registered agent 5a9d1021-8d9f-4203-aa9a-f09c07cc4fe3-S0 at slave(505)@172.17.0.2:42345 (d19c336a231a) with cpus:1; mem:512; disk(reservations: [(STATIC,role1)]):1024; ports:[31000-32000] 3: I1213 02:48:41.277742 20128 slave.cpp:1253] Registered with master [email protected]:42345; given agent ID 5a9d1021-8d9f-4203-aa9a-f09c07cc4fe3-S0 3: I1213 02:48:41.277858 20108 task_status_update_manager.cpp:188] Resuming sending task status updates 3: I1213 02:48:41.278084 20128 slave.cpp:1273] Checkpointing SlaveInfo to '/tmp/PersistentVolumeEndpointsTest_GoodCreateAndDestroyACLBadCredential_61sCia/meta/slaves/5a9d1021-8d9f-4203-aa9a-f09c07cc4fe3-S0/slave.info' 3: I1213 02:48:41.278568 20128 slave.cpp:1330] Forwarding agent update {"offer_operations":{},"resource_categories":{"oversubscribed":true},"resource_version_uuid":"XVRr7K7SRTOGGfg0jBH\/rA==","slave_id":{"value":"5a9d1021-8d9f-4203-aa9a-f09c07cc4fe3-S0"}} 3: I1213 02:48:41.278595 20124 hierarchical.cpp:574] Added agent 5a9d1021-8d9f-4203-aa9a-f09c07cc4fe3-S0 (d19c336a231a) with cpus:1; mem:512; disk(reservations: [(STATIC,role1)]):1024; ports:[31000-32000] (allocated: {}) 3: I1213 02:48:41.278789 20109 master.cpp:7270] Received update of agent 5a9d1021-8d9f-4203-aa9a-f09c07cc4fe3-S0 at slave(505)@172.17.0.2:42345 (d19c336a231a) with total oversubscribed resources {} 3: I1213 02:48:41.279088 20124 hierarchical.cpp:1517] Performed allocation for 1 agents in 262665ns 3: I1213 02:48:41.279157 20109 master.cpp:7368] Ignoring update on agent 5a9d1021-8d9f-4203-aa9a-f09c07cc4fe3-S0 at slave(505)@172.17.0.2:42345 (d19c336a231a) as it reports no changes 3: I1213 02:48:41.280866 20126 process.cpp:3503] Handling HTTP event for process 'master' with path: '/master/create-volumes' 3: I1213 02:48:41.286638 20128 process.cpp:3503] Handling HTTP event for process 'master' with path: '/master/create-volumes' 3: I1213 02:48:41.288975 20122 http.cpp:1185] HTTP POST for /master/create-volumes from 172.17.0.2:44986 3: I1213 02:48:41.289860 20122 master.cpp:3786] Authorizing principal 'test-principal' to create volumes '[{"disk":{"persistence":{"id":"id1","principal":"test-principal"},"volume":{"container_path":"path1","mode":"RW"}},"name":"disk","reservations":[{"role":"role1","type":"STATIC"}],"scalar":{"value":64.0},"type":"SCALAR"}]' 3: I1213 02:48:41.293754 20121 master.cpp:10628] Sending updated checkpointed resources disk(reservations: [(STATIC,role1)])[id1:path1]:64 to agent 5a9d1021-8d9f-4203-aa9a-f09c07cc4fe3-S0 at slave(505)@172.17.0.2:42345 (d19c336a231a) 3: I1213 02:48:41.294986 20116 slave.cpp:3699] Updated checkpointed resources from {} to disk(reservations: [(STATIC,role1)])[id1:path1]:64 3: W1213 02:48:41.295014 20104 process.cpp:2756] Attempted to spawn already running process [email protected]:42345 3: I1213 02:48:41.295914 20104 sched.cpp:232] Version: 1.5.0 3: I1213 02:48:41.296557 20118 sched.cpp:336] New master detected at [email protected]:42345 3: I1213 02:48:41.296658 20118 sched.cpp:396] Authenticating with master [email protected]:42345 3: I1213 02:48:41.296674 20118 sched.cpp:403] Using default CRAM-MD5 authenticatee 3: I1213 02:48:41.297103 20126 authenticatee.cpp:121] Creating new client SASL connection 3: I1213 02:48:41.297526 20120 master.cpp:8932] Authenticating [email protected]:42345 3: I1213 02:48:41.297705 20123 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(998)@172.17.0.2:42345 3: I1213 02:48:41.298117 20107 authenticator.cpp:98] Creating new server SASL connection 3: I1213 02:48:41.298499 20105 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5 3: I1213 02:48:41.298553 20105 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5' 3: I1213 02:48:41.298674 20105 authenticator.cpp:204] Received SASL authentication start 3: I1213 02:48:41.298760 20105 authenticator.cpp:326] Authentication requires more steps 3: I1213 02:48:41.298861 20105 authenticatee.cpp:259] Received SASL authentication step 3: I1213 02:48:41.299039 20105 authenticator.cpp:232] Received SASL authentication step 3: I1213 02:48:41.299082 20105 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'd19c336a231a' server FQDN: 'd19c336a231a' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 3: I1213 02:48:41.299103 20105 auxprop.cpp:181] Looking up auxiliary property '*userPassword' 3: I1213 02:48:41.299160 20105 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' 3: I1213 02:48:41.299177 20105 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'd19c336a231a' server FQDN: 'd19c336a231a' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 3: I1213 02:48:41.299183 20105 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true 3: I1213 02:48:41.299188 20105 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true 3: I1213 02:48:41.299201 20105 authenticator.cpp:318] Authentication success 3: I1213 02:48:41.299321 20111 authenticatee.cpp:299] Authentication success 3: I1213 02:48:41.299429 20119 master.cpp:8962] Successfully authenticated principal 'test-principal' at [email protected]:42345 3: I1213 02:48:41.299456 20108 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(998)@172.17.0.2:42345 3: I1213 02:48:41.299635 20111 sched.cpp:502] Successfully authenticated with master [email protected]:42345 3: I1213 02:48:41.299669 20111 sched.cpp:824] Sending SUBSCRIBE call to [email protected]:42345 3: I1213 02:48:41.299808 20111 sched.cpp:857] Will retry registration in 1.040518775secs if necessary 3: I1213 02:48:41.300072 20108 master.cpp:2957] Received SUBSCRIBE call for framework 'default' at [email protected]:42345 3: I1213 02:48:41.300197 20108 master.cpp:2274] Authorizing framework principal 'test-principal' to receive offers for roles '{ role1 }' 3: I1213 02:48:41.300855 20109 master.cpp:3037] Subscribing framework default with checkpointing disabled and capabilities [ MULTI_ROLE, RESERVATION_REFINEMENT ] 3: I1213 02:48:41.301092 20109 master.cpp:9153] Adding framework 5a9d1021-8d9f-4203-aa9a-f09c07cc4fe3-0000 (default) at [email protected]:42345 with roles { } suppressed 3: I1213 02:48:41.301704 20124 sched.cpp:751] Framework registered with 5a9d1021-8d9f-4203-aa9a-f09c07cc4fe3-0000 3: I1213 02:48:41.301757 20124 sched.cpp:765] Scheduler::registered took 19304ns 3: I1213 02:48:41.301848 20113 hierarchical.cpp:297] Added framework 5a9d1021-8d9f-4203-aa9a-f09c07cc4fe3-0000 3: I1213 02:48:41.303889 20113 hierarchical.cpp:1517] Performed allocation for 1 agents in 1.839657ms 3: I1213 02:48:41.304661 20118 master.cpp:8762] Sending 1 offers to framework 5a9d1021-8d9f-4203-aa9a-f09c07cc4fe3-0000 (default) at [email protected]:42345 3: I1213 02:48:41.305418 20126 sched.cpp:921] Scheduler::resourceOffers took 119375ns 3: I1213 02:48:41.307904 20116 process.cpp:3503] Handling HTTP event for process 'master' with path: '/master/destroy-volumes' 3: I1213 02:48:41.309964 20104 sched.cpp:2009] Asked to stop the driver 3: I1213 02:48:41.310099 20113 sched.cpp:1191] Stopping framework 5a9d1021-8d9f-4203-aa9a-f09c07cc4fe3-0000 3: I1213 02:48:41.310365 20118 master.cpp:9442] Processing TEARDOWN call for framework 5a9d1021-8d9f-4203-aa9a-f09c07cc4fe3-0000 (default) at [email protected]:42345 3: I1213 02:48:41.310394 20118 master.cpp:9454] Removing framework 5a9d1021-8d9f-4203-aa9a-f09c07cc4fe3-0000 (default) at [email protected]:42345 3: I1213 02:48:41.310403 20118 master.cpp:3327] Deactivating framework 5a9d1021-8d9f-4203-aa9a-f09c07cc4fe3-0000 (default) at [email protected]:42345 3: I1213 02:48:41.310535 20120 hierarchical.cpp:405] Deactivated framework 5a9d1021-8d9f-4203-aa9a-f09c07cc4fe3-0000 3: I1213 02:48:41.311179 20118 master.cpp:10683] Removing offer 5a9d1021-8d9f-4203-aa9a-f09c07cc4fe3-O0 3: I1213 02:48:41.311203 20104 slave.cpp:909] Agent terminating 3: I1213 02:48:41.311817 20118 master.cpp:1305] Agent 5a9d1021-8d9f-4203-aa9a-f09c07cc4fe3-S0 at slave(505)@172.17.0.2:42345 (d19c336a231a) disconnected 3: I1213 02:48:41.311841 20118 master.cpp:3364] Disconnecting agent 5a9d1021-8d9f-4203-aa9a-f09c07cc4fe3-S0 at slave(505)@172.17.0.2:42345 (d19c336a231a) 3: I1213 02:48:41.311882 20118 master.cpp:3383] Deactivating agent 5a9d1021-8d9f-4203-aa9a-f09c07cc4fe3-S0 at slave(505)@172.17.0.2:42345 (d19c336a231a) 3: I1213 02:48:41.312844 20127 hierarchical.cpp:1192] Recovered cpus(allocated: role1):1; mem(allocated: role1):512; disk(allocated: role1)(reservations: [(STATIC,role1)]):960; ports(allocated: role1):[31000-32000]; disk(allocated: role1)(reservations: [(STATIC,role1)])[id1:path1]:64 (total: cpus:1; mem:512; disk(reservations: [(STATIC,role1)]):960; ports:[31000-32000]; disk(reservations: [(STATIC,role1)])[id1:path1]:64, allocated: {}) on agent 5a9d1021-8d9f-4203-aa9a-f09c07cc4fe3-S0 from framework 5a9d1021-8d9f-4203-aa9a-f09c07cc4fe3-0000 3: I1213 02:48:41.313392 20127 hierarchical.cpp:344] Removed framework 5a9d1021-8d9f-4203-aa9a-f09c07cc4fe3-0000 3: I1213 02:48:41.313470 20127 hierarchical.cpp:766] Agent 5a9d1021-8d9f-4203-aa9a-f09c07cc4fe3-S0 deactivated 3: I1213 02:48:41.321099 20104 master.cpp:1147] Master terminating 3: I1213 02:48:41.322085 20120 hierarchical.cpp:609] Removed agent 5a9d1021-8d9f-4203-aa9a-f09c07cc4fe3-S0 3: [ OK ] PersistentVolumeEndpointsTest.GoodCreateAndDestroyACLBadCredential (91 ms) 3: [ RUN ] PersistentVolumeEndpointsTest.NoAuthentication 3: I1213 02:48:41.331712 20104 cluster.cpp:172] Creating default 'local' authorizer 3: I1213 02:48:41.337123 20116 master.cpp:456] Master 5e64379e-6505-412f-b0f9-fbf25569802e (d19c336a231a) started on 172.17.0.2:42345 3: I1213 02:48:41.337170 20116 master.cpp:458] Flags at startup: --acls="" --agent_ping_timeout="15secs" --agent_reregister_timeout="10mins" --allocation_interval="1000secs" --allocator="HierarchicalDRF" --authenticate_agents="true" --authenticate_frameworks="true" --authenticate_http_frameworks="true" --authenticate_http_readonly="true" --authenticate_http_readwrite="false" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/8V0M65/credentials" --filter_gpu_resources="true" --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" --roles="role1" --root_submissions="true" --user_sorter="drf" --version="false" --webui_dir="/usr/local/share/mesos/webui" --work_dir="/tmp/8V0M65/master" --zk_session_timeout="10secs" 3: I1213 02:48:41.337611 20116 master.cpp:507] Master only allowing authenticated frameworks to register 3: I1213 02:48:41.337625 20116 master.cpp:513] Master only allowing authenticated agents to register 3: I1213 02:48:41.337633 20116 master.cpp:519] Master only allowing authenticated HTTP frameworks to register 3: I1213 02:48:41.337641 20116 credentials.hpp:37] Loading credentials for authentication from '/tmp/8V0M65/credentials' 3: I1213 02:48:41.338055 20116 master.cpp:563] Using default 'crammd5' authenticator 3: I1213 02:48:41.338274 20116 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly' 3: I1213 02:48:41.338512 20116 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler' 3: I1213 02:48:41.338703 20116 master.cpp:642] Authorization enabled 3: W1213 02:48:41.338718 20116 master.cpp:705] The '--roles' flag is deprecated. This flag will be removed in the future. See the Mesos 0.27 upgrade notes for more information 3: I1213 02:48:41.338976 20106 whitelist_watcher.cpp:77] No whitelist given 3: I1213 02:48:41.338991 20113 hierarchical.cpp:175] Initialized hierarchical allocator process 3: I1213 02:48:41.343394 20123 master.cpp:2209] Elected as the leading master! 3: I1213 02:48:41.343423 20123 master.cpp:1689] Recovering from registrar 3: I1213 02:48:41.343606 20121 registrar.cpp:347] Recovering registrar 3: I1213 02:48:41.344245 20121 registrar.cpp:391] Successfully fetched the registry (0B) in 596992ns 3: I1213 02:48:41.344343 20121 registrar.cpp:495] Applied 1 operations in 27571ns; attempting to update the registry 3: I1213 02:48:41.344864 20121 registrar.cpp:552] Successfully updated the registry in 476160ns 3: I1213 02:48:41.344981 20121 registrar.cpp:424] Successfully recovered registrar 3: I1213 02:48:41.345448 20126 master.cpp:1802] Recovered 0 agents from the registry (135B); allowing 10mins for agents to re-register 3: I1213 02:48:41.345551 20118 hierarchical.cpp:213] Skipping recovery of hierarchical allocator: nothing to recover 3: W1213 02:48:41.350877 20104 process.cpp:2756] Attempted to spawn already running process [email protected]:42345 3: I1213 02:48:41.352074 20104 containerizer.cpp:304] Using isolation { environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni } 3: W1213 02:48:41.353000 20104 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges 3: W1213 02:48:41.353229 20104 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges 3: I1213 02:48:41.353279 20104 provisioner.cpp:299] Using default backend 'copy' 3: I1213 02:48:41.356456 20104 cluster.cpp:460] Creating default 'local' authorizer 3: I1213 02:48:41.359903 20118 slave.cpp:258] Mesos agent started on (506)@172.17.0.2:42345 3: I1213 02:48:41.359930 20118 slave.cpp:259] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://"; --appc_store_dir="/tmp/PersistentVolumeEndpointsTest_NoAuthentication_KrTpWF/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/PersistentVolumeEndpointsTest_NoAuthentication_KrTpWF/credential" --default_role="*" --disallow_sharing_agent_pid_namespace="false" --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/PersistentVolumeEndpointsTest_NoAuthentication_KrTpWF/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/PersistentVolumeEndpointsTest_NoAuthentication_KrTpWF/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/PersistentVolumeEndpointsTest_NoAuthentication_KrTpWF/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --jwt_secret_key="/tmp/PersistentVolumeEndpointsTest_NoAuthentication_KrTpWF/jwt_secret_key" --launcher="posix" --launcher_dir="/mesos/build/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --port="5051" --qos_correction_interval_min="0ns" --quiet="false" --reconfiguration_policy="equal" --recover="reconnect" --recovery_timeout="15mins" --registration_backoff_factor="10ms" --resources="cpus:1;mem:512;disk(role1):1024" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/PersistentVolumeEndpointsTest_NoAuthentication_KrTpWF" --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/PersistentVolumeEndpointsTest_NoAuthentication_wRPA1S" --zk_session_timeout="10secs" 3: I1213 02:48:41.360671 20118 credentials.hpp:86] Loading credential for authentication from '/tmp/PersistentVolumeEndpointsTest_NoAuthentication_KrTpWF/credential' 3: I1213 02:48:41.360829 20118 slave.cpp:291] Agent using credential for: test-principal 3: I1213 02:48:41.360852 20118 credentials.hpp:37] Loading credentials for authentication from '/tmp/PersistentVolumeEndpointsTest_NoAuthentication_KrTpWF/http_credentials' 3: I1213 02:48:41.361135 20118 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-executor' 3: I1213 02:48:41.361258 20118 http.cpp:1066] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-executor' 3: I1213 02:48:41.361452 20118 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly' 3: I1213 02:48:41.361549 20118 http.cpp:1066] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readonly' 3: I1213 02:48:41.361734 20118 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite' 3: I1213 02:48:41.361834 20118 http.cpp:1066] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readwrite' write /dev/stdout: resource temporarily unavailable Process leaked file descriptors. See https://jenkins.io/redirect/troubleshooting/process-leaked-file-descriptors for more information Build step 'Execute shell' marked build as failure Not sending mail to unregistered user [email protected] Not sending mail to unregistered user [email protected] Not sending mail to unregistered user [email protected]
