mesos-reviews mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Mesos Reviewbot <revi...@mesos.apache.org>
Subject Re: Review Request 72887: Tied the hierarchical allocator process to a fixed process ID.
Date Fri, 18 Sep 2020 20:32:38 GMT

-----------------------------------------------------------
This is an automatically generated e-mail. To reply, visit:
https://reviews.apache.org/r/72887/#review221899
-----------------------------------------------------------



Bad patch!

Reviews applied: [72885, 72886, 72851, 72887]

Failed command: ['bash', '-c', "set -o pipefail; export OS='ubuntu:16.04' BUILDTOOL='autotools' COMPILER='gcc' CONFIGURATION='--verbose --disable-libtool-wrappers --disable-parallel-test-execution' ENVIRONMENT='GLOG_v=1 MESOS_VERBOSE=1'; ./support/jenkins/buildbot.sh 2>&1 | tee build_72887"]

Error:
...<truncated>...
ue":"172.17.0.2:45039"},{"name":"MESOS_CHECKPOINT","type":"VALUE","value":"0"},{"name":"MESOS_DIRECTORY","type":"VALUE","value":"/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_0_USZJNk/slaves/631a393a-530c-4374-9764-2d1cfbe6a79c-S0/frameworks/631a393a-530c-4374-9764-2d1cfbe6a79c-0000/executors/5e7a5945-d20c-484b-8b1b-4b19ed3e8a07/runs/da197407-66c8-4f09-a0e2-db1ca2b32c87"},{"name":"MESOS_EXECUTOR_ID","type":"VALUE","value":"5e7a5945-d20c-484b-8b1b-4b19ed3e8a07"},{"name":"MESOS_EXECUTOR_SHUTDOWN_GRACE_PERIOD","type":"VALUE","value":"5secs"},{"name":"MESOS_FRAMEWORK_ID","type":"VALUE","value":"631a393a-530c-4374-9764-2d1cfbe6a79c-0000"},{"name":"MESOS_HTTP_COMMAND_EXECUTOR","type":"VALUE","value":"0"},{"name":"MESOS_SLAVE_ID","type":"VALUE","value":"631a393a-530c-4374-9764-2d1cfbe6a79c-S0"},{"name":"MESOS_SLAVE_PID","type":"VALUE","value":"slave(1093)@172.17.0.2:45039"},{"name":"MESOS_SANDBOX","type":"VALUE","value":"/tmp/DiskResource_PersistentVolumeTest
 _SharedPersistentVolumeRescindOnDestroy_0_USZJNk/slaves/631a393a-530c-4374-9764-2d1cfbe6a79c-S0/frameworks/631a393a-530c-4374-9764-2d1cfbe6a79c-0000/executors/5e7a5945-d20c-484b-8b1b-4b19ed3e8a07/runs/da197407-66c8-4f09-a0e2-db1ca2b32c87"}]},"task_environment":{},"user":"mesos","working_directory":"/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_0_USZJNk/slaves/631a393a-530c-4374-9764-2d1cfbe6a79c-S0/frameworks/631a393a-530c-4374-9764-2d1cfbe6a79c-0000/executors/5e7a5945-d20c-484b-8b1b-4b19ed3e8a07/runs/da197407-66c8-4f09-a0e2-db1ca2b32c87"}" --pipe_read="33" --pipe_write="75" --runtime_directory="/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_0_fl51yp/containers/da197407-66c8-4f09-a0e2-db1ca2b32c87" --unshare_namespace_mnt="false"'
