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/01 13:12:52 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) #4526
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)/4526/display/redirect>
------------------------------------------
[...truncated 31.03 MB...]
3: I1201 13:12:44.239657 24067 sched.cpp:396] Authenticating with master master@172.17.0.2:45652
3: I1201 13:12:44.239675 24067 sched.cpp:403] Using default CRAM-MD5 authenticatee
3: I1201 13:12:44.239701 24065 hierarchical.cpp:1431] Performed allocation for 1 agents in 115204ns
3: I1201 13:12:44.240051 24069 authenticatee.cpp:121] Creating new client SASL connection
3: I1201 13:12:44.240371 24067 master.cpp:8589] Authenticating scheduler-7374a584-ae9a-4907-8e8c-f6313e181824@172.17.0.2:45652
3: I1201 13:12:44.240530 24064 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1545)@172.17.0.2:45652
3: I1201 13:12:44.240737 24070 authenticator.cpp:98] Creating new server SASL connection
3: I1201 13:12:44.240939 24071 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
3: I1201 13:12:44.240962 24071 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
3: I1201 13:12:44.241062 24071 authenticator.cpp:204] Received SASL authentication start
3: I1201 13:12:44.241104 24071 authenticator.cpp:326] Authentication requires more steps
3: I1201 13:12:44.241201 24071 authenticatee.cpp:259] Received SASL authentication step
3: I1201 13:12:44.241308 24071 authenticator.cpp:232] Received SASL authentication step
3: I1201 13:12:44.241334 24071 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '97693488dee2' server FQDN: '97693488dee2' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
3: I1201 13:12:44.241343 24071 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
3: I1201 13:12:44.241370 24071 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
3: I1201 13:12:44.241384 24071 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '97693488dee2' server FQDN: '97693488dee2' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
3: I1201 13:12:44.241394 24071 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
3: I1201 13:12:44.241400 24071 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
3: I1201 13:12:44.241415 24071 authenticator.cpp:318] Authentication success
3: I1201 13:12:44.241533 24068 authenticatee.cpp:299] Authentication success
3: I1201 13:12:44.241598 24063 master.cpp:8619] Successfully authenticated principal 'test-principal' at scheduler-7374a584-ae9a-4907-8e8c-f6313e181824@172.17.0.2:45652
3: I1201 13:12:44.241626 24060 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1545)@172.17.0.2:45652
3: I1201 13:12:44.241911 24058 sched.cpp:502] Successfully authenticated with master master@172.17.0.2:45652
3: I1201 13:12:44.241938 24058 sched.cpp:824] Sending SUBSCRIBE call to master@172.17.0.2:45652
3: I1201 13:12:44.242065 24058 sched.cpp:857] Will retry registration in 1.935817967secs if necessary
3: I1201 13:12:44.242252 24069 master.cpp:2969] Received SUBSCRIBE call for framework 'default' at scheduler-7374a584-ae9a-4907-8e8c-f6313e181824@172.17.0.2:45652
3: I1201 13:12:44.242339 24069 master.cpp:2286] Authorizing framework principal 'test-principal' to receive offers for roles '{ default-role }'
3: I1201 13:12:44.242771 24067 master.cpp:3049] Subscribing framework default with checkpointing disabled and capabilities [ RESERVATION_REFINEMENT, SHARED_RESOURCES ]
3: I1201 13:12:44.243413 24061 sched.cpp:751] Framework registered with a5124651-3ca1-4d96-9fd7-488d4cb4117a-0000
3: I1201 13:12:44.243463 24061 sched.cpp:765] Scheduler::registered took 25269ns
3: I1201 13:12:44.243494 24064 hierarchical.cpp:293] Added framework a5124651-3ca1-4d96-9fd7-488d4cb4117a-0000
3: I1201 13:12:44.245095 24064 hierarchical.cpp:1431] Performed allocation for 1 agents in 1.484782ms
3: I1201 13:12:44.245816 24062 master.cpp:8419] Sending 1 offers to framework a5124651-3ca1-4d96-9fd7-488d4cb4117a-0000 (default) at scheduler-7374a584-ae9a-4907-8e8c-f6313e181824@172.17.0.2:45652
3: I1201 13:12:44.246320 24071 sched.cpp:921] Scheduler::resourceOffers took 88080ns
3: I1201 13:12:44.248518 24063 master.cpp:10331] Removing offer a5124651-3ca1-4d96-9fd7-488d4cb4117a-O0
3: I1201 13:12:44.248703 24063 master.cpp:4236] Processing ACCEPT call for offers: [ a5124651-3ca1-4d96-9fd7-488d4cb4117a-O0 ] on agent a5124651-3ca1-4d96-9fd7-488d4cb4117a-S0 at slave(894)@172.17.0.2:45652 (97693488dee2) for framework a5124651-3ca1-4d96-9fd7-488d4cb4117a-0000 (default) at scheduler-7374a584-ae9a-4907-8e8c-f6313e181824@172.17.0.2:45652
3: I1201 13:12:44.248843 24063 master.cpp:3798] 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_SharedPersistentVolumeRescindOnDestroy_2_wrQeA1\/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_2_wrQeA1\/disk2"},"type":"PATH"},"volume":{"container_path":"path2","mode":"RW"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"shared":{},"type":"SCALAR"}]'
3: I1201 13:12:44.249487 24063 master.cpp:3605] Authorizing framework principal 'test-principal' to launch task 3d50fff3-edb5-4d47-b056-b24e184a09fd
3: I1201 13:12:44.251601 24069 master.cpp:4712] Applying CREATE operation for volumes [{"allocation_info":{"role":"default-role"},"disk":{"persistence":{"id":"id1","principal":"test-principal"},"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_2_wrQeA1\/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_2_wrQeA1\/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 a5124651-3ca1-4d96-9fd7-488d4cb4117a-0000 (default) at scheduler-7374a584-ae9a-4907-8e8c-f6313e181824@172.17.0.2:45652 to agent a5124651-3ca1-4d96-9fd7-488d4cb4117a-S0 at slave(894)@172.17.0.2:45652 (97693488dee2)
3: I1201 13:12:44.253315 24069 master.cpp:10276] Sending updated checkpointed resources disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_2_wrQeA1/disk1,id1:path1]<SHARED>:2048<1>; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_2_wrQeA1/disk2,id2:path2]<SHARED>:2048<1> to agent a5124651-3ca1-4d96-9fd7-488d4cb4117a-S0 at slave(894)@172.17.0.2:45652 (97693488dee2)
3: I1201 13:12:44.254799 24069 master.cpp:11091] Adding task 3d50fff3-edb5-4d47-b056-b24e184a09fd with resources cpus(allocated: default-role):1; mem(allocated: default-role):128 on agent a5124651-3ca1-4d96-9fd7-488d4cb4117a-S0 at slave(894)@172.17.0.2:45652 (97693488dee2)
3: I1201 13:12:44.255144 24069 master.cpp:4984] Launching task 3d50fff3-edb5-4d47-b056-b24e184a09fd of framework a5124651-3ca1-4d96-9fd7-488d4cb4117a-0000 (default) at scheduler-7374a584-ae9a-4907-8e8c-f6313e181824@172.17.0.2:45652 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 a5124651-3ca1-4d96-9fd7-488d4cb4117a-S0 at slave(894)@172.17.0.2:45652 (97693488dee2)
3: I1201 13:12:44.255570 24067 slave.cpp:3605] Updated checkpointed resources from {} to disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_2_wrQeA1/disk1,id1:path1]<SHARED>:2048<1>; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_2_wrQeA1/disk2,id2:path2]<SHARED>:2048<1>
3: I1201 13:12:44.256203 24067 slave.cpp:1817] Got assigned task '3d50fff3-edb5-4d47-b056-b24e184a09fd' for framework a5124651-3ca1-4d96-9fd7-488d4cb4117a-0000
3: I1201 13:12:44.256729 24067 slave.cpp:2085] Authorizing task '3d50fff3-edb5-4d47-b056-b24e184a09fd' for framework a5124651-3ca1-4d96-9fd7-488d4cb4117a-0000
3: I1201 13:12:44.256774 24067 slave.cpp:7411] Authorizing framework principal 'test-principal' to launch task 3d50fff3-edb5-4d47-b056-b24e184a09fd
3: I1201 13:12:44.257506 24062 slave.cpp:2253] Launching task '3d50fff3-edb5-4d47-b056-b24e184a09fd' for framework a5124651-3ca1-4d96-9fd7-488d4cb4117a-0000
3: I1201 13:12:44.258025 24062 paths.cpp:692] Trying to chown '/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_2_Iiplf0/slaves/a5124651-3ca1-4d96-9fd7-488d4cb4117a-S0/frameworks/a5124651-3ca1-4d96-9fd7-488d4cb4117a-0000/executors/3d50fff3-edb5-4d47-b056-b24e184a09fd/runs/860dd8bf-5a1f-4dd5-b1b9-f7ab6827405f' to user 'mesos'
3: I1201 13:12:44.258239 24062 slave.cpp:7885] Launching executor '3d50fff3-edb5-4d47-b056-b24e184a09fd' of framework a5124651-3ca1-4d96-9fd7-488d4cb4117a-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_2_Iiplf0/slaves/a5124651-3ca1-4d96-9fd7-488d4cb4117a-S0/frameworks/a5124651-3ca1-4d96-9fd7-488d4cb4117a-0000/executors/3d50fff3-edb5-4d47-b056-b24e184a09fd/runs/860dd8bf-5a1f-4dd5-b1b9-f7ab6827405f'
3: I1201 13:12:44.259102 24062 slave.cpp:2481] Queued task '3d50fff3-edb5-4d47-b056-b24e184a09fd' for executor '3d50fff3-edb5-4d47-b056-b24e184a09fd' of framework a5124651-3ca1-4d96-9fd7-488d4cb4117a-0000
3: I1201 13:12:44.259171 24062 slave.cpp:950] Successfully attached '/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_2_Iiplf0/slaves/a5124651-3ca1-4d96-9fd7-488d4cb4117a-S0/frameworks/a5124651-3ca1-4d96-9fd7-488d4cb4117a-0000/executors/3d50fff3-edb5-4d47-b056-b24e184a09fd/runs/860dd8bf-5a1f-4dd5-b1b9-f7ab6827405f' to virtual path '/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_2_Iiplf0/slaves/a5124651-3ca1-4d96-9fd7-488d4cb4117a-S0/frameworks/a5124651-3ca1-4d96-9fd7-488d4cb4117a-0000/executors/3d50fff3-edb5-4d47-b056-b24e184a09fd/runs/latest'
3: I1201 13:12:44.259205 24062 slave.cpp:950] Successfully attached '/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_2_Iiplf0/slaves/a5124651-3ca1-4d96-9fd7-488d4cb4117a-S0/frameworks/a5124651-3ca1-4d96-9fd7-488d4cb4117a-0000/executors/3d50fff3-edb5-4d47-b056-b24e184a09fd/runs/860dd8bf-5a1f-4dd5-b1b9-f7ab6827405f' to virtual path '/frameworks/a5124651-3ca1-4d96-9fd7-488d4cb4117a-0000/executors/3d50fff3-edb5-4d47-b056-b24e184a09fd/runs/latest'
3: I1201 13:12:44.259232 24062 slave.cpp:950] Successfully attached '/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_2_Iiplf0/slaves/a5124651-3ca1-4d96-9fd7-488d4cb4117a-S0/frameworks/a5124651-3ca1-4d96-9fd7-488d4cb4117a-0000/executors/3d50fff3-edb5-4d47-b056-b24e184a09fd/runs/860dd8bf-5a1f-4dd5-b1b9-f7ab6827405f' to virtual path '/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_2_Iiplf0/slaves/a5124651-3ca1-4d96-9fd7-488d4cb4117a-S0/frameworks/a5124651-3ca1-4d96-9fd7-488d4cb4117a-0000/executors/3d50fff3-edb5-4d47-b056-b24e184a09fd/runs/860dd8bf-5a1f-4dd5-b1b9-f7ab6827405f'
3: I1201 13:12:44.259460 24062 slave.cpp:2944] Launching container 860dd8bf-5a1f-4dd5-b1b9-f7ab6827405f for executor '3d50fff3-edb5-4d47-b056-b24e184a09fd' of framework a5124651-3ca1-4d96-9fd7-488d4cb4117a-0000
3: I1201 13:12:44.259721 24059 containerizer.cpp:1199] Starting container 860dd8bf-5a1f-4dd5-b1b9-f7ab6827405f
3: I1201 13:12:44.259816 24070 hierarchical.cpp:830] Updated allocation of framework a5124651-3ca1-4d96-9fd7-488d4cb4117a-0000 on agent a5124651-3ca1-4d96-9fd7-488d4cb4117a-S0 from cpus(allocated: default-role):2; mem(allocated: default-role):2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_2_wrQeA1/disk1]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_2_wrQeA1/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_SharedPersistentVolumeRescindOnDestroy_2_wrQeA1/disk1,id1:path1]<SHARED>:2048<1>; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_2_wrQeA1/disk2,id2:path2]<SHARED>:2048<1>
3: I1201 13:12:44.260548 24059 containerizer.cpp:1371] Checkpointed ContainerConfig at '/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_2_iPqtUY/containers/860dd8bf-5a1f-4dd5-b1b9-f7ab6827405f/config'
3: I1201 13:12:44.260582 24059 containerizer.cpp:2923] Transitioning the state of container 860dd8bf-5a1f-4dd5-b1b9-f7ab6827405f from PROVISIONING to PREPARING
3: I1201 13:12:44.262238 24070 hierarchical.cpp:1106] Recovered cpus(allocated: default-role):1; mem(allocated: default-role):1920; ports(allocated: default-role):[31000-32000]; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_2_wrQeA1/disk1,id1:path1]<SHARED>:2048<1>; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_2_wrQeA1/disk2,id2:path2]<SHARED>:2048<1> (total: cpus:2; mem:2048; ports:[31000-32000]; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_2_wrQeA1/disk1,id1:path1]<SHARED>:2048<1>; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_2_wrQeA1/disk2,id2:path2]<SHARED>:2048<1>, allocated: cpus(allocated: default-role):1; mem(allocated: default-role):128) on agent a5124651-3ca1-4d96-9fd7-488d4cb4117a-S0 from framework a5124651-3ca1-4d96-9fd7-488d4cb4117a-0000
3: I1201 13:12:44.264449 24072 containerizer.cpp:1840] Launching 'mesos-containerizer' with flags '--help="false" --launch_info="{"command":{"arguments":["mesos-executor","--launcher_dir=\/mesos\/build\/src"],"shell":false,"value":"\/mesos\/build\/src\/mesos-executor"},"environment":{"variables":[{"name":"LIBPROCESS_PORT","type":"VALUE","value":"0"},{"name":"MESOS_AGENT_ENDPOINT","type":"VALUE","value":"172.17.0.2:45652"},{"name":"MESOS_CHECKPOINT","type":"VALUE","value":"0"},{"name":"MESOS_DIRECTORY","type":"VALUE","value":"\/tmp\/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_2_Iiplf0\/slaves\/a5124651-3ca1-4d96-9fd7-488d4cb4117a-S0\/frameworks\/a5124651-3ca1-4d96-9fd7-488d4cb4117a-0000\/executors\/3d50fff3-edb5-4d47-b056-b24e184a09fd\/runs\/860dd8bf-5a1f-4dd5-b1b9-f7ab6827405f"},{"name":"MESOS_EXECUTOR_AUTHENTICATION_TOKEN","type":"VALUE","value":"eyJhbGciOiJIUzI1NiIsInR5cCI6IkpXVCJ9.eyJjaWQiOiI4NjBkZDhiZi01YTFmLTRkZDUtYjFiOS1mN2FiNjgyNzQwNWYiLCJlaWQiOiIzZDUwZmZmMy1lZGI1LTRkNDctYjA1Ni1iMjRlMTg0YTA5ZmQiLCJmaWQiOiJhNTEyNDY1MS0zY2ExLTRkOTYtOWZkNy00ODhkNGNiNDExN2EtMDAwMCJ9.ULCBhauC2edwgkI3DByWIEIQtDm3Ae1QNHGHbtKZ3d8"},{"name":"MESOS_EXECUTOR_ID","type":"VALUE","value":"3d50fff3-edb5-4d47-b056-b24e184a09fd"},{"name":"MESOS_EXECUTOR_SHUTDOWN_GRACE_PERIOD","type":"VALUE","value":"5secs"},{"name":"MESOS_FRAMEWORK_ID","type":"VALUE","value":"a5124651-3ca1-4d96-9fd7-488d4cb4117a-0000"},{"name":"MESOS_HTTP_COMMAND_EXECUTOR","type":"VALUE","value":"0"},{"name":"MESOS_SLAVE_ID","type":"VALUE","value":"a5124651-3ca1-4d96-9fd7-488d4cb4117a-S0"},{"name":"MESOS_SLAVE_PID","type":"VALUE","value":"slave(894)@172.17.0.2:45652"},{"name":"MESOS_SANDBOX","type":"VALUE","value":"\/tmp\/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_2_Iiplf0\/slaves\/a5124651-3ca1-4d96-9fd7-488d4cb4117a-S0\/frameworks\/a5124651-3ca1-4d96-9fd7-488d4cb4117a-0000\/executors\/3d50fff3-edb5-4d47-b056-b24e184a09fd\/runs\/860dd8bf-5a1f-4dd5-b1b9-f7ab6827405f"}]},"task_environment":{},"user":"mesos","working_directory":"\/tmp\/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_2_Iiplf0\/slaves\/a5124651-3ca1-4d96-9fd7-488d4cb4117a-S0\/frameworks\/a5124651-3ca1-4d96-9fd7-488d4cb4117a-0000\/executors\/3d50fff3-edb5-4d47-b056-b24e184a09fd\/runs\/860dd8bf-5a1f-4dd5-b1b9-f7ab6827405f"}" --pipe_read="14" --pipe_write="15" --runtime_directory="/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_2_iPqtUY/containers/860dd8bf-5a1f-4dd5-b1b9-f7ab6827405f" --unshare_namespace_mnt="false"'
3: I1201 13:12:44.267267 24072 launcher.cpp:140] Forked child with pid '24358' for container '860dd8bf-5a1f-4dd5-b1b9-f7ab6827405f'
3: I1201 13:12:44.267838 24072 containerizer.cpp:2923] Transitioning the state of container 860dd8bf-5a1f-4dd5-b1b9-f7ab6827405f from PREPARING to ISOLATING
3: I1201 13:12:44.269722 24057 containerizer.cpp:2923] Transitioning the state of container 860dd8bf-5a1f-4dd5-b1b9-f7ab6827405f from ISOLATING to FETCHING
3: I1201 13:12:44.269953 24069 fetcher.cpp:379] Starting to fetch URIs for container: 860dd8bf-5a1f-4dd5-b1b9-f7ab6827405f, directory: /tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_2_Iiplf0/slaves/a5124651-3ca1-4d96-9fd7-488d4cb4117a-S0/frameworks/a5124651-3ca1-4d96-9fd7-488d4cb4117a-0000/executors/3d50fff3-edb5-4d47-b056-b24e184a09fd/runs/860dd8bf-5a1f-4dd5-b1b9-f7ab6827405f
3: I1201 13:12:44.270792 24064 containerizer.cpp:2923] Transitioning the state of container 860dd8bf-5a1f-4dd5-b1b9-f7ab6827405f from FETCHING to RUNNING
3: W1201 13:12:44.271579 17570 process.cpp:2756] Attempted to spawn already running process version@172.17.0.2:45652
3: I1201 13:12:44.272258 17570 sched.cpp:232] Version: 1.5.0
3: I1201 13:12:44.272913 24072 sched.cpp:336] New master detected at master@172.17.0.2:45652
3: I1201 13:12:44.273017 24072 sched.cpp:396] Authenticating with master master@172.17.0.2:45652
3: I1201 13:12:44.273036 24072 sched.cpp:403] Using default CRAM-MD5 authenticatee
3: I1201 13:12:44.273382 24062 authenticatee.cpp:121] Creating new client SASL connection
3: I1201 13:12:44.273682 24068 master.cpp:8589] Authenticating scheduler-17f31503-b6a2-42d2-99a8-7828ff99c0f7@172.17.0.2:45652
3: I1201 13:12:44.273883 24063 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1546)@172.17.0.2:45652
3: I1201 13:12:44.274134 24066 authenticator.cpp:98] Creating new server SASL connection
3: I1201 13:12:44.274440 24067 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
3: I1201 13:12:44.274474 24067 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
3: I1201 13:12:44.274593 24057 authenticator.cpp:204] Received SASL authentication start
3: I1201 13:12:44.274646 24057 authenticator.cpp:326] Authentication requires more steps
3: I1201 13:12:44.274749 24069 authenticatee.cpp:259] Received SASL authentication step
3: I1201 13:12:44.274855 24064 authenticator.cpp:232] Received SASL authentication step
3: I1201 13:12:44.274880 24064 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '97693488dee2' server FQDN: '97693488dee2' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
3: I1201 13:12:44.274889 24064 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
3: I1201 13:12:44.274926 24064 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
3: I1201 13:12:44.274945 24064 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '97693488dee2' server FQDN: '97693488dee2' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
3: I1201 13:12:44.274951 24064 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
3: I1201 13:12:44.274955 24064 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
3: I1201 13:12:44.274966 24064 authenticator.cpp:318] Authentication success
3: I1201 13:12:44.275058 24061 authenticatee.cpp:299] Authentication success
3: I1201 13:12:44.275149 24071 master.cpp:8619] Successfully authenticated principal 'test-principal' at scheduler-17f31503-b6a2-42d2-99a8-7828ff99c0f7@172.17.0.2:45652
3: I1201 13:12:44.275254 24070 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1546)@172.17.0.2:45652
3: I1201 13:12:44.275383 24065 sched.cpp:502] Successfully authenticated with master master@172.17.0.2:45652
3: I1201 13:12:44.275403 24065 sched.cpp:824] Sending SUBSCRIBE call to master@172.17.0.2:45652
3: I1201 13:12:44.275508 24065 sched.cpp:857] Will retry registration in 1.55561795secs if necessary
3: I1201 13:12:44.275688 24072 master.cpp:2969] Received SUBSCRIBE call for framework 'default' at scheduler-17f31503-b6a2-42d2-99a8-7828ff99c0f7@172.17.0.2:45652
3: I1201 13:12:44.275760 24072 master.cpp:2286] Authorizing framework principal 'test-principal' to receive offers for roles '{ default-role }'
3: I1201 13:12:44.276221 24062 master.cpp:3049] Subscribing framework default with checkpointing disabled and capabilities [ RESERVATION_REFINEMENT, SHARED_RESOURCES ]
3: I1201 13:12:44.276705 24066 sched.cpp:751] Framework registered with a5124651-3ca1-4d96-9fd7-488d4cb4117a-0001
3: I1201 13:12:44.276721 24063 hierarchical.cpp:293] Added framework a5124651-3ca1-4d96-9fd7-488d4cb4117a-0001
3: I1201 13:12:44.276743 24066 sched.cpp:765] Scheduler::registered took 18694ns
3: I1201 13:12:44.278981 24063 hierarchical.cpp:1431] Performed allocation for 1 agents in 2.126673ms
3: I1201 13:12:44.280077 24067 master.cpp:8419] Sending 1 offers to framework a5124651-3ca1-4d96-9fd7-488d4cb4117a-0001 (default) at scheduler-17f31503-b6a2-42d2-99a8-7828ff99c0f7@172.17.0.2:45652
3: I1201 13:12:44.280684 24067 sched.cpp:921] Scheduler::resourceOffers took 117760ns
3: I1201 13:12:44.281462 24069 master.cpp:5617] Processing KILL call for task '3d50fff3-edb5-4d47-b056-b24e184a09fd' of framework a5124651-3ca1-4d96-9fd7-488d4cb4117a-0000 (default) at scheduler-7374a584-ae9a-4907-8e8c-f6313e181824@172.17.0.2:45652
3: I1201 13:12:44.281533 24069 master.cpp:5691] Telling agent a5124651-3ca1-4d96-9fd7-488d4cb4117a-S0 at slave(894)@172.17.0.2:45652 (97693488dee2) to kill task 3d50fff3-edb5-4d47-b056-b24e184a09fd of framework a5124651-3ca1-4d96-9fd7-488d4cb4117a-0000 (default) at scheduler-7374a584-ae9a-4907-8e8c-f6313e181824@172.17.0.2:45652
3: I1201 13:12:44.281672 24064 slave.cpp:3017] Asked to kill task 3d50fff3-edb5-4d47-b056-b24e184a09fd of framework a5124651-3ca1-4d96-9fd7-488d4cb4117a-0000
3: W1201 13:12:44.281714 24064 slave.cpp:3138] Transitioning the state of task 3d50fff3-edb5-4d47-b056-b24e184a09fd of framework a5124651-3ca1-4d96-9fd7-488d4cb4117a-0000 to TASK_KILLED because the executor is not registered
3: I1201 13:12:44.281816 24064 slave.cpp:4587] Handling status update TASK_KILLED (UUID: 81cb5622-73b1-4f6b-b612-93f2d2dd181f) for task 3d50fff3-edb5-4d47-b056-b24e184a09fd of framework a5124651-3ca1-4d96-9fd7-488d4cb4117a-0000 from @0.0.0.0:0
3: I1201 13:12:44.283139 24063 task_status_update_manager.cpp:328] Received task status update TASK_KILLED (UUID: 81cb5622-73b1-4f6b-b612-93f2d2dd181f) for task 3d50fff3-edb5-4d47-b056-b24e184a09fd of framework a5124651-3ca1-4d96-9fd7-488d4cb4117a-0000
3: I1201 13:12:44.283181 24063 task_status_update_manager.cpp:507] Creating StatusUpdate stream for task 3d50fff3-edb5-4d47-b056-b24e184a09fd of framework a5124651-3ca1-4d96-9fd7-488d4cb4117a-0000
3: I1201 13:12:44.283653 24063 task_status_update_manager.cpp:383] Forwarding task status update TASK_KILLED (UUID: 81cb5622-73b1-4f6b-b612-93f2d2dd181f) for task 3d50fff3-edb5-4d47-b056-b24e184a09fd of framework a5124651-3ca1-4d96-9fd7-488d4cb4117a-0000 to the agent
3: I1201 13:12:44.283821 24057 slave.cpp:5070] Forwarding the update TASK_KILLED (UUID: 81cb5622-73b1-4f6b-b612-93f2d2dd181f) for task 3d50fff3-edb5-4d47-b056-b24e184a09fd of framework a5124651-3ca1-4d96-9fd7-488d4cb4117a-0000 to master@172.17.0.2:45652
3: I1201 13:12:44.284011 24057 slave.cpp:4963] Task status update manager successfully handled status update TASK_KILLED (UUID: 81cb5622-73b1-4f6b-b612-93f2d2dd181f) for task 3d50fff3-edb5-4d47-b056-b24e184a09fd of framework a5124651-3ca1-4d96-9fd7-488d4cb4117a-0000
3: I1201 13:12:44.284273 24067 master.cpp:7557] Status update TASK_KILLED (UUID: 81cb5622-73b1-4f6b-b612-93f2d2dd181f) for task 3d50fff3-edb5-4d47-b056-b24e184a09fd of framework a5124651-3ca1-4d96-9fd7-488d4cb4117a-0000 from agent a5124651-3ca1-4d96-9fd7-488d4cb4117a-S0 at slave(894)@172.17.0.2:45652 (97693488dee2)
3: I1201 13:12:44.284332 24067 master.cpp:7613] Forwarding status update TASK_KILLED (UUID: 81cb5622-73b1-4f6b-b612-93f2d2dd181f) for task 3d50fff3-edb5-4d47-b056-b24e184a09fd of framework a5124651-3ca1-4d96-9fd7-488d4cb4117a-0000
3: I1201 13:12:44.284482 24067 master.cpp:9807] Updating the state of task 3d50fff3-edb5-4d47-b056-b24e184a09fd of framework a5124651-3ca1-4d96-9fd7-488d4cb4117a-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED)
3: I1201 13:12:44.284663 24061 sched.cpp:1029] Scheduler::statusUpdate took 18848ns
3: I1201 13:12:44.285091 24067 master.cpp:5799] Processing ACKNOWLEDGE call 81cb5622-73b1-4f6b-b612-93f2d2dd181f for task 3d50fff3-edb5-4d47-b056-b24e184a09fd of framework a5124651-3ca1-4d96-9fd7-488d4cb4117a-0000 (default) at scheduler-7374a584-ae9a-4907-8e8c-f6313e181824@172.17.0.2:45652 on agent a5124651-3ca1-4d96-9fd7-488d4cb4117a-S0
3: I1201 13:12:44.285084 24069 hierarchical.cpp:1106] 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_2_wrQeA1/disk1,id1:path1]<SHARED>:2048<1>; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_2_wrQeA1/disk2,id2:path2]<SHARED>:2048<1>, allocated: cpus(allocated: default-role):1; mem(allocated: default-role):1920; ports(allocated: default-role):[31000-32000]; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_2_wrQeA1/disk1,id1:path1]<SHARED>:2048<1>; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_2_wrQeA1/disk2,id2:path2]<SHARED>:2048<1>) on agent a5124651-3ca1-4d96-9fd7-488d4cb4117a-S0 from framework a5124651-3ca1-4d96-9fd7-488d4cb4117a-0000
3: I1201 13:12:44.285181 24067 master.cpp:9913] Removing task 3d50fff3-edb5-4d47-b056-b24e184a09fd with resources cpus(allocated: default-role):1; mem(allocated: default-role):128 of framework a5124651-3ca1-4d96-9fd7-488d4cb4117a-0000 on agent a5124651-3ca1-4d96-9fd7-488d4cb4117a-S0 at slave(894)@172.17.0.2:45652 (97693488dee2)
3: I1201 13:12:44.285594 24067 task_status_update_manager.cpp:401] Received task status update acknowledgement (UUID: 81cb5622-73b1-4f6b-b612-93f2d2dd181f) for task 3d50fff3-edb5-4d47-b056-b24e184a09fd of framework a5124651-3ca1-4d96-9fd7-488d4cb4117a-0000
3: I1201 13:12:44.285697 24067 task_status_update_manager.cpp:538] Cleaning up status update stream for task 3d50fff3-edb5-4d47-b056-b24e184a09fd of framework a5124651-3ca1-4d96-9fd7-488d4cb4117a-0000
3: I1201 13:12:44.286118 24059 slave.cpp:3871] Task status update manager successfully handled status update acknowledgement (UUID: 81cb5622-73b1-4f6b-b612-93f2d2dd181f) for task 3d50fff3-edb5-4d47-b056-b24e184a09fd of framework a5124651-3ca1-4d96-9fd7-488d4cb4117a-0000
3: I1201 13:12:44.286172 24059 slave.cpp:8490] Completing task 3d50fff3-edb5-4d47-b056-b24e184a09fd
3: I1201 13:12:44.289055 24072 hierarchical.cpp:1431] Performed allocation for 1 agents in 2.317824ms
3: I1201 13:12:44.289924 24068 master.cpp:8419] Sending 1 offers to framework a5124651-3ca1-4d96-9fd7-488d4cb4117a-0000 (default) at scheduler-7374a584-ae9a-4907-8e8c-f6313e181824@172.17.0.2:45652
3: I1201 13:12:44.290673 24062 sched.cpp:921] Scheduler::resourceOffers took 157652ns
3: I1201 13:12:44.293048 24067 master.cpp:10331] Removing offer a5124651-3ca1-4d96-9fd7-488d4cb4117a-O1
3: I1201 13:12:44.293192 24067 master.cpp:4236] Processing ACCEPT call for offers: [ a5124651-3ca1-4d96-9fd7-488d4cb4117a-O1 ] on agent a5124651-3ca1-4d96-9fd7-488d4cb4117a-S0 at slave(894)@172.17.0.2:45652 (97693488dee2) for framework a5124651-3ca1-4d96-9fd7-488d4cb4117a-0001 (default) at scheduler-17f31503-b6a2-42d2-99a8-7828ff99c0f7@172.17.0.2:45652
3: I1201 13:12:44.293377 24067 master.cpp:3850] Authorizing principal 'test-principal' to destroy volumes '[{"allocation_info":{"role":"default-role"},"disk":{"persistence":{"id":"id1","principal":"test-principal"},"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_2_wrQeA1\/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_2_wrQeA1\/disk2"},"type":"PATH"},"volume":{"container_path":"path2","mode":"RW"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"shared":{},"type":"SCALAR"}]'
3: I1201 13:12:44.296172 24066 master.cpp:10331] Removing offer a5124651-3ca1-4d96-9fd7-488d4cb4117a-O2
3: I1201 13:12:44.296311 24057 sched.cpp:947] Rescinded offer a5124651-3ca1-4d96-9fd7-488d4cb4117a-O2
3: I1201 13:12:44.296430 24057 sched.cpp:958] Scheduler::offerRescinded took 67566ns
3: I1201 13:12:44.296782 17570 sched.cpp:2009] Asked to stop the driver
3: I1201 13:12:44.296905 24071 sched.cpp:1191] Stopping framework a5124651-3ca1-4d96-9fd7-488d4cb4117a-0000
3: I1201 13:12:44.297134 24068 hierarchical.cpp:1106] Recovered cpus(allocated: default-role):1; mem(allocated: default-role):128; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_2_wrQeA1/disk1,id1:path1]<SHARED>:2048<1>; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_2_wrQeA1/disk2,id2:path2]<SHARED>:2048<1> (total: cpus:2; mem:2048; ports:[31000-32000]; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_2_wrQeA1/disk1,id1:path1]<SHARED>:2048<1>; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_2_wrQeA1/disk2,id2:path2]<SHARED>:2048<1>, allocated: cpus(allocated: default-role):1; mem(allocated: default-role):1920; ports(allocated: default-role):[31000-32000]; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_2_wrQeA1/disk1,id1:path1]<SHARED>:2048<1>; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_2_wrQeA1/disk2,id2:path2]<SHARED>:2048<1>) on agent a5124651-3ca1-4d96-9fd7-488d4cb4117a-S0 from framework a5124651-3ca1-4d96-9fd7-488d4cb4117a-0000
3: I1201 13:12:44.297310 17570 sched.cpp:2009] Asked to stop the driver
3: I1201 13:12:44.297405 24059 sched.cpp:1191] Stopping framework a5124651-3ca1-4d96-9fd7-488d4cb4117a-0001
3: I1201 13:12:44.297484 24066 master.cpp:4805] Applying DESTROY operation for volumes [{"allocation_info":{"role":"default-role"},"disk":{"persistence":{"id":"id1","principal":"test-principal"},"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_2_wrQeA1\/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_2_wrQeA1\/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 a5124651-3ca1-4d96-9fd7-488d4cb4117a-0001 (default) at scheduler-17f31503-b6a2-42d2-99a8-7828ff99c0f7@172.17.0.2:45652 to agent a5124651-3ca1-4d96-9fd7-488d4cb4117a-S0 at slave(894)@172.17.0.2:45652 (97693488dee2)
3: I1201 13:12:44.298712 17570 slave.cpp:875] Agent terminating
3: I1201 13:12:44.298775 17570 slave.cpp:3281] Asked to shut down framework a5124651-3ca1-4d96-9fd7-488d4cb4117a-0000 by @0.0.0.0:0
3: I1201 13:12:44.298806 17570 slave.cpp:3306] Shutting down framework a5124651-3ca1-4d96-9fd7-488d4cb4117a-0000
3: I1201 13:12:44.298854 17570 slave.cpp:5946] Shutting down executor '3d50fff3-edb5-4d47-b056-b24e184a09fd' of framework a5124651-3ca1-4d96-9fd7-488d4cb4117a-0000
3: W1201 13:12:44.298869 17570 slave.hpp:785] Unable to send event to executor '3d50fff3-edb5-4d47-b056-b24e184a09fd' of framework a5124651-3ca1-4d96-9fd7-488d4cb4117a-0000: unknown connection type
3: I1201 13:12:44.299146 24066 master.cpp:10276] Sending updated checkpointed resources {} to agent a5124651-3ca1-4d96-9fd7-488d4cb4117a-S0 at slave(894)@172.17.0.2:45652 (97693488dee2)
3: I1201 13:12:44.299870 24069 containerizer.cpp:2326] Destroying container 860dd8bf-5a1f-4dd5-b1b9-f7ab6827405f in RUNNING state
3: I1201 13:12:44.299931 24069 containerizer.cpp:2923] Transitioning the state of container 860dd8bf-5a1f-4dd5-b1b9-f7ab6827405f from RUNNING to DESTROYING
3: I1201 13:12:44.300206 24066 master.cpp:9096] Processing TEARDOWN call for framework a5124651-3ca1-4d96-9fd7-488d4cb4117a-0000 (default) at scheduler-7374a584-ae9a-4907-8e8c-f6313e181824@172.17.0.2:45652
3: I1201 13:12:44.300240 24066 master.cpp:9108] Removing framework a5124651-3ca1-4d96-9fd7-488d4cb4117a-0000 (default) at scheduler-7374a584-ae9a-4907-8e8c-f6313e181824@172.17.0.2:45652
3: I1201 13:12:44.300254 24066 master.cpp:3339] Deactivating framework a5124651-3ca1-4d96-9fd7-488d4cb4117a-0000 (default) at scheduler-7374a584-ae9a-4907-8e8c-f6313e181824@172.17.0.2:45652
3: I1201 13:12:44.300319 24069 launcher.cpp:156] Asked to destroy container 860dd8bf-5a1f-4dd5-b1b9-f7ab6827405f
3: I1201 13:12:44.300689 24066 master.cpp:9096] Processing TEARDOWN call for framework a5124651-3ca1-4d96-9fd7-488d4cb4117a-0001 (default) at scheduler-17f31503-b6a2-42d2-99a8-7828ff99c0f7@172.17.0.2:45652
3: I1201 13:12:44.300716 24066 master.cpp:9108] Removing framework a5124651-3ca1-4d96-9fd7-488d4cb4117a-0001 (default) at scheduler-17f31503-b6a2-42d2-99a8-7828ff99c0f7@172.17.0.2:45652
3: I1201 13:12:44.300729 24066 master.cpp:3339] Deactivating framework a5124651-3ca1-4d96-9fd7-488d4cb4117a-0001 (default) at scheduler-17f31503-b6a2-42d2-99a8-7828ff99c0f7@172.17.0.2:45652
3: I1201 13:12:44.301120 24066 master.cpp:1317] Agent a5124651-3ca1-4d96-9fd7-488d4cb4117a-S0 at slave(894)@172.17.0.2:45652 (97693488dee2) disconnected
3: I1201 13:12:44.301141 24066 master.cpp:3376] Disconnecting agent a5124651-3ca1-4d96-9fd7-488d4cb4117a-S0 at slave(894)@172.17.0.2:45652 (97693488dee2)
3: I1201 13:12:44.301195 24066 master.cpp:3395] Deactivating agent a5124651-3ca1-4d96-9fd7-488d4cb4117a-S0 at slave(894)@172.17.0.2:45652 (97693488dee2)
3: I1201 13:12:44.305088 24061 hierarchical.cpp:830] Updated allocation of framework a5124651-3ca1-4d96-9fd7-488d4cb4117a-0001 on agent a5124651-3ca1-4d96-9fd7-488d4cb4117a-S0 from cpus(allocated: default-role):1; mem(allocated: default-role):1920; ports(allocated: default-role):[31000-32000]; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_2_wrQeA1/disk1,id1:path1]<SHARED>:2048<1>; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_2_wrQeA1/disk2,id2:path2]<SHARED>:2048<1> to cpus(allocated: default-role):1; mem(allocated: default-role):1920; ports(allocated: default-role):[31000-32000]; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_2_wrQeA1/disk1]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_2_wrQeA1/disk2]:2048
3: I1201 13:12:44.306375 24061 hierarchical.cpp:1106] Recovered cpus(allocated: default-role):1; mem(allocated: default-role):1920; ports(allocated: default-role):[31000-32000]; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_2_wrQeA1/disk1]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_2_wrQeA1/disk2]:2048 (total: cpus:2; mem:2048; ports:[31000-32000]; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_2_wrQeA1/disk1]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_2_wrQeA1/disk2]:2048, allocated: {}) on agent a5124651-3ca1-4d96-9fd7-488d4cb4117a-S0 from framework a5124651-3ca1-4d96-9fd7-488d4cb4117a-0001
3: I1201 13:12:44.306519 24061 hierarchical.cpp:406] Deactivated framework a5124651-3ca1-4d96-9fd7-488d4cb4117a-0000
3: I1201 13:12:44.306622 24061 hierarchical.cpp:345] Removed framework a5124651-3ca1-4d96-9fd7-488d4cb4117a-0000
3: I1201 13:12:44.306677 24061 hierarchical.cpp:406] Deactivated framework a5124651-3ca1-4d96-9fd7-488d4cb4117a-0001
3: I1201 13:12:44.306856 24061 hierarchical.cpp:345] Removed framework a5124651-3ca1-4d96-9fd7-488d4cb4117a-0001
3: I1201 13:12:44.306900 24061 hierarchical.cpp:671] Agent a5124651-3ca1-4d96-9fd7-488d4cb4117a-S0 deactivated
3: I1201 13:12:44.387961 24058 containerizer.cpp:2777] Container 860dd8bf-5a1f-4dd5-b1b9-f7ab6827405f has exited
3: I1201 13:12:44.390578 24069 provisioner.cpp:595] Ignoring destroy request for unknown container 860dd8bf-5a1f-4dd5-b1b9-f7ab6827405f
3: I1201 13:12:44.400436 17570 master.cpp:1159] Master terminating
3: I1201 13:12:44.401700 24067 hierarchical.cpp:586] Removed agent a5124651-3ca1-4d96-9fd7-488d4cb4117a-S0
3: [ OK ] DiskResource/PersistentVolumeTest.SharedPersistentVolumeRescindOnDestroy/2 (211 ms)
3: [ RUN ] DiskResource/PersistentVolumeTest.SharedPersistentVolumeRescindOnDestroy/3
3: I1201 13:12:44.411249 17570 cluster.cpp:170] Creating default 'local' authorizer
3: I1201 13:12:44.414526 24059 master.cpp:454] Master ab3f69e4-3e47-4b4b-b746-0cc8e4b3e3ab (97693488dee2) started on 172.17.0.2:45652
3: I1201 13:12:44.414553 24059 master.cpp:456] 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/YqwUIQ/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/YqwUIQ/master" --zk_session_timeout="10secs"
3: I1201 13:12:44.415029 24059 master.cpp:505] Master only allowing authenticated frameworks to register
3: I1201 13:12:44.415045 24059 master.cpp:511] Master only allowing authenticated agents to register
3: I1201 13:12:44.415055 24059 master.cpp:517] Master only allowing authenticated HTTP frameworks to register
3: I1201 13:12:44.415066 24059 credentials.hpp:37] Loading credentials for authentication from '/tmp/YqwUIQ/credentials'
3: I1201 13:12:44.415520 24059 master.cpp:561] Using default 'crammd5' authenticator
3: I1201 13:12:44.415737 24059 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
3: I1201 13:12:44.416018 24059 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
3: I1201 13:12:44.416268 24059 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
3: I1201 13:12:44.416524 24059 master.cpp:640] Authorization enabled
3: I1201 13:12:44.416745 24067 hierarchical.cpp:173] Initialized hierarchical allocator process
3: I1201 13:12:44.416805 24071 whitelist_watcher.cpp:77] No whitelist given
3: I1201 13:12:44.420264 24061 master.cpp:2221] Elected as the leading master!
3: I1201 13:12:44.420294 24061 master.cpp:1701] Recovering from registrar
3: I1201 13:12:44.420493 24072 registrar.cpp:347] Recovering registrar
3: I1201 13:12:44.421195 24072 registrar.cpp:391] Successfully fetched the registry (0B) in 0ns
3: I1201 13:12:44.421315 24072 registrar.cpp:495] Applied 1 operations in 36064ns; attempting to update the registry
3: I1201 13:12:44.421967 24072 registrar.cpp:552] Successfully updated the registry in 0ns
3: I1201 13:12:44.422108 24072 registrar.cpp:424] Successfully recovered registrar
3: I1201 13:12:44.422559 24071 master.cpp:1814] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
3: I1201 13:12:44.422607 24067 hierarchical.cpp:211] Skipping recovery of hierarchical allocator: nothing to recover
3: W1201 13:12:44.427562 17570 process.cpp:2756] Attempted to spawn already running process files@172.17.0.2:45652
3: I1201 13:12:44.428450 17570 containerizer.cpp:304] Using isolation { environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni }
3: W1201 13:12:44.428907 17570 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
3: W1201 13:12:44.429025 17570 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
3: I1201 13:12:44.429054 17570 provisioner.cpp:297] Using default backend 'copy'
3: I1201 13:12:44.430794 17570 cluster.cpp:458] Creating default 'local' authorizer
3: I1201 13:12:44.433079 24060 slave.cpp:253] Mesos agent started on (895)@172.17.0.2:45652
3: I1201 13:12:44.433099 24060 slave.cpp:254] Flags at startup: --acls="" --agent_features="capabilities {
3: type: MULTI_ROLE
3: }
3: capabilities {
3: type: HIERARCHICAL_ROLE
3: }
3: capabilities {
3: type: RESERVATION_REFINEMENT
3: }
3: capabilities {
3: type: RESOURCE_PROVIDER
3: }
3: " --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_3_OHgXlO/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_SharedPersistentVolumeRescindOnDestroy_3_OHgXlO/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_SharedPersistentVolumeRescindOnDestroy_3_OHgXlO/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_SharedPersistentVolumeRescindOnDestroy_3_OHgXlO/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_SharedPersistentVolumeRescindOnDestroy_3_OHgXlO/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --jwt_secret_key="/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_3_OHgXlO/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" --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_3_Ek8bUP\/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_3_Ek8bUP\/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_3_OHgXlO" --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_3_Ubc47O" --zk_session_timeout="10secs"
3: I1201 13:12:44.433564 24060 credentials.hpp:86] Loading credential for authentication from '/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_3_OHgXlO/credential'
3: I1201 13:12:44.433712 24060 slave.cpp:286] Agent using credential for: test-principal
3: I1201 13:12:44.433725 24060 credentials.hpp:37] Loading credentials for authentication from '/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_3_OHgXlO/http_credentials'
3: I1201 13:12:44.434016 24060 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-executor'
3: I1201 13:12:44.434144 24060 http.cpp:1066] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-executor'
3: I1201 13:12:44.434378 24060 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
3: I1201 13:12:44.434491 24060 http.cpp:1066] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readonly'
3: I1201 13:12:44.434687 24060 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
3: I1201 13:12:44.434787 24060 http.cpp:1066] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readwrite'
3: I1201 13:12:44.437907 24060 slave.cpp:585] Agent resources: [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":2048.0},"type":"SCALAR"},{"disk":{"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_3_Ek8bUP\/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_3_Ek8bUP\/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: I1201 13:12:44.438297 24060 slave.cpp:593] Agent attributes: [ ]
3: I1201 13:12:44.438305 24060 slave.cpp:602] Agent hostname: 97693488dee2
3: I1201 13:12:44.438446 24065 task_status_update_manager.cpp:181] Pausing sending task status updates
3: I1201 13:12:44.439976 24058 state.cpp:64] Recovering state from '/tmp/DiskResource_PersistentVolumeTest_SharedPersistentVolumeRescindOnDestroy_3_Ubc47O/meta'
3: I1201 13:12:44.440292 24066 task_status_update_manager.cpp:207] Recovering task status update manager
3: I1201 13:12:44.440510 24067 containerizer.cpp:672] Recovering containerizer
3: I1201 13:12:44.442188 24072 provisioner.cpp:493] Provisioner recovery complete
3: I1201 13:12:44.442471 24071 slave.cpp:6513] Finished recovery
3: I1201 13:12:44.443199 24057 task_status_update_manager.cpp:181] Pausing sending task status updates
3: I1201 13:12:44.443202 24069 slave.cpp:999] New master detected at master@172.17.0.2:45652
3: I1201 13:12:44.443284 24069 slave.cpp:1034] Detecting new master
3: I1201 13:12:44.443600 24069 slave.cpp:1061] Authenticating with master master@172.17.0.2:45652
3: I1201 13:12:44.443701 24069 slave.cpp:1070] Using default CRAM-MD5 authenticatee
3: I1201 13:12:44.444058 24065 authenticatee.cpp:121] Creating new client SASL connection
3: I1201 13:12:44.444445 24070 master.cpp:8589] Authenticating slave(895)@172.17.0.2:45652
3: I1201 13:12:44.444612 24067 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1547)@172.17.0.2:45652
3: I1201 13:12:44.444855 24068 authenticator.cpp:98] Creating new server SASL connection
3: I1201 13:12:44.445101 24062 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
3: I1201 13:12:44.445123 24062 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
3: I1201 13:12:44.445224 24062 authenticator.cpp:204] Received SASL authentication start
3: I1201 13:12:44.445268 24062 authenticator.cpp:326] Authentication requires more steps
3: I1201 13:12:44.445413 24064 authenticatee.cpp:259] Received SASL authentication step
write /dev/stdout: resource temporarily unavailable
+ docker rmi mesos-1512131707-27900
Error response from daemon: conflict: unable to remove repository reference "mesos-1512131707-27900" (must force) - container 97693488dee2 is using its referenced image f530bd0c2f69
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) #4527
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)/4527/display/redirect?page=changes>