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=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4314/display/redirect?page=changes>
Changes: [jpeach] Fixed default executor handling of nested container status. ------------------------------------------ [...truncated 30.66 MB...] 3: I1012 18:03:46.194228 17251 master.cpp:1687] Recovering from registrar 3: I1012 18:03:46.194371 17257 registrar.cpp:347] Recovering registrar 3: I1012 18:03:46.194980 17257 registrar.cpp:391] Successfully fetched the registry (0B) in 0ns 3: I1012 18:03:46.195092 17257 registrar.cpp:495] Applied 1 operations in 28005ns; attempting to update the registry 3: I1012 18:03:46.195698 17257 registrar.cpp:552] Successfully updated the registry in 0ns 3: I1012 18:03:46.195807 17257 registrar.cpp:424] Successfully recovered registrar 3: I1012 18:03:46.196235 17244 master.cpp:1791] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register 3: I1012 18:03:46.196252 17253 hierarchical.cpp:209] Skipping recovery of hierarchical allocator: nothing to recover 3: W1012 18:03:46.201737 17243 process.cpp:3194] Attempted to spawn already running process [email protected]:38823 3: I1012 18:03:46.202777 17243 containerizer.cpp:292] Using isolation { environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni } 3: W1012 18:03:46.203328 17243 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges 3: W1012 18:03:46.203451 17243 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges 3: I1012 18:03:46.203491 17243 provisioner.cpp:255] Using default backend 'copy' 3: I1012 18:03:46.205420 17243 cluster.cpp:448] Creating default 'local' authorizer 3: I1012 18:03:46.207623 17253 slave.cpp:254] Mesos agent started on (824)@172.17.0.2:38823 3: I1012 18:03:46.207638 17253 slave.cpp:255] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://"; --appc_store_dir="/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_v1pL29/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/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_v1pL29/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/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_v1pL29/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/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_v1pL29/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/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_v1pL29/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="[{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":2048.0},"type":"SCALAR"},{"disk":{"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_LcihYn\/disk1"},"type":"PATH"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"},{"disk":{"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_LcihYn\/disk2"},"type":"PATH"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_v1pL29" --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/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_4tSv0L" --zk_session_timeout="10secs" 3: I1012 18:03:46.208149 17253 credentials.hpp:86] Loading credential for authentication from '/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_v1pL29/credential' 3: W1012 18:03:46.208163 17243 process.cpp:3194] Attempted to spawn already running process [email protected]:38823 3: I1012 18:03:46.208303 17253 slave.cpp:287] Agent using credential for: test-principal 3: I1012 18:03:46.208323 17253 credentials.hpp:37] Loading credentials for authentication from '/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_v1pL29/http_credentials' 3: I1012 18:03:46.208534 17253 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly' 3: I1012 18:03:46.208745 17253 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite' 3: I1012 18:03:46.209178 17243 sched.cpp:232] Version: 1.5.0 3: I1012 18:03:46.209748 17267 sched.cpp:336] New master detected at [email protected]:38823 3: I1012 18:03:46.209863 17267 sched.cpp:351] No credentials provided. Attempting to register without authentication 3: I1012 18:03:46.209877 17267 sched.cpp:820] Sending SUBSCRIBE call to [email protected]:38823 3: I1012 18:03:46.209983 17267 sched.cpp:853] Will retry registration in 1.12874584secs if necessary 3: I1012 18:03:46.210160 17265 master.cpp:2929] Received SUBSCRIBE call for framework 'default' at [email protected]:38823 3: I1012 18:03:46.210256 17265 master.cpp:2263] Authorizing framework principal 'test-principal' to receive offers for roles '{ default-role }' 3: I1012 18:03:46.210734 17249 master.cpp:3009] Subscribing framework default with checkpointing disabled and capabilities [ RESERVATION_REFINEMENT ] 3: I1012 18:03:46.211300 17262 sched.cpp:747] Framework registered with 845be154-544b-4828-8e8a-544b47e7c579-0000 3: I1012 18:03:46.211343 17262 sched.cpp:761] Scheduler::registered took 19363ns 3: I1012 18:03:46.211428 17263 hierarchical.cpp:303] Added framework 845be154-544b-4828-8e8a-544b47e7c579-0000 3: I1012 18:03:46.211585 17263 hierarchical.cpp:1943] No allocations performed 3: I1012 18:03:46.211621 17263 hierarchical.cpp:2033] No inverse offers to send out! 3: I1012 18:03:46.211653 17263 hierarchical.cpp:1486] Performed allocation for 0 agents in 115933ns 3: I1012 18:03:46.212601 17253 slave.cpp:585] Agent resources: [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":2048.0},"type":"SCALAR"},{"disk":{"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_LcihYn\/disk1"},"type":"PATH"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"},{"disk":{"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_LcihYn\/disk2"},"type":"PATH"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}] 3: I1012 18:03:46.213099 17253 slave.cpp:593] Agent attributes: [ ] 3: I1012 18:03:46.213109 17253 slave.cpp:602] Agent hostname: 196175a0675a 3: I1012 18:03:46.213286 17257 status_update_manager.cpp:177] Pausing sending status updates 3: I1012 18:03:46.215055 17264 state.cpp:64] Recovering state from '/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_4tSv0L/meta' 3: I1012 18:03:46.215399 17254 status_update_manager.cpp:203] Recovering status update manager 3: I1012 18:03:46.215564 17256 containerizer.cpp:648] Recovering containerizer 3: I1012 18:03:46.217206 17263 provisioner.cpp:416] Provisioner recovery complete 3: I1012 18:03:46.217600 17252 slave.cpp:6310] Finished recovery 3: I1012 18:03:46.217995 17252 slave.cpp:6492] Querying resource estimator for oversubscribable resources 3: I1012 18:03:46.218264 17253 status_update_manager.cpp:177] Pausing sending status updates 3: I1012 18:03:46.218271 17250 slave.cpp:993] New master detected at [email protected]:38823 3: I1012 18:03:46.218322 17250 slave.cpp:1028] Detecting new master 3: I1012 18:03:46.218441 17250 slave.cpp:6506] Received oversubscribable resources {} from the resource estimator 3: I1012 18:03:46.219874 17246 slave.cpp:1055] Authenticating with master [email protected]:38823 3: I1012 18:03:46.219929 17246 slave.cpp:1064] Using default CRAM-MD5 authenticatee 3: I1012 18:03:46.220196 17251 authenticatee.cpp:121] Creating new client SASL connection 3: I1012 18:03:46.220228 17265 hierarchical.cpp:1943] No allocations performed 3: I1012 18:03:46.220253 17265 hierarchical.cpp:2033] No inverse offers to send out! 3: I1012 18:03:46.220289 17265 hierarchical.cpp:1486] Performed allocation for 0 agents in 92913ns 3: I1012 18:03:46.220468 17260 master.cpp:7936] Authenticating slave(824)@172.17.0.2:38823 3: I1012 18:03:46.220587 17245 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1491)@172.17.0.2:38823 3: I1012 18:03:46.220837 17249 authenticator.cpp:98] Creating new server SASL connection 3: I1012 18:03:46.221063 17267 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5 3: I1012 18:03:46.221087 17267 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5' 3: I1012 18:03:46.221196 17248 authenticator.cpp:204] Received SASL authentication start 3: I1012 18:03:46.221247 17248 authenticator.cpp:326] Authentication requires more steps 3: I1012 18:03:46.221333 17248 authenticatee.cpp:259] Received SASL authentication step 3: I1012 18:03:46.221427 17248 authenticator.cpp:232] Received SASL authentication step 3: I1012 18:03:46.221451 17248 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '196175a0675a' server FQDN: '196175a0675a' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 3: I1012 18:03:46.221462 17248 auxprop.cpp:181] Looking up auxiliary property '*userPassword' 3: I1012 18:03:46.221495 17248 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' 3: I1012 18:03:46.221513 17248 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '196175a0675a' server FQDN: '196175a0675a' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 3: I1012 18:03:46.221521 17248 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true 3: I1012 18:03:46.221525 17248 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true 3: I1012 18:03:46.221537 17248 authenticator.cpp:318] Authentication success 3: I1012 18:03:46.221621 17266 authenticatee.cpp:299] Authentication success 3: I1012 18:03:46.221681 17244 master.cpp:7966] Successfully authenticated principal 'test-principal' at slave(824)@172.17.0.2:38823 3: I1012 18:03:46.221741 17262 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1491)@172.17.0.2:38823 3: I1012 18:03:46.221941 17248 slave.cpp:1147] Successfully authenticated with master [email protected]:38823 3: I1012 18:03:46.222252 17248 slave.cpp:1626] Will retry registration in 8.397794ms if necessary 3: I1012 18:03:46.222481 17258 master.cpp:5801] Received register agent message from slave(824)@172.17.0.2:38823 (196175a0675a) 3: I1012 18:03:46.222635 17258 master.cpp:3838] Authorizing agent with principal 'test-principal' 3: I1012 18:03:46.223110 17250 master.cpp:5861] Authorized registration of agent at slave(824)@172.17.0.2:38823 (196175a0675a) 3: I1012 18:03:46.223284 17250 master.cpp:5954] Registering agent at slave(824)@172.17.0.2:38823 (196175a0675a) with id 845be154-544b-4828-8e8a-544b47e7c579-S0 3: I1012 18:03:46.223806 17264 registrar.cpp:495] Applied 1 operations in 68702ns; attempting to update the registry 3: I1012 18:03:46.224401 17264 registrar.cpp:552] Successfully updated the registry in 0ns 3: I1012 18:03:46.224622 17265 master.cpp:6001] Admitted agent 845be154-544b-4828-8e8a-544b47e7c579-S0 at slave(824)@172.17.0.2:38823 (196175a0675a) 3: I1012 18:03:46.225257 17245 slave.cpp:4966] Received ping from slave-observer(741)@172.17.0.2:38823 3: I1012 18:03:46.225494 17256 slave.cpp:1193] Registered with master [email protected]:38823; given agent ID 845be154-544b-4828-8e8a-544b47e7c579-S0 3: I1012 18:03:46.225621 17247 status_update_manager.cpp:184] Resuming sending status updates 3: I1012 18:03:46.225769 17256 slave.cpp:1213] Checkpointing SlaveInfo to '/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_4tSv0L/meta/slaves/845be154-544b-4828-8e8a-544b47e7c579-S0/slave.info' 3: I1012 18:03:46.225409 17265 master.cpp:6032] Registered agent 845be154-544b-4828-8e8a-544b47e7c579-S0 at slave(824)@172.17.0.2:38823 (196175a0675a) with [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":2048.0},"type":"SCALAR"},{"disk":{"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_LcihYn\/disk1"},"type":"PATH"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"},{"disk":{"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_LcihYn\/disk2"},"type":"PATH"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}] 3: I1012 18:03:46.225939 17245 hierarchical.cpp:593] Added agent 845be154-544b-4828-8e8a-544b47e7c579-S0 (196175a0675a) with cpus:2; mem:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_LcihYn/disk1]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_LcihYn/disk2]:2048; ports:[31000-32000] (allocated: {}) 3: I1012 18:03:46.226155 17256 slave.cpp:1262] Forwarding total oversubscribed resources {} 3: I1012 18:03:46.226327 17256 master.cpp:6817] Received update of agent 845be154-544b-4828-8e8a-544b47e7c579-S0 at slave(824)@172.17.0.2:38823 (196175a0675a) with total oversubscribed resources {} 3: I1012 18:03:46.226555 17256 master.cpp:6828] Ignoring update on agent 845be154-544b-4828-8e8a-544b47e7c579-S0 at slave(824)@172.17.0.2:38823 (196175a0675a) as it reports no changes 3: I1012 18:03:46.227722 17245 hierarchical.cpp:2033] No inverse offers to send out! 3: I1012 18:03:46.227761 17245 hierarchical.cpp:1486] Performed allocation for 1 agents in 1.626432ms 3: I1012 18:03:46.228569 17266 master.cpp:7766] Sending 1 offers to framework 845be154-544b-4828-8e8a-544b47e7c579-0000 (default) at [email protected]:38823 3: I1012 18:03:46.229209 17244 sched.cpp:917] Scheduler::resourceOffers took 115673ns 3: I1012 18:03:46.231102 17252 master.cpp:9389] Removing offer 845be154-544b-4828-8e8a-544b47e7c579-O0 3: I1012 18:03:46.231230 17252 master.cpp:4196] Processing ACCEPT call for offers: [ 845be154-544b-4828-8e8a-544b47e7c579-O0 ] on agent 845be154-544b-4828-8e8a-544b47e7c579-S0 at slave(824)@172.17.0.2:38823 (196175a0675a) for framework 845be154-544b-4828-8e8a-544b47e7c579-0000 (default) at [email protected]:38823 3: I1012 18:03:46.231384 17252 master.cpp:3758] Authorizing principal 'test-principal' to create volumes '[{"allocation_info":{"role":"default-role"},"disk":{"persistence":{"id":"id1","principal":"test-principal"},"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_LcihYn\/disk1"},"type":"PATH"},"volume":{"container_path":"path1","mode":"RW"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]' 3: W1012 18:03:46.232460 17252 master.cpp:2348] Dropping CREATE offer operation from framework 845be154-544b-4828-8e8a-544b47e7c579-0000 (default) at [email protected]:38823: Not authorized to create persistent volumes as 'test-principal' 3: I1012 18:03:46.233903 17253 hierarchical.cpp:1161] Recovered cpus(allocated: default-role):2; mem(allocated: default-role):2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_LcihYn/disk1]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_LcihYn/disk2]:2048; ports(allocated: default-role):[31000-32000] (total: cpus:2; mem:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_LcihYn/disk1]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_LcihYn/disk2]:2048; ports:[31000-32000], allocated: {}) on agent 845be154-544b-4828-8e8a-544b47e7c579-S0 from framework 845be154-544b-4828-8e8a-544b47e7c579-0000 3: I1012 18:03:46.237026 17261 hierarchical.cpp:2033] No inverse offers to send out! 3: I1012 18:03:46.237071 17261 hierarchical.cpp:1486] Performed allocation for 1 agents in 1.518545ms 3: I1012 18:03:46.237751 17246 master.cpp:7766] Sending 1 offers to framework 845be154-544b-4828-8e8a-544b47e7c579-0000 (default) at [email protected]:38823 3: I1012 18:03:46.238365 17251 sched.cpp:917] Scheduler::resourceOffers took 167194ns 3: W1012 18:03:46.239084 17243 process.cpp:3194] Attempted to spawn already running process [email protected]:38823 3: I1012 18:03:46.239226 17260 master.cpp:5207] Processing DECLINE call for offers: [ 845be154-544b-4828-8e8a-544b47e7c579-O1 ] for framework 845be154-544b-4828-8e8a-544b47e7c579-0000 (default) at [email protected]:38823 3: I1012 18:03:46.239742 17243 sched.cpp:232] Version: 1.5.0 3: I1012 18:03:46.240103 17260 master.cpp:9389] Removing offer 845be154-544b-4828-8e8a-544b47e7c579-O1 3: I1012 18:03:46.240228 17256 sched.cpp:336] New master detected at [email protected]:38823 3: I1012 18:03:46.240244 17260 master.cpp:3439] Processing SUPPRESS call for framework 845be154-544b-4828-8e8a-544b47e7c579-0000 (default) at [email protected]:38823 3: I1012 18:03:46.240310 17256 sched.cpp:351] No credentials provided. Attempting to register without authentication 3: I1012 18:03:46.240324 17256 sched.cpp:820] Sending SUBSCRIBE call to [email protected]:38823 3: I1012 18:03:46.240419 17256 sched.cpp:853] Will retry registration in 1.886654054secs if necessary 3: I1012 18:03:46.240591 17245 master.cpp:2929] Received SUBSCRIBE call for framework 'creator-framework' at [email protected]:38823 3: I1012 18:03:46.240658 17245 master.cpp:2263] Authorizing framework principal 'creator-principal' to receive offers for roles '{ default-role }' 3: I1012 18:03:46.240813 17249 hierarchical.cpp:1161] Recovered cpus(allocated: default-role):2; mem(allocated: default-role):2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_LcihYn/disk1]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_LcihYn/disk2]:2048; ports(allocated: default-role):[31000-32000] (total: cpus:2; mem:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_LcihYn/disk1]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_LcihYn/disk2]:2048; ports:[31000-32000], allocated: {}) on agent 845be154-544b-4828-8e8a-544b47e7c579-S0 from framework 845be154-544b-4828-8e8a-544b47e7c579-0000 3: I1012 18:03:46.240996 17249 hierarchical.cpp:1273] Suppressed offers for roles { default-role } of framework 845be154-544b-4828-8e8a-544b47e7c579-0000 3: I1012 18:03:46.241061 17249 master.cpp:3009] Subscribing framework creator-framework with checkpointing disabled and capabilities [ RESERVATION_REFINEMENT ] 3: I1012 18:03:46.241482 17257 hierarchical.cpp:303] Added framework 845be154-544b-4828-8e8a-544b47e7c579-0001 3: I1012 18:03:46.241631 17262 sched.cpp:747] Framework registered with 845be154-544b-4828-8e8a-544b47e7c579-0001 3: I1012 18:03:46.241683 17262 sched.cpp:761] Scheduler::registered took 19432ns 3: I1012 18:03:46.243240 17257 hierarchical.cpp:2033] No inverse offers to send out! 3: I1012 18:03:46.243280 17257 hierarchical.cpp:1486] Performed allocation for 1 agents in 1.655027ms 3: I1012 18:03:46.243976 17248 master.cpp:7766] Sending 1 offers to framework 845be154-544b-4828-8e8a-544b47e7c579-0001 (creator-framework) at [email protected]:38823 3: I1012 18:03:46.244469 17258 sched.cpp:917] Scheduler::resourceOffers took 104562ns 3: I1012 18:03:46.246377 17253 hierarchical.cpp:1943] No allocations performed 3: I1012 18:03:46.246435 17253 hierarchical.cpp:2033] No inverse offers to send out! 3: I1012 18:03:46.246472 17253 hierarchical.cpp:1486] Performed allocation for 1 agents in 211003ns 3: I1012 18:03:46.248263 17254 master.cpp:9389] Removing offer 845be154-544b-4828-8e8a-544b47e7c579-O2 3: I1012 18:03:46.248383 17254 master.cpp:4196] Processing ACCEPT call for offers: [ 845be154-544b-4828-8e8a-544b47e7c579-O2 ] on agent 845be154-544b-4828-8e8a-544b47e7c579-S0 at slave(824)@172.17.0.2:38823 (196175a0675a) for framework 845be154-544b-4828-8e8a-544b47e7c579-0001 (creator-framework) at [email protected]:38823 3: I1012 18:03:46.248527 17254 master.cpp:3758] Authorizing principal 'creator-principal' to create volumes '[{"allocation_info":{"role":"default-role"},"disk":{"persistence":{"id":"id1","principal":"creator-principal"},"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_LcihYn\/disk1"},"type":"PATH"},"volume":{"container_path":"path1","mode":"RW"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]' 3: I1012 18:03:46.249945 17254 master.cpp:4639] Applying CREATE operation for volumes [{"allocation_info":{"role":"default-role"},"disk":{"persistence":{"id":"id1","principal":"creator-principal"},"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_LcihYn\/disk1"},"type":"PATH"},"volume":{"container_path":"path1","mode":"RW"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}] from framework 845be154-544b-4828-8e8a-544b47e7c579-0001 (creator-framework) at [email protected]:38823 to agent 845be154-544b-4828-8e8a-544b47e7c579-S0 at slave(824)@172.17.0.2:38823 (196175a0675a) 3: I1012 18:03:46.250530 17254 master.cpp:9335] Sending updated checkpointed resources disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_LcihYn/disk1,id1:path1]:2048 to agent 845be154-544b-4828-8e8a-544b47e7c579-S0 at slave(824)@172.17.0.2:38823 (196175a0675a) 3: I1012 18:03:46.251674 17265 slave.cpp:3519] Updated checkpointed resources from {} to disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_LcihYn/disk1,id1:path1]:2048 3: I1012 18:03:46.254642 17247 hierarchical.cpp:887] Updated allocation of framework 845be154-544b-4828-8e8a-544b47e7c579-0001 on agent 845be154-544b-4828-8e8a-544b47e7c579-S0 from cpus(allocated: default-role):2; mem(allocated: default-role):2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_LcihYn/disk1]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_LcihYn/disk2]:2048; ports(allocated: default-role):[31000-32000] to cpus(allocated: default-role):2; mem(allocated: default-role):2048; ports(allocated: default-role):[31000-32000]; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_LcihYn/disk2]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_LcihYn/disk1,id1:path1]:2048 3: I1012 18:03:46.256487 17247 hierarchical.cpp:1161] Recovered cpus(allocated: default-role):2; mem(allocated: default-role):2048; ports(allocated: default-role):[31000-32000]; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_LcihYn/disk2]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_LcihYn/disk1,id1:path1]:2048 (total: cpus:2; mem:2048; ports:[31000-32000]; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_LcihYn/disk2]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_LcihYn/disk1,id1:path1]:2048, allocated: {}) on agent 845be154-544b-4828-8e8a-544b47e7c579-S0 from framework 845be154-544b-4828-8e8a-544b47e7c579-0001 3: I1012 18:03:46.259829 17256 hierarchical.cpp:2033] No inverse offers to send out! 3: I1012 18:03:46.259876 17256 hierarchical.cpp:1486] Performed allocation for 1 agents in 1.548251ms 3: I1012 18:03:46.260643 17266 master.cpp:7766] Sending 1 offers to framework 845be154-544b-4828-8e8a-544b47e7c579-0001 (creator-framework) at [email protected]:38823 3: I1012 18:03:46.261288 17245 sched.cpp:917] Scheduler::resourceOffers took 133161ns 3: I1012 18:03:46.262054 17244 master.cpp:5207] Processing DECLINE call for offers: [ 845be154-544b-4828-8e8a-544b47e7c579-O3 ] for framework 845be154-544b-4828-8e8a-544b47e7c579-0001 (creator-framework) at [email protected]:38823 3: I1012 18:03:46.263268 17244 master.cpp:9389] Removing offer 845be154-544b-4828-8e8a-544b47e7c579-O3 3: I1012 18:03:46.263447 17244 master.cpp:3439] Processing SUPPRESS call for framework 845be154-544b-4828-8e8a-544b47e7c579-0001 (creator-framework) at [email protected]:38823 3: I1012 18:03:46.264155 17255 hierarchical.cpp:1161] Recovered cpus(allocated: default-role):2; mem(allocated: default-role):2048; ports(allocated: default-role):[31000-32000]; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_LcihYn/disk2]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_LcihYn/disk1,id1:path1]:2048 (total: cpus:2; mem:2048; ports:[31000-32000]; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_LcihYn/disk2]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_LcihYn/disk1,id1:path1]:2048, allocated: {}) on agent 845be154-544b-4828-8e8a-544b47e7c579-S0 from framework 845be154-544b-4828-8e8a-544b47e7c579-0001 3: I1012 18:03:46.264360 17255 hierarchical.cpp:1273] Suppressed offers for roles { default-role } of framework 845be154-544b-4828-8e8a-544b47e7c579-0001 3: I1012 18:03:46.264693 17249 master.cpp:5312] Processing REVIVE call for framework 845be154-544b-4828-8e8a-544b47e7c579-0000 (default) at [email protected]:38823 3: I1012 18:03:46.264849 17257 hierarchical.cpp:1308] Revived offers for roles { default-role } of framework 845be154-544b-4828-8e8a-544b47e7c579-0000 3: I1012 18:03:46.266492 17257 hierarchical.cpp:2033] No inverse offers to send out! 3: I1012 18:03:46.266540 17257 hierarchical.cpp:1486] Performed allocation for 1 agents in 1.580762ms 3: I1012 18:03:46.267309 17248 master.cpp:7766] Sending 1 offers to framework 845be154-544b-4828-8e8a-544b47e7c579-0000 (default) at [email protected]:38823 3: I1012 18:03:46.267818 17258 sched.cpp:917] Scheduler::resourceOffers took 99524ns 3: I1012 18:03:46.269423 17252 master.cpp:9389] Removing offer 845be154-544b-4828-8e8a-544b47e7c579-O4 3: I1012 18:03:46.269542 17252 master.cpp:4196] Processing ACCEPT call for offers: [ 845be154-544b-4828-8e8a-544b47e7c579-O4 ] on agent 845be154-544b-4828-8e8a-544b47e7c579-S0 at slave(824)@172.17.0.2:38823 (196175a0675a) for framework 845be154-544b-4828-8e8a-544b47e7c579-0000 (default) at [email protected]:38823 3: I1012 18:03:46.269680 17252 master.cpp:3810] Authorizing principal 'test-principal' to destroy volumes '[{"allocation_info":{"role":"default-role"},"disk":{"persistence":{"id":"id1","principal":"creator-principal"},"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_LcihYn\/disk1"},"type":"PATH"},"volume":{"container_path":"path1","mode":"RW"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]' 3: W1012 18:03:46.270678 17252 master.cpp:2348] Dropping DESTROY offer operation from framework 845be154-544b-4828-8e8a-544b47e7c579-0000 (default) at [email protected]:38823: Not authorized to destroy persistent volumes as 'test-principal' 3: I1012 18:03:46.272683 17246 hierarchical.cpp:1161] Recovered cpus(allocated: default-role):2; mem(allocated: default-role):2048; ports(allocated: default-role):[31000-32000]; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_LcihYn/disk2]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_LcihYn/disk1,id1:path1]:2048 (total: cpus:2; mem:2048; ports:[31000-32000]; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_LcihYn/disk2]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_LcihYn/disk1,id1:path1]:2048, allocated: {}) on agent 845be154-544b-4828-8e8a-544b47e7c579-S0 from framework 845be154-544b-4828-8e8a-544b47e7c579-0000 3: I1012 18:03:46.276001 17265 hierarchical.cpp:2033] No inverse offers to send out! 3: I1012 18:03:46.276049 17265 hierarchical.cpp:1486] Performed allocation for 1 agents in 1.536786ms 3: I1012 18:03:46.276754 17247 master.cpp:7766] Sending 1 offers to framework 845be154-544b-4828-8e8a-544b47e7c579-0000 (default) at [email protected]:38823 3: I1012 18:03:46.277310 17263 sched.cpp:917] Scheduler::resourceOffers took 107315ns 3: I1012 18:03:46.277634 17243 sched.cpp:2005] Asked to stop the driver 3: I1012 18:03:46.277727 17260 sched.cpp:1187] Stopping framework 845be154-544b-4828-8e8a-544b47e7c579-0000 3: I1012 18:03:46.277976 17256 master.cpp:8447] Processing TEARDOWN call for framework 845be154-544b-4828-8e8a-544b47e7c579-0000 (default) at [email protected]:38823 3: I1012 18:03:46.278004 17256 master.cpp:8459] Removing framework 845be154-544b-4828-8e8a-544b47e7c579-0000 (default) at [email protected]:38823 3: I1012 18:03:46.278015 17256 master.cpp:3299] Deactivating framework 845be154-544b-4828-8e8a-544b47e7c579-0000 (default) at [email protected]:38823 3: I1012 18:03:46.278098 17243 sched.cpp:2005] Asked to stop the driver 3: I1012 18:03:46.278103 17245 hierarchical.cpp:412] Deactivated framework 845be154-544b-4828-8e8a-544b47e7c579-0000 3: I1012 18:03:46.278206 17244 sched.cpp:1187] Stopping framework 845be154-544b-4828-8e8a-544b47e7c579-0001 3: I1012 18:03:46.278957 17256 master.cpp:9389] Removing offer 845be154-544b-4828-8e8a-544b47e7c579-O5 3: I1012 18:03:46.279188 17261 slave.cpp:3211] Asked to shut down framework 845be154-544b-4828-8e8a-544b47e7c579-0000 by [email protected]:38823 3: I1012 18:03:46.279218 17261 slave.cpp:3226] Cannot shut down unknown framework 845be154-544b-4828-8e8a-544b47e7c579-0000 3: I1012 18:03:46.279405 17256 master.cpp:8447] Processing TEARDOWN call for framework 845be154-544b-4828-8e8a-544b47e7c579-0001 (creator-framework) at [email protected]:38823 3: I1012 18:03:46.279428 17256 master.cpp:8459] Removing framework 845be154-544b-4828-8e8a-544b47e7c579-0001 (creator-framework) at [email protected]:38823 3: I1012 18:03:46.279438 17256 master.cpp:3299] Deactivating framework 845be154-544b-4828-8e8a-544b47e7c579-0001 (creator-framework) at [email protected]:38823 3: I1012 18:03:46.279645 17247 slave.cpp:3211] Asked to shut down framework 845be154-544b-4828-8e8a-544b47e7c579-0001 by [email protected]:38823 3: I1012 18:03:46.279673 17247 slave.cpp:3226] Cannot shut down unknown framework 845be154-544b-4828-8e8a-544b47e7c579-0001 3: I1012 18:03:46.279817 17255 hierarchical.cpp:1161] Recovered cpus(allocated: default-role):2; mem(allocated: default-role):2048; ports(allocated: default-role):[31000-32000]; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_LcihYn/disk2]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_LcihYn/disk1,id1:path1]:2048 (total: cpus:2; mem:2048; ports:[31000-32000]; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_LcihYn/disk2]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_LcihYn/disk1,id1:path1]:2048, allocated: {}) on agent 845be154-544b-4828-8e8a-544b47e7c579-S0 from framework 845be154-544b-4828-8e8a-544b47e7c579-0000 3: I1012 18:03:46.280031 17255 hierarchical.cpp:355] Removed framework 845be154-544b-4828-8e8a-544b47e7c579-0000 3: I1012 18:03:46.280084 17255 hierarchical.cpp:412] Deactivated framework 845be154-544b-4828-8e8a-544b47e7c579-0001 3: I1012 18:03:46.280285 17255 hierarchical.cpp:355] Removed framework 845be154-544b-4828-8e8a-544b47e7c579-0001 3: I1012 18:03:46.280557 17243 slave.cpp:869] Agent terminating 3: I1012 18:03:46.280735 17249 master.cpp:1303] Agent 845be154-544b-4828-8e8a-544b47e7c579-S0 at slave(824)@172.17.0.2:38823 (196175a0675a) disconnected 3: I1012 18:03:46.280763 17249 master.cpp:3336] Disconnecting agent 845be154-544b-4828-8e8a-544b47e7c579-S0 at slave(824)@172.17.0.2:38823 (196175a0675a) 3: I1012 18:03:46.280824 17249 master.cpp:3355] Deactivating agent 845be154-544b-4828-8e8a-544b47e7c579-S0 at slave(824)@172.17.0.2:38823 (196175a0675a) 3: I1012 18:03:46.280902 17248 hierarchical.cpp:690] Agent 845be154-544b-4828-8e8a-544b47e7c579-S0 deactivated 3: I1012 18:03:46.286562 17243 master.cpp:1145] Master terminating 3: I1012 18:03:46.287513 17255 hierarchical.cpp:626] Removed agent 845be154-544b-4828-8e8a-544b47e7c579-S0 3: [ OK ] DiskResource/PersistentVolumeTest.BadACLDropCreateAndDestroy/1 (108 ms) 3: [----------] 34 tests from DiskResource/PersistentVolumeTest (11496 ms total) 3: 3: [----------] Global test environment tear-down 3: [==========] 1648 tests from 178 test cases ran. (368674 ms total) 3: [ PASSED ] 1647 tests. 3: [ FAILED ] 1 test, listed below: 3: [ FAILED ] MasterTest.IgnoreOldAgentReregistration 3: 3: 1 FAILED TEST 3: YOU HAVE 21 DISABLED TESTS 3: 3: I1012 18:03:46.351723 17268 process.cpp:1068] Failed to accept socket: future discarded 3/3 Test #3: MesosTests .......................***Failed 373.10 sec 67% tests passed, 1 tests failed out of 3 Total Test time (real) = 393.12 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-1507829240-24171 Untagged: mesos-1507829240-24171:latest Deleted: sha256:97d9d166ded68598c739ae0c403401e3246d55bf542d2bfbc14758d5dffe410b Deleted: sha256:b7fb224e0b9e7d603bdd09102bb468b48449c6e9d400eb5c5e12b3dc66d93845 Deleted: sha256:d6478620081fa02da235173b245b63318b5e62092eef8918bb97bb72ec974d28 Deleted: sha256:43c1a35612029ad544f8a7c5fb14415cb3f839ac08be97438e8af762cdebc39d Deleted: sha256:e7aa298998d83692545249e1816be8554636236f2f3d6a433f18f2dd653f966c Deleted: sha256:8a897d49a1c952e30ee8666a579fcc1d0ad36e118ebf59c9cbe00e7d3b41c6b4 Deleted: sha256:ed0f7c46ffffd4fe5ff6193f2e563fb30daa45236688c0b63c884f7c6bf79a8a Deleted: sha256:09147606fa7354f18694c2427fc4ec82ce35628afec0a2463275fdc2cc986a99 Deleted: sha256:72be86143a2bdb22326fcda16bfa64ed01e643e25111cd35033db8498c58829a Deleted: sha256:fb95266736dea5f0df18289a6217918e756a748ff37487e99f62a45ae99b0b71 Deleted: sha256:294f07b0a888fb1159656772e5809b5267731e6a60e15b3c8d61330f4ecb2f87 Deleted: sha256:447036ffbbf773c89179358f5137039ca0d1919bc195546ddfc16a3e659ff250 Deleted: sha256:ce4f3469c5b1a233a546e316ac32b1931aeb75c6b960c7e8a3bc74c519c7d5f3 Deleted: sha256:81c9be22e6eb635f64b72defd3725b8c86563aba911bf2cc5615b5a7fb8400aa Deleted: sha256:47a3df4c4773d75c9a8977d05a519f14cfeb195e40269a7edba93ce29cffbf75 Deleted: sha256:88a88d5d89cf2f817601a5420f6e5c03633f969868b02b8adc132191258162c7 Deleted: sha256:b8cc38ff3a956f6f43c5b03748f08dfd76f4c8fd439c75252ffa638b2946889e Deleted: sha256:a2abd803ba47851768278d34a37b229f5b47622c604d2eb1c7a9d62f9686f6bb Deleted: sha256:23178122544a65c9fee1baa773b6d0427544f3cb35514187ad89facbe3e26a83 Deleted: sha256:f48a3cd1b12a216decd10d9fb180e6872a31d5a671af132f7ee7e5576e0dc3a8 Deleted: sha256:9fc79a8e81a68ee461ed2f39af154c3081bac3788c70c400cf8a15851d1181fc Deleted: sha256:b3e906b7ba16c0dae8bb75f6f13f8a4262d16317f9b8bb973da17196e43bab8a Deleted: sha256:b7e9f26d33a8ca4303715a328cb5ad8d33b12ac2dd12ae8c89353b1b38547186 Deleted: sha256:4896e9a7ba40a4238e6efc6df1feca02478ed5106937f25e41cf3a6a579921cb Deleted: sha256:e1da9b4dd3b2b9804a20b10fade21859fa22ea60c7e6f391906d00f5bbb90fa9 Deleted: sha256:13a21816ecd4718c4a07cac03ff7598d7f15fb027882001a93219d716c9e8e91 Deleted: sha256:98b2c7b7fc8b1f676af33d848ce1c1ec55a888a45edc3753459d5a6d6cd80ec0 Deleted: sha256:59c5d1e77b836833290f6c9c896ae5d35b30dad4cd041dd44d6e77cf2d78d2b3 Build step 'Execute shell' marked build as failure
