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=centos%3A7,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4443/display/redirect?page=changes>
Changes:
[yujie.jay] Used `Master::_apply` consistently for all offer operations.
[alexr] Added context to agent processing calls.
[yujie.jay] Made the maintainer doc up-to-date.
------------------------------------------
[...truncated 20.90 MB...]
3: I1110 20:50:42.119194 17482 sched.cpp:947] Rescinded offer
bf5375c1-13d4-4816-a37b-79f8dc10d3f8-O1
3: I1110 20:50:42.119282 17482 sched.cpp:958] Scheduler::offerRescinded took
56147ns
3: I1110 20:50:42.120065 17493 hierarchical.cpp:1125] Recovered
ports(allocated: role):[31000-32000]; mem(allocated: role):2048;
disk(allocated: role):1024; cpus(allocated: role)(reservations:
[(DYNAMIC,role,test-principal)]):8; mem(allocated: role)(reservations:
[(DYNAMIC,role,test-principal)]):2048; disk(allocated: role)(reservations:
[(DYNAMIC,role,test-principal)])[persistence_id:container_path]:1024 (total:
ports:[31000-32000]; mem:2048; disk:1024; cpus(reservations:
[(DYNAMIC,role,test-principal)]):8; mem(reservations:
[(DYNAMIC,role,test-principal)]):2048; disk(reservations:
[(DYNAMIC,role,test-principal)])[persistence_id:container_path]:1024,
allocated: {}) on agent bf5375c1-13d4-4816-a37b-79f8dc10d3f8-S0 from framework
bf5375c1-13d4-4816-a37b-79f8dc10d3f8-0000
3: I1110 20:50:42.120841 17487 hierarchical.cpp:1450] Performed allocation for
1 agents in 192368ns
3: W1110 20:50:42.121999 17481 process.cpp:3178] Attempted to spawn already
running process files@172.17.0.3:35939
3: I1110 20:50:42.122860 17494 slave.cpp:262] Mesos agent started on
@172.17.0.3:35939
3: I1110 20:50:42.122884 17494 slave.cpp:263] Flags at startup: --acls=""
--appc_simple_discovery_uri_prefix="http://";
--appc_store_dir="/tmp/ReservationTest_CompatibleCheckpointedResourcesWithPersistentVolumes_1Wmc8L/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/ReservationTest_CompatibleCheckpointedResourcesWithPersistentVolumes_1Wmc8L/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/ReservationTest_CompatibleCheckpointedResourcesWithPersistentVolumes_1Wmc8L/store/docker"
--docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume"
--enforce_container_disk_quota="false" --executor_registration_timeout="1mins"
--executor_reregistration_timeout="2secs"
--executor_secret_key="/tmp/ReservationTest_CompatibleCheckpointedResourcesWithPersistentVolumes_1Wmc8L/executor_secret_key"
--executor_shutdown_grace_period="5secs"
--fetcher_cache_dir="/tmp/ReservationTest_CompatibleCheckpointedResourcesWithPersistentVolumes_1Wmc8L/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/ReservationTest_CompatibleCheckpointedResourcesWithPersistentVolumes_1Wmc8L/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" --recover="reconnect" --recovery_timeout="15mins"
--registration_backoff_factor="10ms" --resources="cpus:12;mem:2048;disk:1024"
--revocable_cpu_low_priority="true"
--runtime_dir="/tmp/ReservationTest_CompatibleCheckpointedResourcesWithPersistentVolumes_1Wmc8L"
--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/ReservationTest_CompatibleCheckpointedResourcesWithPersistentVolumes_gEevh0"
--zk_session_timeout="10secs"
3: I1110 20:50:42.123327 17494 credentials.hpp:86] Loading credential for
authentication from
'/tmp/ReservationTest_CompatibleCheckpointedResourcesWithPersistentVolumes_1Wmc8L/credential'
3: I1110 20:50:42.123473 17494 slave.cpp:295] Agent using credential for:
test-principal
3: I1110 20:50:42.123489 17494 credentials.hpp:37] Loading credentials for
authentication from
'/tmp/ReservationTest_CompatibleCheckpointedResourcesWithPersistentVolumes_1Wmc8L/http_credentials'
3: I1110 20:50:42.123757 17494 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-agent-executor'
3: I1110 20:50:42.123867 17494 http.cpp:1066] Creating default 'jwt' HTTP
authenticator for realm 'mesos-agent-executor'
3: I1110 20:50:42.124110 17494 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-agent-readonly'
3: I1110 20:50:42.124208 17494 http.cpp:1066] Creating default 'jwt' HTTP
authenticator for realm 'mesos-agent-readonly'
3: I1110 20:50:42.124423 17494 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-agent-readwrite'
3: I1110 20:50:42.124528 17494 http.cpp:1066] Creating default 'jwt' HTTP
authenticator for realm 'mesos-agent-readwrite'
3: I1110 20:50:42.125609 17494 slave.cpp:593] Agent resources:
[{"name":"cpus","scalar":{"value":12.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":2048.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
3: I1110 20:50:42.125808 17494 slave.cpp:601] Agent attributes: [ ]
3: I1110 20:50:42.125820 17494 slave.cpp:610] Agent hostname: 40e8ef4cee13
3: I1110 20:50:42.125939 17484 status_update_manager.cpp:177] Pausing sending
status updates
3: I1110 20:50:42.127429 17489 state.cpp:64] Recovering state from
'/tmp/ReservationTest_CompatibleCheckpointedResourcesWithPersistentVolumes_gEevh0/meta'
3: I1110 20:50:42.128172 17489 state.cpp:96] Agent host rebooted
3: I1110 20:50:42.128710 17492 hierarchical.cpp:1450] Performed allocation for
1 agents in 194471ns
3: W1110 20:50:42.129779 17497 slave.cpp:6245] Falling back to recover as a new
agent due to error: Incompatible agent info detected.
3: ------------------------------------------------------------
3: Old agent info:
3: hostname: "40e8ef4cee13"
3: resources {
3: name: "cpus"
3: type: SCALAR
3: scalar {
3: value: 8
3: }
3: }
3: resources {
3: name: "mem"
3: type: SCALAR
3: scalar {
3: value: 4096
3: }
3: }
3: resources {
3: name: "disk"
3: type: SCALAR
3: scalar {
3: value: 2048
3: }
3: }
3: resources {
3: name: "ports"
3: type: RANGES
3: ranges {
3: range {
3: begin: 31000
3: end: 32000
3: }
3: }
3: }
3: id {
3: value: "bf5375c1-13d4-4816-a37b-79f8dc10d3f8-S0"
3: }
3: checkpoint: true
3: port: 35939
3:
3: ------------------------------------------------------------
3: New agent info:
3: hostname: "40e8ef4cee13"
3: resources {
3: name: "cpus"
3: type: SCALAR
3: scalar {
3: value: 12
3: }
3: }
3: resources {
3: name: "mem"
3: type: SCALAR
3: scalar {
3: value: 2048
3: }
3: }
3: resources {
3: name: "disk"
3: type: SCALAR
3: scalar {
3: value: 1024
3: }
3: }
3: resources {
3: name: "ports"
3: type: RANGES
3: ranges {
3: range {
3: begin: 31000
3: end: 32000
3: }
3: }
3: }
3: checkpoint: true
3: port: 35939
3:
3: ------------------------------------------------------------
3: I1110 20:50:42.130033 17497 status_update_manager.cpp:203] Recovering status
update manager
3: I1110 20:50:42.130779 17481 slave.cpp:883] Agent terminating
3: I1110 20:50:42.130888 17481 sched.cpp:2009] Asked to stop the driver
3: I1110 20:50:42.130954 17494 sched.cpp:1191] Stopping framework
bf5375c1-13d4-4816-a37b-79f8dc10d3f8-0000
3: I1110 20:50:42.131206 17492 master.cpp:8793] Processing TEARDOWN call for
framework bf5375c1-13d4-4816-a37b-79f8dc10d3f8-0000 (default) at
scheduler-33e1d38c-0884-40b5-bf35-591886ed132f@172.17.0.3:35939
3: I1110 20:50:42.131240 17492 master.cpp:8805] Removing framework
bf5375c1-13d4-4816-a37b-79f8dc10d3f8-0000 (default) at
scheduler-33e1d38c-0884-40b5-bf35-591886ed132f@172.17.0.3:35939
3: I1110 20:50:42.131254 17492 master.cpp:3332] Deactivating framework
bf5375c1-13d4-4816-a37b-79f8dc10d3f8-0000 (default) at
scheduler-33e1d38c-0884-40b5-bf35-591886ed132f@172.17.0.3:35939
3: I1110 20:50:42.131379 17489 hierarchical.cpp:412] Deactivated framework
bf5375c1-13d4-4816-a37b-79f8dc10d3f8-0000
3: I1110 20:50:42.132108 17483 hierarchical.cpp:355] Removed framework
bf5375c1-13d4-4816-a37b-79f8dc10d3f8-0000
3: I1110 20:50:42.137411 17482 hierarchical.cpp:1450] Performed allocation for
1 agents in 191794ns
3: I1110 20:50:42.138120 17481 master.cpp:1151] Master terminating
3: I1110 20:50:42.139106 17497 hierarchical.cpp:626] Removed agent
bf5375c1-13d4-4816-a37b-79f8dc10d3f8-S0
3: [ OK ]
ReservationTest.CompatibleCheckpointedResourcesWithPersistentVolumes (83 ms)
3: [ RUN ] ReservationTest.IncompatibleCheckpointedResources
3: I1110 20:50:42.145748 17481 cluster.cpp:162] Creating default 'local'
authorizer
3: I1110 20:50:42.149010 17486 master.cpp:448] Master
5b66f4bb-f1a6-4396-bb9d-f8400942a29f (40e8ef4cee13) started on 172.17.0.3:35939
3: I1110 20:50:42.149029 17486 master.cpp:450] Flags at startup: --acls=""
--agent_ping_timeout="15secs" --agent_reregister_timeout="10mins"
--allocation_interval="5ms" --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/E4bVQD/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="role"
--root_submissions="true" --user_sorter="drf" --version="false"
--webui_dir="/usr/local/share/mesos/webui" --work_dir="/tmp/E4bVQD/master"
--zk_session_timeout="10secs"
3: I1110 20:50:42.149288 17486 master.cpp:499] Master only allowing
authenticated frameworks to register
3: I1110 20:50:42.149296 17486 master.cpp:505] Master only allowing
authenticated agents to register
3: I1110 20:50:42.149300 17486 master.cpp:511] Master only allowing
authenticated HTTP frameworks to register
3: I1110 20:50:42.149305 17486 credentials.hpp:37] Loading credentials for
authentication from '/tmp/E4bVQD/credentials'
3: I1110 20:50:42.149538 17486 master.cpp:555] Using default 'crammd5'
authenticator
3: I1110 20:50:42.149657 17486 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-master-readonly'
3: I1110 20:50:42.149770 17486 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-master-readwrite'
3: I1110 20:50:42.149879 17486 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-master-scheduler'
3: I1110 20:50:42.150001 17486 master.cpp:634] Authorization enabled
3: W1110 20:50:42.150013 17486 master.cpp:697] The '--roles' flag is
deprecated. This flag will be removed in the future. See the Mesos 0.27 upgrade
notes for more information
3: I1110 20:50:42.150238 17487 whitelist_watcher.cpp:77] No whitelist given
3: I1110 20:50:42.150326 17490 hierarchical.cpp:171] Initialized hierarchical
allocator process
3: I1110 20:50:42.152819 17484 master.cpp:2213] Elected as the leading master!
3: I1110 20:50:42.152851 17484 master.cpp:1693] Recovering from registrar
3: I1110 20:50:42.153017 17493 registrar.cpp:347] Recovering registrar
3: I1110 20:50:42.153511 17493 registrar.cpp:391] Successfully fetched the
registry (0B) in 461056ns
3: I1110 20:50:42.153590 17493 registrar.cpp:495] Applied 1 operations in
21545ns; attempting to update the registry
3: I1110 20:50:42.154021 17493 registrar.cpp:552] Successfully updated the
registry in 391936ns
3: I1110 20:50:42.154119 17493 registrar.cpp:424] Successfully recovered
registrar
3: I1110 20:50:42.154453 17492 master.cpp:1806] Recovered 0 agents from the
registry (129B); allowing 10mins for agents to re-register
3: I1110 20:50:42.154580 17490 hierarchical.cpp:209] Skipping recovery of
hierarchical allocator: nothing to recover
3: I1110 20:50:42.157116 17484 hierarchical.cpp:1450] Performed allocation for
0 agents in 40210ns
3: W1110 20:50:42.160051 17481 process.cpp:3178] Attempted to spawn already
running process files@172.17.0.3:35939
3: I1110 20:50:42.160858 17493 slave.cpp:262] Mesos agent started on
@172.17.0.3:35939
3: W1110 20:50:42.161154 17481 process.cpp:3178] Attempted to spawn already
running process version@172.17.0.3:35939
3: I1110 20:50:42.160878 17493 slave.cpp:263] Flags at startup: --acls=""
--appc_simple_discovery_uri_prefix="http://";
--appc_store_dir="/tmp/ReservationTest_IncompatibleCheckpointedResources_fVVa0b/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/ReservationTest_IncompatibleCheckpointedResources_fVVa0b/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/ReservationTest_IncompatibleCheckpointedResources_fVVa0b/store/docker"
--docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume"
--enforce_container_disk_quota="false" --executor_registration_timeout="1mins"
--executor_reregistration_timeout="2secs"
--executor_secret_key="/tmp/ReservationTest_IncompatibleCheckpointedResources_fVVa0b/executor_secret_key"
--executor_shutdown_grace_period="5secs"
--fetcher_cache_dir="/tmp/ReservationTest_IncompatibleCheckpointedResources_fVVa0b/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/ReservationTest_IncompatibleCheckpointedResources_fVVa0b/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" --recover="reconnect" --recovery_timeout="15mins"
--registration_backoff_factor="10ms" --resources="cpus:8;mem:4096"
--revocable_cpu_low_priority="true"
--runtime_dir="/tmp/ReservationTest_IncompatibleCheckpointedResources_fVVa0b"
--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/ReservationTest_IncompatibleCheckpointedResources_SlvxVp"
--zk_session_timeout="10secs"
3: I1110 20:50:42.161286 17493 credentials.hpp:86] Loading credential for
authentication from
'/tmp/ReservationTest_IncompatibleCheckpointedResources_fVVa0b/credential'
3: I1110 20:50:42.161449 17493 slave.cpp:295] Agent using credential for:
test-principal
3: I1110 20:50:42.161468 17493 credentials.hpp:37] Loading credentials for
authentication from
'/tmp/ReservationTest_IncompatibleCheckpointedResources_fVVa0b/http_credentials'
3: I1110 20:50:42.161758 17493 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-agent-executor'
3: I1110 20:50:42.161864 17493 http.cpp:1066] Creating default 'jwt' HTTP
authenticator for realm 'mesos-agent-executor'
3: I1110 20:50:42.162076 17493 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-agent-readonly'
3: I1110 20:50:42.162223 17493 http.cpp:1066] Creating default 'jwt' HTTP
authenticator for realm 'mesos-agent-readonly'
3: I1110 20:50:42.162446 17493 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-agent-readwrite'
3: I1110 20:50:42.162456 17481 sched.cpp:232] Version: 1.5.0
3: I1110 20:50:42.162556 17493 http.cpp:1066] Creating default 'jwt' HTTP
authenticator for realm 'mesos-agent-readwrite'
3: I1110 20:50:42.163018 17489 sched.cpp:336] New master detected at
master@172.17.0.3:35939
3: I1110 20:50:42.163142 17489 sched.cpp:396] Authenticating with master
master@172.17.0.3:35939
3: I1110 20:50:42.163161 17489 sched.cpp:403] Using default CRAM-MD5
authenticatee
3: I1110 20:50:42.163424 17496 authenticatee.cpp:121] Creating new client SASL
connection
3: I1110 20:50:42.163666 17484 master.cpp:8286] Authenticating
scheduler-cd63ea06-9812-4760-b979-a30e15530e4c@172.17.0.3:35939
3: I1110 20:50:42.163786 17487 authenticator.cpp:414] Starting authentication
session for crammd5-authenticatee(1073)@172.17.0.3:35939
3: I1110 20:50:42.163730 17493 slave.cpp:593] Agent resources:
[{"name":"cpus","scalar":{"value":8.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":4096.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":3701220.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
3: I1110 20:50:42.163964 17493 slave.cpp:601] Agent attributes: [ ]
3: I1110 20:50:42.163976 17493 slave.cpp:610] Agent hostname: 40e8ef4cee13
3: I1110 20:50:42.164095 17485 authenticator.cpp:98] Creating new server SASL
connection
3: I1110 20:50:42.164106 17495 status_update_manager.cpp:177] Pausing sending
status updates
3: I1110 20:50:42.164275 17492 authenticatee.cpp:213] Received SASL
authentication mechanisms: CRAM-MD5
3: I1110 20:50:42.164299 17492 authenticatee.cpp:239] Attempting to
authenticate with mechanism 'CRAM-MD5'
3: I1110 20:50:42.164394 17485 authenticator.cpp:204] Received SASL
authentication start
3: I1110 20:50:42.164439 17485 authenticator.cpp:326] Authentication requires
more steps
3: I1110 20:50:42.164499 17488 hierarchical.cpp:1450] Performed allocation for
0 agents in 40505ns
3: I1110 20:50:42.164551 17485 authenticatee.cpp:259] Received SASL
authentication step
3: I1110 20:50:42.164674 17497 authenticator.cpp:232] Received SASL
authentication step
3: I1110 20:50:42.164702 17497 auxprop.cpp:109] Request to lookup properties
for user: 'test-principal' realm: '40e8ef4cee13' server FQDN: '40e8ef4cee13'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: false
3: I1110 20:50:42.164712 17497 auxprop.cpp:181] Looking up auxiliary property
'*userPassword'
3: I1110 20:50:42.164743 17497 auxprop.cpp:181] Looking up auxiliary property
'*cmusaslsecretCRAM-MD5'
3: I1110 20:50:42.164760 17497 auxprop.cpp:109] Request to lookup properties
for user: 'test-principal' realm: '40e8ef4cee13' server FQDN: '40e8ef4cee13'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: true
3: I1110 20:50:42.164772 17497 auxprop.cpp:131] Skipping auxiliary property
'*userPassword' since SASL_AUXPROP_AUTHZID == true
3: I1110 20:50:42.164779 17497 auxprop.cpp:131] Skipping auxiliary property
'*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
3: I1110 20:50:42.164793 17497 authenticator.cpp:318] Authentication success
3: I1110 20:50:42.164863 17489 authenticatee.cpp:299] Authentication success
3: I1110 20:50:42.165076 17484 master.cpp:8316] Successfully authenticated
principal 'test-principal' at
scheduler-cd63ea06-9812-4760-b979-a30e15530e4c@172.17.0.3:35939
3: I1110 20:50:42.165098 17495 authenticator.cpp:432] Authentication session
cleanup for crammd5-authenticatee(1073)@172.17.0.3:35939
3: I1110 20:50:42.165187 17491 sched.cpp:502] Successfully authenticated with
master master@172.17.0.3:35939
3: I1110 20:50:42.165208 17491 sched.cpp:824] Sending SUBSCRIBE call to
master@172.17.0.3:35939
3: I1110 20:50:42.165325 17491 sched.cpp:857] Will retry registration in
566.889327ms if necessary
3: I1110 20:50:42.165508 17485 master.cpp:2962] Received SUBSCRIBE call for
framework 'default' at
scheduler-cd63ea06-9812-4760-b979-a30e15530e4c@172.17.0.3:35939
3: I1110 20:50:42.165547 17493 state.cpp:64] Recovering state from
'/tmp/ReservationTest_IncompatibleCheckpointedResources_SlvxVp/meta'
3: I1110 20:50:42.165594 17485 master.cpp:2278] Authorizing framework principal
'test-principal' to receive offers for roles '{ role }'
3: I1110 20:50:42.165781 17485 status_update_manager.cpp:203] Recovering status
update manager
3: I1110 20:50:42.166021 17487 master.cpp:3042] Subscribing framework default
with checkpointing disabled and capabilities [ RESERVATION_REFINEMENT ]
3: I1110 20:50:42.166175 17488 slave.cpp:6422] Finished recovery
3: I1110 20:50:42.166563 17482 sched.cpp:751] Framework registered with
5b66f4bb-f1a6-4396-bb9d-f8400942a29f-0000
3: I1110 20:50:42.166607 17482 sched.cpp:765] Scheduler::registered took 23006ns
3: I1110 20:50:42.166715 17492 hierarchical.cpp:303] Added framework
5b66f4bb-f1a6-4396-bb9d-f8400942a29f-0000
3: I1110 20:50:42.166877 17485 status_update_manager.cpp:177] Pausing sending
status updates
3: I1110 20:50:42.166880 17488 slave.cpp:1007] New master detected at
master@172.17.0.3:35939
3: I1110 20:50:42.166932 17488 slave.cpp:1042] Detecting new master
3: I1110 20:50:42.166960 17492 hierarchical.cpp:1450] Performed allocation for
0 agents in 135974ns
3: I1110 20:50:42.172484 17496 hierarchical.cpp:1450] Performed allocation for
0 agents in 46587ns
3: I1110 20:50:42.176684 17489 slave.cpp:1069] Authenticating with master
master@172.17.0.3:35939
3: I1110 20:50:42.176736 17489 slave.cpp:1078] Using default CRAM-MD5
authenticatee
3: I1110 20:50:42.176913 17486 authenticatee.cpp:121] Creating new client SASL
connection
3: I1110 20:50:42.177132 17483 master.cpp:8286] Authenticating
(596)@172.17.0.3:35939
3: I1110 20:50:42.177230 17493 authenticator.cpp:414] Starting authentication
session for crammd5-authenticatee(1074)@172.17.0.3:35939
3: I1110 20:50:42.177531 17484 authenticator.cpp:98] Creating new server SASL
connection
3: I1110 20:50:42.177820 17484 authenticatee.cpp:213] Received SASL
authentication mechanisms: CRAM-MD5
3: I1110 20:50:42.177847 17484 authenticatee.cpp:239] Attempting to
authenticate with mechanism 'CRAM-MD5'
3: I1110 20:50:42.177958 17484 authenticator.cpp:204] Received SASL
authentication start
3: I1110 20:50:42.178010 17484 authenticator.cpp:326] Authentication requires
more steps
3: I1110 20:50:42.178117 17484 authenticatee.cpp:259] Received SASL
authentication step
3: I1110 20:50:42.178236 17484 authenticator.cpp:232] Received SASL
authentication step
3: I1110 20:50:42.178267 17484 auxprop.cpp:109] Request to lookup properties
for user: 'test-principal' realm: '40e8ef4cee13' server FQDN: '40e8ef4cee13'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: false
3: I1110 20:50:42.178277 17484 auxprop.cpp:181] Looking up auxiliary property
'*userPassword'
3: I1110 20:50:42.178310 17484 auxprop.cpp:181] Looking up auxiliary property
'*cmusaslsecretCRAM-MD5'
3: I1110 20:50:42.178330 17484 auxprop.cpp:109] Request to lookup properties
for user: 'test-principal' realm: '40e8ef4cee13' server FQDN: '40e8ef4cee13'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: true
3: I1110 20:50:42.178354 17484 auxprop.cpp:131] Skipping auxiliary property
'*userPassword' since SASL_AUXPROP_AUTHZID == true
3: I1110 20:50:42.178361 17484 auxprop.cpp:131] Skipping auxiliary property
'*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
3: I1110 20:50:42.178375 17484 authenticator.cpp:318] Authentication success
3: I1110 20:50:42.178483 17497 authenticatee.cpp:299] Authentication success
3: I1110 20:50:42.178580 17494 master.cpp:8316] Successfully authenticated
principal 'test-principal' at (596)@172.17.0.3:35939
3: I1110 20:50:42.178592 17485 authenticator.cpp:432] Authentication session
cleanup for crammd5-authenticatee(1074)@172.17.0.3:35939
3: I1110 20:50:42.178736 17492 slave.cpp:1161] Successfully authenticated with
master master@172.17.0.3:35939
3: I1110 20:50:42.178939 17492 slave.cpp:1682] Will retry registration in
7.275592ms if necessary
3: I1110 20:50:42.179100 17486 master.cpp:6033] Received register agent message
from (596)@172.17.0.3:35939 (40e8ef4cee13)
3: I1110 20:50:42.179204 17486 master.cpp:3871] Authorizing agent with
principal 'test-principal'
3: I1110 20:50:42.179498 17493 master.cpp:6093] Authorized registration of
agent at (596)@172.17.0.3:35939 (40e8ef4cee13)
3: I1110 20:50:42.179584 17493 master.cpp:6186] Registering agent at
(596)@172.17.0.3:35939 (40e8ef4cee13) with id
5b66f4bb-f1a6-4396-bb9d-f8400942a29f-S0
3: I1110 20:50:42.180042 17487 registrar.cpp:495] Applied 1 operations in
73923ns; attempting to update the registry
3: I1110 20:50:42.180706 17484 registrar.cpp:552] Successfully updated the
registry in 598016ns
3: I1110 20:50:42.181002 17494 master.cpp:6233] Admitted agent
5b66f4bb-f1a6-4396-bb9d-f8400942a29f-S0 at (596)@172.17.0.3:35939 (40e8ef4cee13)
3: I1110 20:50:42.181318 17496 hierarchical.cpp:1450] Performed allocation for
0 agents in 79573ns
3: I1110 20:50:42.181768 17485 slave.cpp:1207] Registered with master
master@172.17.0.3:35939; given agent ID 5b66f4bb-f1a6-4396-bb9d-f8400942a29f-S0
3: I1110 20:50:42.181859 17486 status_update_manager.cpp:184] Resuming sending
status updates
3: I1110 20:50:42.181694 17494 master.cpp:6264] Registered agent
5b66f4bb-f1a6-4396-bb9d-f8400942a29f-S0 at (596)@172.17.0.3:35939
(40e8ef4cee13) with
[{"name":"cpus","scalar":{"value":8.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":4096.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":3701220.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
3: I1110 20:50:42.181972 17489 hierarchical.cpp:593] Added agent
5b66f4bb-f1a6-4396-bb9d-f8400942a29f-S0 (40e8ef4cee13) with cpus:8; mem:4096;
disk:3701220; ports:[31000-32000] (allocated: {})
3: I1110 20:50:42.182065 17485 slave.cpp:1227] Checkpointing SlaveInfo to
'/tmp/ReservationTest_IncompatibleCheckpointedResources_SlvxVp/meta/slaves/5b66f4bb-f1a6-4396-bb9d-f8400942a29f-S0/slave.info'
3: I1110 20:50:42.182973 17489 hierarchical.cpp:1450] Performed allocation for
1 agents in 848558ns
3: I1110 20:50:42.183444 17483 master.cpp:8116] Sending 1 offers to framework
5b66f4bb-f1a6-4396-bb9d-f8400942a29f-0000 (default) at
scheduler-cd63ea06-9812-4760-b979-a30e15530e4c@172.17.0.3:35939
3: I1110 20:50:42.183943 17493 sched.cpp:921] Scheduler::resourceOffers took
142492ns
3: I1110 20:50:42.185873 17491 master.cpp:10016] Removing offer
5b66f4bb-f1a6-4396-bb9d-f8400942a29f-O0
3: I1110 20:50:42.186018 17491 master.cpp:4229] Processing ACCEPT call for
offers: [ 5b66f4bb-f1a6-4396-bb9d-f8400942a29f-O0 ] on agent
5b66f4bb-f1a6-4396-bb9d-f8400942a29f-S0 at (596)@172.17.0.3:35939
(40e8ef4cee13) for framework 5b66f4bb-f1a6-4396-bb9d-f8400942a29f-0000
(default) at scheduler-cd63ea06-9812-4760-b979-a30e15530e4c@172.17.0.3:35939
3: I1110 20:50:42.186187 17491 master.cpp:3656] Authorizing principal
'test-principal' to reserve resources
'[{"allocation_info":{"role":"role"},"name":"cpus","reservations":[{"principal":"test-principal","role":"role","type":"DYNAMIC"}],"scalar":{"value":8.0},"type":"SCALAR"},{"allocation_info":{"role":"role"},"name":"mem","reservations":[{"principal":"test-principal","role":"role","type":"DYNAMIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]'
3: I1110 20:50:42.188009 17491 master.cpp:4562] Applying RESERVE operation for
resources
[{"allocation_info":{"role":"role"},"name":"cpus","reservations":[{"principal":"test-principal","role":"role","type":"DYNAMIC"}],"scalar":{"value":8.0},"type":"SCALAR"},{"allocation_info":{"role":"role"},"name":"mem","reservations":[{"principal":"test-principal","role":"role","type":"DYNAMIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]
from framework 5b66f4bb-f1a6-4396-bb9d-f8400942a29f-0000 (default) at
scheduler-cd63ea06-9812-4760-b979-a30e15530e4c@172.17.0.3:35939 to agent
5b66f4bb-f1a6-4396-bb9d-f8400942a29f-S0 at (596)@172.17.0.3:35939 (40e8ef4cee13)
3: I1110 20:50:42.188853 17491 master.cpp:9961] Sending updated checkpointed
resources cpus(reservations: [(DYNAMIC,role,test-principal)]):8;
mem(reservations: [(DYNAMIC,role,test-principal)]):2048 to agent
5b66f4bb-f1a6-4396-bb9d-f8400942a29f-S0 at (596)@172.17.0.3:35939 (40e8ef4cee13)
write /dev/stdout: resource temporarily unavailable
+ docker rmi mesos-1510344711-13766
Build step 'Execute shell' marked build as failure
Not sending mail to unregistered user yujie....@gmail.com