I0918 20:32:17.119801  5514 launcher.cpp:145] Forked child with pid '18633' for container 'da197407-66c8-4f09-a0e2-db1ca2b32c87'
I0918 20:32:17.120762  5514 containerizer.cpp:3414] Transitioning the state of container da197407-66c8-4f09-a0e2-db1ca2b32c87 from PREPARING to ISOLATING after 0ns
I0918 20:32:17.124999  5533 containerizer.cpp:3414] Transitioning the state of container da197407-66c8-4f09-a0e2-db1ca2b32c87 from ISOLATING to FETCHING after 0ns
I0918 20:32:17.125757  5518 fetcher.cpp:369] Starting to fetch URIs for container: da197407-66c8-4f09-a0e2-db1ca2b32c87, directory: /tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_0_USZJNk/slaves/631a393a-530c-4374-9764-2d1cfbe6a79c-S0/frameworks/631a393a-530c-4374-9764-2d1cfbe6a79c-0000/executors/5e7a5945-d20c-484b-8b1b-4b19ed3e8a07/runs/da197407-66c8-4f09-a0e2-db1ca2b32c87
I0918 20:32:17.127964  5528 containerizer.cpp:3414] Transitioning the state of container da197407-66c8-4f09-a0e2-db1ca2b32c87 from FETCHING to RUNNING after 0ns
I0918 20:32:17.183001  5523 status_update_manager_process.hpp:528] Forwarding operation status update OPERATION_FINISHED (Status UUID: 19e67bb0-0806-41d9-8697-5000a87755fb) for operation UUID ba7e157c-7530-46f2-8df1-987b215a1bb1 of framework '631a393a-530c-4374-9764-2d1cfbe6a79c-0000' on agent 631a393a-530c-4374-9764-2d1cfbe6a79c-S0
I0918 20:32:17.183495  5530 slave.cpp:9186] Updating the state of operation with no ID (uuid: ba7e157c-7530-46f2-8df1-987b215a1bb1) for framework 631a393a-530c-4374-9764-2d1cfbe6a79c-0000 (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)
I0918 20:32:17.183563  5530 slave.cpp:6577] Forwarding status update of operation with no ID (operation_uuid: ba7e157c-7530-46f2-8df1-987b215a1bb1) for framework 631a393a-530c-4374-9764-2d1cfbe6a79c-0000
I0918 20:32:17.184068  5532 master.cpp:11405] Updating the state of operation '' (uuid: ba7e157c-7530-46f2-8df1-987b215a1bb1) for framework 631a393a-530c-4374-9764-2d1cfbe6a79c-0000 (latest state: OPERATION_PENDING, status update state: OPERATION_FINISHED)
I0918 20:32:17.185118  5515 status_update_manager_process.hpp:252] Received operation status update acknowledgement (UUID: 19e67bb0-0806-41d9-8697-5000a87755fb) for stream ba7e157c-7530-46f2-8df1-987b215a1bb1
I0918 20:32:17.185218  5515 status_update_manager_process.hpp:929] Checkpointing ACK for operation status update OPERATION_FINISHED (Status UUID: 19e67bb0-0806-41d9-8697-5000a87755fb) for operation UUID ba7e157c-7530-46f2-8df1-987b215a1bb1 of framework '631a393a-530c-4374-9764-2d1cfbe6a79c-0000' on agent 631a393a-530c-4374-9764-2d1cfbe6a79c-S0
I0918 20:32:17.249274  5515 status_update_manager_process.hpp:490] Cleaning up operation status update stream ba7e157c-7530-46f2-8df1-987b215a1bb1
I0918 20:32:17.251127  5513 slave.cpp:4688] Updated checkpointed operations from [ ba7e157c-7530-46f2-8df1-987b215a1bb1 (CREATE for framework 631a393a-530c-4374-9764-2d1cfbe6a79c-0000, latest state: OPERATION_FINISHED) ] to [  ]
W0918 20:32:17.252677  5510 process.cpp:2877] Attempted to spawn already running process version@172.17.0.2:45039
I0918 20:32:17.254159  5510 sched.cpp:239] Version: 1.11.0
I0918 20:32:17.255802  5525 sched.cpp:343] New master detected at master@172.17.0.2:45039
I0918 20:32:17.256237  5525 sched.cpp:408] Authenticating with master master@172.17.0.2:45039
I0918 20:32:17.256283  5525 sched.cpp:415] Using default CRAM-MD5 authenticatee
I0918 20:32:17.257333  5527 authenticatee.cpp:121] Creating new client SASL connection
I0918 20:32:17.258026  5516 master.cpp:9780] Authenticating scheduler-8cb3b4db-70cd-49f5-ac7b-8eeba348471b@172.17.0.2:45039
I0918 20:32:17.258428  5526 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1888)@172.17.0.2:45039
I0918 20:32:17.259106  5533 authenticator.cpp:98] Creating new server SASL connection
I0918 20:32:17.259575  5518 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0918 20:32:17.259652  5518 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0918 20:32:17.259948  5529 authenticator.cpp:204] Received SASL authentication start
I0918 20:32:17.260083  5529 authenticator.cpp:326] Authentication requires more steps
I0918 20:32:17.260412  5534 authenticatee.cpp:259] Received SASL authentication step
I0918 20:32:17.260731  5528 authenticator.cpp:232] Received SASL authentication step
I0918 20:32:17.260810  5528 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '1c4e7fa2a761' server FQDN: '1c4e7fa2a761' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
I0918 20:32:17.260854  5528 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0918 20:32:17.260988  5528 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0918 20:32:17.261061  5528 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '1c4e7fa2a761' server FQDN: '1c4e7fa2a761' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
I0918 20:32:17.261096  5528 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0918 20:32:17.261124  5528 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0918 20:32:17.261173  5528 authenticator.cpp:318] Authentication success
I0918 20:32:17.261446  5521 authenticatee.cpp:299] Authentication success
I0918 20:32:17.261654  5524 master.cpp:9812] Successfully authenticated principal 'test-principal' at scheduler-8cb3b4db-70cd-49f5-ac7b-8eeba348471b@172.17.0.2:45039
I0918 20:32:17.261741  5517 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1888)@172.17.0.2:45039
I0918 20:32:17.262434  5528 sched.cpp:520] Successfully authenticated with master master@172.17.0.2:45039
I0918 20:32:17.262527  5528 sched.cpp:835] Sending SUBSCRIBE call to master@172.17.0.2:45039
I0918 20:32:17.262881  5528 sched.cpp:870] Will retry registration in 127.505234ms if necessary
I0918 20:32:17.263226  5513 master.cpp:2968] Received SUBSCRIBE call for framework 'default' at scheduler-8cb3b4db-70cd-49f5-ac7b-8eeba348471b@172.17.0.2:45039
I0918 20:32:17.267607  5512 master.cpp:3054] Subscribing framework default with checkpointing disabled and capabilities [ MULTI_ROLE, RESERVATION_REFINEMENT, SHARED_RESOURCES ]
I0918 20:32:17.271776  5512 master.cpp:10011] Adding framework 631a393a-530c-4374-9764-2d1cfbe6a79c-0001 (default) at scheduler-8cb3b4db-70cd-49f5-ac7b-8eeba348471b@172.17.0.2:45039 with roles {  } suppressed
I0918 20:32:17.272732  5527 sched.cpp:751] Framework registered with 631a393a-530c-4374-9764-2d1cfbe6a79c-0001
I0918 20:32:17.272852  5527 sched.cpp:770] Scheduler::registered took 55393ns
I0918 20:32:17.272881  5514 hierarchical.cpp:793] Added framework 631a393a-530c-4374-9764-2d1cfbe6a79c-0001
I0918 20:32:17.275804  5514 hierarchical.cpp:1957] Performed allocation for 1 agents in 2.580689ms
I0918 20:32:17.277415  5516 master.cpp:9589] Sending offers [ 631a393a-530c-4374-9764-2d1cfbe6a79c-O1 ] to framework 631a393a-530c-4374-9764-2d1cfbe6a79c-0001 (default) at scheduler-8cb3b4db-70cd-49f5-ac7b-8eeba348471b@172.17.0.2:45039
I0918 20:32:17.278721  5526 sched.cpp:934] Scheduler::resourceOffers took 157231ns
I0918 20:32:17.280392  5518 master.cpp:5706] Processing KILL call for task '5e7a5945-d20c-484b-8b1b-4b19ed3e8a07' of framework 631a393a-530c-4374-9764-2d1cfbe6a79c-0000 (default) at scheduler-dbd2d907-2aaf-42d6-9049-6628ee6fd067@172.17.0.2:45039
I0918 20:32:17.280596  5518 master.cpp:5754] Telling agent 631a393a-530c-4374-9764-2d1cfbe6a79c-S0 at slave(1093)@172.17.0.2:45039 (1c4e7fa2a761) to kill task 5e7a5945-d20c-484b-8b1b-4b19ed3e8a07 of framework 631a393a-530c-4374-9764-2d1cfbe6a79c-0000 (default) at scheduler-dbd2d907-2aaf-42d6-9049-6628ee6fd067@172.17.0.2:45039
I0918 20:32:17.280989  5518 slave.cpp:3979] Asked to kill task 5e7a5945-d20c-484b-8b1b-4b19ed3e8a07 of framework 631a393a-530c-4374-9764-2d1cfbe6a79c-0000
W0918 20:32:17.281127  5518 slave.cpp:4126] Transitioning the state of task 5e7a5945-d20c-484b-8b1b-4b19ed3e8a07 of framework 631a393a-530c-4374-9764-2d1cfbe6a79c-0000 to TASK_KILLED because the executor is not registered
I0918 20:32:17.281437  5518 slave.cpp:5981] Handling status update TASK_KILLED (Status UUID: 80d09cdc-c960-4cc2-8dc1-51ec5e5ea776) for task 5e7a5945-d20c-484b-8b1b-4b19ed3e8a07 of framework 631a393a-530c-4374-9764-2d1cfbe6a79c-0000 from @0.0.0.0:0
I0918 20:32:17.286013  5522 task_status_update_manager.cpp:328] Received task status update TASK_KILLED (Status UUID: 80d09cdc-c960-4cc2-8dc1-51ec5e5ea776) for task 5e7a5945-d20c-484b-8b1b-4b19ed3e8a07 of framework 631a393a-530c-4374-9764-2d1cfbe6a79c-0000
I0918 20:32:17.286113  5522 task_status_update_manager.cpp:507] Creating StatusUpdate stream for task 5e7a5945-d20c-484b-8b1b-4b19ed3e8a07 of framework 631a393a-530c-4374-9764-2d1cfbe6a79c-0000
I0918 20:32:17.286976  5522 task_status_update_manager.cpp:383] Forwarding task status update TASK_KILLED (Status UUID: 80d09cdc-c960-4cc2-8dc1-51ec5e5ea776) for task 5e7a5945-d20c-484b-8b1b-4b19ed3e8a07 of framework 631a393a-530c-4374-9764-2d1cfbe6a79c-0000 to the agent
I0918 20:32:17.287374  5512 slave.cpp:6527] Forwarding the update TASK_KILLED (Status UUID: 80d09cdc-c960-4cc2-8dc1-51ec5e5ea776) for task 5e7a5945-d20c-484b-8b1b-4b19ed3e8a07 of framework 631a393a-530c-4374-9764-2d1cfbe6a79c-0000 to master@172.17.0.2:45039
I0918 20:32:17.287830  5512 slave.cpp:6411] Task status update manager successfully handled status update TASK_KILLED (Status UUID: 80d09cdc-c960-4cc2-8dc1-51ec5e5ea776) for task 5e7a5945-d20c-484b-8b1b-4b19ed3e8a07 of framework 631a393a-530c-4374-9764-2d1cfbe6a79c-0000
I0918 20:32:17.288058  5527 master.cpp:8170] Status update TASK_KILLED (Status UUID: 80d09cdc-c960-4cc2-8dc1-51ec5e5ea776) for task 5e7a5945-d20c-484b-8b1b-4b19ed3e8a07 of framework 631a393a-530c-4374-9764-2d1cfbe6a79c-0000 from agent 631a393a-530c-4374-9764-2d1cfbe6a79c-S0 at slave(1093)@172.17.0.2:45039 (1c4e7fa2a761)
I0918 20:32:17.288189  5527 master.cpp:8227] Forwarding status update TASK_KILLED (Status UUID: 80d09cdc-c960-4cc2-8dc1-51ec5e5ea776) for task 5e7a5945-d20c-484b-8b1b-4b19ed3e8a07 of framework 631a393a-530c-4374-9764-2d1cfbe6a79c-0000
I0918 20:32:17.288683  5527 master.cpp:11205] Updating the state of task 5e7a5945-d20c-484b-8b1b-4b19ed3e8a07 of framework 631a393a-530c-4374-9764-2d1cfbe6a79c-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED)
I0918 20:32:17.288949  5525 sched.cpp:1042] Scheduler::statusUpdate took 53152ns
I0918 20:32:17.290280  5527 master.cpp:5880] Processing ACKNOWLEDGE call for status 80d09cdc-c960-4cc2-8dc1-51ec5e5ea776 for task 5e7a5945-d20c-484b-8b1b-4b19ed3e8a07 of framework 631a393a-530c-4374-9764-2d1cfbe6a79c-0000 (default) at scheduler-dbd2d907-2aaf-42d6-9049-6628ee6fd067@172.17.0.2:45039 on agent 631a393a-530c-4374-9764-2d1cfbe6a79c-S0
I0918 20:32:17.290293  5514 hierarchical.cpp:1680] Recovered cpus(allocated: default-role):1; mem(allocated: default-role):128 (total: cpus:2; mem:2048; disk(reservations: [(STATIC,default-role)])[id1:path1]<SHARED>:2048<1>; ports:[31000-32000]; disk(reservations: [(STATIC,default-role)])[id2:path2]<SHARED>:2048<1>, offered or allocated: mem(allocated: default-role):1920; cpus(allocated: default-role):1; ports(allocated: default-role):[31000-32000]; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[id1:path1]<SHARED>:2048<1>; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[id2:path2]<SHARED>:2048<1>) on agent 631a393a-530c-4374-9764-2d1cfbe6a79c-S0 from framework 631a393a-530c-4374-9764-2d1cfbe6a79c-0000
I0918 20:32:17.290573  5527 master.cpp:11305] Removing task 5e7a5945-d20c-484b-8b1b-4b19ed3e8a07 with resources cpus(allocated: default-role):1; mem(allocated: default-role):128 of framework 631a393a-530c-4374-9764-2d1cfbe6a79c-0000 on agent 631a393a-530c-4374-9764-2d1cfbe6a79c-S0 at slave(1093)@172.17.0.2:45039 (1c4e7fa2a761)
I0918 20:32:17.291600  5533 task_status_update_manager.cpp:401] Received task status update acknowledgement (UUID: 80d09cdc-c960-4cc2-8dc1-51ec5e5ea776) for task 5e7a5945-d20c-484b-8b1b-4b19ed3e8a07 of framework 631a393a-530c-4374-9764-2d1cfbe6a79c-0000
I0918 20:32:17.291875  5533 task_status_update_manager.cpp:538] Cleaning up status update stream for task 5e7a5945-d20c-484b-8b1b-4b19ed3e8a07 of framework 631a393a-530c-4374-9764-2d1cfbe6a79c-0000
I0918 20:32:17.292867  5529 slave.cpp:5090] Task status update manager successfully handled status update acknowledgement (UUID: 80d09cdc-c960-4cc2-8dc1-51ec5e5ea776) for task 5e7a5945-d20c-484b-8b1b-4b19ed3e8a07 of framework 631a393a-530c-4374-9764-2d1cfbe6a79c-0000
I0918 20:32:17.292990  5529 slave.cpp:11039] Completing task 5e7a5945-d20c-484b-8b1b-4b19ed3e8a07
I0918 20:32:17.299692  5511 hierarchical.cpp:1957] Performed allocation for 1 agents in 3.417297ms
I0918 20:32:17.300868  5517 master.cpp:9589] Sending offers [ 631a393a-530c-4374-9764-2d1cfbe6a79c-O2 ] to framework 631a393a-530c-4374-9764-2d1cfbe6a79c-0000 (default) at scheduler-dbd2d907-2aaf-42d6-9049-6628ee6fd067@172.17.0.2:45039
I0918 20:32:17.301985  5523 sched.cpp:934] Scheduler::resourceOffers took 192827ns
I0918 20:32:17.304859  5511 hierarchical.cpp:1957] Performed allocation for 1 agents in 259019ns
I0918 20:32:17.305517  5514 master.cpp:4366] Processing ACCEPT call for offers: [ 631a393a-530c-4374-9764-2d1cfbe6a79c-O1 ] on agent 631a393a-530c-4374-9764-2d1cfbe6a79c-S0 at slave(1093)@172.17.0.2:45039 (1c4e7fa2a761) for framework 631a393a-530c-4374-9764-2d1cfbe6a79c-0001 (default) at scheduler-8cb3b4db-70cd-49f5-ac7b-8eeba348471b@172.17.0.2:45039
I0918 20:32:17.306506  5514 master.cpp:11888] Removing offer 631a393a-530c-4374-9764-2d1cfbe6a79c-O1
I0918 20:32:17.307718  5526 sched.cpp:960] Rescinded offer 631a393a-530c-4374-9764-2d1cfbe6a79c-O2
I0918 20:32:17.307942  5526 sched.cpp:971] Scheduler::offerRescinded took 124532ns
I0918 20:32:17.308216  5514 master.cpp:11888] Removing offer 631a393a-530c-4374-9764-2d1cfbe6a79c-O2
I0918 20:32:17.308579  5510 sched.cpp:2166] Asked to stop the driver
I0918 20:32:17.308719  5532 sched.cpp:1204] Stopping framework 631a393a-530c-4374-9764-2d1cfbe6a79c-0000
I0918 20:32:17.309254  5510 sched.cpp:2166] Asked to stop the driver
I0918 20:32:17.308883  5514 master.cpp:4756] Applying DESTROY operation for volumes [{"allocation_info":{"role":"default-role"},"disk":{"persistence":{"id":"id1","principal":"test-principal"},"volume":{"container_path":"path1","mode":"RW"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"shared":{},"type":"SCALAR"},{"allocation_info":{"role":"default-role"},"disk":{"persistence":{"id":"id2","principal":"test-principal"},"volume":{"container_path":"path2","mode":"RW"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"shared":{},"type":"SCALAR"}] from framework 631a393a-530c-4374-9764-2d1cfbe6a79c-0001 (default) at scheduler-8cb3b4db-70cd-49f5-ac7b-8eeba348471b@172.17.0.2:45039 to agent 631a393a-530c-4374-9764-2d1cfbe6a79c-S0 at slave(1093)@172.17.0.2:45039 (1c4e7fa2a761)
I0918 20:32:17.309468  5521 sched.cpp:1204] Stopping framework 631a393a-530c-4374-9764-2d1cfbe6a79c-0001
I0918 20:32:17.310428  5514 master.cpp:11753] Sending operation '' (uuid: 75a4b3bd-ed0b-4b5f-a15e-3932315a7c4a) to agent 631a393a-530c-4374-9764-2d1cfbe6a79c-S0 at slave(1093)@172.17.0.2:45039 (1c4e7fa2a761)
I0918 20:32:17.311066  5514 master.cpp:10372] Processing TEARDOWN call for framework 631a393a-530c-4374-9764-2d1cfbe6a79c-0000 (default) at scheduler-dbd2d907-2aaf-42d6-9049-6628ee6fd067@172.17.0.2:45039
I0918 20:32:17.311105  5514 master.cpp:10384] Removing framework 631a393a-530c-4374-9764-2d1cfbe6a79c-0000 (default) at scheduler-dbd2d907-2aaf-42d6-9049-6628ee6fd067@172.17.0.2:45039
I0918 20:32:17.311128  5514 master.cpp:3437] Deactivating framework 631a393a-530c-4374-9764-2d1cfbe6a79c-0000 (default) at scheduler-dbd2d907-2aaf-42d6-9049-6628ee6fd067@172.17.0.2:45039
I0918 20:32:17.311933  5514 master.cpp:10372] Processing TEARDOWN call for framework 631a393a-530c-4374-9764-2d1cfbe6a79c-0001 (default) at scheduler-8cb3b4db-70cd-49f5-ac7b-8eeba348471b@172.17.0.2:45039
I0918 20:32:17.311966  5514 master.cpp:10384] Removing framework 631a393a-530c-4374-9764-2d1cfbe6a79c-0001 (default) at scheduler-8cb3b4db-70cd-49f5-ac7b-8eeba348471b@172.17.0.2:45039
I0918 20:32:17.311985  5514 master.cpp:3437] Deactivating framework 631a393a-530c-4374-9764-2d1cfbe6a79c-0001 (default) at scheduler-8cb3b4db-70cd-49f5-ac7b-8eeba348471b@172.17.0.2:45039
I0918 20:32:17.313087  5511 hierarchical.cpp:1680] Recovered disk(allocated: default-role)(reservations: [(STATIC,default-role)])[id1:path1]<SHARED>:2048<1>; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[id2:path2]<SHARED>:2048<1>; cpus(allocated: default-role):1; mem(allocated: default-role):128 (total: cpus:2; mem:2048; disk(reservations: [(STATIC,default-role)])[id1:path1]<SHARED>:2048<1>; ports:[31000-32000]; disk(reservations: [(STATIC,default-role)])[id2:path2]<SHARED>:2048<1>, offered or allocated: mem(allocated: default-role):1920; cpus(allocated: default-role):1; ports(allocated: default-role):[31000-32000]; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[id1:path1]<SHARED>:2048<1>; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[id2:path2]<SHARED>:2048<1>) on agent 631a393a-530c-4374-9764-2d1cfbe6a79c-S0 from framework 631a393a-530c-4374-9764-2d1cfbe6a79c-0000
I0918 20:32:17.314132  5523 slave.cpp:4688] Updated checkpointed operations from [  ] to [ 75a4b3bd-ed0b-4b5f-a15e-3932315a7c4a (DESTROY for framework 631a393a-530c-4374-9764-2d1cfbe6a79c-0001, latest state: OPERATION_PENDING) ]
I0918 20:32:17.315508  5523 slave.cpp:9186] Updating the state of operation with no ID (uuid: 75a4b3bd-ed0b-4b5f-a15e-3932315a7c4a) for framework 631a393a-530c-4374-9764-2d1cfbe6a79c-0001 (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)
I0918 20:32:17.317365  5523 slave.cpp:4781] Deleting persistent volume 'id1' at '/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_0_USZJNk/volumes/roles/default-role/id1'
I0918 20:32:17.317628  5523 slave.cpp:4781] Deleting persistent volume 'id2' at '/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_0_USZJNk/volumes/roles/default-role/id2'
I0918 20:32:17.317817  5511 hierarchical.cpp:1416] Updated allocation of framework 631a393a-530c-4374-9764-2d1cfbe6a79c-0001 on agent 631a393a-530c-4374-9764-2d1cfbe6a79c-S0 from ports(allocated: default-role):[31000-32000]; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[id1:path1]<SHARED>:2048<1>; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[id2:path2]<SHARED>:2048<1>; cpus(allocated: default-role):1; mem(allocated: default-role):1920 to ports(allocated: default-role):[31000-32000]; mem(allocated: default-role):1920; disk(allocated: default-role)(reservations: [(STATIC,default-role)]):4096; cpus(allocated: default-role):1
I0918 20:32:17.317984  5511 hierarchical.cpp:1881] Allocation paused
I0918 20:32:17.318075  5523 slave.cpp:4664] Updated checkpointed resources from disk(reservations: [(STATIC,default-role)])[id1:path1]<SHARED>:2048<1>; disk(reservations: [(STATIC,default-role)])[id2:path2]<SHARED>:2048<1> to {}
I0918 20:32:17.318198  5511 hierarchical.cpp:1680] Recovered disk(allocated: default-role)(reservations: [(STATIC,default-role)]):4096 (total: cpus:2; mem:2048; disk(reservations: [(STATIC,default-role)]):4096; ports:[31000-32000], offered or allocated: ports(allocated: default-role):[31000-32000]; mem(allocated: default-role):1920; cpus(allocated: default-role):1) on agent 631a393a-530c-4374-9764-2d1cfbe6a79c-S0 from framework 631a393a-530c-4374-9764-2d1cfbe6a79c-0001
I0918 20:32:17.318997  5511 hierarchical.cpp:1680] Recovered ports(allocated: default-role):[31000-32000]; mem(allocated: default-role):1920; cpus(allocated: default-role):1 (total: cpus:2; mem:2048; disk(reservations: [(STATIC,default-role)]):4096; ports:[31000-32000], offered or allocated: {}) on agent 631a393a-530c-4374-9764-2d1cfbe6a79c-S0 from framework 631a393a-530c-4374-9764-2d1cfbe6a79c-0001
I0918 20:32:17.319308  5523 slave.cpp:4688] Updated checkpointed operations from [ 75a4b3bd-ed0b-4b5f-a15e-3932315a7c4a (DESTROY for framework 631a393a-530c-4374-9764-2d1cfbe6a79c-0001, latest state: OPERATION_PENDING) ] to [ 75a4b3bd-ed0b-4b5f-a15e-3932315a7c4a (DESTROY for framework 631a393a-530c-4374-9764-2d1cfbe6a79c-0001, latest state: OPERATION_FINISHED) ]
I0918 20:32:17.319787  5523 slave.cpp:1002] Agent terminating
I0918 20:32:17.319805  5533 status_update_manager_process.hpp:152] Received operation status update OPERATION_FINISHED (Status UUID: 982d51f1-202e-4f04-a7f6-14f91cd2a406) for operation UUID 75a4b3bd-ed0b-4b5f-a15e-3932315a7c4a of framework '631a393a-530c-4374-9764-2d1cfbe6a79c-0001' on agent 631a393a-530c-4374-9764-2d1cfbe6a79c-S0
I0918 20:32:17.319919  5523 slave.cpp:4285] Asked to shut down framework 631a393a-530c-4374-9764-2d1cfbe6a79c-0000 by @0.0.0.0:0
I0918 20:32:17.319952  5533 status_update_manager_process.hpp:414] Creating operation status update stream 75a4b3bd-ed0b-4b5f-a15e-3932315a7c4a checkpoint=true
I0918 20:32:17.319936  5511 hierarchical.cpp:1891] Allocation resumed
I0918 20:32:17.319991  5523 slave.cpp:4310] Shutting down framework 631a393a-530c-4374-9764-2d1cfbe6a79c-0000
I0918 20:32:17.320159  5523 slave.cpp:7473] Shutting down executor '5e7a5945-d20c-484b-8b1b-4b19ed3e8a07' of framework 631a393a-530c-4374-9764-2d1cfbe6a79c-0000
W0918 20:32:17.320221  5523 slave.hpp:1034] Unable to send event to executor '5e7a5945-d20c-484b-8b1b-4b19ed3e8a07' of framework 631a393a-530c-4374-9764-2d1cfbe6a79c-0000: unknown connection type
I0918 20:32:17.320546  5533 status_update_manager_process.hpp:929] Checkpointing UPDATE for operation status update OPERATION_FINISHED (Status UUID: 982d51f1-202e-4f04-a7f6-14f91cd2a406) for operation UUID 75a4b3bd-ed0b-4b5f-a15e-3932315a7c4a of framework '631a393a-530c-4374-9764-2d1cfbe6a79c-0001' on agent 631a393a-530c-4374-9764-2d1cfbe6a79c-S0
I0918 20:32:17.321485  5511 hierarchical.cpp:1957] Performed allocation for 1 agents in 1.378723ms
W0918 20:32:17.321491  5515 master.cpp:9391] Master returning resources offered to framework 631a393a-530c-4374-9764-2d1cfbe6a79c-0001 because the framework has terminated, is not connected, or is inactive
I0918 20:32:17.321664  5515 master.cpp:1301] Agent 631a393a-530c-4374-9764-2d1cfbe6a79c-S0 at slave(1093)@172.17.0.2:45039 (1c4e7fa2a761) disconnected
I0918 20:32:17.321707  5515 master.cpp:3472] Disconnecting agent 631a393a-530c-4374-9764-2d1cfbe6a79c-S0 at slave(1093)@172.17.0.2:45039 (1c4e7fa2a761)
I0918 20:32:17.321795  5515 master.cpp:3491] Deactivating agent 631a393a-530c-4374-9764-2d1cfbe6a79c-S0 at slave(1093)@172.17.0.2:45039 (1c4e7fa2a761)
I0918 20:32:17.322564  5526 containerizer.cpp:2716] Destroying container da197407-66c8-4f09-a0e2-db1ca2b32c87 in RUNNING state
I0918 20:32:17.322665  5526 containerizer.cpp:3414] Transitioning the state of container da197407-66c8-4f09-a0e2-db1ca2b32c87 from RUNNING to DESTROYING after 1.519817984secs
I0918 20:32:17.323189  5526 launcher.cpp:161] Asked to destroy container da197407-66c8-4f09-a0e2-db1ca2b32c87
I0918 20:32:17.325899  5511 hierarchical.cpp:906] Deactivated framework 631a393a-530c-4374-9764-2d1cfbe6a79c-0000
I0918 20:32:17.325968  5511 hierarchical.cpp:1881] Allocation paused
I0918 20:32:17.326206  5511 hierarchical.cpp:850] Removed framework 631a393a-530c-4374-9764-2d1cfbe6a79c-0000
I0918 20:32:17.326275  5511 hierarchical.cpp:1891] Allocation resumed
I0918 20:32:17.326362  5511 hierarchical.cpp:906] Deactivated framework 631a393a-530c-4374-9764-2d1cfbe6a79c-0001
I0918 20:32:17.326421  5511 hierarchical.cpp:1881] Allocation paused
I0918 20:32:17.326499  5511 hierarchical.cpp:821] Recovering disk(allocated: default-role)(reservations: [(STATIC,default-role)]):4096; ports(allocated: default-role):[31000-32000]; cpus(allocated: default-role):2; mem(allocated: default-role):2048 from removing framework 631a393a-530c-4374-9764-2d1cfbe6a79c-0001 (agent total: cpus:2; mem:2048; disk(reservations: [(STATIC,default-role)]):4096; ports:[31000-32000], offered or allocated: disk(allocated: default-role)(reservations: [(STATIC,default-role)]):4096; ports(allocated: default-role):[31000-32000]; cpus(allocated: default-role):2; mem(allocated: default-role):2048)
I0918 20:32:17.328611  5511 hierarchical.cpp:850] Removed framework 631a393a-530c-4374-9764-2d1cfbe6a79c-0001
I0918 20:32:17.328685  5511 hierarchical.cpp:1891] Allocation resumed
I0918 20:32:17.328815  5511 hierarchical.cpp:1260] Agent 631a393a-530c-4374-9764-2d1cfbe6a79c-S0 deactivated
I0918 20:32:17.374977  5533 status_update_manager_process.hpp:528] Forwarding operation status update OPERATION_FINISHED (Status UUID: 982d51f1-202e-4f04-a7f6-14f91cd2a406) for operation UUID 75a4b3bd-ed0b-4b5f-a15e-3932315a7c4a of framework '631a393a-530c-4374-9764-2d1cfbe6a79c-0001' on agent 631a393a-530c-4374-9764-2d1cfbe6a79c-S0
I0918 20:32:17.397110  5521 containerizer.cpp:3252] Container da197407-66c8-4f09-a0e2-db1ca2b32c87 has exited
I0918 20:32:17.402086  5521 provisioner.cpp:652] Ignoring destroy request for unknown container da197407-66c8-4f09-a0e2-db1ca2b32c87
I0918 20:32:17.420411  5510 master.cpp:1149] Master terminating
I0918 20:32:17.421229  5524 hierarchical.cpp:1236] Removed all filters for agent 631a393a-530c-4374-9764-2d1cfbe6a79c-S0
I0918 20:32:17.421274  5524 hierarchical.cpp:1112] Removed agent 631a393a-530c-4374-9764-2d1cfbe6a79c-S0
[       OK ] DiskResource/PersistentVolumeTest.SharedPersistentVolumeRescindOnDestroy/0 (630 ms)
[ RUN      ] DiskResource/PersistentVolumeTest.SharedPersistentVolumeRescindOnDestroy/1
I0918 20:32:17.438974  5510 cluster.cpp:195] Creating default 'local' authorizer
I0918 20:32:17.447747  5533 master.cpp:450] Master 233412ed-bba4-477c-87db-0d2940460191 (1c4e7fa2a761) started on 172.17.0.2:45039
I0918 20:32:17.447794  5533 master.cpp:453] Flags at startup: --acls="" --agent_ping_timeout="15secs" --agent_reregister_timeout="10mins" --allocation_interval="1secs" --allocator="hierarchical" --authenticate_agents="true" --authenticate_frameworks="true" --authenticate_http_frameworks="true" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authentication_v0_timeout="15secs" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/jXrbDD/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_operator_event_stream_subscribers="1000" --max_unreachable_tasks_per_framework="1000" --memory_profiling="false" --min_alloc
 atable_resources="cpus:0.01|mem:32" --offer_constraints_re2_max_mem="4KB" --offer_constraints_re2_max_program_size="100" --port="5050" --publish_per_framework_metrics="true" --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" --require_agent_domain="false" --role_sorter="drf" --root_submissions="true" --version="false" --webui_dir="/tmp/SRC/build/mesos-1.11.0/_inst/share/mesos/webui" --work_dir="/tmp/jXrbDD/master" --zk_session_timeout="10secs"
