See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=clang,CONFIGURATION=--verbose%20--disable-libtool-wrappers,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4499/display/redirect?page=changes>
Changes: [benjamin.hindman] Fixed typos in libprocess documentation. ------------------------------------------ [...truncated 33.92 MB...] 3: " --appc_simple_discovery_uri_prefix="http://"; --appc_store_dir="/tmp/ResourceProviderCapability_ReservationTest_PreventUnreservingAlienResources_1_rPs4Wu/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/ResourceProviderCapability_ReservationTest_PreventUnreservingAlienResources_1_rPs4Wu/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/ResourceProviderCapability_ReservationTest_PreventUnreservingAlienResources_1_rPs4Wu/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/ResourceProviderCapability_ReservationTest_PreventUnreservingAlienResources_1_rPs4Wu/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/ResourceProviderCapability_ReservationTest_PreventUnreservingAlienResources_1_rPs4Wu/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:1;mem:512" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/ResourceProviderCapability_ReservationTest_PreventUnreservingAlienResources_1_rPs4Wu" --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/ResourceProviderCapability_ReservationTest_PreventUnreservingAlienResources_1_M3rXM8" --zk_session_timeout="10secs" 3: I1126 01:52:58.566205 15948 credentials.hpp:86] Loading credential for authentication from '/tmp/ResourceProviderCapability_ReservationTest_PreventUnreservingAlienResources_1_rPs4Wu/credential' 3: I1126 01:52:58.566498 15948 slave.cpp:295] Agent using credential for: test-principal 3: I1126 01:52:58.566519 15948 credentials.hpp:37] Loading credentials for authentication from '/tmp/ResourceProviderCapability_ReservationTest_PreventUnreservingAlienResources_1_rPs4Wu/http_credentials' 3: I1126 01:52:58.566787 15948 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly' 3: I1126 01:52:58.567062 15948 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite' 3: I1126 01:52:58.567371 15942 hierarchical.cpp:1457] Performed allocation for 0 agents in 63878ns 3: I1126 01:52:58.568334 15948 slave.cpp:593] Agent resources: [{"name":"cpus","scalar":{"value":1.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":512.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":3701220.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}] 3: I1126 01:52:58.568575 15948 slave.cpp:601] Agent attributes: [ ] 3: I1126 01:52:58.568588 15948 slave.cpp:610] Agent hostname: eacc7407be8c 3: I1126 01:52:58.568761 15945 task_status_update_manager.cpp:181] Pausing sending task status updates 3: I1126 01:52:58.570439 15941 state.cpp:64] Recovering state from '/tmp/ResourceProviderCapability_ReservationTest_PreventUnreservingAlienResources_1_M3rXM8/meta' 3: I1126 01:52:58.573325 15951 hierarchical.cpp:1457] Performed allocation for 0 agents in 94526ns 3: I1126 01:52:58.578357 15938 task_status_update_manager.cpp:207] Recovering task status update manager 3: I1126 01:52:58.578534 15943 containerizer.cpp:668] Recovering containerizer 3: I1126 01:52:58.579284 15940 hierarchical.cpp:1457] Performed allocation for 0 agents in 78010ns 3: I1126 01:52:58.580070 15941 provisioner.cpp:455] Provisioner recovery complete 3: I1126 01:52:58.580368 15938 slave.cpp:6493] Finished recovery 3: I1126 01:52:58.581074 15938 slave.cpp:1007] New master detected at [email protected]:42382 3: I1126 01:52:58.581123 15938 slave.cpp:1042] Detecting new master 3: I1126 01:52:58.581148 15936 task_status_update_manager.cpp:181] Pausing sending task status updates 3: I1126 01:52:58.584856 15942 hierarchical.cpp:1457] Performed allocation for 0 agents in 47251ns 3: I1126 01:52:58.587133 15943 slave.cpp:1069] Authenticating with master [email protected]:42382 3: I1126 01:52:58.587215 15943 slave.cpp:1078] Using default CRAM-MD5 authenticatee 3: I1126 01:52:58.587481 15944 authenticatee.cpp:121] Creating new client SASL connection 3: I1126 01:52:58.587786 15946 master.cpp:8312] Authenticating slave(945)@172.17.0.3:42382 3: I1126 01:52:58.587883 15940 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1691)@172.17.0.3:42382 3: I1126 01:52:58.588340 15951 authenticator.cpp:98] Creating new server SASL connection 3: I1126 01:52:58.588682 15949 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5 3: I1126 01:52:58.588709 15949 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5' 3: I1126 01:52:58.588806 15949 authenticator.cpp:204] Received SASL authentication start 3: I1126 01:52:58.588920 15949 authenticator.cpp:326] Authentication requires more steps 3: I1126 01:52:58.589035 15939 authenticatee.cpp:259] Received SASL authentication step 3: I1126 01:52:58.589171 15939 authenticator.cpp:232] Received SASL authentication step 3: I1126 01:52:58.589203 15939 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'eacc7407be8c' server FQDN: 'eacc7407be8c' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 3: I1126 01:52:58.589222 15939 auxprop.cpp:181] Looking up auxiliary property '*userPassword' 3: I1126 01:52:58.589273 15939 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' 3: I1126 01:52:58.589303 15939 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'eacc7407be8c' server FQDN: 'eacc7407be8c' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 3: I1126 01:52:58.589313 15939 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true 3: I1126 01:52:58.589321 15939 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true 3: I1126 01:52:58.589337 15939 authenticator.cpp:318] Authentication success 3: I1126 01:52:58.589437 15950 authenticatee.cpp:299] Authentication success 3: I1126 01:52:58.589475 15937 master.cpp:8342] Successfully authenticated principal 'test-principal' at slave(945)@172.17.0.3:42382 3: I1126 01:52:58.589524 15936 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1691)@172.17.0.3:42382 3: I1126 01:52:58.589754 15950 slave.cpp:1161] Successfully authenticated with master [email protected]:42382 3: I1126 01:52:58.590010 15950 slave.cpp:1685] Will retry registration in 3.422746ms if necessary 3: I1126 01:52:58.590250 15944 master.cpp:6036] Received register agent message from slave(945)@172.17.0.3:42382 (eacc7407be8c) 3: I1126 01:52:58.590281 15944 master.cpp:3872] Authorizing agent with principal 'test-principal' 3: I1126 01:52:58.590596 15946 hierarchical.cpp:1457] Performed allocation for 0 agents in 57599ns 3: I1126 01:52:58.590852 15946 master.cpp:6098] Authorized registration of agent at slave(945)@172.17.0.3:42382 (eacc7407be8c) 3: I1126 01:52:58.590951 15946 master.cpp:6191] Registering agent at slave(945)@172.17.0.3:42382 (eacc7407be8c) with id d11e81eb-7ac1-42b4-b103-31129efab547-S0 3: I1126 01:52:58.591536 15948 registrar.cpp:495] Applied 1 operations in 161210ns; attempting to update the registry 3: I1126 01:52:58.592885 15948 registrar.cpp:552] Successfully updated the registry in 1.026304ms 3: I1126 01:52:58.593289 15945 master.cpp:6240] Admitted agent d11e81eb-7ac1-42b4-b103-31129efab547-S0 at slave(945)@172.17.0.3:42382 (eacc7407be8c) 3: I1126 01:52:58.594087 15945 master.cpp:6276] Registered agent d11e81eb-7ac1-42b4-b103-31129efab547-S0 at slave(945)@172.17.0.3:42382 (eacc7407be8c) with cpus:1; mem:512; disk:3701220; ports:[31000-32000] 3: I1126 01:52:58.594208 15936 slave.cpp:1207] Registered with master [email protected]:42382; given agent ID d11e81eb-7ac1-42b4-b103-31129efab547-S0 3: I1126 01:52:58.594287 15945 task_status_update_manager.cpp:188] Resuming sending task status updates 3: I1126 01:52:58.594563 15947 hierarchical.cpp:600] Added agent d11e81eb-7ac1-42b4-b103-31129efab547-S0 (eacc7407be8c) with cpus:1; mem:512; disk:3701220; ports:[31000-32000] (allocated: {}) 3: I1126 01:52:58.594606 15936 slave.cpp:1227] Checkpointing SlaveInfo to '/tmp/ResourceProviderCapability_ReservationTest_PreventUnreservingAlienResources_1_M3rXM8/meta/slaves/d11e81eb-7ac1-42b4-b103-31129efab547-S0/slave.info' 3: I1126 01:52:58.594849 15947 hierarchical.cpp:1457] Performed allocation for 1 agents in 146776ns 3: I1126 01:52:58.595077 15936 slave.cpp:1288] Forwarding total resources cpus:1; mem:512; disk:3701220; ports:[31000-32000] 3: I1126 01:52:58.595134 15936 slave.cpp:1298] Forwarding total oversubscribed resources {} 3: I1126 01:52:58.595651 15936 master.cpp:7078] Received update of agent d11e81eb-7ac1-42b4-b103-31129efab547-S0 at slave(945)@172.17.0.3:42382 (eacc7407be8c) with total resources cpus:1; mem:512; disk:3701220; ports:[31000-32000] 3: I1126 01:52:58.595702 15936 master.cpp:7091] Received update of agent d11e81eb-7ac1-42b4-b103-31129efab547-S0 at slave(945)@172.17.0.3:42382 (eacc7407be8c) with total oversubscribed resources {} 3: I1126 01:52:58.595927 15936 master.cpp:7109] Ignoring update on agent d11e81eb-7ac1-42b4-b103-31129efab547-S0 at slave(945)@172.17.0.3:42382 (eacc7407be8c) as it reports no changes 3: W1126 01:52:58.596191 15935 process.cpp:2756] Attempted to spawn already running process [email protected]:42382 3: W1126 01:52:58.596581 15935 process.cpp:2756] Attempted to spawn already running process [email protected]:42382 3: I1126 01:52:58.596585 15939 hierarchical.cpp:1457] Performed allocation for 1 agents in 109704ns 3: I1126 01:52:58.597474 15935 sched.cpp:232] Version: 1.5.0 3: I1126 01:52:58.598053 15944 sched.cpp:336] New master detected at [email protected]:42382 3: I1126 01:52:58.598134 15944 sched.cpp:396] Authenticating with master [email protected]:42382 3: I1126 01:52:58.598152 15944 sched.cpp:403] Using default CRAM-MD5 authenticatee 3: I1126 01:52:58.598577 15951 authenticatee.cpp:121] Creating new client SASL connection 3: I1126 01:52:58.599196 15949 master.cpp:8312] Authenticating [email protected]:42382 3: I1126 01:52:58.599481 15949 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1692)@172.17.0.3:42382 3: I1126 01:52:58.599982 15949 authenticator.cpp:98] Creating new server SASL connection 3: I1126 01:52:58.600435 15951 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5 3: I1126 01:52:58.600467 15951 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5' 3: I1126 01:52:58.600618 15951 authenticator.cpp:204] Received SASL authentication start 3: I1126 01:52:58.600703 15951 authenticator.cpp:326] Authentication requires more steps 3: I1126 01:52:58.600836 15951 authenticatee.cpp:259] Received SASL authentication step 3: I1126 01:52:58.600993 15951 authenticator.cpp:232] Received SASL authentication step 3: I1126 01:52:58.601027 15951 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'eacc7407be8c' server FQDN: 'eacc7407be8c' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 3: I1126 01:52:58.601043 15951 auxprop.cpp:181] Looking up auxiliary property '*userPassword' 3: I1126 01:52:58.601096 15951 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' 3: I1126 01:52:58.601124 15951 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'eacc7407be8c' server FQDN: 'eacc7407be8c' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 3: I1126 01:52:58.601135 15951 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true 3: I1126 01:52:58.601140 15951 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true 3: I1126 01:52:58.601158 15951 authenticator.cpp:318] Authentication success 3: I1126 01:52:58.601258 15939 authenticatee.cpp:299] Authentication success 3: I1126 01:52:58.602391 15947 hierarchical.cpp:1457] Performed allocation for 1 agents in 173496ns 3: I1126 01:52:58.602893 15945 master.cpp:8342] Successfully authenticated principal 'test-principal' at [email protected]:42382 3: I1126 01:52:58.602952 15943 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1692)@172.17.0.3:42382 3: I1126 01:52:58.602943 15939 sched.cpp:502] Successfully authenticated with master [email protected]:42382 3: I1126 01:52:58.603021 15939 sched.cpp:824] Sending SUBSCRIBE call to [email protected]:42382 3: I1126 01:52:58.608338 15949 hierarchical.cpp:1457] Performed allocation for 1 agents in 210466ns 3: I1126 01:52:58.608844 15939 sched.cpp:857] Will retry registration in 1.045620684secs if necessary 3: I1126 01:52:58.609164 15946 master.cpp:2963] Received SUBSCRIBE call for framework 'framework1' at [email protected]:42382 3: I1126 01:52:58.609195 15946 master.cpp:2280] Authorizing framework principal 'test-principal' to receive offers for roles '{ role1 }' 3: I1126 01:52:58.609833 15950 master.cpp:3043] Subscribing framework framework1 with checkpointing disabled and capabilities [ RESERVATION_REFINEMENT ] 3: I1126 01:52:58.610723 15942 sched.cpp:751] Framework registered with d11e81eb-7ac1-42b4-b103-31129efab547-0000 3: I1126 01:52:58.610783 15942 sched.cpp:765] Scheduler::registered took 35376ns 3: I1126 01:52:58.610808 15947 hierarchical.cpp:306] Added framework d11e81eb-7ac1-42b4-b103-31129efab547-0000 3: I1126 01:52:58.611819 15947 hierarchical.cpp:1457] Performed allocation for 1 agents in 906211ns 3: I1126 01:52:58.612453 15936 master.cpp:8142] Sending 1 offers to framework d11e81eb-7ac1-42b4-b103-31129efab547-0000 (framework1) at [email protected]:42382 3: I1126 01:52:58.613097 15945 sched.cpp:921] Scheduler::resourceOffers took 106412ns 3: I1126 01:52:58.614238 15946 hierarchical.cpp:1457] Performed allocation for 1 agents in 177537ns 3: I1126 01:52:58.614852 15943 master.cpp:10063] Removing offer d11e81eb-7ac1-42b4-b103-31129efab547-O0 3: I1126 01:52:58.614954 15943 master.cpp:4230] Processing ACCEPT call for offers: [ d11e81eb-7ac1-42b4-b103-31129efab547-O0 ] on agent d11e81eb-7ac1-42b4-b103-31129efab547-S0 at slave(945)@172.17.0.3:42382 (eacc7407be8c) for framework d11e81eb-7ac1-42b4-b103-31129efab547-0000 (framework1) at [email protected]:42382 3: I1126 01:52:58.615098 15943 master.cpp:3657] Authorizing principal 'test-principal' to reserve resources '[{"allocation_info":{"role":"role1"},"name":"mem","reservations":[{"principal":"test-principal","role":"role1","type":"DYNAMIC"}],"scalar":{"value":256.0},"type":"SCALAR"}]' 3: I1126 01:52:58.616312 15945 master.cpp:4563] Applying RESERVE operation for resources [{"allocation_info":{"role":"role1"},"name":"mem","reservations":[{"principal":"test-principal","role":"role1","type":"DYNAMIC"}],"scalar":{"value":256.0},"type":"SCALAR"}] from framework d11e81eb-7ac1-42b4-b103-31129efab547-0000 (framework1) at [email protected]:42382 to agent d11e81eb-7ac1-42b4-b103-31129efab547-S0 at slave(945)@172.17.0.3:42382 (eacc7407be8c) 3: I1126 01:52:58.616775 15945 master.cpp:9945] Sending offer operation '' (uuid: 00901709-4e2e-4c54-a788-4c1ab40a4b1e) to agent d11e81eb-7ac1-42b4-b103-31129efab547-S0 at slave(945)@172.17.0.3:42382 (eacc7407be8c) 3: I1126 01:52:58.618257 15951 slave.cpp:3594] Updated checkpointed resources from {} to mem(reservations: [(DYNAMIC,role1,test-principal)]):256 3: I1126 01:52:58.618345 15951 slave.cpp:6989] Updating the state of offer operation '' (uuid: 00901709-4e2e-4c54-a788-4c1ab40a4b1e) of framework d11e81eb-7ac1-42b4-b103-31129efab547-0000 (latest state: OFFER_OPERATION_FINISHED, status update state: OFFER_OPERATION_FINISHED) 3: I1126 01:52:58.618618 15944 master.cpp:9731] Updating the state of offer operation '' (uuid: 00901709-4e2e-4c54-a788-4c1ab40a4b1e) of framework d11e81eb-7ac1-42b4-b103-31129efab547-0000 (latest state: OFFER_OPERATION_FINISHED, status update state: OFFER_OPERATION_FINISHED) 3: I1126 01:52:58.619395 15949 hierarchical.cpp:856] Updated allocation of framework d11e81eb-7ac1-42b4-b103-31129efab547-0000 on agent d11e81eb-7ac1-42b4-b103-31129efab547-S0 from cpus(allocated: role1):1; mem(allocated: role1):512; disk(allocated: role1):3701220; ports(allocated: role1):[31000-32000] to cpus(allocated: role1):1; mem(allocated: role1):256; disk(allocated: role1):3701220; ports(allocated: role1):[31000-32000]; mem(allocated: role1)(reservations: [(DYNAMIC,role1,test-principal)]):256 3: I1126 01:52:58.620661 15949 hierarchical.cpp:1132] Recovered cpus(allocated: role1):1; mem(allocated: role1):256; disk(allocated: role1):3701220; ports(allocated: role1):[31000-32000]; mem(allocated: role1)(reservations: [(DYNAMIC,role1,test-principal)]):256 (total: cpus:1; mem:256; disk:3701220; ports:[31000-32000]; mem(reservations: [(DYNAMIC,role1,test-principal)]):256, allocated: {}) on agent d11e81eb-7ac1-42b4-b103-31129efab547-S0 from framework d11e81eb-7ac1-42b4-b103-31129efab547-0000 3: I1126 01:52:58.622423 15949 hierarchical.cpp:1457] Performed allocation for 1 agents in 1.366439ms 3: I1126 01:52:58.622910 15941 master.cpp:8142] Sending 1 offers to framework d11e81eb-7ac1-42b4-b103-31129efab547-0000 (framework1) at [email protected]:42382 3: I1126 01:52:58.623400 15950 sched.cpp:921] Scheduler::resourceOffers took 110041ns 3: I1126 01:52:58.624429 15943 master.cpp:5434] Processing DECLINE call for offers: [ d11e81eb-7ac1-42b4-b103-31129efab547-O1 ] for framework d11e81eb-7ac1-42b4-b103-31129efab547-0000 (framework1) at [email protected]:42382 3: I1126 01:52:58.624462 15935 sched.cpp:232] Version: 1.5.0 3: I1126 01:52:58.625067 15943 master.cpp:10063] Removing offer d11e81eb-7ac1-42b4-b103-31129efab547-O1 3: I1126 01:52:58.625152 15945 sched.cpp:336] New master detected at [email protected]:42382 3: I1126 01:52:58.625273 15945 sched.cpp:396] Authenticating with master [email protected]:42382 3: I1126 01:52:58.625293 15945 sched.cpp:403] Using default CRAM-MD5 authenticatee 3: I1126 01:52:58.625547 15951 authenticatee.cpp:121] Creating new client SASL connection 3: I1126 01:52:58.625826 15944 master.cpp:8312] Authenticating [email protected]:42382 3: I1126 01:52:58.626057 15939 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1693)@172.17.0.3:42382 3: I1126 01:52:58.626302 15947 hierarchical.cpp:1132] Recovered cpus(allocated: role1):1; mem(allocated: role1):256; disk(allocated: role1):3701220; ports(allocated: role1):[31000-32000]; mem(allocated: role1)(reservations: [(DYNAMIC,role1,test-principal)]):256 (total: cpus:1; mem:256; disk:3701220; ports:[31000-32000]; mem(reservations: [(DYNAMIC,role1,test-principal)]):256, allocated: {}) on agent d11e81eb-7ac1-42b4-b103-31129efab547-S0 from framework d11e81eb-7ac1-42b4-b103-31129efab547-0000 3: W1126 01:52:58.626379 15947 hierarchical.cpp:1153] Using 365 days to create the refused resources offer filter because the input value is too big 3: I1126 01:52:58.626399 15947 hierarchical.cpp:1178] Framework d11e81eb-7ac1-42b4-b103-31129efab547-0000 filtered agent d11e81eb-7ac1-42b4-b103-31129efab547-S0 for 365days 3: I1126 01:52:58.626533 15950 authenticator.cpp:98] Creating new server SASL connection 3: I1126 01:52:58.626731 15946 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5 3: I1126 01:52:58.626757 15946 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5' 3: I1126 01:52:58.626866 15942 authenticator.cpp:204] Received SASL authentication start 3: I1126 01:52:58.626926 15942 authenticator.cpp:326] Authentication requires more steps 3: I1126 01:52:58.627025 15942 authenticatee.cpp:259] Received SASL authentication step 3: I1126 01:52:58.627130 15942 authenticator.cpp:232] Received SASL authentication step 3: I1126 01:52:58.627156 15942 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'eacc7407be8c' server FQDN: 'eacc7407be8c' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 3: I1126 01:52:58.627192 15942 auxprop.cpp:181] Looking up auxiliary property '*userPassword' 3: I1126 01:52:58.627240 15942 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' 3: I1126 01:52:58.627267 15942 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'eacc7407be8c' server FQDN: 'eacc7407be8c' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 3: I1126 01:52:58.627279 15942 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true 3: I1126 01:52:58.627286 15942 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true 3: I1126 01:52:58.627303 15942 authenticator.cpp:318] Authentication success 3: I1126 01:52:58.627378 15938 authenticatee.cpp:299] Authentication success 3: I1126 01:52:58.627524 15945 master.cpp:8342] Successfully authenticated principal 'test-principal' at [email protected]:42382 3: I1126 01:52:58.627535 15938 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1693)@172.17.0.3:42382 3: I1126 01:52:58.627696 15942 sched.cpp:502] Successfully authenticated with master [email protected]:42382 3: I1126 01:52:58.627732 15942 sched.cpp:824] Sending SUBSCRIBE call to [email protected]:42382 3: I1126 01:52:58.627838 15942 sched.cpp:857] Will retry registration in 345.291519ms if necessary 3: I1126 01:52:58.628044 15936 master.cpp:2963] Received SUBSCRIBE call for framework 'framework2' at [email protected]:42382 3: I1126 01:52:58.628064 15936 master.cpp:2280] Authorizing framework principal 'test-principal' to receive offers for roles '{ role2 }' 3: I1126 01:52:58.628581 15944 hierarchical.cpp:2175] Filtered offer with cpus:1; mem:256; disk:3701220; ports:[31000-32000]; mem(reservations: [(DYNAMIC,role1,test-principal)]):256 on agent d11e81eb-7ac1-42b4-b103-31129efab547-S0 for role role1 of framework d11e81eb-7ac1-42b4-b103-31129efab547-0000 3: I1126 01:52:58.628686 15944 hierarchical.cpp:1457] Performed allocation for 1 agents in 530889ns 3: I1126 01:52:58.628788 15947 master.cpp:3043] Subscribing framework framework2 with checkpointing disabled and capabilities [ RESERVATION_REFINEMENT ] 3: I1126 01:52:58.629318 15946 sched.cpp:751] Framework registered with d11e81eb-7ac1-42b4-b103-31129efab547-0001 3: I1126 01:52:58.629384 15946 sched.cpp:765] Scheduler::registered took 48313ns 3: I1126 01:52:58.629532 15950 hierarchical.cpp:306] Added framework d11e81eb-7ac1-42b4-b103-31129efab547-0001 3: I1126 01:52:58.630571 15950 hierarchical.cpp:2175] Filtered offer with mem(reservations: [(DYNAMIC,role1,test-principal)]):256 on agent d11e81eb-7ac1-42b4-b103-31129efab547-S0 for role role1 of framework d11e81eb-7ac1-42b4-b103-31129efab547-0000 3: I1126 01:52:58.630820 15950 hierarchical.cpp:1457] Performed allocation for 1 agents in 1.182826ms 3: I1126 01:52:58.631181 15943 master.cpp:8142] Sending 1 offers to framework d11e81eb-7ac1-42b4-b103-31129efab547-0001 (framework2) at [email protected]:42382 3: I1126 01:52:58.631640 15940 sched.cpp:921] Scheduler::resourceOffers took 99460ns 3: I1126 01:52:58.633123 15941 master.cpp:10063] Removing offer d11e81eb-7ac1-42b4-b103-31129efab547-O2 3: I1126 01:52:58.633226 15941 master.cpp:4230] Processing ACCEPT call for offers: [ d11e81eb-7ac1-42b4-b103-31129efab547-O2 ] on agent d11e81eb-7ac1-42b4-b103-31129efab547-S0 at slave(945)@172.17.0.3:42382 (eacc7407be8c) for framework d11e81eb-7ac1-42b4-b103-31129efab547-0001 (framework2) at [email protected]:42382 3: I1126 01:52:58.633343 15941 master.cpp:3725] Authorizing principal 'test-principal' to unreserve resources '[{"allocation_info":{"role":"role2"},"name":"mem","reservations":[{"principal":"test-principal","role":"role1","type":"DYNAMIC"}],"scalar":{"value":256.0},"type":"SCALAR"}]' 3: I1126 01:52:58.634676 15950 hierarchical.cpp:2175] Filtered offer with mem(reservations: [(DYNAMIC,role1,test-principal)]):256 on agent d11e81eb-7ac1-42b4-b103-31129efab547-S0 for role role1 of framework d11e81eb-7ac1-42b4-b103-31129efab547-0000 3: W1126 01:52:58.634690 15943 master.cpp:2365] Dropping UNRESERVE offer operation from framework d11e81eb-7ac1-42b4-b103-31129efab547-0001 (framework2) at [email protected]:42382: cpus(allocated: role2):1; mem(allocated: role2):256; disk(allocated: role2):3701220; ports(allocated: role2):[31000-32000] does not contain mem(allocated: role2)(reservations: [(DYNAMIC,role1,test-principal)]):256 3: I1126 01:52:58.634943 15950 hierarchical.cpp:1457] Performed allocation for 1 agents in 694796ns 3: I1126 01:52:58.635834 15950 hierarchical.cpp:1132] Recovered cpus(allocated: role2):1; mem(allocated: role2):256; disk(allocated: role2):3701220; ports(allocated: role2):[31000-32000] (total: cpus:1; mem:256; disk:3701220; ports:[31000-32000]; mem(reservations: [(DYNAMIC,role1,test-principal)]):256, allocated: {}) on agent d11e81eb-7ac1-42b4-b103-31129efab547-S0 from framework d11e81eb-7ac1-42b4-b103-31129efab547-0001 3: I1126 01:52:58.641835 15949 hierarchical.cpp:2175] Filtered offer with mem(reservations: [(DYNAMIC,role1,test-principal)]):256 on agent d11e81eb-7ac1-42b4-b103-31129efab547-S0 for role role1 of framework d11e81eb-7ac1-42b4-b103-31129efab547-0000 3: I1126 01:52:58.642262 15949 hierarchical.cpp:1457] Performed allocation for 1 agents in 1.589773ms 3: I1126 01:52:58.643131 15951 master.cpp:8142] Sending 1 offers to framework d11e81eb-7ac1-42b4-b103-31129efab547-0001 (framework2) at [email protected]:42382 3: I1126 01:52:58.643877 15945 sched.cpp:921] Scheduler::resourceOffers took 123479ns 3: I1126 01:52:58.644295 15935 sched.cpp:2009] Asked to stop the driver 3: W1126 01:52:58.644340 15944 sched.cpp:1402] Attempting to decline an unknown offer d11e81eb-7ac1-42b4-b103-31129efab547-O2 3: I1126 01:52:58.644475 15944 sched.cpp:1191] Stopping framework d11e81eb-7ac1-42b4-b103-31129efab547-0001 3: I1126 01:52:58.644616 15937 master.cpp:5434] Processing DECLINE call for offers: [ d11e81eb-7ac1-42b4-b103-31129efab547-O2 ] for framework d11e81eb-7ac1-42b4-b103-31129efab547-0001 (framework2) at [email protected]:42382 3: W1126 01:52:58.644734 15937 master.cpp:5455] Ignoring decline of offer d11e81eb-7ac1-42b4-b103-31129efab547-O2 since it is no longer valid 3: I1126 01:52:58.644883 15937 master.cpp:8819] Processing TEARDOWN call for framework d11e81eb-7ac1-42b4-b103-31129efab547-0001 (framework2) at [email protected]:42382 3: I1126 01:52:58.644912 15937 master.cpp:8831] Removing framework d11e81eb-7ac1-42b4-b103-31129efab547-0001 (framework2) at [email protected]:42382 3: I1126 01:52:58.644925 15937 master.cpp:3333] Deactivating framework d11e81eb-7ac1-42b4-b103-31129efab547-0001 (framework2) at [email protected]:42382 3: I1126 01:52:58.645059 15943 hierarchical.cpp:419] Deactivated framework d11e81eb-7ac1-42b4-b103-31129efab547-0001 3: I1126 01:52:58.645437 15937 master.cpp:10063] Removing offer d11e81eb-7ac1-42b4-b103-31129efab547-O3 3: I1126 01:52:58.645794 15949 slave.cpp:3270] Asked to shut down framework d11e81eb-7ac1-42b4-b103-31129efab547-0001 by [email protected]:42382 3: I1126 01:52:58.645856 15949 slave.cpp:3285] Cannot shut down unknown framework d11e81eb-7ac1-42b4-b103-31129efab547-0001 3: I1126 01:52:58.645864 15937 master.cpp:5539] Processing REVIVE call for framework d11e81eb-7ac1-42b4-b103-31129efab547-0000 (framework1) at [email protected]:42382 3: I1126 01:52:58.645977 15950 hierarchical.cpp:1132] Recovered cpus(allocated: role2):1; mem(allocated: role2):256; disk(allocated: role2):3701220; ports(allocated: role2):[31000-32000] (total: cpus:1; mem:256; disk:3701220; ports:[31000-32000]; mem(reservations: [(DYNAMIC,role1,test-principal)]):256, allocated: {}) on agent d11e81eb-7ac1-42b4-b103-31129efab547-S0 from framework d11e81eb-7ac1-42b4-b103-31129efab547-0001 3: I1126 01:52:58.646283 15950 hierarchical.cpp:358] Removed framework d11e81eb-7ac1-42b4-b103-31129efab547-0001 3: I1126 01:52:58.646343 15950 hierarchical.cpp:1279] Revived offers for roles { role1 } of framework d11e81eb-7ac1-42b4-b103-31129efab547-0000 3: I1126 01:52:58.647459 15950 hierarchical.cpp:1457] Performed allocation for 1 agents in 1.015176ms 3: I1126 01:52:58.647992 15942 master.cpp:8142] Sending 1 offers to framework d11e81eb-7ac1-42b4-b103-31129efab547-0000 (framework1) at [email protected]:42382 3: I1126 01:52:58.648092 15938 hierarchical.cpp:1457] Performed allocation for 1 agents in 122930ns 3: I1126 01:52:58.648928 15945 sched.cpp:921] Scheduler::resourceOffers took 175676ns 3: I1126 01:52:58.649407 15935 sched.cpp:2009] Asked to stop the driver 3: I1126 01:52:58.649484 15947 sched.cpp:1191] Stopping framework d11e81eb-7ac1-42b4-b103-31129efab547-0000 3: I1126 01:52:58.649695 15946 master.cpp:8819] Processing TEARDOWN call for framework d11e81eb-7ac1-42b4-b103-31129efab547-0000 (framework1) at [email protected]:42382 3: I1126 01:52:58.649720 15946 master.cpp:8831] Removing framework d11e81eb-7ac1-42b4-b103-31129efab547-0000 (framework1) at [email protected]:42382 3: I1126 01:52:58.649730 15946 master.cpp:3333] Deactivating framework d11e81eb-7ac1-42b4-b103-31129efab547-0000 (framework1) at [email protected]:42382 3: I1126 01:52:58.649879 15940 hierarchical.cpp:419] Deactivated framework d11e81eb-7ac1-42b4-b103-31129efab547-0000 3: I1126 01:52:58.650502 15946 master.cpp:10063] Removing offer d11e81eb-7ac1-42b4-b103-31129efab547-O4 3: I1126 01:52:58.650665 15938 slave.cpp:3270] Asked to shut down framework d11e81eb-7ac1-42b4-b103-31129efab547-0000 by [email protected]:42382 3: I1126 01:52:58.650756 15938 slave.cpp:3285] Cannot shut down unknown framework d11e81eb-7ac1-42b4-b103-31129efab547-0000 3: I1126 01:52:58.651515 15938 slave.cpp:883] Agent terminating 3: I1126 01:52:58.651538 15937 hierarchical.cpp:1132] Recovered cpus(allocated: role1):1; mem(allocated: role1):256; disk(allocated: role1):3701220; ports(allocated: role1):[31000-32000]; mem(allocated: role1)(reservations: [(DYNAMIC,role1,test-principal)]):256 (total: cpus:1; mem:256; disk:3701220; ports:[31000-32000]; mem(reservations: [(DYNAMIC,role1,test-principal)]):256, allocated: {}) on agent d11e81eb-7ac1-42b4-b103-31129efab547-S0 from framework d11e81eb-7ac1-42b4-b103-31129efab547-0000 3: I1126 01:52:58.651691 15938 master.cpp:1311] Agent d11e81eb-7ac1-42b4-b103-31129efab547-S0 at slave(945)@172.17.0.3:42382 (eacc7407be8c) disconnected 3: I1126 01:52:58.651712 15938 master.cpp:3370] Disconnecting agent d11e81eb-7ac1-42b4-b103-31129efab547-S0 at slave(945)@172.17.0.3:42382 (eacc7407be8c) 3: I1126 01:52:58.651757 15938 master.cpp:3389] Deactivating agent d11e81eb-7ac1-42b4-b103-31129efab547-S0 at slave(945)@172.17.0.3:42382 (eacc7407be8c) 3: I1126 01:52:58.651816 15937 hierarchical.cpp:358] Removed framework d11e81eb-7ac1-42b4-b103-31129efab547-0000 3: I1126 01:52:58.651906 15937 hierarchical.cpp:697] Agent d11e81eb-7ac1-42b4-b103-31129efab547-S0 deactivated 3: I1126 01:52:58.653807 15936 hierarchical.cpp:1457] Performed allocation for 1 agents in 136948ns 3: I1126 01:52:58.657686 15935 master.cpp:1153] Master terminating 3: I1126 01:52:58.658417 15950 hierarchical.cpp:633] Removed agent d11e81eb-7ac1-42b4-b103-31129efab547-S0 3: I1126 01:52:58.659389 15946 hierarchical.cpp:1457] Performed allocation for 0 agents in 67315ns 3: [ OK ] ResourceProviderCapability/ReservationTest.PreventUnreservingAlienResources/1 (119 ms) 3: [----------] 36 tests from ResourceProviderCapability/ReservationTest (3458 ms total) 3: 3: [----------] Global test environment tear-down 3: [==========] 1763 tests from 181 test cases ran. (371268 ms total) 3: [ PASSED ] 1762 tests. 3: [ FAILED ] 1 test, listed below: 3: [ FAILED ] SlaveTest.RegisteredAgentReregisterAfterFailover 3: 3: 1 FAILED TEST 3: YOU HAVE 23 DISABLED TESTS 3: 3: I1126 01:52:58.741801 15952 process.cpp:887] Failed to accept socket: future discarded 3/3 Test #3: MesosTests .......................***Failed 376.03 sec 67% tests passed, 1 tests failed out of 3 Total Test time (real) = 392.94 sec The following tests FAILED: 3 - MesosTests (Failed) Errors while running CTest make[3]: *** [CMakeFiles/check] Error 8 make[3]: Leaving directory `/mesos/build' make[2]: *** [CMakeFiles/check.dir/all] Error 2 make[2]: Leaving directory `/mesos/build' make[1]: Leaving directory `/mesos/build' make[1]: *** [CMakeFiles/check.dir/rule] Error 2 make: *** [check] Error 2 + docker rmi mesos-1511659127-24329 Untagged: mesos-1511659127-24329:latest Deleted: sha256:313ed455bf168e20bd9d48be890fdd35f38497098225b69ae2c02cc2ec66f3d4 Build step 'Execute shell' marked build as failure Not sending mail to unregistered user [email protected]
