See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=gcc,CONFIGURATION=--verbose%20--disable-libtool-wrappers,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4578/display/redirect?page=changes>
Changes: [yujie.jay] Refactored agent to keep track of local resource providers. [yujie.jay] Passed the message directly to the Master::registerSlave handler. [yujie.jay] Sent resource version uuid only for agent default resources. ------------------------------------------ [...truncated 25.06 MB...] 3: I1211 23:46:01.077560 16057 master.cpp:8718] Sending 1 offers to framework 55d4bfae-f827-4a90-87e2-6067216d2059-0000 (default) at [email protected]:46521 3: I1211 23:46:01.078084 16065 sched.cpp:921] Scheduler::resourceOffers took 118477ns 3: I1211 23:46:01.080356 16066 process.cpp:3503] Handling HTTP event for process 'master' with path: '/master/api/v1' 3: I1211 23:46:01.081712 16054 http.cpp:1185] HTTP POST for /master/api/v1 from 172.17.0.7:58646 3: I1211 23:46:01.081979 16054 http.cpp:704] Processing call UNRESERVE_RESOURCES 3: I1211 23:46:01.082296 16054 master.cpp:3719] Authorizing principal 'test-principal' to unreserve resources '[{"name":"cpus","reservations":[{"principal":"test-principal","role":"role","type":"DYNAMIC"}],"scalar":{"value":1.0},"type":"SCALAR"},{"name":"mem","reservations":[{"principal":"test-principal","role":"role","type":"DYNAMIC"}],"scalar":{"value":512.0},"type":"SCALAR"}]' 3: I1211 23:46:01.083940 16074 master.cpp:10638] Removing offer 55d4bfae-f827-4a90-87e2-6067216d2059-O0 3: I1211 23:46:01.084045 16059 sched.cpp:947] Rescinded offer 55d4bfae-f827-4a90-87e2-6067216d2059-O0 3: I1211 23:46:01.084105 16059 sched.cpp:958] Scheduler::offerRescinded took 20269ns 3: I1211 23:46:01.084808 16052 hierarchical.cpp:1188] Recovered cpus(allocated: role):1; mem(allocated: role):512; disk(allocated: role):1024; ports(allocated: role):[31000-32000]; cpus(allocated: role)(reservations: [(DYNAMIC,role,test-principal)]):1; mem(allocated: role)(reservations: [(DYNAMIC,role,test-principal)]):512 (total: cpus:1; mem:512; disk:1024; ports:[31000-32000]; cpus(reservations: [(DYNAMIC,role,test-principal)]):1; mem(reservations: [(DYNAMIC,role,test-principal)]):512, allocated: {}) on agent 55d4bfae-f827-4a90-87e2-6067216d2059-S0 from framework 55d4bfae-f827-4a90-87e2-6067216d2059-0000 3: I1211 23:46:01.084895 16052 hierarchical.cpp:1234] Framework 55d4bfae-f827-4a90-87e2-6067216d2059-0000 filtered agent 55d4bfae-f827-4a90-87e2-6067216d2059-S0 for 5secs 3: I1211 23:46:01.087348 16072 master.cpp:10583] Sending updated checkpointed resources {} to agent 55d4bfae-f827-4a90-87e2-6067216d2059-S0 at slave(691)@172.17.0.7:46521 (cef124683596) 3: I1211 23:46:01.087927 16069 slave.cpp:3699] Updated checkpointed resources from cpus(reservations: [(DYNAMIC,role,test-principal)]):1; mem(reservations: [(DYNAMIC,role,test-principal)]):512 to {} 3: I1211 23:46:01.088479 16053 master.cpp:5588] Processing REVIVE call for framework 55d4bfae-f827-4a90-87e2-6067216d2059-0000 (default) at [email protected]:46521 3: I1211 23:46:01.088644 16053 hierarchical.cpp:1335] Revived offers for roles { role } of framework 55d4bfae-f827-4a90-87e2-6067216d2059-0000 3: I1211 23:46:01.089861 16053 hierarchical.cpp:1513] Performed allocation for 1 agents in 1.098461ms 3: I1211 23:46:01.090234 16063 master.cpp:8718] Sending 1 offers to framework 55d4bfae-f827-4a90-87e2-6067216d2059-0000 (default) at [email protected]:46521 3: I1211 23:46:01.090688 16055 sched.cpp:921] Scheduler::resourceOffers took 96540ns 3: I1211 23:46:01.091038 16050 sched.cpp:2009] Asked to stop the driver 3: I1211 23:46:01.091132 16051 sched.cpp:1191] Stopping framework 55d4bfae-f827-4a90-87e2-6067216d2059-0000 3: I1211 23:46:01.091342 16059 master.cpp:9398] Processing TEARDOWN call for framework 55d4bfae-f827-4a90-87e2-6067216d2059-0000 (default) at [email protected]:46521 3: I1211 23:46:01.091372 16059 master.cpp:9410] Removing framework 55d4bfae-f827-4a90-87e2-6067216d2059-0000 (default) at [email protected]:46521 3: I1211 23:46:01.091393 16059 master.cpp:3327] Deactivating framework 55d4bfae-f827-4a90-87e2-6067216d2059-0000 (default) at [email protected]:46521 3: I1211 23:46:01.091534 16072 hierarchical.cpp:405] Deactivated framework 55d4bfae-f827-4a90-87e2-6067216d2059-0000 3: I1211 23:46:01.091909 16061 slave.cpp:909] Agent terminating 3: I1211 23:46:01.091938 16059 master.cpp:10638] Removing offer 55d4bfae-f827-4a90-87e2-6067216d2059-O1 3: I1211 23:46:01.092438 16059 master.cpp:1305] Agent 55d4bfae-f827-4a90-87e2-6067216d2059-S0 at slave(691)@172.17.0.7:46521 (cef124683596) disconnected 3: I1211 23:46:01.092465 16059 master.cpp:3364] Disconnecting agent 55d4bfae-f827-4a90-87e2-6067216d2059-S0 at slave(691)@172.17.0.7:46521 (cef124683596) 3: I1211 23:46:01.092517 16059 master.cpp:3383] Deactivating agent 55d4bfae-f827-4a90-87e2-6067216d2059-S0 at slave(691)@172.17.0.7:46521 (cef124683596) 3: I1211 23:46:01.092730 16069 hierarchical.cpp:1188] Recovered cpus(allocated: role):2; mem(allocated: role):1024; disk(allocated: role):1024; ports(allocated: role):[31000-32000] (total: cpus:2; mem:1024; disk:1024; ports:[31000-32000], allocated: {}) on agent 55d4bfae-f827-4a90-87e2-6067216d2059-S0 from framework 55d4bfae-f827-4a90-87e2-6067216d2059-0000 3: I1211 23:46:01.092983 16069 hierarchical.cpp:344] Removed framework 55d4bfae-f827-4a90-87e2-6067216d2059-0000 3: I1211 23:46:01.093034 16069 hierarchical.cpp:762] Agent 55d4bfae-f827-4a90-87e2-6067216d2059-S0 deactivated 3: I1211 23:46:01.098631 16050 master.cpp:1147] Master terminating 3: I1211 23:46:01.099555 16054 hierarchical.cpp:605] Removed agent 55d4bfae-f827-4a90-87e2-6067216d2059-S0 3: [ OK ] ContentType/MasterAPITest.UnreserveResources/0 (87 ms) 3: [ RUN ] ContentType/MasterAPITest.UnreserveResources/1 3: I1211 23:46:01.106006 16050 cluster.cpp:172] Creating default 'local' authorizer 3: I1211 23:46:01.108830 16059 master.cpp:456] Master dba736b4-7d47-4b68-a4e2-7bef3756a7fd (cef124683596) started on 172.17.0.7:46521 3: I1211 23:46:01.108850 16059 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="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/Mwkguv/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" --root_submissions="true" --user_sorter="drf" --version="false" --webui_dir="/usr/local/share/mesos/webui" --work_dir="/tmp/Mwkguv/master" --zk_session_timeout="10secs" 3: I1211 23:46:01.109212 16059 master.cpp:507] Master only allowing authenticated frameworks to register 3: I1211 23:46:01.109225 16059 master.cpp:513] Master only allowing authenticated agents to register 3: I1211 23:46:01.109232 16059 master.cpp:519] Master only allowing authenticated HTTP frameworks to register 3: I1211 23:46:01.109241 16059 credentials.hpp:37] Loading credentials for authentication from '/tmp/Mwkguv/credentials' 3: I1211 23:46:01.109530 16059 master.cpp:563] Using default 'crammd5' authenticator 3: I1211 23:46:01.109683 16059 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly' 3: I1211 23:46:01.109835 16059 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite' 3: I1211 23:46:01.109968 16059 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler' 3: I1211 23:46:01.110102 16059 master.cpp:642] Authorization enabled 3: I1211 23:46:01.110311 16056 hierarchical.cpp:175] Initialized hierarchical allocator process 3: I1211 23:46:01.110338 16074 whitelist_watcher.cpp:77] No whitelist given 3: I1211 23:46:01.113034 16067 master.cpp:2209] Elected as the leading master! 3: I1211 23:46:01.113057 16067 master.cpp:1689] Recovering from registrar 3: I1211 23:46:01.113226 16061 registrar.cpp:347] Recovering registrar 3: I1211 23:46:01.113875 16061 registrar.cpp:391] Successfully fetched the registry (0B) in 611072ns 3: I1211 23:46:01.113976 16061 registrar.cpp:495] Applied 1 operations in 29156ns; attempting to update the registry 3: I1211 23:46:01.114502 16061 registrar.cpp:552] Successfully updated the registry in 482816ns 3: I1211 23:46:01.114619 16061 registrar.cpp:424] Successfully recovered registrar 3: I1211 23:46:01.115069 16058 hierarchical.cpp:213] Skipping recovery of hierarchical allocator: nothing to recover 3: I1211 23:46:01.115061 16065 master.cpp:1802] Recovered 0 agents from the registry (135B); allowing 10mins for agents to re-register 3: W1211 23:46:01.119374 16050 process.cpp:2756] Attempted to spawn already running process [email protected]:46521 3: I1211 23:46:01.120255 16050 containerizer.cpp:304] Using isolation { environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni } 3: W1211 23:46:01.120776 16050 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges 3: W1211 23:46:01.120885 16050 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges 3: I1211 23:46:01.120914 16050 provisioner.cpp:299] Using default backend 'copy' 3: I1211 23:46:01.122726 16050 cluster.cpp:460] Creating default 'local' authorizer 3: I1211 23:46:01.124817 16071 slave.cpp:258] Mesos agent started on (692)@172.17.0.7:46521 3: I1211 23:46:01.124836 16071 slave.cpp:259] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://"; --appc_store_dir="/tmp/ContentType_MasterAPITest_UnreserveResources_1_WR0AY0/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/ContentType_MasterAPITest_UnreserveResources_1_WR0AY0/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/ContentType_MasterAPITest_UnreserveResources_1_WR0AY0/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/ContentType_MasterAPITest_UnreserveResources_1_WR0AY0/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/ContentType_MasterAPITest_UnreserveResources_1_WR0AY0/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/build/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --port="5051" --qos_correction_interval_min="0ns" --quiet="false" --reconfiguration_policy="equal" --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/ContentType_MasterAPITest_UnreserveResources_1_WR0AY0" --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/ContentType_MasterAPITest_UnreserveResources_1_Ab6peL" --zk_session_timeout="10secs" 3: I1211 23:46:01.125355 16071 credentials.hpp:86] Loading credential for authentication from '/tmp/ContentType_MasterAPITest_UnreserveResources_1_WR0AY0/credential' 3: I1211 23:46:01.125519 16071 slave.cpp:291] Agent using credential for: test-principal 3: I1211 23:46:01.125540 16071 credentials.hpp:37] Loading credentials for authentication from '/tmp/ContentType_MasterAPITest_UnreserveResources_1_WR0AY0/http_credentials' 3: I1211 23:46:01.125802 16071 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly' 3: I1211 23:46:01.125968 16071 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite' 3: I1211 23:46:01.127426 16071 slave.cpp:590] Agent resources: [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}] 3: I1211 23:46:01.127651 16071 slave.cpp:598] Agent attributes: [ ] 3: I1211 23:46:01.127662 16071 slave.cpp:607] Agent hostname: cef124683596 3: I1211 23:46:01.127804 16055 task_status_update_manager.cpp:181] Pausing sending task status updates 3: I1211 23:46:01.129313 16054 state.cpp:66] Recovering state from '/tmp/ContentType_MasterAPITest_UnreserveResources_1_Ab6peL/meta' 3: I1211 23:46:01.129602 16054 task_status_update_manager.cpp:207] Recovering task status update manager 3: I1211 23:46:01.129806 16061 containerizer.cpp:674] Recovering containerizer 3: I1211 23:46:01.131451 16051 provisioner.cpp:495] Provisioner recovery complete 3: I1211 23:46:01.131821 16055 slave.cpp:6727] Finished recovery 3: I1211 23:46:01.132517 16071 task_status_update_manager.cpp:181] Pausing sending task status updates 3: I1211 23:46:01.132536 16072 slave.cpp:1033] New master detected at [email protected]:46521 3: I1211 23:46:01.132623 16072 slave.cpp:1088] Detecting new master 3: I1211 23:46:01.142976 16068 slave.cpp:1115] Authenticating with master [email protected]:46521 3: I1211 23:46:01.143049 16068 slave.cpp:1124] Using default CRAM-MD5 authenticatee 3: I1211 23:46:01.143311 16057 authenticatee.cpp:121] Creating new client SASL connection 3: I1211 23:46:01.143620 16054 master.cpp:8888] Authenticating slave(692)@172.17.0.7:46521 3: I1211 23:46:01.143788 16064 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1307)@172.17.0.7:46521 3: I1211 23:46:01.144129 16074 authenticator.cpp:98] Creating new server SASL connection 3: I1211 23:46:01.144376 16060 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5 3: I1211 23:46:01.144403 16060 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5' 3: I1211 23:46:01.144515 16060 authenticator.cpp:204] Received SASL authentication start 3: I1211 23:46:01.144573 16060 authenticator.cpp:326] Authentication requires more steps 3: I1211 23:46:01.144687 16066 authenticatee.cpp:259] Received SASL authentication step 3: I1211 23:46:01.144811 16053 authenticator.cpp:232] Received SASL authentication step 3: I1211 23:46:01.144841 16053 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'cef124683596' server FQDN: 'cef124683596' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 3: I1211 23:46:01.144850 16053 auxprop.cpp:181] Looking up auxiliary property '*userPassword' 3: I1211 23:46:01.144879 16053 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' 3: I1211 23:46:01.144896 16053 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'cef124683596' server FQDN: 'cef124683596' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 3: I1211 23:46:01.144906 16053 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true 3: I1211 23:46:01.144912 16053 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true 3: I1211 23:46:01.144924 16053 authenticator.cpp:318] Authentication success 3: I1211 23:46:01.144994 16058 authenticatee.cpp:299] Authentication success 3: I1211 23:46:01.145081 16061 master.cpp:8918] Successfully authenticated principal 'test-principal' at slave(692)@172.17.0.7:46521 3: I1211 23:46:01.145143 16056 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1307)@172.17.0.7:46521 3: I1211 23:46:01.145303 16062 slave.cpp:1207] Successfully authenticated with master [email protected]:46521 3: I1211 23:46:01.145480 16062 slave.cpp:1684] Will retry registration in 19.782942ms if necessary 3: I1211 23:46:01.145651 16067 master.cpp:6077] Received register agent message from slave(692)@172.17.0.7:46521 (cef124683596) 3: I1211 23:46:01.145720 16067 master.cpp:3866] Authorizing agent with principal 'test-principal' 3: I1211 23:46:01.146107 16052 master.cpp:6147] Authorized registration of agent at slave(692)@172.17.0.7:46521 (cef124683596) 3: I1211 23:46:01.146184 16052 master.cpp:6240] Registering agent at slave(692)@172.17.0.7:46521 (cef124683596) with id dba736b4-7d47-4b68-a4e2-7bef3756a7fd-S0 3: I1211 23:46:01.146788 16069 registrar.cpp:495] Applied 1 operations in 140887ns; attempting to update the registry 3: I1211 23:46:01.147436 16057 registrar.cpp:552] Successfully updated the registry in 581120ns 3: I1211 23:46:01.147574 16054 master.cpp:6288] Admitted agent dba736b4-7d47-4b68-a4e2-7bef3756a7fd-S0 at slave(692)@172.17.0.7:46521 (cef124683596) 3: I1211 23:46:01.148260 16054 master.cpp:6337] Registered agent dba736b4-7d47-4b68-a4e2-7bef3756a7fd-S0 at slave(692)@172.17.0.7:46521 (cef124683596) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] 3: I1211 23:46:01.148313 16066 slave.cpp:1253] Registered with master [email protected]:46521; given agent ID dba736b4-7d47-4b68-a4e2-7bef3756a7fd-S0 3: I1211 23:46:01.148404 16061 task_status_update_manager.cpp:188] Resuming sending task status updates 3: I1211 23:46:01.148658 16065 hierarchical.cpp:572] Added agent dba736b4-7d47-4b68-a4e2-7bef3756a7fd-S0 (cef124683596) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] (allocated: {}) 3: I1211 23:46:01.148684 16066 slave.cpp:1273] Checkpointing SlaveInfo to '/tmp/ContentType_MasterAPITest_UnreserveResources_1_Ab6peL/meta/slaves/dba736b4-7d47-4b68-a4e2-7bef3756a7fd-S0/slave.info' 3: I1211 23:46:01.148995 16065 hierarchical.cpp:1513] Performed allocation for 1 agents in 154016ns 3: I1211 23:46:01.149255 16066 slave.cpp:1330] Forwarding agent update {"offer_operations":{},"resource_categories":{"oversubscribed":true},"resource_version_uuid":"thxlWheiTOOOyKRomaTT9Q==","slave_id":{"value":"dba736b4-7d47-4b68-a4e2-7bef3756a7fd-S0"}} 3: I1211 23:46:01.149440 16070 master.cpp:7238] Received update of agent dba736b4-7d47-4b68-a4e2-7bef3756a7fd-S0 at slave(692)@172.17.0.7:46521 (cef124683596) with total oversubscribed resources {} 3: I1211 23:46:01.149636 16070 master.cpp:7336] Ignoring update on agent dba736b4-7d47-4b68-a4e2-7bef3756a7fd-S0 at slave(692)@172.17.0.7:46521 (cef124683596) as it reports no changes 3: I1211 23:46:01.151079 16055 process.cpp:3503] Handling HTTP event for process 'master' with path: '/master/api/v1' 3: I1211 23:46:01.152635 16057 http.cpp:1185] HTTP POST for /master/api/v1 from 172.17.0.7:58648 3: I1211 23:46:01.153239 16057 http.cpp:704] Processing call RESERVE_RESOURCES 3: I1211 23:46:01.153506 16057 master.cpp:3651] Authorizing principal 'test-principal' to reserve resources '[{"name":"cpus","reservations":[{"principal":"test-principal","role":"role","type":"DYNAMIC"}],"scalar":{"value":1.0},"type":"SCALAR"},{"name":"mem","reservations":[{"principal":"test-principal","role":"role","type":"DYNAMIC"}],"scalar":{"value":512.0},"type":"SCALAR"}]' 3: I1211 23:46:01.156879 16054 master.cpp:10583] Sending updated checkpointed resources cpus(reservations: [(DYNAMIC,role,test-principal)]):1; mem(reservations: [(DYNAMIC,role,test-principal)]):512 to agent dba736b4-7d47-4b68-a4e2-7bef3756a7fd-S0 at slave(692)@172.17.0.7:46521 (cef124683596) 3: I1211 23:46:01.157778 16063 slave.cpp:3699] Updated checkpointed resources from {} to cpus(reservations: [(DYNAMIC,role,test-principal)]):1; mem(reservations: [(DYNAMIC,role,test-principal)]):512 3: W1211 23:46:01.157843 16050 process.cpp:2756] Attempted to spawn already running process [email protected]:46521 3: I1211 23:46:01.158759 16050 sched.cpp:232] Version: 1.5.0 3: I1211 23:46:01.159420 16068 sched.cpp:336] New master detected at [email protected]:46521 3: I1211 23:46:01.159514 16068 sched.cpp:396] Authenticating with master [email protected]:46521 3: I1211 23:46:01.159529 16068 sched.cpp:403] Using default CRAM-MD5 authenticatee 3: I1211 23:46:01.159755 16071 authenticatee.cpp:121] Creating new client SASL connection 3: I1211 23:46:01.159981 16068 master.cpp:8888] Authenticating [email protected]:46521 3: I1211 23:46:01.160090 16074 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1308)@172.17.0.7:46521 3: I1211 23:46:01.160325 16060 authenticator.cpp:98] Creating new server SASL connection 3: I1211 23:46:01.160523 16058 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5 3: I1211 23:46:01.160544 16058 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5' 3: I1211 23:46:01.160650 16053 authenticator.cpp:204] Received SASL authentication start 3: I1211 23:46:01.160697 16053 authenticator.cpp:326] Authentication requires more steps 3: I1211 23:46:01.160797 16066 authenticatee.cpp:259] Received SASL authentication step 3: I1211 23:46:01.160933 16065 authenticator.cpp:232] Received SASL authentication step 3: I1211 23:46:01.160972 16065 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'cef124683596' server FQDN: 'cef124683596' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 3: I1211 23:46:01.161002 16065 auxprop.cpp:181] Looking up auxiliary property '*userPassword' 3: I1211 23:46:01.161036 16065 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' 3: I1211 23:46:01.161063 16065 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'cef124683596' server FQDN: 'cef124683596' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 3: I1211 23:46:01.161077 16065 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true 3: I1211 23:46:01.161085 16065 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true 3: I1211 23:46:01.161103 16065 authenticator.cpp:318] Authentication success 3: I1211 23:46:01.161201 16054 authenticatee.cpp:299] Authentication success 3: I1211 23:46:01.161304 16070 master.cpp:8918] Successfully authenticated principal 'test-principal' at [email protected]:46521 3: I1211 23:46:01.161352 16054 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1308)@172.17.0.7:46521 3: I1211 23:46:01.161466 16059 sched.cpp:502] Successfully authenticated with master [email protected]:46521 3: I1211 23:46:01.161490 16059 sched.cpp:824] Sending SUBSCRIBE call to [email protected]:46521 3: I1211 23:46:01.161605 16059 sched.cpp:857] Will retry registration in 1.576098554secs if necessary 3: I1211 23:46:01.161772 16052 master.cpp:2957] Received SUBSCRIBE call for framework 'default' at [email protected]:46521 3: I1211 23:46:01.161844 16052 master.cpp:2274] Authorizing framework principal 'test-principal' to receive offers for roles '{ role }' 3: I1211 23:46:01.162267 16056 master.cpp:3037] Subscribing framework default with checkpointing disabled and capabilities [ MULTI_ROLE, RESERVATION_REFINEMENT ] 3: I1211 23:46:01.162446 16056 master.cpp:9109] Adding framework dba736b4-7d47-4b68-a4e2-7bef3756a7fd-0000 (default) at [email protected]:46521 with roles { } suppressed 3: I1211 23:46:01.162870 16055 sched.cpp:751] Framework registered with dba736b4-7d47-4b68-a4e2-7bef3756a7fd-0000 3: I1211 23:46:01.162910 16055 sched.cpp:765] Scheduler::registered took 19974ns 3: I1211 23:46:01.162994 16069 hierarchical.cpp:297] Added framework dba736b4-7d47-4b68-a4e2-7bef3756a7fd-0000 3: I1211 23:46:01.166204 16069 hierarchical.cpp:1513] Performed allocation for 1 agents in 3.073457ms 3: I1211 23:46:01.166898 16071 master.cpp:8718] Sending 1 offers to framework dba736b4-7d47-4b68-a4e2-7bef3756a7fd-0000 (default) at [email protected]:46521 3: I1211 23:46:01.167465 16068 sched.cpp:921] Scheduler::resourceOffers took 117776ns 3: I1211 23:46:01.170413 16070 process.cpp:3503] Handling HTTP event for process 'master' with path: '/master/api/v1' 3: I1211 23:46:01.171813 16052 http.cpp:1185] HTTP POST for /master/api/v1 from 172.17.0.7:58650 3: I1211 23:46:01.172708 16052 http.cpp:704] Processing call UNRESERVE_RESOURCES 3: I1211 23:46:01.172976 16052 master.cpp:3719] Authorizing principal 'test-principal' to unreserve resources '[{"name":"cpus","reservations":[{"principal":"test-principal","role":"role","type":"DYNAMIC"}],"scalar":{"value":1.0},"type":"SCALAR"},{"name":"mem","reservations":[{"principal":"test-principal","role":"role","type":"DYNAMIC"}],"scalar":{"value":512.0},"type":"SCALAR"}]' 3: I1211 23:46:01.174654 16073 master.cpp:10638] Removing offer dba736b4-7d47-4b68-a4e2-7bef3756a7fd-O0 3: I1211 23:46:01.174777 16057 sched.cpp:947] Rescinded offer dba736b4-7d47-4b68-a4e2-7bef3756a7fd-O0 3: I1211 23:46:01.174837 16057 sched.cpp:958] Scheduler::offerRescinded took 19640ns 3: I1211 23:46:01.175580 16055 hierarchical.cpp:1188] Recovered cpus(allocated: role):1; mem(allocated: role):512; disk(allocated: role):1024; ports(allocated: role):[31000-32000]; cpus(allocated: role)(reservations: [(DYNAMIC,role,test-principal)]):1; mem(allocated: role)(reservations: [(DYNAMIC,role,test-principal)]):512 (total: cpus:1; mem:512; disk:1024; ports:[31000-32000]; cpus(reservations: [(DYNAMIC,role,test-principal)]):1; mem(reservations: [(DYNAMIC,role,test-principal)]):512, allocated: {}) on agent dba736b4-7d47-4b68-a4e2-7bef3756a7fd-S0 from framework dba736b4-7d47-4b68-a4e2-7bef3756a7fd-0000 3: I1211 23:46:01.175665 16055 hierarchical.cpp:1234] Framework dba736b4-7d47-4b68-a4e2-7bef3756a7fd-0000 filtered agent dba736b4-7d47-4b68-a4e2-7bef3756a7fd-S0 for 5secs 3: I1211 23:46:01.178181 16069 master.cpp:10583] Sending updated checkpointed resources {} to agent dba736b4-7d47-4b68-a4e2-7bef3756a7fd-S0 at slave(692)@172.17.0.7:46521 (cef124683596) 3: I1211 23:46:01.178663 16060 slave.cpp:3699] Updated checkpointed resources from cpus(reservations: [(DYNAMIC,role,test-principal)]):1; mem(reservations: [(DYNAMIC,role,test-principal)]):512 to {} 3: I1211 23:46:01.179462 16063 master.cpp:5588] Processing REVIVE call for framework dba736b4-7d47-4b68-a4e2-7bef3756a7fd-0000 (default) at [email protected]:46521 3: I1211 23:46:01.179672 16063 hierarchical.cpp:1335] Revived offers for roles { role } of framework dba736b4-7d47-4b68-a4e2-7bef3756a7fd-0000 3: I1211 23:46:01.181007 16063 hierarchical.cpp:1513] Performed allocation for 1 agents in 1.187019ms 3: I1211 23:46:01.181413 16067 master.cpp:8718] Sending 1 offers to framework dba736b4-7d47-4b68-a4e2-7bef3756a7fd-0000 (default) at [email protected]:46521 3: I1211 23:46:01.181843 16059 sched.cpp:921] Scheduler::resourceOffers took 90815ns 3: I1211 23:46:01.182260 16050 sched.cpp:2009] Asked to stop the driver 3: I1211 23:46:01.182379 16057 sched.cpp:1191] Stopping framework dba736b4-7d47-4b68-a4e2-7bef3756a7fd-0000 3: I1211 23:46:01.182639 16055 master.cpp:9398] Processing TEARDOWN call for framework dba736b4-7d47-4b68-a4e2-7bef3756a7fd-0000 (default) at [email protected]:46521 3: I1211 23:46:01.182670 16055 master.cpp:9410] Removing framework dba736b4-7d47-4b68-a4e2-7bef3756a7fd-0000 (default) at [email protected]:46521 3: I1211 23:46:01.182682 16055 master.cpp:3327] Deactivating framework dba736b4-7d47-4b68-a4e2-7bef3756a7fd-0000 (default) at [email protected]:46521 3: I1211 23:46:01.182821 16069 hierarchical.cpp:405] Deactivated framework dba736b4-7d47-4b68-a4e2-7bef3756a7fd-0000 3: I1211 23:46:01.183188 16055 master.cpp:10638] Removing offer dba736b4-7d47-4b68-a4e2-7bef3756a7fd-O1 3: I1211 23:46:01.183254 16062 slave.cpp:909] Agent terminating 3: I1211 23:46:01.183672 16055 master.cpp:1305] Agent dba736b4-7d47-4b68-a4e2-7bef3756a7fd-S0 at slave(692)@172.17.0.7:46521 (cef124683596) disconnected 3: I1211 23:46:01.183693 16055 master.cpp:3364] Disconnecting agent dba736b4-7d47-4b68-a4e2-7bef3756a7fd-S0 at slave(692)@172.17.0.7:46521 (cef124683596) 3: I1211 23:46:01.183732 16055 master.cpp:3383] Deactivating agent dba736b4-7d47-4b68-a4e2-7bef3756a7fd-S0 at slave(692)@172.17.0.7:46521 (cef124683596) 3: I1211 23:46:01.184101 16060 hierarchical.cpp:1188] Recovered cpus(allocated: role):2; mem(allocated: role):1024; disk(allocated: role):1024; ports(allocated: role):[31000-32000] (total: cpus:2; mem:1024; disk:1024; ports:[31000-32000], allocated: {}) on agent dba736b4-7d47-4b68-a4e2-7bef3756a7fd-S0 from framework dba736b4-7d47-4b68-a4e2-7bef3756a7fd-0000 3: I1211 23:46:01.184391 16060 hierarchical.cpp:344] Removed framework dba736b4-7d47-4b68-a4e2-7bef3756a7fd-0000 3: I1211 23:46:01.184445 16060 hierarchical.cpp:762] Agent dba736b4-7d47-4b68-a4e2-7bef3756a7fd-S0 deactivated 3: I1211 23:46:01.189991 16050 master.cpp:1147] Master terminating 3: I1211 23:46:01.190577 16057 hierarchical.cpp:605] Removed agent dba736b4-7d47-4b68-a4e2-7bef3756a7fd-S0 3: [ OK ] ContentType/MasterAPITest.UnreserveResources/1 (90 ms) 3: [ RUN ] ContentType/MasterAPITest.UpdateAndGetMaintenanceSchedule/0 3: I1211 23:46:01.196820 16050 cluster.cpp:172] Creating default 'local' authorizer 3: I1211 23:46:01.199690 16066 master.cpp:456] Master c931d27b-f18d-4dc1-82fb-62766a728e5a (cef124683596) started on 172.17.0.7:46521 3: I1211 23:46:01.199715 16066 master.cpp:458] Flags at startup: --acls="update_maintenance_schedules { 3: principals { 3: values: "test-principal-2" 3: } 3: machines { 3: type: NONE 3: } 3: } 3: get_maintenance_schedules { 3: principals { 3: values: "test-principal-2" 3: } 3: machines { 3: type: NONE 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/krfQei/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" --root_submissions="true" --user_sorter="drf" --version="false" --webui_dir="/usr/local/share/mesos/webui" --work_dir="/tmp/krfQei/master" --zk_session_timeout="10secs" 3: I1211 23:46:01.200086 16066 master.cpp:507] Master only allowing authenticated frameworks to register 3: I1211 23:46:01.200094 16066 master.cpp:513] Master only allowing authenticated agents to register 3: I1211 23:46:01.200098 16066 master.cpp:519] Master only allowing authenticated HTTP frameworks to register 3: I1211 23:46:01.200103 16066 credentials.hpp:37] Loading credentials for authentication from '/tmp/krfQei/credentials' 3: I1211 23:46:01.200356 16066 master.cpp:563] Using default 'crammd5' authenticator 3: I1211 23:46:01.200491 16066 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly' 3: I1211 23:46:01.200631 16066 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite' 3: I1211 23:46:01.200749 16066 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler' 3: I1211 23:46:01.200862 16066 master.cpp:642] Authorization enabled 3: I1211 23:46:01.200994 16054 hierarchical.cpp:175] Initialized hierarchical allocator process 3: I1211 23:46:01.201038 16073 whitelist_watcher.cpp:77] No whitelist given 3: I1211 23:46:01.203757 16055 master.cpp:2209] Elected as the leading master! 3: I1211 23:46:01.203781 16055 master.cpp:1689] Recovering from registrar 3: I1211 23:46:01.203927 16069 registrar.cpp:347] Recovering registrar 3: I1211 23:46:01.204538 16069 registrar.cpp:391] Successfully fetched the registry (0B) in 581120ns 3: I1211 23:46:01.204648 16069 registrar.cpp:495] Applied 1 operations in 31402ns; attempting to update the registry 3: I1211 23:46:01.205199 16069 registrar.cpp:552] Successfully updated the registry in 504832ns 3: I1211 23:46:01.205323 16069 registrar.cpp:424] Successfully recovered registrar 3: I1211 23:46:01.205724 16065 master.cpp:1802] Recovered 0 agents from the registry (135B); allowing 10mins for agents to re-register 3: I1211 23:46:01.205741 16056 hierarchical.cpp:213] Skipping recovery of hierarchical allocator: nothing to recover 3: I1211 23:46:01.208281 16068 process.cpp:3503] Handling HTTP event for process 'master' with path: '/master/api/v1' 3: I1211 23:46:01.209780 16053 http.cpp:1185] HTTP POST for /master/api/v1 from 172.17.0.7:58652 3: I1211 23:46:01.209923 16053 http.cpp:704] Processing call UPDATE_MAINTENANCE_SCHEDULE 3: I1211 23:46:01.213335 16066 process.cpp:3503] Handling HTTP event for process 'master' with path: '/master/api/v1' + docker rmi mesos-1513033586-7062 Build step 'Execute shell' marked build as failure Not sending mail to unregistered user [email protected] Not sending mail to unregistered user [email protected]