I0918 20:32:17.448487  5533 master.cpp:502] Master only allowing authenticated frameworks to register
I0918 20:32:17.448515  5533 master.cpp:508] Master only allowing authenticated agents to register
I0918 20:32:17.448529  5533 master.cpp:514] Master only allowing authenticated HTTP frameworks to register
I0918 20:32:17.448554  5533 credentials.hpp:37] Loading credentials for authentication from '/tmp/jXrbDD/credentials'
I0918 20:32:17.449028  5533 master.cpp:558] Using default 'crammd5' authenticator
I0918 20:32:17.449376  5533 http.cpp:1296] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I0918 20:32:17.449772  5533 http.cpp:1296] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I0918 20:32:17.450139  5533 http.cpp:1296] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I0918 20:32:17.450482  5533 master.cpp:639] Authorization enabled
I0918 20:32:17.451229  5517 whitelist_watcher.cpp:77] No whitelist given
I0918 20:32:17.451423  5514 hierarchical.cpp:656] Initialized hierarchical allocator process
I0918 20:32:17.455435  5511 master.cpp:2174] Elected as the leading master!
I0918 20:32:17.455493  5511 master.cpp:1670] Recovering from registrar
I0918 20:32:17.455746  5528 registrar.cpp:339] Recovering registrar
I0918 20:32:17.456979  5528 registrar.cpp:383] Successfully fetched the registry (0B) in 0ns
I0918 20:32:17.457195  5528 registrar.cpp:487] Applied 1 operations in 72746ns; attempting to update the registry
I0918 20:32:17.458377  5528 registrar.cpp:544] Successfully updated the registry in 0ns
I0918 20:32:17.458639  5528 registrar.cpp:416] Successfully recovered registrar
I0918 20:32:17.459498  5513 master.cpp:1823] Recovered 0 agents from the registry (144B); allowing 10mins for agents to reregister
I0918 20:32:17.459553  5527 hierarchical.cpp:695] Skipping recovery of hierarchical allocator: nothing to recover
W0918 20:32:17.471948  5510 process.cpp:2877] Attempted to spawn already running process files@172.17.0.2:45039
I0918 20:32:17.473176  5510 resolver.cpp:69] Creating default secret resolver
I0918 20:32:17.474491  5510 containerizer.cpp:320] Using isolation { environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni }
W0918 20:32:17.475596  5510 backend.cpp:76] Failed to create 'overlay' backend: OverlayBackend requires root privileges
W0918 20:32:17.475647  5510 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0918 20:32:17.475680  5510 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0918 20:32:17.475736  5510 provisioner.cpp:294] Using default backend 'copy'
I0918 20:32:17.480289  5510 cluster.cpp:620] Creating default 'local' authorizer
I0918 20:32:17.486017  5515 slave.cpp:281] Mesos agent started on (1094)@172.17.0.2:45039
I0918 20:32:17.486068  5515 slave.cpp:282] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/jXrbDD/gdkKo2/store/appc" --authenticate_http_readonly="true" --authenticate_http_readwrite="false" --authenticatee="crammd5" --authentication_backoff_factor="1secs" --authentication_timeout_max="1mins" --authentication_timeout_min="5secs" --authorizer="local" --cgroups_cpu_enable_pids_and_tids_count="false" --cgroups_destroy_timeout="1mins" --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/jXrbDD/gdkKo2/credential" --default_role="*" --disallow_sharing_agent_ipc_namespace="false" --disallow_sharing_agent_pid_namespace="false" --disk_watch_interval="1mins" --docker="docker" --docker_ignore_runtime="false" --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/jXrbDD/gdkKo2/store/docker" --docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --docker_volume_chown="false" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_reregistration_timeout="2secs" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/jXrbDD/gdkKo2/fetch" --fetcher_cache_size="2GB" --fetcher_stall_timeout="1mins" --frameworks_home="/tmp/jXrbDD/gdkKo2/frameworks" --gc_delay="1weeks" --gc_disk_headroom="0.1" --gc_non_executor_container_sandboxes="false" --help="false" --hostname_lookup="true" --http_command_executor="false" --http_credentials="/tmp/jXrbDD/gdkKo2/http_credentials" --http_executor_domain_sockets="false" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/tmp/SRC/build/mesos-1.11.0/_build/sub/src" -
 -logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --memory_profiling="false" --network_cni_metrics="true" --network_cni_root_dir_persist="false" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --port="5051" --qos_correction_interval_min="0ns" --quiet="false" --reconfiguration_policy="equal" --recover="reconnect" --recovery_timeout="15mins" --registration_backoff_factor="10ms" --resources="[{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":2048.0},"type":"SCALAR"},{"disk":{"source":{"path":{"root":"/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_1_NmWTLJ/disk1"},"type":"PATH"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"},{"disk":{"source":{"path":{"root":"/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_1_NmWTLJ/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_SharedPersistentVolumeRescindOnDestroy_1_tUkMbW" --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_SharedPersistentVolumeRescindOnDestroy_1_RdpPYP" --zk_session_timeout="10secs"
I0918 20:32:17.486971  5515 credentials.hpp:73] Loading credential for authentication from '/tmp/jXrbDD/gdkKo2/credential'
I0918 20:32:17.487236  5515 slave.cpp:314] Agent using credential for: test-principal
I0918 20:32:17.487278  5515 credentials.hpp:37] Loading credentials for authentication from '/tmp/jXrbDD/gdkKo2/http_credentials'
I0918 20:32:17.487641  5515 http.cpp:1296] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0918 20:32:17.488381  5515 disk_profile_adaptor.cpp:78] Creating default disk profile adaptor module
I0918 20:32:17.492748  5515 slave.cpp:629] 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_SharedPersistentVolumeRescindOnDestroy_1_NmWTLJ/disk1"},"type":"PATH"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"},{"disk":{"source":{"path":{"root":"/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_1_NmWTLJ/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"}]
I0918 20:32:17.493500  5515 slave.cpp:637] Agent attributes: [  ]
I0918 20:32:17.493530  5515 slave.cpp:646] Agent hostname: 1c4e7fa2a761
I0918 20:32:17.493882  5533 status_update_manager_process.hpp:379] Pausing operation status update manager
I0918 20:32:17.493912  5512 task_status_update_manager.cpp:181] Pausing sending task status updates
I0918 20:32:17.496713  5519 state.cpp:68] Recovering state from '/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_1_RdpPYP/meta'
I0918 20:32:17.497149  5525 slave.cpp:7742] Finished recovering checkpointed state from '/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_1_RdpPYP/meta', beginning agent recovery
I0918 20:32:17.498059  5531 task_status_update_manager.cpp:207] Recovering task status update manager
I0918 20:32:17.498950  5520 containerizer.cpp:830] Recovering Mesos containers
I0918 20:32:17.499660  5520 containerizer.cpp:1170] Recovering isolators
I0918 20:32:17.501320  5514 containerizer.cpp:1209] Recovering provisioner
I0918 20:32:17.502615  5534 provisioner.cpp:518] Provisioner recovery complete
I0918 20:32:17.504119  5532 composing.cpp:343] Finished recovering all containerizers
I0918 20:32:17.504518  5524 slave.cpp:8224] Recovering executors
I0918 20:32:17.504678  5524 slave.cpp:8377] Finished recovery
I0918 20:32:17.505947  5522 task_status_update_manager.cpp:181] Pausing sending task status updates
I0918 20:32:17.505949  5521 slave.cpp:1473] New master detected at master@172.17.0.2:45039
I0918 20:32:17.505959  5526 status_update_manager_process.hpp:379] Pausing operation status update manager
I0918 20:32:17.506242  5521 slave.cpp:1538] Detecting new master
I0918 20:32:17.507656  5527 slave.cpp:1565] Authenticating with master master@172.17.0.2:45039
I0918 20:32:17.507819  5527 slave.cpp:1574] Using default CRAM-MD5 authenticatee
I0918 20:32:17.508332  5520 authenticatee.cpp:121] Creating new client SASL connection
I0918 20:32:17.508877  5533 master.cpp:9780] Authenticating slave(1094)@172.17.0.2:45039
I0918 20:32:17.509150  5514 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1889)@172.17.0.2:45039
I0918 20:32:17.509657  5517 authenticator.cpp:98] Creating new server SASL connection
I0918 20:32:17.510073  5511 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0918 20:32:17.510146  5511 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0918 20:32:17.510439  5511 authenticator.cpp:204] Received SASL authentication start
I0918 20:32:17.510612  5511 authenticator.cpp:326] Authentication requires more steps
I0918 20:32:17.510905  5519 authenticatee.cpp:259] Received SASL authentication step
I0918 20:32:17.511190  5518 authenticator.cpp:232] Received SASL authentication step
I0918 20:32:17.511282  5518 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '1c4e7fa2a761' server FQDN: '1c4e7fa2a761' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
I0918 20:32:17.511320  5518 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0918 20:32:17.511404  5518 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0918 20:32:17.511482  5518 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '1c4e7fa2a761' server FQDN: '1c4e7fa2a761' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
I0918 20:32:17.511523  5518 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0918 20:32:17.511554  5518 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0918 20:32:17.511606  5518 authenticator.cpp:318] Authentication success
I0918 20:32:17.511916  5516 authenticatee.cpp:299] Authentication success
I0918 20:32:17.512023  5525 master.cpp:9812] Successfully authenticated principal 'test-principal' at slave(1094)@172.17.0.2:45039
I0918 20:32:17.512094  5531 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1889)@172.17.0.2:45039
I0918 20:32:17.512820  5534 slave.cpp:1665] Successfully authenticated with master master@172.17.0.2:45039
I0918 20:32:17.513797  5534 slave.cpp:2131] Will retry registration in 1.988199ms if necessary
I0918 20:32:17.514200  5524 master.cpp:6273] Received register agent message from slave(1094)@172.17.0.2:45039 (1c4e7fa2a761)
I0918 20:32:17.515523  5524 master.cpp:3804] Authorizing principal 'test-principal' to perform action RESERVE_RESOURCES on object {"value":"*","resource":{"name":"cpus","type":"SCALAR","scalar":{"value":2.0}}}
I0918 20:32:17.515816  5524 master.cpp:3804] Authorizing principal 'test-principal' to perform action RESERVE_RESOURCES on object {"value":"default-role","resource":{"name":"disk","type":"SCALAR","scalar":{"value":2048.0},"reservations":[{"type":"STATIC","role":"default-role"}],"disk":{"source":{"type":"PATH","path":{"root":"/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_1_NmWTLJ/disk1"}}}}}
I0918 20:32:17.516152  5524 master.cpp:3804] Authorizing principal 'test-principal' to perform action REGISTER_AGENT on ANY object
I0918 20:32:17.517196  5521 master.cpp:6340] Authorized registration of agent at slave(1094)@172.17.0.2:45039 (1c4e7fa2a761)
I0918 20:32:17.517369  5521 master.cpp:6452] Registering agent at slave(1094)@172.17.0.2:45039 (1c4e7fa2a761) with id 233412ed-bba4-477c-87db-0d2940460191-S0
I0918 20:32:17.518796  5512 registrar.cpp:487] Applied 1 operations in 565437ns; attempting to update the registry
I0918 20:32:17.520046  5512 registrar.cpp:544] Successfully updated the registry in 0ns
I0918 20:32:17.520412  5517 master.cpp:6500] Admitted agent 233412ed-bba4-477c-87db-0d2940460191-S0 at slave(1094)@172.17.0.2:45039 (1c4e7fa2a761)
I0918 20:32:17.522106  5511 slave.cpp:1698] Registered with master master@172.17.0.2:45039; given agent ID 233412ed-bba4-477c-87db-0d2940460191-S0
I0918 20:32:17.522042  5517 master.cpp:6545] Registered agent 233412ed-bba4-477c-87db-0d2940460191-S0 at slave(1094)@172.17.0.2:45039 (1c4e7fa2a761) with cpus:2; mem:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_1_NmWTLJ/disk1]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_1_NmWTLJ/disk2]:2048; ports:[31000-32000]
I0918 20:32:17.522392  5532 task_status_update_manager.cpp:188] Resuming sending task status updates
I0918 20:32:17.523022  5511 slave.cpp:1733] Checkpointing SlaveInfo to '/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_1_RdpPYP/meta/slaves/233412ed-bba4-477c-87db-0d2940460191-S0/slave.info'
I0918 20:32:17.523160  5528 status_update_manager_process.hpp:385] Resuming operation status update manager
I0918 20:32:17.523169  5519 hierarchical.cpp:1063] Added agent 233412ed-bba4-477c-87db-0d2940460191-S0 (1c4e7fa2a761) with cpus:2; mem:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_1_NmWTLJ/disk1]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_1_NmWTLJ/disk2]:2048; ports:[31000-32000] (offered or allocated: {})
I0918 20:32:17.523782  5519 hierarchical.cpp:1957] Performed allocation for 1 agents in 259587ns
I0918 20:32:17.525418  5511 slave.cpp:1793] Forwarding agent update {"operations":{},"resource_providers":{},"resource_version_uuid":{"value":"RHvxmnRqRJWSSgFT7cBXDA=="},"slave_id":{"value":"233412ed-bba4-477c-87db-0d2940460191-S0"},"update_oversubscribed_resources":false}
I0918 20:32:17.526506  5526 master.cpp:7688] Ignoring update on agent 233412ed-bba4-477c-87db-0d2940460191-S0 at slave(1094)@172.17.0.2:45039 (1c4e7fa2a761) as it reports no changes
W0918 20:32:17.648854  5510 process.cpp:2877] Attempted to spawn already running process version@172.17.0.2:45039
I0918 20:32:17.650566  5510 sched.cpp:239] Version: 1.11.0
I0918 20:32:17.651669  5515 sched.cpp:343] New master detected at master@172.17.0.2:45039
I0918 20:32:17.651859  5515 sched.cpp:408] Authenticating with master master@172.17.0.2:45039
I0918 20:32:17.651895  5515 sched.cpp:415] Using default CRAM-MD5 authenticatee
I0918 20:32:17.652856  5518 authenticatee.cpp:121] Creating new client SASL connection
I0918 20:32:17.653376  5532 master.cpp:9780] Authenticating scheduler-51362b45-3d6a-4d6a-884e-490fe14ac542@172.17.0.2:45039
I0918 20:32:17.653676  5525 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1890)@172.17.0.2:45039
I0918 20:32:17.654281  5528 authenticator.cpp:98] Creating new server SASL connection
I0918 20:32:17.654814  5524 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0918 20:32:17.654872  5524 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0918 20:32:17.655161  5531 authenticator.cpp:204] Received SASL authentication start
I0918 20:32:17.655282  5531 authenticator.cpp:326] Authentication requires more steps
I0918 20:32:17.655567  5522 authenticatee.cpp:259] Received SASL authentication step
I0918 20:32:17.655628  5534 hierarchical.cpp:1957] Performed allocation for 1 agents in 395126ns
I0918 20:32:17.655848  5511 authenticator.cpp:232] Received SASL authentication step
I0918 20:32:17.655932  5511 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '1c4e7fa2a761' server FQDN: '1c4e7fa2a761' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
I0918 20:32:17.655969  5511 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0918 20:32:17.656034  5511 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0918 20:32:17.656085  5511 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '1c4e7fa2a761' server FQDN: '1c4e7fa2a761' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
I0918 20:32:17.656109  5511 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0918 20:32:17.656128  5511 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0918 20:32:17.656163  5511 authenticator.cpp:318] Authentication success
I0918 20:32:17.656365  5526 authenticatee.cpp:299] Authentication success
I0918 20:32:17.656510  5516 master.cpp:9812] Successfully authenticated principal 'test-principal' at scheduler-51362b45-3d6a-4d6a-884e-490fe14ac542@172.17.0.2:45039
I0918 20:32:17.656577  5529 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1890)@172.17.0.2:45039
I0918 20:32:17.657088  5521 sched.cpp:520] Successfully authenticated with master master@172.17.0.2:45039
I0918 20:32:17.657146  5521 sched.cpp:835] Sending SUBSCRIBE call to master@172.17.0.2:45039
I0918 20:32:17.657496  5521 sched.cpp:870] Will retry registration in 1.817934386secs if necessary
I0918 20:32:17.657920  5514 master.cpp:2968] Received SUBSCRIBE call for framework 'default' at scheduler-51362b45-3d6a-4d6a-884e-490fe14ac542@172.17.0.2:45039
I0918 20:32:17.657969  5534 hierarchical.cpp:1957] Performed allocation for 1 agents in 262417ns
I0918 20:32:17.662489  5513 master.cpp:3054] Subscribing framework default with checkpointing disabled and capabilities [ MULTI_ROLE, RESERVATION_REFINEMENT, SHARED_RESOURCES ]
I0918 20:32:17.664659  5534 hierarchical.cpp:1957] Performed allocation for 1 agents in 275640ns
I0918 20:32:17.666205  5513 master.cpp:10011] Adding framework 233412ed-bba4-477c-87db-0d2940460191-0000 (default) at scheduler-51362b45-3d6a-4d6a-884e-490fe14ac542@172.17.0.2:45039 with roles {  } suppressed
I0918 20:32:17.666882  5532 sched.cpp:751] Framework registered with 233412ed-bba4-477c-87db-0d2940460191-0000
I0918 20:32:17.666954  5532 sched.cpp:770] Scheduler::registered took 32938ns
I0918 20:32:17.672487  5534 hierarchical.cpp:793] Added framework 233412ed-bba4-477c-87db-0d2940460191-0000
I0918 20:32:17.674558  5534 hierarchical.cpp:1957] Performed allocation for 1 agents in 1.878747ms
I0918 20:32:17.675837  5523 master.cpp:9589] Sending offers [ 233412ed-bba4-477c-87db-0d2940460191-O0 ] to framework 233412ed-bba4-477c-87db-0d2940460191-0000 (default) at scheduler-51362b45-3d6a-4d6a-884e-490fe14ac542@172.17.0.2:45039
I0918 20:32:17.676913  5528 sched.cpp:934] Scheduler::resourceOffers took 132104ns
I0918 20:32:17.685068  5524 master.cpp:4366] Processing ACCEPT call for offers: [ 233412ed-bba4-477c-87db-0d2940460191-O0 ] on agent 233412ed-bba4-477c-87db-0d2940460191-S0 at slave(1094)@172.17.0.2:45039 (1c4e7fa2a761) for framework 233412ed-bba4-477c-87db-0d2940460191-0000 (default) at scheduler-51362b45-3d6a-4d6a-884e-490fe14ac542@172.17.0.2:45039
I0918 20:32:17.686604  5524 master.cpp:11888] Removing offer 233412ed-bba4-477c-87db-0d2940460191-O0
I0918 20:32:17.688217  5524 master.cpp:4691] Applying CREATE operation for volumes [{"allocation_info":{"role":"default-role"},"disk":{"persistence":{"id":"id1","principal":"test-principal"},"source":{"path":{"root":"/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_1_NmWTLJ/disk1"},"type":"PATH"},"volume":{"container_path":"path1","mode":"RW"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"shared":{},"type":"SCALAR"},{"allocation_info":{"role":"default-role"},"disk":{"persistence":{"id":"id2","principal":"test-principal"},"source":{"path":{"root":"/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_1_NmWTLJ/disk2"},"type":"PATH"},"volume":{"container_path":"path2","mode":"RW"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"shared":{},"type":"SCALAR"}] from framework 233412ed-bba4-477c-87db-0d2940460191-0000 (default) at schedul
 er-51362b45-3d6a-4d6a-884e-490fe14ac542@172.17.0.2:45039 to agent 233412ed-bba4-477c-87db-0d2940460191-S0 at slave(1094)@172.17.0.2:45039 (1c4e7fa2a761)
I0918 20:32:17.690176  5524 master.cpp:11753] Sending operation '' (uuid: 69afbafc-b938-4fb3-a460-b580c63f1eeb) to agent 233412ed-bba4-477c-87db-0d2940460191-S0 at slave(1094)@172.17.0.2:45039 (1c4e7fa2a761)
I0918 20:32:17.691854  5531 slave.cpp:4688] Updated checkpointed operations from [  ] to [ 69afbafc-b938-4fb3-a460-b580c63f1eeb (CREATE for framework 233412ed-bba4-477c-87db-0d2940460191-0000, latest state: OPERATION_PENDING) ]
I0918 20:32:17.691900  5524 master.cpp:3909] Adding task a7aa7de6-4b7c-4c38-a42f-ac2a8e0419e0 with resources cpus(allocated: default-role):1; mem(allocated: default-role):128 of framework 233412ed-bba4-477c-87db-0d2940460191-0000 (default) at scheduler-51362b45-3d6a-4d6a-884e-490fe14ac542@172.17.0.2:45039 on agent 233412ed-bba4-477c-87db-0d2940460191-S0 at slave(1094)@172.17.0.2:45039 (1c4e7fa2a761)
I0918 20:32:17.692647  5531 slave.cpp:9186] Updating the state of operation with no ID (uuid: 69afbafc-b938-4fb3-a460-b580c63f1eeb) for framework 233412ed-bba4-477c-87db-0d2940460191-0000 (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)
I0918 20:32:17.692574  5524 master.cpp:5045] Launching task a7aa7de6-4b7c-4c38-a42f-ac2a8e0419e0 of framework 233412ed-bba4-477c-87db-0d2940460191-0000 (default) at scheduler-51362b45-3d6a-4d6a-884e-490fe14ac542@172.17.0.2:45039 with resources [{"allocation_info":{"role":"default-role"},"name":"cpus","scalar":{"value":1.0},"type":"SCALAR"},{"allocation_info":{"role":"default-role"},"name":"mem","scalar":{"value":128.0},"type":"SCALAR"}] on agent 233412ed-bba4-477c-87db-0d2940460191-S0 at slave(1094)@172.17.0.2:45039 (1c4e7fa2a761) on  new executor
I0918 20:32:17.695858  5531 slave.cpp:4664] Updated checkpointed resources from {} to disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_1_NmWTLJ/disk1,id1:path1]<SHARED>:2048<1>; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_1_NmWTLJ/disk2,id2:path2]<SHARED>:2048<1>
I0918 20:32:17.696945  5531 slave.cpp:4688] Updated checkpointed operations from [ 69afbafc-b938-4fb3-a460-b580c63f1eeb (CREATE for framework 233412ed-bba4-477c-87db-0d2940460191-0000, latest state: OPERATION_PENDING) ] to [ 69afbafc-b938-4fb3-a460-b580c63f1eeb (CREATE for framework 233412ed-bba4-477c-87db-0d2940460191-0000, latest state: OPERATION_FINISHED) ]
I0918 20:32:17.697504  5511 status_update_manager_process.hpp:152] Received operation status update OPERATION_FINISHED (Status UUID: 31cbfcbc-e2e2-42a3-a01c-205b38ba04e1) for operation UUID 69afbafc-b938-4fb3-a460-b580c63f1eeb of framework '233412ed-bba4-477c-87db-0d2940460191-0000' on agent 233412ed-bba4-477c-87db-0d2940460191-S0
I0918 20:32:17.697641  5511 status_update_manager_process.hpp:414] Creating operation status update stream 69afbafc-b938-4fb3-a460-b580c63f1eeb checkpoint=true
I0918 20:32:17.698204  5511 status_update_manager_process.hpp:929] Checkpointing UPDATE for operation status update OPERATION_FINISHED (Status UUID: 31cbfcbc-e2e2-42a3-a01c-205b38ba04e1) for operation UUID 69afbafc-b938-4fb3-a460-b580c63f1eeb of framework '233412ed-bba4-477c-87db-0d2940460191-0000' on agent 233412ed-bba4-477c-87db-0d2940460191-S0
I0918 20:32:17.698797  5522 hierarchical.cpp:1416] Updated allocation of framework 233412ed-bba4-477c-87db-0d2940460191-0000 on agent 233412ed-bba4-477c-87db-0d2940460191-S0 from disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_1_NmWTLJ/disk1]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_1_NmWTLJ/disk2]:2048; ports(allocated: default-role):[31000-32000]; cpus(allocated: default-role):2; mem(allocated: default-role):2048 to mem(allocated: default-role):2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_1_NmWTLJ/disk1,id1:path1]<SHARED>:2048<1>; ports(allocated: default-role):[31000-32000]; cpus(allocated: default-role):2; disk(allocated: default-role)(reservations: [(
 STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_1_NmWTLJ/disk2,id2:path2]<SHARED>:2048<1>
I0918 20:32:17.699075  5522 hierarchical.cpp:1881] Allocation paused
I0918 20:32:17.699282  5531 slave.cpp:2300] Got assigned task 'a7aa7de6-4b7c-4c38-a42f-ac2a8e0419e0' for framework 233412ed-bba4-477c-87db-0d2940460191-0000
I0918 20:32:17.699626  5522 hierarchical.cpp:1680] Recovered disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_1_NmWTLJ/disk2,id2:path2]<SHARED>:2048<1>; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_1_NmWTLJ/disk1,id1:path1]<SHARED>:2048<1> (total: cpus:2; mem:2048; ports:[31000-32000]; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_1_NmWTLJ/disk1,id1:path1]<SHARED>:2048<1>; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_1_NmWTLJ/disk2,id2:path2]<SHARED>:2048<1>, offered or allocated: mem(allocated: default-role):2048; cpus(allocated: default-role):2; ports(allocated: default-role):[31000-32000]) on agent 233412ed-bba4-47
 7c-87db-0d2940460191-S0 from framework 233412ed-bba4-477c-87db-0d2940460191-0000
