-----------------------------------------------------------
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
>
>
