You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@mesos.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2017/12/19 22:58:56 UTC
Build failed in Jenkins: Mesos-Buildbot » cmake,gcc,--verbose --disable-libtool-wrappers --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,centos:7,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23) #4628
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=gcc,CONFIGURATION=--verbose%20--disable-libtool-wrappers%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4628/display/redirect?page=changes>
Changes:
[andrew] Windows: Deleted unused and unnecessary OS version functions.
[andrew] Removed executable bit from text files.
[andrew] Removed executable bit from text file.
[andrew] Removed executable bit from website files.
------------------------------------------
[...truncated 33.19 MB...]
3: I1219 22:58:44.155645 24917 executor.cpp:740] Received kill for task 07407703-e10d-47b3-8a0e-c4a233fc47d0 with grace period of 3secs
3: I1219 22:58:44.155714 24917 executor.cpp:855] Sending SIGTERM to process tree at pid 24943
3: I1219 22:58:44.160488 24917 executor.cpp:868] Sent SIGTERM to the following process trees:
3: [
3: --- 24943 sleep 1000
3: ]
3: I1219 22:58:44.160504 24917 executor.cpp:872] Scheduling escalation to SIGKILL in 3secs from now
3: I1219 22:58:44.208374 24921 executor.cpp:930] Command terminated with signal Terminated (pid: 24943)
3: I1219 22:58:44.211161 24246 slave.cpp:4768] Handling status update TASK_KILLED (Status UUID: f4f4bd0c-d78a-4e01-84f0-2e52eec68ecc) for task 07407703-e10d-47b3-8a0e-c4a233fc47d0 of framework 2d40ce54-0e0d-4c0e-a2fb-f72f156c128e-0000 from executor(1)@172.17.0.2:58932
3: I1219 22:58:44.213812 24244 posix.cpp:144] Removing symlink '/tmp/DiskResource_PersistentVolumeTest_DestroyPersistentVolumeMultipleTasks_1_9zgSDv/slaves/2d40ce54-0e0d-4c0e-a2fb-f72f156c128e-S0/frameworks/2d40ce54-0e0d-4c0e-a2fb-f72f156c128e-0000/executors/07407703-e10d-47b3-8a0e-c4a233fc47d0/runs/203bbde2-02dc-42b5-b403-78bcb755c58c/path1' for persistent volume disk(allocated: default-role)(reservations: [(STATIC,default-role)])[id1:path1]<SHARED>:2048 of container 203bbde2-02dc-42b5-b403-78bcb755c58c
3: I1219 22:58:44.214848 24253 task_status_update_manager.cpp:328] Received task status update TASK_KILLED (Status UUID: f4f4bd0c-d78a-4e01-84f0-2e52eec68ecc) for task 07407703-e10d-47b3-8a0e-c4a233fc47d0 of framework 2d40ce54-0e0d-4c0e-a2fb-f72f156c128e-0000
3: I1219 22:58:44.215023 24253 task_status_update_manager.cpp:383] Forwarding task status update TASK_KILLED (Status UUID: f4f4bd0c-d78a-4e01-84f0-2e52eec68ecc) for task 07407703-e10d-47b3-8a0e-c4a233fc47d0 of framework 2d40ce54-0e0d-4c0e-a2fb-f72f156c128e-0000 to the agent
3: I1219 22:58:44.215258 24247 slave.cpp:5251] Forwarding the update TASK_KILLED (Status UUID: f4f4bd0c-d78a-4e01-84f0-2e52eec68ecc) for task 07407703-e10d-47b3-8a0e-c4a233fc47d0 of framework 2d40ce54-0e0d-4c0e-a2fb-f72f156c128e-0000 to master@172.17.0.2:32771
3: I1219 22:58:44.215494 24247 slave.cpp:5144] Task status update manager successfully handled status update TASK_KILLED (Status UUID: f4f4bd0c-d78a-4e01-84f0-2e52eec68ecc) for task 07407703-e10d-47b3-8a0e-c4a233fc47d0 of framework 2d40ce54-0e0d-4c0e-a2fb-f72f156c128e-0000
3: I1219 22:58:44.215549 24247 slave.cpp:5160] Sending acknowledgement for status update TASK_KILLED (Status UUID: f4f4bd0c-d78a-4e01-84f0-2e52eec68ecc) for task 07407703-e10d-47b3-8a0e-c4a233fc47d0 of framework 2d40ce54-0e0d-4c0e-a2fb-f72f156c128e-0000 to executor(1)@172.17.0.2:58932
3: I1219 22:58:44.215768 24249 master.cpp:7854] Status update TASK_KILLED (Status UUID: f4f4bd0c-d78a-4e01-84f0-2e52eec68ecc) for task 07407703-e10d-47b3-8a0e-c4a233fc47d0 of framework 2d40ce54-0e0d-4c0e-a2fb-f72f156c128e-0000 from agent 2d40ce54-0e0d-4c0e-a2fb-f72f156c128e-S0 at slave(931)@172.17.0.2:32771 (164fcfb53fc4)
3: I1219 22:58:44.215833 24249 master.cpp:7910] Forwarding status update TASK_KILLED (Status UUID: f4f4bd0c-d78a-4e01-84f0-2e52eec68ecc) for task 07407703-e10d-47b3-8a0e-c4a233fc47d0 of framework 2d40ce54-0e0d-4c0e-a2fb-f72f156c128e-0000
3: I1219 22:58:44.216087 24249 master.cpp:10158] Updating the state of task 07407703-e10d-47b3-8a0e-c4a233fc47d0 of framework 2d40ce54-0e0d-4c0e-a2fb-f72f156c128e-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED)
3: I1219 22:58:44.216351 24252 sched.cpp:1029] Scheduler::statusUpdate took 67653ns
3: I1219 22:58:44.216909 24249 master.cpp:5842] Processing ACKNOWLEDGE call f4f4bd0c-d78a-4e01-84f0-2e52eec68ecc for task 07407703-e10d-47b3-8a0e-c4a233fc47d0 of framework 2d40ce54-0e0d-4c0e-a2fb-f72f156c128e-0000 (default) at scheduler-2a6c7128-1b5e-4d34-947b-01194aa76a87@172.17.0.2:32771 on agent 2d40ce54-0e0d-4c0e-a2fb-f72f156c128e-S0
3: I1219 22:58:44.217066 24249 master.cpp:10264] Removing task 07407703-e10d-47b3-8a0e-c4a233fc47d0 with resources cpus(allocated: default-role):1; mem(allocated: default-role):128; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[id1:path1]<SHARED>:2048<1> of framework 2d40ce54-0e0d-4c0e-a2fb-f72f156c128e-0000 on agent 2d40ce54-0e0d-4c0e-a2fb-f72f156c128e-S0 at slave(931)@172.17.0.2:32771 (164fcfb53fc4)
3: I1219 22:58:44.217480 24241 hierarchical.cpp:1192] Recovered cpus(allocated: default-role):1; mem(allocated: default-role):128; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[id1:path1]<SHARED>:2048<1> (total: cpus:2; mem:2048; disk(reservations: [(STATIC,default-role)])[id1:path1]<SHARED>:2048<1>; ports:[31000-32000]; disk(reservations: [(STATIC,default-role)]):2048, allocated: cpus(allocated: default-role):1; mem(allocated: default-role):1920; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[id1:path1]<SHARED>:2048<1>; disk(allocated: default-role)(reservations: [(STATIC,default-role)]):2048; ports(allocated: default-role):[31000-32000]) on agent 2d40ce54-0e0d-4c0e-a2fb-f72f156c128e-S0 from framework 2d40ce54-0e0d-4c0e-a2fb-f72f156c128e-0000
3: I1219 22:58:44.217698 24239 task_status_update_manager.cpp:401] Received task status update acknowledgement (UUID: f4f4bd0c-d78a-4e01-84f0-2e52eec68ecc) for task 07407703-e10d-47b3-8a0e-c4a233fc47d0 of framework 2d40ce54-0e0d-4c0e-a2fb-f72f156c128e-0000
3: I1219 22:58:44.217840 24239 task_status_update_manager.cpp:538] Cleaning up status update stream for task 07407703-e10d-47b3-8a0e-c4a233fc47d0 of framework 2d40ce54-0e0d-4c0e-a2fb-f72f156c128e-0000
3: I1219 22:58:44.218327 24245 slave.cpp:4046] Task status update manager successfully handled status update acknowledgement (UUID: f4f4bd0c-d78a-4e01-84f0-2e52eec68ecc) for task 07407703-e10d-47b3-8a0e-c4a233fc47d0 of framework 2d40ce54-0e0d-4c0e-a2fb-f72f156c128e-0000
3: I1219 22:58:44.218392 24245 slave.cpp:8975] Completing task 07407703-e10d-47b3-8a0e-c4a233fc47d0
3: I1219 22:58:44.226851 24246 master.cpp:10678] Removing offer 2d40ce54-0e0d-4c0e-a2fb-f72f156c128e-O2
3: I1219 22:58:44.227005 24246 master.cpp:4273] Processing ACCEPT call for offers: [ 2d40ce54-0e0d-4c0e-a2fb-f72f156c128e-O2 ] on agent 2d40ce54-0e0d-4c0e-a2fb-f72f156c128e-S0 at slave(931)@172.17.0.2:32771 (164fcfb53fc4) for framework 2d40ce54-0e0d-4c0e-a2fb-f72f156c128e-0000 (default) at scheduler-2a6c7128-1b5e-4d34-947b-01194aa76a87@172.17.0.2:32771
3: I1219 22:58:44.227186 24246 master.cpp:3838] Authorizing principal 'test-principal' to destroy 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"}]'
3: I1219 22:58:44.229034 24242 master.cpp:4842] 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"}] from framework 2d40ce54-0e0d-4c0e-a2fb-f72f156c128e-0000 (default) at scheduler-2a6c7128-1b5e-4d34-947b-01194aa76a87@172.17.0.2:32771 to agent 2d40ce54-0e0d-4c0e-a2fb-f72f156c128e-S0 at slave(931)@172.17.0.2:32771 (164fcfb53fc4)
3: I1219 22:58:44.229872 24242 master.cpp:10575] Sending offer operation '' (uuid: 31a872f6-333e-4ee5-aaf9-362a5c1074e8) to agent 2d40ce54-0e0d-4c0e-a2fb-f72f156c128e-S0 at slave(931)@172.17.0.2:32771 (164fcfb53fc4)
3: I1219 22:58:44.231673 24253 slave.cpp:3790] Deleting persistent volume 'id1' at '/tmp/DiskResource_PersistentVolumeTest_DestroyPersistentVolumeMultipleTasks_1_9zgSDv/volumes/roles/default-role/id1'
3: I1219 22:58:44.232049 24253 slave.cpp:3718] Updated checkpointed resources from disk(reservations: [(STATIC,default-role)])[id1:path1]<SHARED>:2048<1> to {}
3: I1219 22:58:44.232164 24253 slave.cpp:7458] Updating the state of offer operation '' (uuid: 31a872f6-333e-4ee5-aaf9-362a5c1074e8) of framework 2d40ce54-0e0d-4c0e-a2fb-f72f156c128e-0000 (latest state: OFFER_OPERATION_FINISHED, status update state: OFFER_OPERATION_FINISHED)
3: I1219 22:58:44.232522 24253 master.cpp:10363] Updating the state of offer operation '' (uuid: 31a872f6-333e-4ee5-aaf9-362a5c1074e8) of framework 2d40ce54-0e0d-4c0e-a2fb-f72f156c128e-0000 (latest state: OFFER_OPERATION_FINISHED, status update state: OFFER_OPERATION_FINISHED)
3: I1219 22:58:44.235493 24247 hierarchical.cpp:925] Updated allocation of framework 2d40ce54-0e0d-4c0e-a2fb-f72f156c128e-0000 on agent 2d40ce54-0e0d-4c0e-a2fb-f72f156c128e-S0 from cpus(allocated: default-role):1; mem(allocated: default-role):1920; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[id1:path1]<SHARED>:2048<1>; disk(allocated: default-role)(reservations: [(STATIC,default-role)]):2048; ports(allocated: default-role):[31000-32000] to cpus(allocated: default-role):1; mem(allocated: default-role):1920; disk(allocated: default-role)(reservations: [(STATIC,default-role)]):4096; ports(allocated: default-role):[31000-32000]
3: I1219 22:58:44.236951 24247 hierarchical.cpp:1192] Recovered cpus(allocated: default-role):1; mem(allocated: default-role):1920; disk(allocated: default-role)(reservations: [(STATIC,default-role)]):4096; ports(allocated: default-role):[31000-32000] (total: cpus:2; mem:2048; disk(reservations: [(STATIC,default-role)]):4096; ports:[31000-32000], allocated: {}) on agent 2d40ce54-0e0d-4c0e-a2fb-f72f156c128e-S0 from framework 2d40ce54-0e0d-4c0e-a2fb-f72f156c128e-0000
3: I1219 22:58:44.239280 24249 hierarchical.cpp:1517] Performed allocation for 1 agents in 1.590989ms
3: I1219 22:58:44.240046 24245 master.cpp:8762] Sending 1 offers to framework 2d40ce54-0e0d-4c0e-a2fb-f72f156c128e-0000 (default) at scheduler-2a6c7128-1b5e-4d34-947b-01194aa76a87@172.17.0.2:32771
3: I1219 22:58:44.240721 24251 sched.cpp:921] Scheduler::resourceOffers took 133780ns
3: I1219 22:58:44.241395 17609 sched.cpp:2009] Asked to stop the driver
3: I1219 22:58:44.241580 24248 sched.cpp:1191] Stopping framework 2d40ce54-0e0d-4c0e-a2fb-f72f156c128e-0000
3: I1219 22:58:44.241868 24242 master.cpp:9442] Processing TEARDOWN call for framework 2d40ce54-0e0d-4c0e-a2fb-f72f156c128e-0000 (default) at scheduler-2a6c7128-1b5e-4d34-947b-01194aa76a87@172.17.0.2:32771
3: I1219 22:58:44.241904 24242 master.cpp:9454] Removing framework 2d40ce54-0e0d-4c0e-a2fb-f72f156c128e-0000 (default) at scheduler-2a6c7128-1b5e-4d34-947b-01194aa76a87@172.17.0.2:32771
3: I1219 22:58:44.241930 24242 master.cpp:3327] Deactivating framework 2d40ce54-0e0d-4c0e-a2fb-f72f156c128e-0000 (default) at scheduler-2a6c7128-1b5e-4d34-947b-01194aa76a87@172.17.0.2:32771
3: I1219 22:58:44.242089 24247 hierarchical.cpp:405] Deactivated framework 2d40ce54-0e0d-4c0e-a2fb-f72f156c128e-0000
3: I1219 22:58:44.242728 24242 master.cpp:10678] Removing offer 2d40ce54-0e0d-4c0e-a2fb-f72f156c128e-O3
3: I1219 22:58:44.242733 17609 slave.cpp:927] Agent terminating
3: I1219 22:58:44.242800 17609 slave.cpp:3394] Asked to shut down framework 2d40ce54-0e0d-4c0e-a2fb-f72f156c128e-0000 by @0.0.0.0:0
3: I1219 22:58:44.242825 17609 slave.cpp:3419] Shutting down framework 2d40ce54-0e0d-4c0e-a2fb-f72f156c128e-0000
3: I1219 22:58:44.242866 17609 slave.cpp:6127] Shutting down executor '07407703-e10d-47b3-8a0e-c4a233fc47d0' of framework 2d40ce54-0e0d-4c0e-a2fb-f72f156c128e-0000 at executor(1)@172.17.0.2:58932
3: I1219 22:58:44.243111 17609 slave.cpp:6127] Shutting down executor '8fa2978c-053c-4756-91bf-041aefde6925' of framework 2d40ce54-0e0d-4c0e-a2fb-f72f156c128e-0000 at executor(1)@172.17.0.2:58588
3: I1219 22:58:44.243350 24240 hierarchical.cpp:1192] Recovered cpus(allocated: default-role):2; mem(allocated: default-role):2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)]):4096; ports(allocated: default-role):[31000-32000] (total: cpus:2; mem:2048; disk(reservations: [(STATIC,default-role)]):4096; ports:[31000-32000], allocated: {}) on agent 2d40ce54-0e0d-4c0e-a2fb-f72f156c128e-S0 from framework 2d40ce54-0e0d-4c0e-a2fb-f72f156c128e-0000
3: I1219 22:58:44.243635 24239 master.cpp:1305] Agent 2d40ce54-0e0d-4c0e-a2fb-f72f156c128e-S0 at slave(931)@172.17.0.2:32771 (164fcfb53fc4) disconnected
3: I1219 22:58:44.243659 24239 master.cpp:3364] Disconnecting agent 2d40ce54-0e0d-4c0e-a2fb-f72f156c128e-S0 at slave(931)@172.17.0.2:32771 (164fcfb53fc4)
3: I1219 22:58:44.243772 24240 hierarchical.cpp:344] Removed framework 2d40ce54-0e0d-4c0e-a2fb-f72f156c128e-0000
3: I1219 22:58:44.243866 24239 master.cpp:3383] Deactivating agent 2d40ce54-0e0d-4c0e-a2fb-f72f156c128e-S0 at slave(931)@172.17.0.2:32771 (164fcfb53fc4)
3: I1219 22:58:44.244118 24923 exec.cpp:435] Executor asked to shutdown
3: I1219 22:58:44.244390 24932 exec.cpp:435] Executor asked to shutdown
3: I1219 22:58:44.244480 24912 executor.cpp:171] Received SHUTDOWN event
3: I1219 22:58:44.244506 24912 executor.cpp:748] Shutting down
3: I1219 22:58:44.244765 24252 hierarchical.cpp:766] Agent 2d40ce54-0e0d-4c0e-a2fb-f72f156c128e-S0 deactivated
3: I1219 22:58:44.244838 24927 executor.cpp:171] Received SHUTDOWN event
3: I1219 22:58:44.244880 24927 executor.cpp:748] Shutting down
3: I1219 22:58:44.245617 24240 containerizer.cpp:2352] Destroying container 203bbde2-02dc-42b5-b403-78bcb755c58c in RUNNING state
3: I1219 22:58:44.245645 24240 containerizer.cpp:2966] Transitioning the state of container 203bbde2-02dc-42b5-b403-78bcb755c58c from RUNNING to DESTROYING
3: I1219 22:58:44.246044 24240 launcher.cpp:156] Asked to destroy container 203bbde2-02dc-42b5-b403-78bcb755c58c
3: I1219 22:58:44.340874 24253 containerizer.cpp:2805] Container 203bbde2-02dc-42b5-b403-78bcb755c58c has exited
3: I1219 22:58:44.343950 24248 provisioner.cpp:598] Ignoring destroy request for unknown container 203bbde2-02dc-42b5-b403-78bcb755c58c
3: I1219 22:58:44.345754 24246 containerizer.cpp:2352] Destroying container eed19097-08ca-4bb9-a935-4e2f60610bb0 in RUNNING state
3: I1219 22:58:44.345783 24246 containerizer.cpp:2966] Transitioning the state of container eed19097-08ca-4bb9-a935-4e2f60610bb0 from RUNNING to DESTROYING
3: I1219 22:58:44.346158 24246 launcher.cpp:156] Asked to destroy container eed19097-08ca-4bb9-a935-4e2f60610bb0
3: I1219 22:58:44.442129 24241 containerizer.cpp:2805] Container eed19097-08ca-4bb9-a935-4e2f60610bb0 has exited
3: I1219 22:58:44.444821 24249 provisioner.cpp:598] Ignoring destroy request for unknown container eed19097-08ca-4bb9-a935-4e2f60610bb0
3: I1219 22:58:44.455988 17609 master.cpp:1147] Master terminating
3: I1219 22:58:44.457096 24241 hierarchical.cpp:609] Removed agent 2d40ce54-0e0d-4c0e-a2fb-f72f156c128e-S0
3: [ OK ] DiskResource/PersistentVolumeTest.DestroyPersistentVolumeMultipleTasks/1 (877 ms)
3: [ RUN ] DiskResource/PersistentVolumeTest.DestroyPersistentVolumeMultipleTasks/2
3: I1219 22:58:44.466102 17609 cluster.cpp:172] Creating default 'local' authorizer
3: I1219 22:58:44.469776 24251 master.cpp:456] Master f2cd1de5-33de-414a-864a-ddb2d33ed054 (164fcfb53fc4) started on 172.17.0.2:32771
3: I1219 22:58:44.469802 24251 master.cpp:458] Flags at startup: --acls="" --agent_ping_timeout="15secs" --agent_reregister_timeout="10mins" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate_agents="true" --authenticate_frameworks="true" --authenticate_http_frameworks="true" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/PWuQFu/credentials" --filter_gpu_resources="true" --framework_sorter="drf" --help="false" --hostname_lookup="true" --http_authenticators="basic" --http_framework_authenticators="basic" --initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO" --max_agent_ping_timeouts="5" --max_completed_frameworks="50" --max_completed_tasks_per_framework="1000" --max_unreachable_tasks_per_framework="1000" --port="5050" --quiet="false" --recovery_agent_removal_limit="100%" --registry="in_memory" --registry_fetch_timeout="1mins" --registry_gc_interval="15mins" --registry_max_agent_age="2weeks" --registry_max_agent_count="102400" --registry_store_timeout="100secs" --registry_strict="false" --root_submissions="true" --user_sorter="drf" --version="false" --webui_dir="/usr/local/share/mesos/webui" --work_dir="/tmp/PWuQFu/master" --zk_session_timeout="10secs"
3: I1219 22:58:44.470197 24251 master.cpp:507] Master only allowing authenticated frameworks to register
3: I1219 22:58:44.470207 24251 master.cpp:513] Master only allowing authenticated agents to register
3: I1219 22:58:44.470213 24251 master.cpp:519] Master only allowing authenticated HTTP frameworks to register
3: I1219 22:58:44.470221 24251 credentials.hpp:37] Loading credentials for authentication from '/tmp/PWuQFu/credentials'
3: I1219 22:58:44.470645 24251 master.cpp:563] Using default 'crammd5' authenticator
3: I1219 22:58:44.470849 24251 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
3: I1219 22:58:44.471069 24251 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
3: I1219 22:58:44.471271 24251 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
3: I1219 22:58:44.471458 24251 master.cpp:642] Authorization enabled
3: I1219 22:58:44.471699 24241 whitelist_watcher.cpp:77] No whitelist given
3: I1219 22:58:44.471707 24246 hierarchical.cpp:175] Initialized hierarchical allocator process
3: I1219 22:58:44.475602 24240 master.cpp:2209] Elected as the leading master!
3: I1219 22:58:44.475630 24240 master.cpp:1689] Recovering from registrar
3: I1219 22:58:44.475816 24239 registrar.cpp:347] Recovering registrar
3: I1219 22:58:44.476702 24239 registrar.cpp:391] Successfully fetched the registry (0B) in 0ns
3: I1219 22:58:44.476836 24239 registrar.cpp:495] Applied 1 operations in 39724ns; attempting to update the registry
3: I1219 22:58:44.477579 24239 registrar.cpp:552] Successfully updated the registry in 0ns
3: I1219 22:58:44.477736 24239 registrar.cpp:424] Successfully recovered registrar
3: I1219 22:58:44.478178 24244 master.cpp:1802] Recovered 0 agents from the registry (135B); allowing 10mins for agents to re-register
3: I1219 22:58:44.478209 24246 hierarchical.cpp:213] Skipping recovery of hierarchical allocator: nothing to recover
3: W1219 22:58:44.485559 17609 process.cpp:2756] Attempted to spawn already running process files@172.17.0.2:32771
3: I1219 22:58:44.486816 17609 containerizer.cpp:304] Using isolation { environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni }
3: W1219 22:58:44.487480 17609 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
3: W1219 22:58:44.487646 17609 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
3: I1219 22:58:44.487690 17609 provisioner.cpp:299] Using default backend 'copy'
3: I1219 22:58:44.489809 17609 cluster.cpp:460] Creating default 'local' authorizer
3: I1219 22:58:44.492305 24245 slave.cpp:258] Mesos agent started on (932)@172.17.0.2:32771
3: I1219 22:58:44.492319 24245 slave.cpp:259] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/DiskResource_PersistentVolumeTest_DestroyPersistentVolumeMultipleTasks_2_qgi8ci/store/appc" --authenticate_http_executors="true" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticatee="crammd5" --authentication_backoff_factor="1secs" --authorizer="local" --cgroups_cpu_enable_pids_and_tids_count="false" --cgroups_enable_cfs="false" --cgroups_hierarchy="/sys/fs/cgroup" --cgroups_limit_swap="false" --cgroups_root="mesos" --container_disk_watch_interval="15secs" --containerizers="mesos" --credential="/tmp/DiskResource_PersistentVolumeTest_DestroyPersistentVolumeMultipleTasks_2_qgi8ci/credential" --default_role="*" --disallow_sharing_agent_pid_namespace="false" --disk_watch_interval="1mins" --docker="docker" --docker_kill_orphans="true" --docker_registry="https://registry-1.docker.io" --docker_remove_delay="6hrs" --docker_socket="/var/run/docker.sock" --docker_stop_timeout="0ns" --docker_store_dir="/tmp/DiskResource_PersistentVolumeTest_DestroyPersistentVolumeMultipleTasks_2_qgi8ci/store/docker" --docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_reregistration_timeout="2secs" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/DiskResource_PersistentVolumeTest_DestroyPersistentVolumeMultipleTasks_2_qgi8ci/fetch" --fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks" --gc_disk_headroom="0.1" --hadoop_home="" --help="false" --hostname_lookup="true" --http_command_executor="false" --http_credentials="/tmp/DiskResource_PersistentVolumeTest_DestroyPersistentVolumeMultipleTasks_2_qgi8ci/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --jwt_secret_key="/tmp/DiskResource_PersistentVolumeTest_DestroyPersistentVolumeMultipleTasks_2_qgi8ci/jwt_secret_key" --launcher="posix" --launcher_dir="/mesos/build/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --port="5051" --qos_correction_interval_min="0ns" --quiet="false" --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_DestroyPersistentVolumeMultipleTasks_2_EN62O5\/disk1"},"type":"PATH"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"},{"disk":{"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_DestroyPersistentVolumeMultipleTasks_2_EN62O5\/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_DestroyPersistentVolumeMultipleTasks_2_qgi8ci" --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_DestroyPersistentVolumeMultipleTasks_2_Zq140G" --zk_session_timeout="10secs"
3: I1219 22:58:44.492645 24245 credentials.hpp:86] Loading credential for authentication from '/tmp/DiskResource_PersistentVolumeTest_DestroyPersistentVolumeMultipleTasks_2_qgi8ci/credential'
3: I1219 22:58:44.492776 24245 slave.cpp:291] Agent using credential for: test-principal
3: I1219 22:58:44.492791 24245 credentials.hpp:37] Loading credentials for authentication from '/tmp/DiskResource_PersistentVolumeTest_DestroyPersistentVolumeMultipleTasks_2_qgi8ci/http_credentials'
3: I1219 22:58:44.493026 24245 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-executor'
3: I1219 22:58:44.493144 24245 http.cpp:1066] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-executor'
3: I1219 22:58:44.493362 24245 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
3: I1219 22:58:44.493463 24245 http.cpp:1066] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readonly'
3: I1219 22:58:44.493655 24245 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
3: I1219 22:58:44.493757 24245 http.cpp:1066] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readwrite'
3: I1219 22:58:44.496901 24245 slave.cpp:608] 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_DestroyPersistentVolumeMultipleTasks_2_EN62O5\/disk1"},"type":"PATH"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"},{"disk":{"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_DestroyPersistentVolumeMultipleTasks_2_EN62O5\/disk2"},"type":"PATH"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
3: I1219 22:58:44.497285 24245 slave.cpp:616] Agent attributes: [ ]
3: I1219 22:58:44.497295 24245 slave.cpp:625] Agent hostname: 164fcfb53fc4
3: I1219 22:58:44.497462 24238 task_status_update_manager.cpp:181] Pausing sending task status updates
3: I1219 22:58:44.499017 24241 state.cpp:66] Recovering state from '/tmp/DiskResource_PersistentVolumeTest_DestroyPersistentVolumeMultipleTasks_2_Zq140G/meta'
3: I1219 22:58:44.499423 24241 task_status_update_manager.cpp:207] Recovering task status update manager
3: I1219 22:58:44.499680 24251 containerizer.cpp:674] Recovering containerizer
3: I1219 22:58:44.501507 24239 provisioner.cpp:495] Provisioner recovery complete
3: I1219 22:58:44.502001 24244 slave.cpp:6773] Finished recovery
3: I1219 22:58:44.502986 24244 slave.cpp:1051] New master detected at master@172.17.0.2:32771
3: I1219 22:58:44.502997 24247 task_status_update_manager.cpp:181] Pausing sending task status updates
3: I1219 22:58:44.503088 24244 slave.cpp:1106] Detecting new master
3: I1219 22:58:44.503343 24250 slave.cpp:1133] Authenticating with master master@172.17.0.2:32771
3: I1219 22:58:44.503429 24250 slave.cpp:1142] Using default CRAM-MD5 authenticatee
3: I1219 22:58:44.503762 24240 authenticatee.cpp:121] Creating new client SASL connection
3: I1219 22:58:44.504165 24251 master.cpp:8932] Authenticating slave(932)@172.17.0.2:32771
3: I1219 22:58:44.504354 24245 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1618)@172.17.0.2:32771
3: I1219 22:58:44.504637 24238 authenticator.cpp:98] Creating new server SASL connection
3: I1219 22:58:44.504920 24249 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
3: I1219 22:58:44.504945 24249 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
3: I1219 22:58:44.505069 24249 authenticator.cpp:204] Received SASL authentication start
3: I1219 22:58:44.505199 24249 authenticator.cpp:326] Authentication requires more steps
3: I1219 22:58:44.505363 24249 authenticatee.cpp:259] Received SASL authentication step
3: I1219 22:58:44.505491 24249 authenticator.cpp:232] Received SASL authentication step
3: I1219 22:58:44.505532 24249 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '164fcfb53fc4' server FQDN: '164fcfb53fc4' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
3: I1219 22:58:44.505544 24249 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
3: I1219 22:58:44.505578 24249 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
3: I1219 22:58:44.505601 24249 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '164fcfb53fc4' server FQDN: '164fcfb53fc4' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
3: I1219 22:58:44.505609 24249 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
3: I1219 22:58:44.505614 24249 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
3: I1219 22:58:44.505630 24249 authenticator.cpp:318] Authentication success
3: I1219 22:58:44.505764 24241 authenticatee.cpp:299] Authentication success
3: I1219 22:58:44.505879 24243 master.cpp:8962] Successfully authenticated principal 'test-principal' at slave(932)@172.17.0.2:32771
3: I1219 22:58:44.505879 24252 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1618)@172.17.0.2:32771
3: I1219 22:58:44.506069 24249 slave.cpp:1225] Successfully authenticated with master master@172.17.0.2:32771
3: I1219 22:58:44.506294 24249 slave.cpp:1704] Will retry registration in 15.064894ms if necessary
3: I1219 22:58:44.506623 24240 master.cpp:6077] Received register agent message from slave(932)@172.17.0.2:32771 (164fcfb53fc4)
3: I1219 22:58:44.506726 24240 master.cpp:3866] Authorizing agent with principal 'test-principal'
3: I1219 22:58:44.507166 24251 master.cpp:6147] Authorized registration of agent at slave(932)@172.17.0.2:32771 (164fcfb53fc4)
3: I1219 22:58:44.507243 24251 master.cpp:6240] Registering agent at slave(932)@172.17.0.2:32771 (164fcfb53fc4) with id f2cd1de5-33de-414a-864a-ddb2d33ed054-S0
3: I1219 22:58:44.507796 24238 registrar.cpp:495] Applied 1 operations in 106731ns; attempting to update the registry
3: I1219 22:58:44.508399 24238 registrar.cpp:552] Successfully updated the registry in 0ns
3: I1219 22:58:44.508601 24241 master.cpp:6288] Admitted agent f2cd1de5-33de-414a-864a-ddb2d33ed054-S0 at slave(932)@172.17.0.2:32771 (164fcfb53fc4)
3: I1219 22:58:44.509691 24241 master.cpp:6337] Registered agent f2cd1de5-33de-414a-864a-ddb2d33ed054-S0 at slave(932)@172.17.0.2:32771 (164fcfb53fc4) with cpus:2; mem:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_DestroyPersistentVolumeMultipleTasks_2_EN62O5/disk1]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_DestroyPersistentVolumeMultipleTasks_2_EN62O5/disk2]:2048; ports:[31000-32000]
3: I1219 22:58:44.509744 24247 slave.cpp:1271] Registered with master master@172.17.0.2:32771; given agent ID f2cd1de5-33de-414a-864a-ddb2d33ed054-S0
3: I1219 22:58:44.509877 24241 task_status_update_manager.cpp:188] Resuming sending task status updates
3: I1219 22:58:44.510233 24247 slave.cpp:1291] Checkpointing SlaveInfo to '/tmp/DiskResource_PersistentVolumeTest_DestroyPersistentVolumeMultipleTasks_2_Zq140G/meta/slaves/f2cd1de5-33de-414a-864a-ddb2d33ed054-S0/slave.info'
3: I1219 22:58:44.511054 24247 slave.cpp:1348] Forwarding agent update {"offer_operations":{},"resource_version_uuid":{"value":"08eH5pUOReel8FTSm6eXFw=="},"slave_id":{"value":"f2cd1de5-33de-414a-864a-ddb2d33ed054-S0"},"update_oversubscribed_resources":true}
3: I1219 22:58:44.511198 24244 hierarchical.cpp:574] Added agent f2cd1de5-33de-414a-864a-ddb2d33ed054-S0 (164fcfb53fc4) with cpus:2; mem:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_DestroyPersistentVolumeMultipleTasks_2_EN62O5/disk1]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_DestroyPersistentVolumeMultipleTasks_2_EN62O5/disk2]:2048; ports:[31000-32000] (allocated: {})
3: I1219 22:58:44.511286 24249 master.cpp:7266] Received update of agent f2cd1de5-33de-414a-864a-ddb2d33ed054-S0 at slave(932)@172.17.0.2:32771 (164fcfb53fc4) with total oversubscribed resources {}
3: I1219 22:58:44.511612 24249 master.cpp:7362] Ignoring update on agent f2cd1de5-33de-414a-864a-ddb2d33ed054-S0 at slave(932)@172.17.0.2:32771 (164fcfb53fc4) as it reports no changes
3: I1219 22:58:44.511673 24244 hierarchical.cpp:1517] Performed allocation for 1 agents in 235700ns
3: W1219 22:58:44.512128 17609 process.cpp:2756] Attempted to spawn already running process version@172.17.0.2:32771
3: I1219 22:58:44.512976 17609 sched.cpp:232] Version: 1.5.0
3: I1219 22:58:44.513581 24238 sched.cpp:336] New master detected at master@172.17.0.2:32771
3: I1219 22:58:44.513676 24238 sched.cpp:396] Authenticating with master master@172.17.0.2:32771
3: I1219 22:58:44.513694 24238 sched.cpp:403] Using default CRAM-MD5 authenticatee
3: I1219 22:58:44.513926 24250 authenticatee.cpp:121] Creating new client SASL connection
3: I1219 22:58:44.513970 24248 hierarchical.cpp:1517] Performed allocation for 1 agents in 213203ns
3: I1219 22:58:44.514161 24243 master.cpp:8932] Authenticating scheduler-ed117396-5504-4388-8a8b-b32e35917929@172.17.0.2:32771
3: I1219 22:58:44.514297 24248 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1619)@172.17.0.2:32771
3: I1219 22:58:44.514606 24247 authenticator.cpp:98] Creating new server SASL connection
3: I1219 22:58:44.514839 24249 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
3: I1219 22:58:44.514860 24249 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
3: I1219 22:58:44.514948 24249 authenticator.cpp:204] Received SASL authentication start
3: I1219 22:58:44.514986 24249 authenticator.cpp:326] Authentication requires more steps
3: I1219 22:58:44.515116 24240 authenticatee.cpp:259] Received SASL authentication step
3: I1219 22:58:44.515269 24245 authenticator.cpp:232] Received SASL authentication step
3: I1219 22:58:44.515296 24245 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '164fcfb53fc4' server FQDN: '164fcfb53fc4' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
3: I1219 22:58:44.515313 24245 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
3: I1219 22:58:44.515337 24245 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
3: I1219 22:58:44.515352 24245 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '164fcfb53fc4' server FQDN: '164fcfb53fc4' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
3: I1219 22:58:44.515358 24245 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
3: I1219 22:58:44.515362 24245 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
3: I1219 22:58:44.515374 24245 authenticator.cpp:318] Authentication success
3: I1219 22:58:44.515512 24239 authenticatee.cpp:299] Authentication success
3: I1219 22:58:44.515537 24242 master.cpp:8962] Successfully authenticated principal 'test-principal' at scheduler-ed117396-5504-4388-8a8b-b32e35917929@172.17.0.2:32771
3: I1219 22:58:44.515580 24246 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1619)@172.17.0.2:32771
3: I1219 22:58:44.515813 24242 sched.cpp:502] Successfully authenticated with master master@172.17.0.2:32771
3: I1219 22:58:44.515832 24242 sched.cpp:824] Sending SUBSCRIBE call to master@172.17.0.2:32771
3: I1219 22:58:44.515993 24242 sched.cpp:857] Will retry registration in 230.586179ms if necessary
3: I1219 22:58:44.516216 24242 master.cpp:2957] Received SUBSCRIBE call for framework 'default' at scheduler-ed117396-5504-4388-8a8b-b32e35917929@172.17.0.2:32771
3: I1219 22:58:44.516288 24242 master.cpp:2274] Authorizing framework principal 'test-principal' to receive offers for roles '{ default-role }'
3: I1219 22:58:44.516890 24243 master.cpp:3037] Subscribing framework default with checkpointing disabled and capabilities [ MULTI_ROLE, RESERVATION_REFINEMENT, SHARED_RESOURCES ]
3: I1219 22:58:44.517089 24243 master.cpp:9153] Adding framework f2cd1de5-33de-414a-864a-ddb2d33ed054-0000 (default) at scheduler-ed117396-5504-4388-8a8b-b32e35917929@172.17.0.2:32771 with roles { } suppressed
3: I1219 22:58:44.517550 24244 sched.cpp:751] Framework registered with f2cd1de5-33de-414a-864a-ddb2d33ed054-0000
3: I1219 22:58:44.517606 24244 sched.cpp:765] Scheduler::registered took 24124ns
3: I1219 22:58:44.517776 24248 hierarchical.cpp:297] Added framework f2cd1de5-33de-414a-864a-ddb2d33ed054-0000
3: I1219 22:58:44.520170 24248 hierarchical.cpp:1517] Performed allocation for 1 agents in 2.232279ms
3: I1219 22:58:44.520988 24245 master.cpp:8762] Sending 1 offers to framework f2cd1de5-33de-414a-864a-ddb2d33ed054-0000 (default) at scheduler-ed117396-5504-4388-8a8b-b32e35917929@172.17.0.2:32771
3: I1219 22:58:44.521664 24253 sched.cpp:921] Scheduler::resourceOffers took 134362ns
3: I1219 22:58:44.524513 24238 master.cpp:10678] Removing offer f2cd1de5-33de-414a-864a-ddb2d33ed054-O0
3: I1219 22:58:44.524750 24238 master.cpp:4273] Processing ACCEPT call for offers: [ f2cd1de5-33de-414a-864a-ddb2d33ed054-O0 ] on agent f2cd1de5-33de-414a-864a-ddb2d33ed054-S0 at slave(932)@172.17.0.2:32771 (164fcfb53fc4) for framework f2cd1de5-33de-414a-864a-ddb2d33ed054-0000 (default) at scheduler-ed117396-5504-4388-8a8b-b32e35917929@172.17.0.2:32771
3: I1219 22:58:44.524883 24238 master.cpp:3786] Authorizing principal 'test-principal' to create volumes '[{"allocation_info":{"role":"default-role"},"disk":{"persistence":{"id":"id1","principal":"test-principal"},"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_DestroyPersistentVolumeMultipleTasks_2_EN62O5\/disk1"},"type":"PATH"},"volume":{"container_path":"path1","mode":"RW"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"shared":{},"type":"SCALAR"}]'
3: I1219 22:58:44.525398 24238 master.cpp:3786] Authorizing principal 'test-principal' to create volumes '[{"allocation_info":{"role":"default-role"},"disk":{"persistence":{"id":"id2","principal":"test-principal"},"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_DestroyPersistentVolumeMultipleTasks_2_EN62O5\/disk2"},"type":"PATH"},"volume":{"container_path":"path2","mode":"RW"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]'
3: I1219 22:58:44.525866 24238 master.cpp:3593] Authorizing framework principal 'test-principal' to launch task baaaffe0-4620-4e2f-b02b-a5baf27dc429
3: I1219 22:58:44.526152 24238 master.cpp:3593] Authorizing framework principal 'test-principal' to launch task cdaae295-43e1-4c13-81a7-a08145c617f8
3: I1219 22:58:44.528333 24244 master.cpp:4749] Applying CREATE operation for volumes [{"allocation_info":{"role":"default-role"},"disk":{"persistence":{"id":"id1","principal":"test-principal"},"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_DestroyPersistentVolumeMultipleTasks_2_EN62O5\/disk1"},"type":"PATH"},"volume":{"container_path":"path1","mode":"RW"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"shared":{},"type":"SCALAR"}] from framework f2cd1de5-33de-414a-864a-ddb2d33ed054-0000 (default) at scheduler-ed117396-5504-4388-8a8b-b32e35917929@172.17.0.2:32771 to agent f2cd1de5-33de-414a-864a-ddb2d33ed054-S0 at slave(932)@172.17.0.2:32771 (164fcfb53fc4)
3: I1219 22:58:44.529217 24244 master.cpp:10623] Sending updated checkpointed resources disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_DestroyPersistentVolumeMultipleTasks_2_EN62O5/disk1,id1:path1]<SHARED>:2048<1> to agent f2cd1de5-33de-414a-864a-ddb2d33ed054-S0 at slave(932)@172.17.0.2:32771 (164fcfb53fc4)
3: I1219 22:58:44.530198 24244 master.cpp:4749] Applying CREATE operation for volumes [{"allocation_info":{"role":"default-role"},"disk":{"persistence":{"id":"id2","principal":"test-principal"},"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_DestroyPersistentVolumeMultipleTasks_2_EN62O5\/disk2"},"type":"PATH"},"volume":{"container_path":"path2","mode":"RW"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}] from framework f2cd1de5-33de-414a-864a-ddb2d33ed054-0000 (default) at scheduler-ed117396-5504-4388-8a8b-b32e35917929@172.17.0.2:32771 to agent f2cd1de5-33de-414a-864a-ddb2d33ed054-S0 at slave(932)@172.17.0.2:32771 (164fcfb53fc4)
3: I1219 22:58:44.530812 24240 slave.cpp:3718] Updated checkpointed resources from {} to disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_DestroyPersistentVolumeMultipleTasks_2_EN62O5/disk1,id1:path1]<SHARED>:2048<1>
3: I1219 22:58:44.531088 24244 master.cpp:10623] Sending updated checkpointed resources disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_DestroyPersistentVolumeMultipleTasks_2_EN62O5/disk1,id1:path1]<SHARED>:2048<1>; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_DestroyPersistentVolumeMultipleTasks_2_EN62O5/disk2,id2:path2]:2048 to agent f2cd1de5-33de-414a-864a-ddb2d33ed054-S0 at slave(932)@172.17.0.2:32771 (164fcfb53fc4)
3: I1219 22:58:44.532778 24248 slave.cpp:3718] Updated checkpointed resources from disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_DestroyPersistentVolumeMultipleTasks_2_EN62O5/disk1,id1:path1]<SHARED>:2048<1> to disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_DestroyPersistentVolumeMultipleTasks_2_EN62O5/disk1,id1:path1]<SHARED>:2048<1>; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_DestroyPersistentVolumeMultipleTasks_2_EN62O5/disk2,id2:path2]:2048
3: I1219 22:58:44.533036 24244 master.cpp:11433] Adding task baaaffe0-4620-4e2f-b02b-a5baf27dc429 with resources cpus(allocated: default-role):1; mem(allocated: default-role):128; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_DestroyPersistentVolumeMultipleTasks_2_EN62O5/disk1,id1:path1]<SHARED>:2048<1> on agent f2cd1de5-33de-414a-864a-ddb2d33ed054-S0 at slave(932)@172.17.0.2:32771 (164fcfb53fc4)
3: I1219 22:58:44.533443 24244 master.cpp:5024] Launching task baaaffe0-4620-4e2f-b02b-a5baf27dc429 of framework f2cd1de5-33de-414a-864a-ddb2d33ed054-0000 (default) at scheduler-ed117396-5504-4388-8a8b-b32e35917929@172.17.0.2:32771 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"},{"allocation_info":{"role":"default-role"},"disk":{"persistence":{"id":"id1","principal":"test-principal"},"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_DestroyPersistentVolumeMultipleTasks_2_EN62O5\/disk1"},"type":"PATH"},"volume":{"container_path":"path1","mode":"RW"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"shared":{},"type":"SCALAR"}] on agent f2cd1de5-33de-414a-864a-ddb2d33ed054-S0 at slave(932)@172.17.0.2:32771 (164fcfb53fc4)
3: I1219 22:58:44.534446 24245 slave.cpp:1827] Got assigned task 'baaaffe0-4620-4e2f-b02b-a5baf27dc429' for framework f2cd1de5-33de-414a-864a-ddb2d33ed054-0000
3: I1219 22:58:44.535070 24245 slave.cpp:2097] Authorizing task 'baaaffe0-4620-4e2f-b02b-a5baf27dc429' for framework f2cd1de5-33de-414a-864a-ddb2d33ed054-0000
3: I1219 22:58:44.535127 24245 slave.cpp:7896] Authorizing framework principal 'test-principal' to launch task baaaffe0-4620-4e2f-b02b-a5baf27dc429
3: I1219 22:58:44.535198 24244 master.cpp:11433] Adding task cdaae295-43e1-4c13-81a7-a08145c617f8 with resources cpus(allocated: default-role):1; mem(allocated: default-role):256; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_DestroyPersistentVolumeMultipleTasks_2_EN62O5/disk2,id2:path2]:2048 on agent f2cd1de5-33de-414a-864a-ddb2d33ed054-S0 at slave(932)@172.17.0.2:32771 (164fcfb53fc4)
3: I1219 22:58:44.535512 24244 master.cpp:5024] Launching task cdaae295-43e1-4c13-81a7-a08145c617f8 of framework f2cd1de5-33de-414a-864a-ddb2d33ed054-0000 (default) at scheduler-ed117396-5504-4388-8a8b-b32e35917929@172.17.0.2:32771 with resources [{"allocation_info":{"role":"default-role"},"name":"cpus","scalar":{"value":1.0},"type":"SCALAR"},{"allocation_info":{"role":"default-role"},"name":"mem","scalar":{"value":256.0},"type":"SCALAR"},{"allocation_info":{"role":"default-role"},"disk":{"persistence":{"id":"id2","principal":"test-principal"},"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_DestroyPersistentVolumeMultipleTasks_2_EN62O5\/disk2"},"type":"PATH"},"volume":{"container_path":"path2","mode":"RW"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}] on agent f2cd1de5-33de-414a-864a-ddb2d33ed054-S0 at slave(932)@172.17.0.2:32771 (164fcfb53fc4)
3: I1219 22:58:44.536216 24239 slave.cpp:2490] Launching task 'baaaffe0-4620-4e2f-b02b-a5baf27dc429' for framework f2cd1de5-33de-414a-864a-ddb2d33ed054-0000
3: I1219 22:58:44.536831 24239 paths.cpp:756] Trying to chown '/tmp/DiskResource_PersistentVolumeTest_DestroyPersistentVolumeMultipleTasks_2_Zq140G/slaves/f2cd1de5-33de-414a-864a-ddb2d33ed054-S0/frameworks/f2cd1de5-33de-414a-864a-ddb2d33ed054-0000/executors/baaaffe0-4620-4e2f-b02b-a5baf27dc429/runs/37ac990b-a6ca-4240-bd44-001e5c3d7829' to user 'mesos'
3: I1219 22:58:44.537115 24239 slave.cpp:8370] Launching executor 'baaaffe0-4620-4e2f-b02b-a5baf27dc429' of framework f2cd1de5-33de-414a-864a-ddb2d33ed054-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_DestroyPersistentVolumeMultipleTasks_2_Zq140G/slaves/f2cd1de5-33de-414a-864a-ddb2d33ed054-S0/frameworks/f2cd1de5-33de-414a-864a-ddb2d33ed054-0000/executors/baaaffe0-4620-4e2f-b02b-a5baf27dc429/runs/37ac990b-a6ca-4240-bd44-001e5c3d7829'
3: I1219 22:58:44.538334 24239 slave.cpp:2576] Queued task 'baaaffe0-4620-4e2f-b02b-a5baf27dc429' for executor 'baaaffe0-4620-4e2f-b02b-a5baf27dc429' of framework f2cd1de5-33de-414a-864a-ddb2d33ed054-0000
3: I1219 22:58:44.539095 24239 slave.cpp:1827] Got assigned task 'cdaae295-43e1-4c13-81a7-a08145c617f8' for framework f2cd1de5-33de-414a-864a-ddb2d33ed054-0000
3: I1219 22:58:44.539697 24239 slave.cpp:1002] Successfully attached '/tmp/DiskResource_PersistentVolumeTest_DestroyPersistentVolumeMultipleTasks_2_Zq140G/slaves/f2cd1de5-33de-414a-864a-ddb2d33ed054-S0/frameworks/f2cd1de5-33de-414a-864a-ddb2d33ed054-0000/executors/baaaffe0-4620-4e2f-b02b-a5baf27dc429/runs/37ac990b-a6ca-4240-bd44-001e5c3d7829' to virtual path '/tmp/DiskResource_PersistentVolumeTest_DestroyPersistentVolumeMultipleTasks_2_Zq140G/slaves/f2cd1de5-33de-414a-864a-ddb2d33ed054-S0/frameworks/f2cd1de5-33de-414a-864a-ddb2d33ed054-0000/executors/baaaffe0-4620-4e2f-b02b-a5baf27dc429/runs/latest'
3: I1219 22:58:44.539754 24239 slave.cpp:1002] Successfully attached '/tmp/DiskResource_PersistentVolumeTest_DestroyPersistentVolumeMultipleTasks_2_Zq140G/slaves/f2cd1de5-33de-414a-864a-ddb2d33ed054-S0/frameworks/f2cd1de5-33de-414a-864a-ddb2d33ed054-0000/executors/baaaffe0-4620-4e2f-b02b-a5baf27dc429/runs/37ac990b-a6ca-4240-bd44-001e5c3d7829' to virtual path '/frameworks/f2cd1de5-33de-414a-864a-ddb2d33ed054-0000/executors/baaaffe0-4620-4e2f-b02b-a5baf27dc429/runs/latest'
3: I1219 22:58:44.540266 24239 slave.cpp:3042] Launching container 37ac990b-a6ca-4240-bd44-001e5c3d7829 for executor 'baaaffe0-4620-4e2f-b02b-a5baf27dc429' of framework f2cd1de5-33de-414a-864a-ddb2d33ed054-0000
3: I1219 22:58:44.540915 24239 slave.cpp:1002] Successfully attached '/tmp/DiskResource_PersistentVolumeTest_DestroyPersistentVolumeMultipleTasks_2_Zq140G/slaves/f2cd1de5-33de-414a-864a-ddb2d33ed054-S0/frameworks/f2cd1de5-33de-414a-864a-ddb2d33ed054-0000/executors/baaaffe0-4620-4e2f-b02b-a5baf27dc429/runs/37ac990b-a6ca-4240-bd44-001e5c3d7829' to virtual path '/tmp/DiskResource_PersistentVolumeTest_DestroyPersistentVolumeMultipleTasks_2_Zq140G/slaves/f2cd1de5-33de-414a-864a-ddb2d33ed054-S0/frameworks/f2cd1de5-33de-414a-864a-ddb2d33ed054-0000/executors/baaaffe0-4620-4e2f-b02b-a5baf27dc429/runs/37ac990b-a6ca-4240-bd44-001e5c3d7829'
3: I1219 22:58:44.541054 24239 slave.cpp:2097] Authorizing task 'cdaae295-43e1-4c13-81a7-a08145c617f8' for framework f2cd1de5-33de-414a-864a-ddb2d33ed054-0000
3: I1219 22:58:44.541133 24239 slave.cpp:7896] Authorizing framework principal 'test-principal' to launch task cdaae295-43e1-4c13-81a7-a08145c617f8
3: I1219 22:58:44.541818 24241 hierarchical.cpp:925] Updated allocation of framework f2cd1de5-33de-414a-864a-ddb2d33ed054-0000 on agent f2cd1de5-33de-414a-864a-ddb2d33ed054-S0 from cpus(allocated: default-role):2; mem(allocated: default-role):2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_DestroyPersistentVolumeMultipleTasks_2_EN62O5/disk1]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_DestroyPersistentVolumeMultipleTasks_2_EN62O5/disk2]:2048; ports(allocated: default-role):[31000-32000] to cpus(allocated: default-role):2; mem(allocated: default-role):2048; ports(allocated: default-role):[31000-32000]; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_DestroyPersistentVolumeMultipleTasks_2_EN62O5/disk1,id1:path1]<SHARED>:2048<1>; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_DestroyPersistentVolumeMultipleTasks_2_EN62O5/disk2,id2:path2]:2048
3: I1219 22:58:44.541941 24247 containerizer.cpp:1207] Starting container 37ac990b-a6ca-4240-bd44-001e5c3d7829
3: I1219 22:58:44.542189 24249 slave.cpp:2490] Launching task 'cdaae295-43e1-4c13-81a7-a08145c617f8' for framework f2cd1de5-33de-414a-864a-ddb2d33ed054-0000
3: I1219 22:58:44.542532 24249 paths.cpp:756] Trying to chown '/tmp/DiskResource_PersistentVolumeTest_DestroyPersistentVolumeMultipleTasks_2_Zq140G/slaves/f2cd1de5-33de-414a-864a-ddb2d33ed054-S0/frameworks/f2cd1de5-33de-414a-864a-ddb2d33ed054-0000/executors/cdaae295-43e1-4c13-81a7-a08145c617f8/runs/b33511f6-55ee-4384-8797-5e12cec0d705' to user 'mesos'
3: I1219 22:58:44.542757 24249 slave.cpp:8370] Launching executor 'cdaae295-43e1-4c13-81a7-a08145c617f8' of framework f2cd1de5-33de-414a-864a-ddb2d33ed054-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_DestroyPersistentVolumeMultipleTasks_2_Zq140G/slaves/f2cd1de5-33de-414a-864a-ddb2d33ed054-S0/frameworks/f2cd1de5-33de-414a-864a-ddb2d33ed054-0000/executors/cdaae295-43e1-4c13-81a7-a08145c617f8/runs/b33511f6-55ee-4384-8797-5e12cec0d705'
3: I1219 22:58:44.543409 24247 containerizer.cpp:1380] Checkpointed ContainerConfig at '/tmp/DiskResource_PersistentVolumeTest_DestroyPersistentVolumeMultipleTasks_2_qgi8ci/containers/37ac990b-a6ca-4240-bd44-001e5c3d7829/config'
3: I1219 22:58:44.543454 24247 containerizer.cpp:2966] Transitioning the state of container 37ac990b-a6ca-4240-bd44-001e5c3d7829 from PROVISIONING to PREPARING
write /dev/stdout: resource temporarily unavailable
+ docker rmi mesos-1513721800-4918
Build step 'Execute shell' marked build as failure
Jenkins build is back to normal : Mesos-Buildbot » cmake,gcc,--verbose --disable-libtool-wrappers --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,centos:7,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23) #4629
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=gcc,CONFIGURATION=--verbose%20--disable-libtool-wrappers%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4629/display/redirect>