I0918 20:32:17.700947  5531 slave.cpp:2707] Authorizing task 'a7aa7de6-4b7c-4c38-a42f-ac2a8e0419e0' for framework 233412ed-bba4-477c-87db-0d2940460191-0000
I0918 20:32:17.701025  5531 slave.cpp:9716] Authorizing framework principal 'test-principal' to launch task a7aa7de6-4b7c-4c38-a42f-ac2a8e0419e0
I0918 20:32:17.701548  5522 hierarchical.cpp:1680] Recovered mem(allocated: default-role):1920; cpus(allocated: default-role):1; ports(allocated: default-role):[31000-32000] (total: cpus:2; mem:2048; ports:[31000-32000]; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_1_NmWTLJ/disk1,id1:path1]<SHARED>:2048<1>; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_1_NmWTLJ/disk2,id2:path2]<SHARED>:2048<1>, offered or allocated: mem(allocated: default-role):128; cpus(allocated: default-role):1) on agent 233412ed-bba4-477c-87db-0d2940460191-S0 from framework 233412ed-bba4-477c-87db-0d2940460191-0000
I0918 20:32:17.702942  5522 hierarchical.cpp:1891] Allocation resumed
I0918 20:32:17.704551  5521 slave.cpp:3181] Launching task 'a7aa7de6-4b7c-4c38-a42f-ac2a8e0419e0' for framework 233412ed-bba4-477c-87db-0d2940460191-0000
I0918 20:32:17.704723  5521 paths.cpp:832] Creating sandbox '/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_1_RdpPYP/slaves/233412ed-bba4-477c-87db-0d2940460191-S0/frameworks/233412ed-bba4-477c-87db-0d2940460191-0000/executors/a7aa7de6-4b7c-4c38-a42f-ac2a8e0419e0/runs/3f12574e-3b6c-4704-b617-21d3d598102e' for user 'mesos'
I0918 20:32:17.705787  5521 slave.cpp:10380] Launching executor 'a7aa7de6-4b7c-4c38-a42f-ac2a8e0419e0' of framework 233412ed-bba4-477c-87db-0d2940460191-0000 with resources [{"allocation_info":{"role":"default-role"},"name":"cpus","scalar":{"value":0.1},"type":"SCALAR"},{"allocation_info":{"role":"default-role"},"name":"mem","scalar":{"value":32.0},"type":"SCALAR"}] in work directory '/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_1_RdpPYP/slaves/233412ed-bba4-477c-87db-0d2940460191-S0/frameworks/233412ed-bba4-477c-87db-0d2940460191-0000/executors/a7aa7de6-4b7c-4c38-a42f-ac2a8e0419e0/runs/3f12574e-3b6c-4704-b617-21d3d598102e'
I0918 20:32:17.708039  5521 slave.cpp:3415] Queued task 'a7aa7de6-4b7c-4c38-a42f-ac2a8e0419e0' for executor 'a7aa7de6-4b7c-4c38-a42f-ac2a8e0419e0' of framework 233412ed-bba4-477c-87db-0d2940460191-0000
I0918 20:32:17.708218  5521 slave.cpp:1206] Successfully attached '/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_1_RdpPYP/slaves/233412ed-bba4-477c-87db-0d2940460191-S0/frameworks/233412ed-bba4-477c-87db-0d2940460191-0000/executors/a7aa7de6-4b7c-4c38-a42f-ac2a8e0419e0/runs/3f12574e-3b6c-4704-b617-21d3d598102e' to virtual path '/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_1_RdpPYP/slaves/233412ed-bba4-477c-87db-0d2940460191-S0/frameworks/233412ed-bba4-477c-87db-0d2940460191-0000/executors/a7aa7de6-4b7c-4c38-a42f-ac2a8e0419e0/runs/latest'
I0918 20:32:17.708305  5521 slave.cpp:1206] Successfully attached '/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_1_RdpPYP/slaves/233412ed-bba4-477c-87db-0d2940460191-S0/frameworks/233412ed-bba4-477c-87db-0d2940460191-0000/executors/a7aa7de6-4b7c-4c38-a42f-ac2a8e0419e0/runs/3f12574e-3b6c-4704-b617-21d3d598102e' to virtual path '/frameworks/233412ed-bba4-477c-87db-0d2940460191-0000/executors/a7aa7de6-4b7c-4c38-a42f-ac2a8e0419e0/runs/latest'
I0918 20:32:17.708370  5521 slave.cpp:1206] Successfully attached '/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_1_RdpPYP/slaves/233412ed-bba4-477c-87db-0d2940460191-S0/frameworks/233412ed-bba4-477c-87db-0d2940460191-0000/executors/a7aa7de6-4b7c-4c38-a42f-ac2a8e0419e0/runs/3f12574e-3b6c-4704-b617-21d3d598102e' to virtual path '/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_1_RdpPYP/slaves/233412ed-bba4-477c-87db-0d2940460191-S0/frameworks/233412ed-bba4-477c-87db-0d2940460191-0000/executors/a7aa7de6-4b7c-4c38-a42f-ac2a8e0419e0/runs/3f12574e-3b6c-4704-b617-21d3d598102e'
I0918 20:32:17.709224  5521 slave.cpp:3876] Launching container 3f12574e-3b6c-4704-b617-21d3d598102e for executor 'a7aa7de6-4b7c-4c38-a42f-ac2a8e0419e0' of framework 233412ed-bba4-477c-87db-0d2940460191-0000
I0918 20:32:17.710341  5513 containerizer.cpp:1405] Starting container 3f12574e-3b6c-4704-b617-21d3d598102e
I0918 20:32:17.711174  5513 containerizer.cpp:3414] Transitioning the state of container 3f12574e-3b6c-4704-b617-21d3d598102e from STARTING to PROVISIONING after 0ns
I0918 20:32:17.712602  5513 containerizer.cpp:1584] Checkpointed ContainerConfig at '/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_1_tUkMbW/containers/3f12574e-3b6c-4704-b617-21d3d598102e/config'
I0918 20:32:17.712677  5513 containerizer.cpp:3414] Transitioning the state of container 3f12574e-3b6c-4704-b617-21d3d598102e from PROVISIONING to PREPARING after 0ns
I0918 20:32:17.723309  5514 containerizer.cpp:2110] Launching 'mesos-containerizer' with flags '--help="false" --launch_info="{"command":{"arguments":["mesos-executor","--launcher_dir=/tmp/SRC/build/mesos-1.11.0/_build/sub/src"],"shell":false,"value":"/tmp/SRC/build/mesos-1.11.0/_build/sub/src/mesos-executor"},"environment":{"variables":[{"name":"LIBPROCESS_PORT","type":"VALUE","value":"0"},{"name":"MESOS_AGENT_ENDPOINT","type":"VALUE","value":"172.17.0.2:45039"},{"name":"MESOS_CHECKPOINT","type":"VALUE","value":"0"},{"name":"MESOS_DIRECTORY","type":"VALUE","value":"/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_1_RdpPYP/slaves/233412ed-bba4-477c-87db-0d2940460191-S0/frameworks/233412ed-bba4-477c-87db-0d2940460191-0000/executors/a7aa7de6-4b7c-4c38-a42f-ac2a8e0419e0/runs/3f12574e-3b6c-4704-b617-21d3d598102e"},{"name":"MESOS_EXECUTOR_ID","type":"VALUE","value":"a7aa7de6-4b7c-4c38-a42f-ac2a8e0419e0"},{"name":"MESOS_EXECUTOR_SHUTDOWN_GRACE_PERIOD","type":"V
 ALUE","value":"5secs"},{"name":"MESOS_FRAMEWORK_ID","type":"VALUE","value":"233412ed-bba4-477c-87db-0d2940460191-0000"},{"name":"MESOS_HTTP_COMMAND_EXECUTOR","type":"VALUE","value":"0"},{"name":"MESOS_SLAVE_ID","type":"VALUE","value":"233412ed-bba4-477c-87db-0d2940460191-S0"},{"name":"MESOS_SLAVE_PID","type":"VALUE","value":"slave(1094)@172.17.0.2:45039"},{"name":"MESOS_SANDBOX","type":"VALUE","value":"/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_1_RdpPYP/slaves/233412ed-bba4-477c-87db-0d2940460191-S0/frameworks/233412ed-bba4-477c-87db-0d2940460191-0000/executors/a7aa7de6-4b7c-4c38-a42f-ac2a8e0419e0/runs/3f12574e-3b6c-4704-b617-21d3d598102e"}]},"task_environment":{},"user":"mesos","working_directory":"/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_1_RdpPYP/slaves/233412ed-bba4-477c-87db-0d2940460191-S0/frameworks/233412ed-bba4-477c-87db-0d2940460191-0000/executors/a7aa7de6-4b7c-4c38-a42f-ac2a8e0419e0/runs/3f12574e-3b6c-
 4704-b617-21d3d598102e"}" --pipe_read="33" --pipe_write="75" --runtime_directory="/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_1_tUkMbW/containers/3f12574e-3b6c-4704-b617-21d3d598102e" --unshare_namespace_mnt="false"'
I0918 20:32:17.741011  5514 launcher.cpp:145] Forked child with pid '18635' for container '3f12574e-3b6c-4704-b617-21d3d598102e'
I0918 20:32:17.742611  5514 containerizer.cpp:3414] Transitioning the state of container 3f12574e-3b6c-4704-b617-21d3d598102e from PREPARING to ISOLATING after 0ns
I0918 20:32:17.747571  5512 containerizer.cpp:3414] Transitioning the state of container 3f12574e-3b6c-4704-b617-21d3d598102e from ISOLATING to FETCHING after 0ns
I0918 20:32:17.748138  5529 fetcher.cpp:369] Starting to fetch URIs for container: 3f12574e-3b6c-4704-b617-21d3d598102e, directory: /tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_1_RdpPYP/slaves/233412ed-bba4-477c-87db-0d2940460191-S0/frameworks/233412ed-bba4-477c-87db-0d2940460191-0000/executors/a7aa7de6-4b7c-4c38-a42f-ac2a8e0419e0/runs/3f12574e-3b6c-4704-b617-21d3d598102e
I0918 20:32:17.750226  5517 containerizer.cpp:3414] Transitioning the state of container 3f12574e-3b6c-4704-b617-21d3d598102e from FETCHING to RUNNING after 0ns
I0918 20:32:17.803864  5511 status_update_manager_process.hpp:528] Forwarding operation status update OPERATION_FINISHED (Status UUID: 31cbfcbc-e2e2-42a3-a01c-205b38ba04e1) for operation UUID 69afbafc-b938-4fb3-a460-b580c63f1eeb of framework '233412ed-bba4-477c-87db-0d2940460191-0000' on agent 233412ed-bba4-477c-87db-0d2940460191-S0
I0918 20:32:17.804493  5523 slave.cpp:9186] Updating the state of operation with no ID (uuid: 69afbafc-b938-4fb3-a460-b580c63f1eeb) for framework 233412ed-bba4-477c-87db-0d2940460191-0000 (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)
I0918 20:32:17.804589  5523 slave.cpp:6577] Forwarding status update of operation with no ID (operation_uuid: 69afbafc-b938-4fb3-a460-b580c63f1eeb) for framework 233412ed-bba4-477c-87db-0d2940460191-0000
I0918 20:32:17.805186  5528 master.cpp:11405] Updating the state of operation '' (uuid: 69afbafc-b938-4fb3-a460-b580c63f1eeb) for framework 233412ed-bba4-477c-87db-0d2940460191-0000 (latest state: OPERATION_PENDING, status update state: OPERATION_FINISHED)
I0918 20:32:17.806300  5518 status_update_manager_process.hpp:252] Received operation status update acknowledgement (UUID: 31cbfcbc-e2e2-42a3-a01c-205b38ba04e1) for stream 69afbafc-b938-4fb3-a460-b580c63f1eeb
I0918 20:32:17.806403  5518 status_update_manager_process.hpp:929] Checkpointing ACK for operation status update OPERATION_FINISHED (Status UUID: 31cbfcbc-e2e2-42a3-a01c-205b38ba04e1) for operation UUID 69afbafc-b938-4fb3-a460-b580c63f1eeb of framework '233412ed-bba4-477c-87db-0d2940460191-0000' on agent 233412ed-bba4-477c-87db-0d2940460191-S0
I0918 20:32:17.853861  5518 status_update_manager_process.hpp:490] Cleaning up operation status update stream 69afbafc-b938-4fb3-a460-b580c63f1eeb
I0918 20:32:17.856895  5527 slave.cpp:4688] Updated checkpointed operations from [ 69afbafc-b938-4fb3-a460-b580c63f1eeb (CREATE for framework 233412ed-bba4-477c-87db-0d2940460191-0000, latest state: OPERATION_FINISHED) ] to [  ]
W0918 20:32:17.858716  5510 process.cpp:2877] Attempted to spawn already running process version@172.17.0.2:45039
I0918 20:32:17.860183  5510 sched.cpp:239] Version: 1.11.0
I0918 20:32:17.861985  5531 sched.cpp:343] New master detected at master@172.17.0.2:45039
I0918 20:32:17.862226  5531 sched.cpp:408] Authenticating with master master@172.17.0.2:45039
I0918 20:32:17.862263  5531 sched.cpp:415] Using default CRAM-MD5 authenticatee
I0918 20:32:17.862932  5530 authenticatee.cpp:121] Creating new client SASL connection
I0918 20:32:17.863487  5520 master.cpp:9780] Authenticating scheduler-2129879d-9fce-4d0c-ab55-e859cf498eb8@172.17.0.2:45039
I0918 20:32:17.863814  5522 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1891)@172.17.0.2:45039
I0918 20:32:17.864545  5512 authenticator.cpp:98] Creating new server SASL connection
I0918 20:32:17.865152  5529 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0918 20:32:17.865224  5529 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0918 20:32:17.865520  5515 authenticator.cpp:204] Received SASL authentication start
I0918 20:32:17.865689  5515 authenticator.cpp:326] Authentication requires more steps
I0918 20:32:17.865949  5515 authenticatee.cpp:259] Received SASL authentication step
I0918 20:32:17.866286  5525 authenticator.cpp:232] Received SASL authentication step
I0918 20:32:17.866374  5525 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '1c4e7fa2a761' server FQDN: '1c4e7fa2a761' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
I0918 20:32:17.866415  5525 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0918 20:32:17.866569  5525 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0918 20:32:17.866643  5525 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '1c4e7fa2a761' server FQDN: '1c4e7fa2a761' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
I0918 20:32:17.866677  5525 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0918 20:32:17.866703  5525 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0918 20:32:17.866751  5525 authenticator.cpp:318] Authentication success
I0918 20:32:17.867008  5532 authenticatee.cpp:299] Authentication success
I0918 20:32:17.867174  5517 master.cpp:9812] Successfully authenticated principal 'test-principal' at scheduler-2129879d-9fce-4d0c-ab55-e859cf498eb8@172.17.0.2:45039
I0918 20:32:17.867426  5513 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1891)@172.17.0.2:45039
I0918 20:32:17.867962  5525 sched.cpp:520] Successfully authenticated with master master@172.17.0.2:45039
I0918 20:32:17.868016  5525 sched.cpp:835] Sending SUBSCRIBE call to master@172.17.0.2:45039
I0918 20:32:17.868346  5525 sched.cpp:870] Will retry registration in 1.557891928secs if necessary
I0918 20:32:17.868777  5524 master.cpp:2968] Received SUBSCRIBE call for framework 'default' at scheduler-2129879d-9fce-4d0c-ab55-e859cf498eb8@172.17.0.2:45039
I0918 20:32:17.875260  5531 master.cpp:3054] Subscribing framework default with checkpointing disabled and capabilities [ MULTI_ROLE, RESERVATION_REFINEMENT, SHARED_RESOURCES ]
I0918 20:32:17.879441  5531 master.cpp:10011] Adding framework 233412ed-bba4-477c-87db-0d2940460191-0001 (default) at scheduler-2129879d-9fce-4d0c-ab55-e859cf498eb8@172.17.0.2:45039 with roles {  } suppressed
I0918 20:32:17.880468  5522 sched.cpp:751] Framework registered with 233412ed-bba4-477c-87db-0d2940460191-0001
I0918 20:32:17.880581  5522 sched.cpp:770] Scheduler::registered took 56892ns
I0918 20:32:17.880672  5520 hierarchical.cpp:793] Added framework 233412ed-bba4-477c-87db-0d2940460191-0001
I0918 20:32:17.885020  5520 hierarchical.cpp:1957] Performed allocation for 1 agents in 3.864842ms
I0918 20:32:17.887192  5512 master.cpp:9589] Sending offers [ 233412ed-bba4-477c-87db-0d2940460191-O1 ] to framework 233412ed-bba4-477c-87db-0d2940460191-0001 (default) at scheduler-2129879d-9fce-4d0c-ab55-e859cf498eb8@172.17.0.2:45039
I0918 20:32:17.888806  5529 sched.cpp:934] Scheduler::resourceOffers took 176367ns
I0918 20:32:17.891099  5515 master.cpp:5706] Processing KILL call for task 'a7aa7de6-4b7c-4c38-a42f-ac2a8e0419e0' of framework 233412ed-bba4-477c-87db-0d2940460191-0000 (default) at scheduler-51362b45-3d6a-4d6a-884e-490fe14ac542@172.17.0.2:45039
I0918 20:32:17.891288  5515 master.cpp:5754] Telling agent 233412ed-bba4-477c-87db-0d2940460191-S0 at slave(1094)@172.17.0.2:45039 (1c4e7fa2a761) to kill task a7aa7de6-4b7c-4c38-a42f-ac2a8e0419e0 of framework 233412ed-bba4-477c-87db-0d2940460191-0000 (default) at scheduler-51362b45-3d6a-4d6a-884e-490fe14ac542@172.17.0.2:45039
I0918 20:32:17.891907  5511 slave.cpp:3979] Asked to kill task a7aa7de6-4b7c-4c38-a42f-ac2a8e0419e0 of framework 233412ed-bba4-477c-87db-0d2940460191-0000
W0918 20:32:17.892072  5511 slave.cpp:4126] Transitioning the state of task a7aa7de6-4b7c-4c38-a42f-ac2a8e0419e0 of framework 233412ed-bba4-477c-87db-0d2940460191-0000 to TASK_KILLED because the executor is not registered
I0918 20:32:17.892385  5511 slave.cpp:5981] Handling status update TASK_KILLED (Status UUID: 41592e00-0de0-4670-aef1-1ec38446bafa) for task a7aa7de6-4b7c-4c38-a42f-ac2a8e0419e0 of framework 233412ed-bba4-477c-87db-0d2940460191-0000 from @0.0.0.0:0
I0918 20:32:17.898200  5530 task_status_update_manager.cpp:328] Received task status update TASK_KILLED (Status UUID: 41592e00-0de0-4670-aef1-1ec38446bafa) for task a7aa7de6-4b7c-4c38-a42f-ac2a8e0419e0 of framework 233412ed-bba4-477c-87db-0d2940460191-0000
I0918 20:32:17.898315  5530 task_status_update_manager.cpp:507] Creating StatusUpdate stream for task a7aa7de6-4b7c-4c38-a42f-ac2a8e0419e0 of framework 233412ed-bba4-477c-87db-0d2940460191-0000
I0918 20:32:17.899312  5530 task_status_update_manager.cpp:383] Forwarding task status update TASK_KILLED (Status UUID: 41592e00-0de0-4670-aef1-1ec38446bafa) for task a7aa7de6-4b7c-4c38-a42f-ac2a8e0419e0 of framework 233412ed-bba4-477c-87db-0d2940460191-0000 to the agent
I0918 20:32:17.899788  5526 slave.cpp:6527] Forwarding the update TASK_KILLED (Status UUID: 41592e00-0de0-4670-aef1-1ec38446bafa) for task a7aa7de6-4b7c-4c38-a42f-ac2a8e0419e0 of framework 233412ed-bba4-477c-87db-0d2940460191-0000 to master@172.17.0.2:45039
I0918 20:32:17.900207  5526 slave.cpp:6411] Task status update manager successfully handled status update TASK_KILLED (Status UUID: 41592e00-0de0-4670-aef1-1ec38446bafa) for task a7aa7de6-4b7c-4c38-a42f-ac2a8e0419e0 of framework 233412ed-bba4-477c-87db-0d2940460191-0000
I0918 20:32:17.900496  5531 master.cpp:8170] Status update TASK_KILLED (Status UUID: 41592e00-0de0-4670-aef1-1ec38446bafa) for task a7aa7de6-4b7c-4c38-a42f-ac2a8e0419e0 of framework 233412ed-bba4-477c-87db-0d2940460191-0000 from agent 233412ed-bba4-477c-87db-0d2940460191-S0 at slave(1094)@172.17.0.2:45039 (1c4e7fa2a761)
I0918 20:32:17.900609  5531 master.cpp:8227] Forwarding status update TASK_KILLED (Status UUID: 41592e00-0de0-4670-aef1-1ec38446bafa) for task a7aa7de6-4b7c-4c38-a42f-ac2a8e0419e0 of framework 233412ed-bba4-477c-87db-0d2940460191-0000
I0918 20:32:17.901072  5531 master.cpp:11205] Updating the state of task a7aa7de6-4b7c-4c38-a42f-ac2a8e0419e0 of framework 233412ed-bba4-477c-87db-0d2940460191-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED)
I0918 20:32:17.901286  5522 sched.cpp:1042] Scheduler::statusUpdate took 40403ns
I0918 20:32:17.902360  5531 master.cpp:5880] Processing ACKNOWLEDGE call for status 41592e00-0de0-4670-aef1-1ec38446bafa for task a7aa7de6-4b7c-4c38-a42f-ac2a8e0419e0 of framework 233412ed-bba4-477c-87db-0d2940460191-0000 (default) at scheduler-51362b45-3d6a-4d6a-884e-490fe14ac542@172.17.0.2:45039 on agent 233412ed-bba4-477c-87db-0d2940460191-S0
I0918 20:32:17.902673  5531 master.cpp:11305] Removing task a7aa7de6-4b7c-4c38-a42f-ac2a8e0419e0 with resources cpus(allocated: default-role):1; mem(allocated: default-role):128 of framework 233412ed-bba4-477c-87db-0d2940460191-0000 on agent 233412ed-bba4-477c-87db-0d2940460191-S0 at slave(1094)@172.17.0.2:45039 (1c4e7fa2a761)
I0918 20:32:17.902962  5514 hierarchical.cpp:1680] Recovered cpus(allocated: default-role):1; mem(allocated: default-role):128 (total: cpus:2; mem:2048; ports:[31000-32000]; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_1_NmWTLJ/disk1,id1:path1]<SHARED>:2048<1>; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_1_NmWTLJ/disk2,id2:path2]<SHARED>:2048<1>, offered or allocated: mem(allocated: default-role):1920; cpus(allocated: default-role):1; ports(allocated: default-role):[31000-32000]; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_1_NmWTLJ/disk1,id1:path1]<SHARED>:2048<1>; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_1
 _NmWTLJ/disk2,id2:path2]<SHARED>:2048<1>) on agent 233412ed-bba4-477c-87db-0d2940460191-S0 from framework 233412ed-bba4-477c-87db-0d2940460191-0000
I0918 20:32:17.903676  5529 task_status_update_manager.cpp:401] Received task status update acknowledgement (UUID: 41592e00-0de0-4670-aef1-1ec38446bafa) for task a7aa7de6-4b7c-4c38-a42f-ac2a8e0419e0 of framework 233412ed-bba4-477c-87db-0d2940460191-0000
I0918 20:32:17.903880  5529 task_status_update_manager.cpp:538] Cleaning up status update stream for task a7aa7de6-4b7c-4c38-a42f-ac2a8e0419e0 of framework 233412ed-bba4-477c-87db-0d2940460191-0000
I0918 20:32:17.904773  5521 slave.cpp:5090] Task status update manager successfully handled status update acknowledgement (UUID: 41592e00-0de0-4670-aef1-1ec38446bafa) for task a7aa7de6-4b7c-4c38-a42f-ac2a8e0419e0 of framework 233412ed-bba4-477c-87db-0d2940460191-0000
I0918 20:32:17.904889  5521 slave.cpp:11039] Completing task a7aa7de6-4b7c-4c38-a42f-ac2a8e0419e0
I0918 20:32:17.911062  5528 hierarchical.cpp:1957] Performed allocation for 1 agents in 3.235776ms
I0918 20:32:17.912408  5519 master.cpp:9589] Sending offers [ 233412ed-bba4-477c-87db-0d2940460191-O2 ] to framework 233412ed-bba4-477c-87db-0d2940460191-0000 (default) at scheduler-51362b45-3d6a-4d6a-884e-490fe14ac542@172.17.0.2:45039
I0918 20:32:17.913612  5513 sched.cpp:934] Scheduler::resourceOffers took 230324ns
I0918 20:32:17.915033  5528 hierarchical.cpp:1957] Performed allocation for 1 agents in 289262ns
I0918 20:32:17.917088  5530 master.cpp:4366] Processing ACCEPT call for offers: [ 233412ed-bba4-477c-87db-0d2940460191-O1 ] on agent 233412ed-bba4-477c-87db-0d2940460191-S0 at slave(1094)@172.17.0.2:45039 (1c4e7fa2a761) for framework 233412ed-bba4-477c-87db-0d2940460191-0001 (default) at scheduler-2129879d-9fce-4d0c-ab55-e859cf498eb8@172.17.0.2:45039
I0918 20:32:17.918320  5530 master.cpp:11888] Removing offer 233412ed-bba4-477c-87db-0d2940460191-O1
I0918 20:32:17.919780  5526 sched.cpp:960] Rescinded offer 233412ed-bba4-477c-87db-0d2940460191-O2
I0918 20:32:17.920006  5526 sched.cpp:971] Scheduler::offerRescinded took 140021ns
I0918 20:32:17.920475  5510 sched.cpp:2166] Asked to stop the driver
I0918 20:32:17.920581  5530 master.cpp:11888] Removing offer 233412ed-bba4-477c-87db-0d2940460191-O2
I0918 20:32:17.920686  5514 sched.cpp:1204] Stopping framework 233412ed-bba4-477c-87db-0d2940460191-0000
I0918 20:32:17.921340  5510 sched.cpp:2166] Asked to stop the driver
I0918 20:32:17.921509  5515 sched.cpp:1204] Stopping framework 233412ed-bba4-477c-87db-0d2940460191-0001
I0918 20:32:17.921525  5530 master.cpp:4756] Applying DESTROY operation for volumes [{"allocation_info":{"role":"default-role"},"disk":{"persistence":{"id":"id1","principal":"test-principal"},"source":{"path":{"root":"/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_1_NmWTLJ/disk1"},"type":"PATH"},"volume":{"container_path":"path1","mode":"RW"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"shared":{},"type":"SCALAR"},{"allocation_info":{"role":"default-role"},"disk":{"persistence":{"id":"id2","principal":"test-principal"},"source":{"path":{"root":"/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_1_NmWTLJ/disk2"},"type":"PATH"},"volume":{"container_path":"path2","mode":"RW"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"shared":{},"type":"SCALAR"}] from framework 233412ed-bba4-477c-87db-0d2940460191-0001 (default) at schedu
 ler-2129879d-9fce-4d0c-ab55-e859cf498eb8@172.17.0.2:45039 to agent 233412ed-bba4-477c-87db-0d2940460191-S0 at slave(1094)@172.17.0.2:45039 (1c4e7fa2a761)
I0918 20:32:17.923527  5530 master.cpp:11753] Sending operation '' (uuid: a90367ab-85d1-44a6-8444-af8971310c80) to agent 233412ed-bba4-477c-87db-0d2940460191-S0 at slave(1094)@172.17.0.2:45039 (1c4e7fa2a761)
I0918 20:32:17.924486  5530 master.cpp:10372] Processing TEARDOWN call for framework 233412ed-bba4-477c-87db-0d2940460191-0000 (default) at scheduler-51362b45-3d6a-4d6a-884e-490fe14ac542@172.17.0.2:45039
I0918 20:32:17.924549  5530 master.cpp:10384] Removing framework 233412ed-bba4-477c-87db-0d2940460191-0000 (default) at scheduler-51362b45-3d6a-4d6a-884e-490fe14ac542@172.17.0.2:45039
I0918 20:32:17.924582  5530 master.cpp:3437] Deactivating framework 233412ed-bba4-477c-87db-0d2940460191-0000 (default) at scheduler-51362b45-3d6a-4d6a-884e-490fe14ac542@172.17.0.2:45039
I0918 20:32:17.924726  5528 hierarchical.cpp:1680] Recovered disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_1_NmWTLJ/disk1,id1:path1]<SHARED>:2048<1>; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_1_NmWTLJ/disk2,id2:path2]<SHARED>:2048<1>; cpus(allocated: default-role):1; mem(allocated: default-role):128 (total: cpus:2; mem:2048; ports:[31000-32000]; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_1_NmWTLJ/disk1,id1:path1]<SHARED>:2048<1>; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_1_NmWTLJ/disk2,id2:path2]<SHARED>:2048<1>, offered or allocated: mem(allocated: default-role):1920; cpus(allocated: default-role):1; port
 s(allocated: default-role):[31000-32000]; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_1_NmWTLJ/disk1,id1:path1]<SHARED>:2048<1>; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_1_NmWTLJ/disk2,id2:path2]<SHARED>:2048<1>) on agent 233412ed-bba4-477c-87db-0d2940460191-S0 from framework 233412ed-bba4-477c-87db-0d2940460191-0000
I0918 20:32:17.925529  5530 master.cpp:10372] Processing TEARDOWN call for framework 233412ed-bba4-477c-87db-0d2940460191-0001 (default) at scheduler-2129879d-9fce-4d0c-ab55-e859cf498eb8@172.17.0.2:45039
I0918 20:32:17.925583  5530 master.cpp:10384] Removing framework 233412ed-bba4-477c-87db-0d2940460191-0001 (default) at scheduler-2129879d-9fce-4d0c-ab55-e859cf498eb8@172.17.0.2:45039
I0918 20:32:17.925616  5530 master.cpp:3437] Deactivating framework 233412ed-bba4-477c-87db-0d2940460191-0001 (default) at scheduler-2129879d-9fce-4d0c-ab55-e859cf498eb8@172.17.0.2:45039
I0918 20:32:17.927606  5527 slave.cpp:4688] Updated checkpointed operations from [  ] to [ a90367ab-85d1-44a6-8444-af8971310c80 (DESTROY for framework 233412ed-bba4-477c-87db-0d2940460191-0001, latest state: OPERATION_PENDING) ]
I0918 20:32:17.929035  5527 slave.cpp:9186] Updating the state of operation with no ID (uuid: a90367ab-85d1-44a6-8444-af8971310c80) for framework 233412ed-bba4-477c-87db-0d2940460191-0001 (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)
W0918 20:32:17.930215  5520 master.cpp:9391] Master returning resources offered to framework 233412ed-bba4-477c-87db-0d2940460191-0000 because the framework has terminated, is not connected, or is inactive
I0918 20:32:17.930215  5528 hierarchical.cpp:1957] Performed allocation for 1 agents in 2.566456ms
I0918 20:32:17.930990  5527 slave.cpp:4781] Deleting persistent volume 'id1' at '/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_1_NmWTLJ/disk1/volumes/roles/default-role/id1'
I0918 20:32:17.931242  5527 slave.cpp:4781] Deleting persistent volume 'id2' at '/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_1_NmWTLJ/disk2/volumes/roles/default-role/id2'
I0918 20:32:17.931630  5527 slave.cpp:4664] Updated checkpointed resources from disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_1_NmWTLJ/disk1,id1:path1]<SHARED>:2048<1>; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_1_NmWTLJ/disk2,id2:path2]<SHARED>:2048<1> to {}
I0918 20:32:17.932718  5527 slave.cpp:4688] Updated checkpointed operations from [ a90367ab-85d1-44a6-8444-af8971310c80 (DESTROY for framework 233412ed-bba4-477c-87db-0d2940460191-0001, latest state: OPERATION_PENDING) ] to [ a90367ab-85d1-44a6-8444-af8971310c80 (DESTROY for framework 233412ed-bba4-477c-87db-0d2940460191-0001, latest state: OPERATION_FINISHED) ]
I0918 20:32:17.933113  5527 slave.cpp:1002] Agent terminating
I0918 20:32:17.933120  5526 status_update_manager_process.hpp:152] Received operation status update OPERATION_FINISHED (Status UUID: b9c7fe56-a784-4064-97de-9447651d4d78) for operation UUID a90367ab-85d1-44a6-8444-af8971310c80 of framework '233412ed-bba4-477c-87db-0d2940460191-0001' on agent 233412ed-bba4-477c-87db-0d2940460191-S0
I0918 20:32:17.933179  5527 slave.cpp:4285] Asked to shut down framework 233412ed-bba4-477c-87db-0d2940460191-0000 by @0.0.0.0:0
I0918 20:32:17.933208  5526 status_update_manager_process.hpp:414] Creating operation status update stream a90367ab-85d1-44a6-8444-af8971310c80 checkpoint=true
I0918 20:32:17.933229  5527 slave.cpp:4310] Shutting down framework 233412ed-bba4-477c-87db-0d2940460191-0000
I0918 20:32:17.933368  5527 slave.cpp:7473] Shutting down executor 'a7aa7de6-4b7c-4c38-a42f-ac2a8e0419e0' of framework 233412ed-bba4-477c-87db-0d2940460191-0000
W0918 20:32:17.933416  5527 slave.hpp:1034] Unable to send event to executor 'a7aa7de6-4b7c-4c38-a42f-ac2a8e0419e0' of framework 233412ed-bba4-477c-87db-0d2940460191-0000: unknown connection type
I0918 20:32:17.933738  5526 status_update_manager_process.hpp:929] Checkpointing UPDATE for operation status update OPERATION_FINISHED (Status UUID: b9c7fe56-a784-4064-97de-9447651d4d78) for operation UUID a90367ab-85d1-44a6-8444-af8971310c80 of framework '233412ed-bba4-477c-87db-0d2940460191-0001' on agent 233412ed-bba4-477c-87db-0d2940460191-S0
I0918 20:32:17.935420  5515 master.cpp:1301] Agent 233412ed-bba4-477c-87db-0d2940460191-S0 at slave(1094)@172.17.0.2:45039 (1c4e7fa2a761) disconnected
I0918 20:32:17.935478  5515 master.cpp:3472] Disconnecting agent 233412ed-bba4-477c-87db-0d2940460191-S0 at slave(1094)@172.17.0.2:45039 (1c4e7fa2a761)
I0918 20:32:17.935576  5515 master.cpp:3491] Deactivating agent 233412ed-bba4-477c-87db-0d2940460191-S0 at slave(1094)@172.17.0.2:45039 (1c4e7fa2a761)
I0918 20:32:17.936877  5530 containerizer.cpp:2716] Destroying container 3f12574e-3b6c-4704-b617-21d3d598102e in RUNNING state
I0918 20:32:17.936982  5530 containerizer.cpp:3414] Transitioning the state of container 3f12574e-3b6c-4704-b617-21d3d598102e from RUNNING to DESTROYING after 1.502541056secs
I0918 20:32:17.937628  5530 launcher.cpp:161] Asked to destroy container 3f12574e-3b6c-4704-b617-21d3d598102e
I0918 20:32:17.945044  5528 hierarchical.cpp:1416] Updated allocation of framework 233412ed-bba4-477c-87db-0d2940460191-0001 on agent 233412ed-bba4-477c-87db-0d2940460191-S0 from ports(allocated: default-role):[31000-32000]; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_1_NmWTLJ/disk1,id1:path1]<SHARED>:2048<1>; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_1_NmWTLJ/disk2,id2:path2]<SHARED>:2048<1>; cpus(allocated: default-role):1; mem(allocated: default-role):1920 to ports(allocated: default-role):[31000-32000]; mem(allocated: default-role):1920; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_1_NmWTLJ/disk1]:2048; cpus(allocated: default-role):1; disk(allocated: default-r
 ole)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_1_NmWTLJ/disk2]:2048
I0918 20:32:17.945247  5528 hierarchical.cpp:1881] Allocation paused
I0918 20:32:17.945588  5528 hierarchical.cpp:1680] Recovered disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_1_NmWTLJ/disk2]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_1_NmWTLJ/disk1]:2048 (total: cpus:2; mem:2048; ports:[31000-32000]; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_1_NmWTLJ/disk1]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_1_NmWTLJ/disk2]:2048, offered or allocated: mem(allocated: default-role):2048; cpus(allocated: default-role):2; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_1_N
 mWTLJ/disk2,id2:path2]<SHARED>:2048<1>; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_1_NmWTLJ/disk1,id1:path1]<SHARED>:2048<1>; ports(allocated: default-role):[31000-32000]) on agent 233412ed-bba4-477c-87db-0d2940460191-S0 from framework 233412ed-bba4-477c-87db-0d2940460191-0001
I0918 20:32:17.947512  5528 hierarchical.cpp:1680] Recovered ports(allocated: default-role):[31000-32000]; mem(allocated: default-role):1920; cpus(allocated: default-role):1 (total: cpus:2; mem:2048; ports:[31000-32000]; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_1_NmWTLJ/disk1]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_1_NmWTLJ/disk2]:2048, offered or allocated: mem(allocated: default-role):128; cpus(allocated: default-role):1; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_1_NmWTLJ/disk2,id2:path2]<SHARED>:2048<1>; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_1_NmWTLJ/disk1,id1:path1]<SHARED>:2048<1>) 
 on agent 233412ed-bba4-477c-87db-0d2940460191-S0 from framework 233412ed-bba4-477c-87db-0d2940460191-0001
I0918 20:32:17.948997  5528 hierarchical.cpp:1891] Allocation resumed
I0918 20:32:17.949131  5528 hierarchical.cpp:906] Deactivated framework 233412ed-bba4-477c-87db-0d2940460191-0000
I0918 20:32:17.949211  5528 hierarchical.cpp:1881] Allocation paused
I0918 20:32:17.949291  5528 hierarchical.cpp:821] Recovering disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_1_NmWTLJ/disk1,id1:path1]<SHARED>:2048<1>; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_1_NmWTLJ/disk2,id2:path2]<SHARED>:2048<1>; cpus(allocated: default-role):1; mem(allocated: default-role):128 from removing framework 233412ed-bba4-477c-87db-0d2940460191-0000 (agent total: cpus:2; mem:2048; ports:[31000-32000]; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_1_NmWTLJ/disk1]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_1_NmWTLJ/disk2]:2048, offered or allocated: mem(allocated: default-role):128; cpus(all
 ocated: default-role):1; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_1_NmWTLJ/disk2,id2:path2]<SHARED>:2048<1>; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_1_NmWTLJ/disk1,id1:path1]<SHARED>:2048<1>)
F0918 20:32:17.951318  5528 sorter.hpp:356] Check failed: totals.contains(quantitiesToRemove) cpus:1; mem:128 does not contain cpus:1; disk:4096; mem:128
*** Check failure stack trace: ***
I0918 20:32:18.009641  5511 containerizer.cpp:3252] Container 3f12574e-3b6c-4704-b617-21d3d598102e has exited
    @     0x7fb1b4127c4a  google::LogMessage::Fail()
I0918 20:32:18.012754  5526 status_update_manager_process.hpp:528] Forwarding operation status update OPERATION_FINISHED (Status UUID: b9c7fe56-a784-4064-97de-9447651d4d78) for operation UUID a90367ab-85d1-44a6-8444-af8971310c80 of framework '233412ed-bba4-477c-87db-0d2940460191-0001' on agent 233412ed-bba4-477c-87db-0d2940460191-S0
I0918 20:32:18.015130  5513 provisioner.cpp:652] Ignoring destroy request for unknown container 3f12574e-3b6c-4704-b617-21d3d598102e
    @     0x7fb1b4127b8f  google::LogMessage::SendToLog()
    @     0x7fb1b4127516  google::LogMessage::Flush()
    @     0x7fb1b412adaa  google::LogMessageFatal::~LogMessageFatal()
    @     0x7fb1b13483dc  mesos::internal::master::allocator::DRFSorter::Node::Allocation::subtract()
    @     0x7fb1b1345966  mesos::internal::master::allocator::DRFSorter::unallocated()
    @     0x7fb1b12a37d0  mesos::internal::master::allocator::internal::HierarchicalAllocatorProcess::untrackAllocatedResources()
    @     0x7fb1b1292406  mesos::internal::master::allocator::internal::HierarchicalAllocatorProcess::removeFramework()
    @     0x557317f6f883  _ZZN7process8dispatchIN5mesos8internal6master9allocator21MesosAllocatorProcessERKNS1_11FrameworkIDES8_EEvRKNS_3PIDIT_EEMSA_FvT0_EOT1_ENKUlOS6_PNS_11ProcessBaseEE_clESJ_SL_
    @     0x557317ff8d4c  _ZN5cpp176invokeIZN7process8dispatchIN5mesos8internal6master9allocator21MesosAllocatorProcessERKNS3_11FrameworkIDESA_EEvRKNS1_3PIDIT_EEMSC_FvT0_EOT1_EUlOS8_PNS1_11ProcessBaseEE_JS8_SN_EEEDTclcl7forwardISC_Efp_Espcl7forwardIT0_Efp0_EEEOSC_DpOSP_
    @     0x557317ff55d7  _ZN6lambda8internal7PartialIZN7process8dispatchIN5mesos8internal6master9allocator21MesosAllocatorProcessERKNS4_11FrameworkIDESB_EEvRKNS2_3PIDIT_EEMSD_FvT0_EOT1_EUlOS9_PNS2_11ProcessBaseEE_JS9_St12_PlaceholderILi1EEEE13invoke_expandISP_St5tupleIJS9_SR_EESU_IJOSO_EEJLm0ELm1EEEEDTcl6invokecl7forwardISD_Efp_Espcl6expandcl3getIXT2_EEcl7forwardISH_Efp0_EEcl7forwardISK_Efp2_EEEEOSD_OSH_N5cpp1416integer_sequenceImJXspT2_EEEESL_
    @     0x557317ff1fde  _ZNO6lambda8internal7PartialIZN7process8dispatchIN5mesos8internal6master9allocator21MesosAllocatorProcessERKNS4_11FrameworkIDESB_EEvRKNS2_3PIDIT_EEMSD_FvT0_EOT1_EUlOS9_PNS2_11ProcessBaseEE_IS9_St12_PlaceholderILi1EEEEclIISO_EEEDTcl13invoke_expandcl4movedtdefpT1fEcl4movedtdefpT10bound_argsEcvN5cpp1416integer_sequenceImILm0ELm1EEEE_Ecl16forward_as_tuplespcl7forwardIT_Efp_EEEEDpOSX_
    @     0x557317ff0cb9  _ZN5cpp176invokeIN6lambda8internal7PartialIZN7process8dispatchIN5mesos8internal6master9allocator21MesosAllocatorProcessERKNS6_11FrameworkIDESD_EEvRKNS4_3PIDIT_EEMSF_FvT0_EOT1_EUlOSB_PNS4_11ProcessBaseEE_ISB_St12_PlaceholderILi1EEEEEISQ_EEEDTclcl7forwardISF_Efp_Espcl7forwardIT0_Efp0_EEEOSF_DpOSV_
    @     0x557317ff04dd  _ZN6lambda8internal6InvokeIvEclINS0_7PartialIZN7process8dispatchIN5mesos8internal6master9allocator21MesosAllocatorProcessERKNS7_11FrameworkIDESE_EEvRKNS5_3PIDIT_EEMSG_FvT0_EOT1_EUlOSC_PNS5_11ProcessBaseEE_JSC_St12_PlaceholderILi1EEEEEJSR_EEEvOSG_DpOT0_
    @     0x557317fedd6a  _ZNO6lambda12CallableOnceIFvPN7process11ProcessBaseEEE10CallableFnINS_8internal7PartialIZNS1_8dispatchIN5mesos8internal6master9allocator21MesosAllocatorProcessERKNSA_11FrameworkIDESH_EEvRKNS1_3PIDIT_EEMSJ_FvT0_EOT1_EUlOSF_S3_E_ISF_St12_PlaceholderILi1EEEEEEclEOS3_
    @     0x7fb1b402f8fe  _ZNO6lambda12CallableOnceIFvPN7process11ProcessBaseEEEclES3_
    @     0x7fb1b3ff22e6  process::ProcessBase::consume()
    @     0x7fb1b401bbb8  _ZNO7process13DispatchEvent7consumeEPNS_13EventConsumerE
    @     0x557317b3f744  process::ProcessBase::serve()
    @     0x7fb1b3feeac3  process::ProcessManager::resume()
    @     0x7fb1b3feac44  _ZZN7process14ProcessManager12init_threadsEvENKUlvE_clEv
    @     0x7fb1b40144fa  _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEE9_M_invokeIJEEEvSt12_Index_tupleIJXspT_EEE
    @     0x7fb1b401355c  _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEEclEv
    @     0x7fb1b40123a0  _ZNSt6thread5_ImplISt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEEE6_M_runEv
    @     0x7fb1a0cc5c80  (unknown)
    @     0x7fb1a04d86ba  start_thread
    @     0x7fb1a020e41d  clone
Makefile:16043: recipe for target 'check-local' failed
make[4]: *** [check-local] Aborted (core dumped)
make[4]: Leaving directory '/tmp/SRC/build/mesos-1.11.0/_build/sub/src'
Makefile:15151: recipe for target 'check-am' failed
make[3]: *** [check-am] Error 2
make[3]: Leaving directory '/tmp/SRC/build/mesos-1.11.0/_build/sub/src'
Makefile:15155: recipe for target 'check' failed
make[2]: *** [check] Error 2
make[2]: Leaving directory '/tmp/SRC/build/mesos-1.11.0/_build/sub/src'
Makefile:785: recipe for target 'check-recursive' failed
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory '/tmp/SRC/build/mesos-1.11.0/_build/sub'
Makefile:991: recipe for target 'distcheck' failed
make: *** [distcheck] Error 1
Untagged: mesos/mesos-build:ubuntu-16.04
Untagged: mesos/mesos-build@sha256:fa967cbcfb44f55708a3cbc87f245c6d29dd891464db558af56a03ee321526bb
Deleted: sha256:e895c0531b9a9a288fabe479a49f7059aed83645351ac99ec2ea2616822c9f97
Deleted: sha256:09119b0b314a69ba6ec6251f2e89f4199fe1e874c84f9abf07dcbd23dbc3f1c1
Deleted: sha256:562fe6af5a3883058be9c784a839392215ed4185a21d21f1d99df0d17f3ae6e7
Deleted: sha256:0dcdfbe322a4f332f73ac70905d557300ec6dae3bd19586758772c750c7b4a19
Deleted: sha256:c66652d605f01094e2db53d62505dbd524e076d5aa69b89d5e620003803eb149
Deleted: sha256:a2cf79bfb9593c05fd7142ddb49afef77ea4ad5d2464e841f09fe62ffee396e0
Deleted: sha256:f7a904214b390f39d98573882f631dc908df8b2b540cf04e1062f8182c1efffd
Deleted: sha256:ce1f6fcaa83dfce189d76e08f184085732eab4eeb2562d2399953958405c5bec
Deleted: sha256:cce92fda689ab9033f0b8db214bc63edd1ae3e05831a0f3a9418976d7dc7ccdd
Deleted: sha256:d22094bbd65447c59a42c580eaa3a44cee9cd855f00905f59409be21bcefc745
Deleted: sha256:b8976847450013f3eb5e9a81a5778f73ed7bef67e6393049712ef17102b4b7b7
Deleted: sha256:b8c891f0ffec910a12757d733b178e3f62d81dbbde2b31d3b754071c416108ed

Full log: https://ci-builds.apache.org/job/Mesos/job/Mesos-Reviewbot/651/console

- Mesos Reviewbot


On Sept. 18, 2020, 4:54 p.m., Andrei Sekretenko wrote:
> 
> -----------------------------------------------------------
> This is an automatically generated e-mail. To reply, visit:
> https://reviews.apache.org/r/72887/
> -----------------------------------------------------------
> 
> (Updated Sept. 18, 2020, 4:54 p.m.)
> 
> 
> Review request for mesos and Benjamin Mahler.
> 
> 
> Bugs: MESOS-10177
>     https://issues.apache.org/jira/browse/MESOS-10177
> 
> 
> Repository: mesos
> 
> 
> Description
> -------
> 
> This allows the user to specify a fixed URL like
> `https://localhost:5050/hierarchical_allocator/offer_constraints_debug`
> when querying allocator HTTP endpoints instead of looking up
> the allocator UPID and using that to compose an URL (like
> `https://localhost:5050/hierarchical_allocator(0)/...`)
> 
> 
> Diffs
> -----
> 
>   src/master/allocator/mesos/hierarchical.hpp 7e1980ef74bb4138b83707de7698f1993cc21e41 
> 
> 
> Diff: https://reviews.apache.org/r/72887/diff/1/
> 
> 
> Testing
> -------
> 
> `make check` on several platforms + tested the debug endpoint manually
> 
> 
> Thanks,
> 
> Andrei Sekretenko
> 
>


Mime
  • Unnamed multipart/alternative (inline, None, 0 bytes)
View raw message