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/11/21 14:41:38 UTC
Build failed in Jenkins: Mesos-Buildbot » cmake,gcc,--verbose --disable-libtool-wrappers --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23) #4484
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=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4484/display/redirect?page=changes>
Changes:
[mpark] MesosTidy [misc-use-after-move]: Added NOLINT.
[mpark] Fixed MesosTidy warning [google-readability-redundant-smartptr-get].
[alexr] Fixed `wait()` and `destroy()` in composing containerizer.
[alexr] Updated composing containerizer tests.
------------------------------------------
[...truncated 7.81 MB...]
3: I1121 14:41:27.904073 19998 master.cpp:10793] Adding task 1 with resources [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}] on agent 43c41da1-0aad-4513-bee4-c035f334ec24-S0 at slave(160)@172.17.0.2:37544 (0afbb000a8ae)
3: I1121 14:41:27.905086 19998 master.cpp:4978] Launching task 1 of framework 43c41da1-0aad-4513-bee4-c035f334ec24-0000 (default) at scheduler-60240216-859f-4f37-8ed9-fdc3cd8b839d@172.17.0.2:37544 with resources [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}] on agent 43c41da1-0aad-4513-bee4-c035f334ec24-S0 at slave(160)@172.17.0.2:37544 (0afbb000a8ae)
3: I1121 14:41:27.906240 20008 slave.cpp:1803] Got assigned task '1' for framework 43c41da1-0aad-4513-bee4-c035f334ec24-0000
3: I1121 14:41:27.906772 20008 slave.cpp:2071] Authorizing task '1' for framework 43c41da1-0aad-4513-bee4-c035f334ec24-0000
3: I1121 14:41:27.906824 20008 slave.cpp:7264] Authorizing framework principal 'test-principal' to launch task 1
3: I1121 14:41:27.907536 20005 slave.cpp:2239] Launching task '1' for framework 43c41da1-0aad-4513-bee4-c035f334ec24-0000
3: I1121 14:41:27.908340 20005 paths.cpp:621] Trying to chown '/tmp/SlaveTest_CommandTaskWithArguments_pX3cMI/slaves/43c41da1-0aad-4513-bee4-c035f334ec24-S0/frameworks/43c41da1-0aad-4513-bee4-c035f334ec24-0000/executors/1/runs/e6e4ad1f-d63c-46d0-b5eb-0280abb5dbdc' to user 'mesos'
3: I1121 14:41:27.908790 20005 slave.cpp:7738] Launching executor '1' of framework 43c41da1-0aad-4513-bee4-c035f334ec24-0000 with resources [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":0.1},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":32.0},"type":"SCALAR"}] in work directory '/tmp/SlaveTest_CommandTaskWithArguments_pX3cMI/slaves/43c41da1-0aad-4513-bee4-c035f334ec24-S0/frameworks/43c41da1-0aad-4513-bee4-c035f334ec24-0000/executors/1/runs/e6e4ad1f-d63c-46d0-b5eb-0280abb5dbdc'
3: I1121 14:41:27.909998 20005 slave.cpp:2467] Queued task '1' for executor '1' of framework 43c41da1-0aad-4513-bee4-c035f334ec24-0000
3: I1121 14:41:27.910114 20005 slave.cpp:958] Successfully attached '/tmp/SlaveTest_CommandTaskWithArguments_pX3cMI/slaves/43c41da1-0aad-4513-bee4-c035f334ec24-S0/frameworks/43c41da1-0aad-4513-bee4-c035f334ec24-0000/executors/1/runs/e6e4ad1f-d63c-46d0-b5eb-0280abb5dbdc' to virtual path '/tmp/SlaveTest_CommandTaskWithArguments_pX3cMI/slaves/43c41da1-0aad-4513-bee4-c035f334ec24-S0/frameworks/43c41da1-0aad-4513-bee4-c035f334ec24-0000/executors/1/runs/latest'
3: I1121 14:41:27.910152 20005 slave.cpp:958] Successfully attached '/tmp/SlaveTest_CommandTaskWithArguments_pX3cMI/slaves/43c41da1-0aad-4513-bee4-c035f334ec24-S0/frameworks/43c41da1-0aad-4513-bee4-c035f334ec24-0000/executors/1/runs/e6e4ad1f-d63c-46d0-b5eb-0280abb5dbdc' to virtual path '/frameworks/43c41da1-0aad-4513-bee4-c035f334ec24-0000/executors/1/runs/latest'
3: I1121 14:41:27.910182 20005 slave.cpp:958] Successfully attached '/tmp/SlaveTest_CommandTaskWithArguments_pX3cMI/slaves/43c41da1-0aad-4513-bee4-c035f334ec24-S0/frameworks/43c41da1-0aad-4513-bee4-c035f334ec24-0000/executors/1/runs/e6e4ad1f-d63c-46d0-b5eb-0280abb5dbdc' to virtual path '/tmp/SlaveTest_CommandTaskWithArguments_pX3cMI/slaves/43c41da1-0aad-4513-bee4-c035f334ec24-S0/frameworks/43c41da1-0aad-4513-bee4-c035f334ec24-0000/executors/1/runs/e6e4ad1f-d63c-46d0-b5eb-0280abb5dbdc'
3: I1121 14:41:27.910580 20005 slave.cpp:2930] Launching container e6e4ad1f-d63c-46d0-b5eb-0280abb5dbdc for executor '1' of framework 43c41da1-0aad-4513-bee4-c035f334ec24-0000
3: I1121 14:41:27.910944 19999 containerizer.cpp:1195] Starting container e6e4ad1f-d63c-46d0-b5eb-0280abb5dbdc
3: I1121 14:41:27.911913 19999 containerizer.cpp:1367] Checkpointed ContainerConfig at '/tmp/SlaveTest_CommandTaskWithArguments_2raEXH/containers/e6e4ad1f-d63c-46d0-b5eb-0280abb5dbdc/config'
3: I1121 14:41:27.911945 19999 containerizer.cpp:2919] Transitioning the state of container e6e4ad1f-d63c-46d0-b5eb-0280abb5dbdc from PROVISIONING to PREPARING
3: I1121 14:41:27.917013 20006 containerizer.cpp:1836] 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:37544"},{"name":"MESOS_CHECKPOINT","type":"VALUE","value":"0"},{"name":"MESOS_DIRECTORY","type":"VALUE","value":"\/tmp\/SlaveTest_CommandTaskWithArguments_pX3cMI\/slaves\/43c41da1-0aad-4513-bee4-c035f334ec24-S0\/frameworks\/43c41da1-0aad-4513-bee4-c035f334ec24-0000\/executors\/1\/runs\/e6e4ad1f-d63c-46d0-b5eb-0280abb5dbdc"},{"name":"MESOS_EXECUTOR_AUTHENTICATION_TOKEN","type":"VALUE","value":"eyJhbGciOiJIUzI1NiIsInR5cCI6IkpXVCJ9.eyJjaWQiOiJlNmU0YWQxZi1kNjNjLTQ2ZDAtYjVlYi0wMjgwYWJiNWRiZGMiLCJlaWQiOiIxIiwiZmlkIjoiNDNjNDFkYTEtMGFhZC00NTEzLWJlZTQtYzAzNWYzMzRlYzI0LTAwMDAifQ.rzkdQ6Zc_DWAn_VqhkUUfC7V79-J5z_XM0ix6HQJL8c"},{"name":"MESOS_EXECUTOR_ID","type":"VALUE","value":"1"},{"name":"MESOS_EXECUTOR_SHUTDOWN_GRACE_PERIOD","type":"VALUE","value":"5secs"},{"name":"MESOS_FRAMEWORK_ID","type":"VALUE","value":"43c41da1-0aad-4513-bee4-c035f334ec24-0000"},{"name":"MESOS_HTTP_COMMAND_EXECUTOR","type":"VALUE","value":"0"},{"name":"MESOS_SLAVE_ID","type":"VALUE","value":"43c41da1-0aad-4513-bee4-c035f334ec24-S0"},{"name":"MESOS_SLAVE_PID","type":"VALUE","value":"slave(160)@172.17.0.2:37544"},{"name":"MESOS_SANDBOX","type":"VALUE","value":"\/tmp\/SlaveTest_CommandTaskWithArguments_pX3cMI\/slaves\/43c41da1-0aad-4513-bee4-c035f334ec24-S0\/frameworks\/43c41da1-0aad-4513-bee4-c035f334ec24-0000\/executors\/1\/runs\/e6e4ad1f-d63c-46d0-b5eb-0280abb5dbdc"}]},"task_environment":{},"user":"mesos","working_directory":"\/tmp\/SlaveTest_CommandTaskWithArguments_pX3cMI\/slaves\/43c41da1-0aad-4513-bee4-c035f334ec24-S0\/frameworks\/43c41da1-0aad-4513-bee4-c035f334ec24-0000\/executors\/1\/runs\/e6e4ad1f-d63c-46d0-b5eb-0280abb5dbdc"}" --pipe_read="8" --pipe_write="9" --runtime_directory="/tmp/SlaveTest_CommandTaskWithArguments_2raEXH/containers/e6e4ad1f-d63c-46d0-b5eb-0280abb5dbdc" --unshare_namespace_mnt="false"'
3: I1121 14:41:27.919901 20006 launcher.cpp:140] Forked child with pid '22273' for container 'e6e4ad1f-d63c-46d0-b5eb-0280abb5dbdc'
3: I1121 14:41:27.920444 20006 containerizer.cpp:2919] Transitioning the state of container e6e4ad1f-d63c-46d0-b5eb-0280abb5dbdc from PREPARING to ISOLATING
3: I1121 14:41:27.921685 20006 containerizer.cpp:2919] Transitioning the state of container e6e4ad1f-d63c-46d0-b5eb-0280abb5dbdc from ISOLATING to FETCHING
3: I1121 14:41:27.921962 19991 fetcher.cpp:379] Starting to fetch URIs for container: e6e4ad1f-d63c-46d0-b5eb-0280abb5dbdc, directory: /tmp/SlaveTest_CommandTaskWithArguments_pX3cMI/slaves/43c41da1-0aad-4513-bee4-c035f334ec24-S0/frameworks/43c41da1-0aad-4513-bee4-c035f334ec24-0000/executors/1/runs/e6e4ad1f-d63c-46d0-b5eb-0280abb5dbdc
3: I1121 14:41:27.922878 20004 containerizer.cpp:2919] Transitioning the state of container e6e4ad1f-d63c-46d0-b5eb-0280abb5dbdc from FETCHING to RUNNING
3: I1121 14:41:28.224443 20007 slave.cpp:4063] Got registration for executor '1' of framework 43c41da1-0aad-4513-bee4-c035f334ec24-0000 from executor(1)@172.17.0.2:42261
3: I1121 14:41:28.228669 19993 slave.cpp:2669] Sending queued task '1' to executor '1' of framework 43c41da1-0aad-4513-bee4-c035f334ec24-0000 at executor(1)@172.17.0.2:42261
3: I1121 14:41:28.246501 20010 slave.cpp:4517] Handling status update TASK_STARTING (UUID: 2b6c8a21-18ec-4ace-83ef-bab605bfbf00) for task 1 of framework 43c41da1-0aad-4513-bee4-c035f334ec24-0000 from executor(1)@172.17.0.2:42261
3: I1121 14:41:28.248939 20008 task_status_update_manager.cpp:328] Received task status update TASK_STARTING (UUID: 2b6c8a21-18ec-4ace-83ef-bab605bfbf00) for task 1 of framework 43c41da1-0aad-4513-bee4-c035f334ec24-0000
3: I1121 14:41:28.249009 20008 task_status_update_manager.cpp:507] Creating StatusUpdate stream for task 1 of framework 43c41da1-0aad-4513-bee4-c035f334ec24-0000
3: I1121 14:41:28.249944 20008 task_status_update_manager.cpp:383] Forwarding task status update TASK_STARTING (UUID: 2b6c8a21-18ec-4ace-83ef-bab605bfbf00) for task 1 of framework 43c41da1-0aad-4513-bee4-c035f334ec24-0000 to the agent
3: I1121 14:41:28.250314 20009 slave.cpp:5000] Forwarding the update TASK_STARTING (UUID: 2b6c8a21-18ec-4ace-83ef-bab605bfbf00) for task 1 of framework 43c41da1-0aad-4513-bee4-c035f334ec24-0000 to master@172.17.0.2:37544
3: I1121 14:41:28.250639 20009 slave.cpp:4893] Task status update manager successfully handled status update TASK_STARTING (UUID: 2b6c8a21-18ec-4ace-83ef-bab605bfbf00) for task 1 of framework 43c41da1-0aad-4513-bee4-c035f334ec24-0000
3: I1121 14:41:28.250685 20009 slave.cpp:4909] Sending acknowledgement for status update TASK_STARTING (UUID: 2b6c8a21-18ec-4ace-83ef-bab605bfbf00) for task 1 of framework 43c41da1-0aad-4513-bee4-c035f334ec24-0000 to executor(1)@172.17.0.2:42261
3: I1121 14:41:28.250953 20000 master.cpp:7268] Status update TASK_STARTING (UUID: 2b6c8a21-18ec-4ace-83ef-bab605bfbf00) for task 1 of framework 43c41da1-0aad-4513-bee4-c035f334ec24-0000 from agent 43c41da1-0aad-4513-bee4-c035f334ec24-S0 at slave(160)@172.17.0.2:37544 (0afbb000a8ae)
3: I1121 14:41:28.251021 20000 master.cpp:7330] Forwarding status update TASK_STARTING (UUID: 2b6c8a21-18ec-4ace-83ef-bab605bfbf00) for task 1 of framework 43c41da1-0aad-4513-bee4-c035f334ec24-0000
3: I1121 14:41:28.251260 20000 master.cpp:9523] Updating the state of task 1 of framework 43c41da1-0aad-4513-bee4-c035f334ec24-0000 (latest state: TASK_STARTING, status update state: TASK_STARTING)
3: I1121 14:41:28.251610 20005 sched.cpp:1029] Scheduler::statusUpdate took 147198ns
3: I1121 14:41:28.252084 20011 master.cpp:5793] Processing ACKNOWLEDGE call 2b6c8a21-18ec-4ace-83ef-bab605bfbf00 for task 1 of framework 43c41da1-0aad-4513-bee4-c035f334ec24-0000 (default) at scheduler-60240216-859f-4f37-8ed9-fdc3cd8b839d@172.17.0.2:37544 on agent 43c41da1-0aad-4513-bee4-c035f334ec24-S0
3: I1121 14:41:28.252683 20010 task_status_update_manager.cpp:401] Received task status update acknowledgement (UUID: 2b6c8a21-18ec-4ace-83ef-bab605bfbf00) for task 1 of framework 43c41da1-0aad-4513-bee4-c035f334ec24-0000
3: I1121 14:41:28.253072 19989 slave.cpp:3801] Task status update manager successfully handled status update acknowledgement (UUID: 2b6c8a21-18ec-4ace-83ef-bab605bfbf00) for task 1 of framework 43c41da1-0aad-4513-bee4-c035f334ec24-0000
3: I1121 14:41:28.273736 19992 slave.cpp:4517] Handling status update TASK_RUNNING (UUID: d5cefbb4-9515-497e-b446-f1c5352b11b2) for task 1 of framework 43c41da1-0aad-4513-bee4-c035f334ec24-0000 from executor(1)@172.17.0.2:42261
3: I1121 14:41:28.276759 19995 task_status_update_manager.cpp:328] Received task status update TASK_RUNNING (UUID: d5cefbb4-9515-497e-b446-f1c5352b11b2) for task 1 of framework 43c41da1-0aad-4513-bee4-c035f334ec24-0000
3: I1121 14:41:28.276886 19995 task_status_update_manager.cpp:383] Forwarding task status update TASK_RUNNING (UUID: d5cefbb4-9515-497e-b446-f1c5352b11b2) for task 1 of framework 43c41da1-0aad-4513-bee4-c035f334ec24-0000 to the agent
3: I1121 14:41:28.277084 19988 slave.cpp:5000] Forwarding the update TASK_RUNNING (UUID: d5cefbb4-9515-497e-b446-f1c5352b11b2) for task 1 of framework 43c41da1-0aad-4513-bee4-c035f334ec24-0000 to master@172.17.0.2:37544
3: I1121 14:41:28.277285 19988 slave.cpp:4893] Task status update manager successfully handled status update TASK_RUNNING (UUID: d5cefbb4-9515-497e-b446-f1c5352b11b2) for task 1 of framework 43c41da1-0aad-4513-bee4-c035f334ec24-0000
3: I1121 14:41:28.277331 19988 slave.cpp:4909] Sending acknowledgement for status update TASK_RUNNING (UUID: d5cefbb4-9515-497e-b446-f1c5352b11b2) for task 1 of framework 43c41da1-0aad-4513-bee4-c035f334ec24-0000 to executor(1)@172.17.0.2:42261
3: I1121 14:41:28.277494 20001 master.cpp:7268] Status update TASK_RUNNING (UUID: d5cefbb4-9515-497e-b446-f1c5352b11b2) for task 1 of framework 43c41da1-0aad-4513-bee4-c035f334ec24-0000 from agent 43c41da1-0aad-4513-bee4-c035f334ec24-S0 at slave(160)@172.17.0.2:37544 (0afbb000a8ae)
3: I1121 14:41:28.277546 20001 master.cpp:7330] Forwarding status update TASK_RUNNING (UUID: d5cefbb4-9515-497e-b446-f1c5352b11b2) for task 1 of framework 43c41da1-0aad-4513-bee4-c035f334ec24-0000
3: I1121 14:41:28.277742 20001 master.cpp:9523] Updating the state of task 1 of framework 43c41da1-0aad-4513-bee4-c035f334ec24-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING)
3: I1121 14:41:28.278123 20005 sched.cpp:1029] Scheduler::statusUpdate took 128396ns
3: I1121 14:41:28.278522 20011 master.cpp:5793] Processing ACKNOWLEDGE call d5cefbb4-9515-497e-b446-f1c5352b11b2 for task 1 of framework 43c41da1-0aad-4513-bee4-c035f334ec24-0000 (default) at scheduler-60240216-859f-4f37-8ed9-fdc3cd8b839d@172.17.0.2:37544 on agent 43c41da1-0aad-4513-bee4-c035f334ec24-S0
3: I1121 14:41:28.278897 19992 task_status_update_manager.cpp:401] Received task status update acknowledgement (UUID: d5cefbb4-9515-497e-b446-f1c5352b11b2) for task 1 of framework 43c41da1-0aad-4513-bee4-c035f334ec24-0000
3: I1121 14:41:28.279124 20007 slave.cpp:3801] Task status update manager successfully handled status update acknowledgement (UUID: d5cefbb4-9515-497e-b446-f1c5352b11b2) for task 1 of framework 43c41da1-0aad-4513-bee4-c035f334ec24-0000
3: I1121 14:41:28.416494 20006 slave.cpp:4517] Handling status update TASK_FINISHED (UUID: d3a7a016-605c-4b7b-9916-f11a42fe0c76) for task 1 of framework 43c41da1-0aad-4513-bee4-c035f334ec24-0000 from executor(1)@172.17.0.2:42261
3: I1121 14:41:28.421145 20007 task_status_update_manager.cpp:328] Received task status update TASK_FINISHED (UUID: d3a7a016-605c-4b7b-9916-f11a42fe0c76) for task 1 of framework 43c41da1-0aad-4513-bee4-c035f334ec24-0000
3: I1121 14:41:28.421380 20007 task_status_update_manager.cpp:383] Forwarding task status update TASK_FINISHED (UUID: d3a7a016-605c-4b7b-9916-f11a42fe0c76) for task 1 of framework 43c41da1-0aad-4513-bee4-c035f334ec24-0000 to the agent
3: I1121 14:41:28.421680 19996 slave.cpp:5000] Forwarding the update TASK_FINISHED (UUID: d3a7a016-605c-4b7b-9916-f11a42fe0c76) for task 1 of framework 43c41da1-0aad-4513-bee4-c035f334ec24-0000 to master@172.17.0.2:37544
3: I1121 14:41:28.422021 19996 slave.cpp:4893] Task status update manager successfully handled status update TASK_FINISHED (UUID: d3a7a016-605c-4b7b-9916-f11a42fe0c76) for task 1 of framework 43c41da1-0aad-4513-bee4-c035f334ec24-0000
3: I1121 14:41:28.422089 19996 slave.cpp:4909] Sending acknowledgement for status update TASK_FINISHED (UUID: d3a7a016-605c-4b7b-9916-f11a42fe0c76) for task 1 of framework 43c41da1-0aad-4513-bee4-c035f334ec24-0000 to executor(1)@172.17.0.2:42261
3: I1121 14:41:28.422349 20006 master.cpp:7268] Status update TASK_FINISHED (UUID: d3a7a016-605c-4b7b-9916-f11a42fe0c76) for task 1 of framework 43c41da1-0aad-4513-bee4-c035f334ec24-0000 from agent 43c41da1-0aad-4513-bee4-c035f334ec24-S0 at slave(160)@172.17.0.2:37544 (0afbb000a8ae)
3: I1121 14:41:28.422433 20006 master.cpp:7330] Forwarding status update TASK_FINISHED (UUID: d3a7a016-605c-4b7b-9916-f11a42fe0c76) for task 1 of framework 43c41da1-0aad-4513-bee4-c035f334ec24-0000
3: I1121 14:41:28.422698 20006 master.cpp:9523] Updating the state of task 1 of framework 43c41da1-0aad-4513-bee4-c035f334ec24-0000 (latest state: TASK_FINISHED, status update state: TASK_FINISHED)
3: I1121 14:41:28.423167 19994 sched.cpp:1029] Scheduler::statusUpdate took 181519ns
3: I1121 14:41:28.423578 19987 sched.cpp:2009] Asked to stop the driver
3: I1121 14:41:28.423698 20006 master.cpp:5793] Processing ACKNOWLEDGE call d3a7a016-605c-4b7b-9916-f11a42fe0c76 for task 1 of framework 43c41da1-0aad-4513-bee4-c035f334ec24-0000 (default) at scheduler-60240216-859f-4f37-8ed9-fdc3cd8b839d@172.17.0.2:37544 on agent 43c41da1-0aad-4513-bee4-c035f334ec24-S0
3: I1121 14:41:28.423749 20000 sched.cpp:1191] Stopping framework 43c41da1-0aad-4513-bee4-c035f334ec24-0000
3: I1121 14:41:28.423785 20006 master.cpp:9617] Removing task 1 with resources [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}] of framework 43c41da1-0aad-4513-bee4-c035f334ec24-0000 on agent 43c41da1-0aad-4513-bee4-c035f334ec24-S0 at slave(160)@172.17.0.2:37544 (0afbb000a8ae)
3: I1121 14:41:28.424448 20003 hierarchical.cpp:1132] Recovered cpus(allocated: *):2; mem(allocated: *):1024; disk(allocated: *):1024; ports(allocated: *):[31000-32000] (total: cpus:2; mem:1024; disk:1024; ports:[31000-32000], allocated: {}) on agent 43c41da1-0aad-4513-bee4-c035f334ec24-S0 from framework 43c41da1-0aad-4513-bee4-c035f334ec24-0000
3: I1121 14:41:28.424767 20006 master.cpp:8813] Processing TEARDOWN call for framework 43c41da1-0aad-4513-bee4-c035f334ec24-0000 (default) at scheduler-60240216-859f-4f37-8ed9-fdc3cd8b839d@172.17.0.2:37544
3: I1121 14:41:28.424791 20006 master.cpp:8825] Removing framework 43c41da1-0aad-4513-bee4-c035f334ec24-0000 (default) at scheduler-60240216-859f-4f37-8ed9-fdc3cd8b839d@172.17.0.2:37544
3: I1121 14:41:28.424801 20006 master.cpp:3333] Deactivating framework 43c41da1-0aad-4513-bee4-c035f334ec24-0000 (default) at scheduler-60240216-859f-4f37-8ed9-fdc3cd8b839d@172.17.0.2:37544
3: I1121 14:41:28.424952 19999 task_status_update_manager.cpp:401] Received task status update acknowledgement (UUID: d3a7a016-605c-4b7b-9916-f11a42fe0c76) for task 1 of framework 43c41da1-0aad-4513-bee4-c035f334ec24-0000
3: I1121 14:41:28.424976 20011 hierarchical.cpp:419] Deactivated framework 43c41da1-0aad-4513-bee4-c035f334ec24-0000
3: I1121 14:41:28.425106 20010 slave.cpp:3267] Asked to shut down framework 43c41da1-0aad-4513-bee4-c035f334ec24-0000 by master@172.17.0.2:37544
3: I1121 14:41:28.425163 20010 slave.cpp:3292] Shutting down framework 43c41da1-0aad-4513-bee4-c035f334ec24-0000
3: I1121 14:41:28.425174 19999 task_status_update_manager.cpp:538] Cleaning up status update stream for task 1 of framework 43c41da1-0aad-4513-bee4-c035f334ec24-0000
3: I1121 14:41:28.425222 20010 slave.cpp:5876] Shutting down executor '1' of framework 43c41da1-0aad-4513-bee4-c035f334ec24-0000 at executor(1)@172.17.0.2:42261
3: I1121 14:41:28.425699 19991 hierarchical.cpp:358] Removed framework 43c41da1-0aad-4513-bee4-c035f334ec24-0000
3: I1121 14:41:28.425844 19988 slave.cpp:3801] Task status update manager successfully handled status update acknowledgement (UUID: d3a7a016-605c-4b7b-9916-f11a42fe0c76) for task 1 of framework 43c41da1-0aad-4513-bee4-c035f334ec24-0000
3: I1121 14:41:28.425901 19988 slave.cpp:8343] Completing task 1
3: I1121 14:41:28.426061 20009 containerizer.cpp:2322] Destroying container e6e4ad1f-d63c-46d0-b5eb-0280abb5dbdc in RUNNING state
3: I1121 14:41:28.426089 20009 containerizer.cpp:2919] Transitioning the state of container e6e4ad1f-d63c-46d0-b5eb-0280abb5dbdc from RUNNING to DESTROYING
3: I1121 14:41:28.426470 20009 launcher.cpp:156] Asked to destroy container e6e4ad1f-d63c-46d0-b5eb-0280abb5dbdc
3: I1121 14:41:28.440610 19999 slave.cpp:5132] Got exited event for executor(1)@172.17.0.2:42261
3: I1121 14:41:28.450175 20003 containerizer.cpp:2773] Container e6e4ad1f-d63c-46d0-b5eb-0280abb5dbdc has exited
3: I1121 14:41:28.453230 20009 provisioner.cpp:557] Ignoring destroy request for unknown container e6e4ad1f-d63c-46d0-b5eb-0280abb5dbdc
3: I1121 14:41:28.454450 20000 slave.cpp:5541] Executor '1' of framework 43c41da1-0aad-4513-bee4-c035f334ec24-0000 terminated with signal Killed
3: I1121 14:41:28.454500 20000 slave.cpp:5645] Cleaning up executor '1' of framework 43c41da1-0aad-4513-bee4-c035f334ec24-0000 at executor(1)@172.17.0.2:42261
3: I1121 14:41:28.454861 20003 gc.cpp:90] Scheduling '/tmp/SlaveTest_CommandTaskWithArguments_pX3cMI/slaves/43c41da1-0aad-4513-bee4-c035f334ec24-S0/frameworks/43c41da1-0aad-4513-bee4-c035f334ec24-0000/executors/1/runs/e6e4ad1f-d63c-46d0-b5eb-0280abb5dbdc' for gc 6.99999473780148days in the future
3: I1121 14:41:28.455350 20000 slave.cpp:5752] Cleaning up framework 43c41da1-0aad-4513-bee4-c035f334ec24-0000
3: I1121 14:41:28.455356 20001 gc.cpp:90] Scheduling '/tmp/SlaveTest_CommandTaskWithArguments_pX3cMI/slaves/43c41da1-0aad-4513-bee4-c035f334ec24-S0/frameworks/43c41da1-0aad-4513-bee4-c035f334ec24-0000/executors/1' for gc 6.99999473179259days in the future
3: I1121 14:41:28.455477 20005 task_status_update_manager.cpp:289] Closing task status update streams for framework 43c41da1-0aad-4513-bee4-c035f334ec24-0000
3: I1121 14:41:28.455654 19997 gc.cpp:90] Scheduling '/tmp/SlaveTest_CommandTaskWithArguments_pX3cMI/slaves/43c41da1-0aad-4513-bee4-c035f334ec24-S0/frameworks/43c41da1-0aad-4513-bee4-c035f334ec24-0000' for gc 6.99999472774222days in the future
3: I1121 14:41:28.455723 20000 slave.cpp:883] Agent terminating
3: I1121 14:41:28.456068 20002 master.cpp:1311] Agent 43c41da1-0aad-4513-bee4-c035f334ec24-S0 at slave(160)@172.17.0.2:37544 (0afbb000a8ae) disconnected
3: I1121 14:41:28.456109 20002 master.cpp:3370] Disconnecting agent 43c41da1-0aad-4513-bee4-c035f334ec24-S0 at slave(160)@172.17.0.2:37544 (0afbb000a8ae)
3: I1121 14:41:28.456194 20002 master.cpp:3389] Deactivating agent 43c41da1-0aad-4513-bee4-c035f334ec24-S0 at slave(160)@172.17.0.2:37544 (0afbb000a8ae)
3: I1121 14:41:28.456388 19994 hierarchical.cpp:697] Agent 43c41da1-0aad-4513-bee4-c035f334ec24-S0 deactivated
3: I1121 14:41:28.465742 19987 master.cpp:1153] Master terminating
3: I1121 14:41:28.466580 19992 hierarchical.cpp:633] Removed agent 43c41da1-0aad-4513-bee4-c035f334ec24-S0
3: [ OK ] SlaveTest.CommandTaskWithArguments (625 ms)
3: [ RUN ] SlaveTest.CommandTaskWithKillPolicy
3: I1121 14:41:28.476722 19987 cluster.cpp:162] Creating default 'local' authorizer
3: I1121 14:41:28.481015 20000 master.cpp:448] Master 8cc2598f-1196-4b01-a77b-d7c281d7df33 (0afbb000a8ae) started on 172.17.0.2:37544
3: I1121 14:41:28.481071 20000 master.cpp:450] 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/QqYnoQ/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/QqYnoQ/master" --zk_session_timeout="10secs"
3: I1121 14:41:28.481490 20000 master.cpp:499] Master only allowing authenticated frameworks to register
3: I1121 14:41:28.481501 20000 master.cpp:505] Master only allowing authenticated agents to register
3: I1121 14:41:28.481506 20000 master.cpp:511] Master only allowing authenticated HTTP frameworks to register
3: I1121 14:41:28.481514 20000 credentials.hpp:37] Loading credentials for authentication from '/tmp/QqYnoQ/credentials'
3: I1121 14:41:28.481910 20000 master.cpp:555] Using default 'crammd5' authenticator
3: I1121 14:41:28.482131 20000 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
3: I1121 14:41:28.482367 20000 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
3: I1121 14:41:28.482543 20000 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
3: I1121 14:41:28.482697 20000 master.cpp:634] Authorization enabled
3: I1121 14:41:28.482959 19989 whitelist_watcher.cpp:77] No whitelist given
3: I1121 14:41:28.483006 19993 hierarchical.cpp:173] Initialized hierarchical allocator process
3: I1121 14:41:28.487021 20004 master.cpp:2215] Elected as the leading master!
3: I1121 14:41:28.487059 20004 master.cpp:1695] Recovering from registrar
3: I1121 14:41:28.487296 20003 registrar.cpp:347] Recovering registrar
3: I1121 14:41:28.488101 20003 registrar.cpp:391] Successfully fetched the registry (0B) in 750080ns
3: I1121 14:41:28.488245 20003 registrar.cpp:495] Applied 1 operations in 42220ns; attempting to update the registry
3: I1121 14:41:28.488966 20003 registrar.cpp:552] Successfully updated the registry in 651008ns
3: I1121 14:41:28.489151 20003 registrar.cpp:424] Successfully recovered registrar
3: I1121 14:41:28.489576 20002 master.cpp:1808] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
3: I1121 14:41:28.489641 19991 hierarchical.cpp:211] Skipping recovery of hierarchical allocator: nothing to recover
3: W1121 14:41:28.495364 19987 process.cpp:2756] Attempted to spawn already running process files@172.17.0.2:37544
3: I1121 14:41:28.496577 19987 containerizer.cpp:301] Using isolation { environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni }
3: W1121 14:41:28.497251 19987 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
3: W1121 14:41:28.497392 19987 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
3: I1121 14:41:28.497437 19987 provisioner.cpp:259] Using default backend 'copy'
3: I1121 14:41:28.499774 19987 cluster.cpp:448] Creating default 'local' authorizer
3: I1121 14:41:28.502348 19991 slave.cpp:262] Mesos agent started on (161)@172.17.0.2:37544
3: I1121 14:41:28.502373 19991 slave.cpp:263] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/SlaveTest_CommandTaskWithKillPolicy_GCRC1U/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/SlaveTest_CommandTaskWithKillPolicy_GCRC1U/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/SlaveTest_CommandTaskWithKillPolicy_GCRC1U/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_secret_key="/tmp/SlaveTest_CommandTaskWithKillPolicy_GCRC1U/executor_secret_key" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/SlaveTest_CommandTaskWithKillPolicy_GCRC1U/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/SlaveTest_CommandTaskWithKillPolicy_GCRC1U/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/build/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --port="5051" --qos_correction_interval_min="0ns" --quiet="false" --recover="reconnect" --recovery_timeout="15mins" --registration_backoff_factor="10ms" --resources="cpus:2;gpus:0;mem:1024;disk:1024;ports:[31000-32000]" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/SlaveTest_CommandTaskWithKillPolicy_GCRC1U" --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/SlaveTest_CommandTaskWithKillPolicy_ASTZHS" --zk_session_timeout="10secs"
3: I1121 14:41:28.502918 19991 credentials.hpp:86] Loading credential for authentication from '/tmp/SlaveTest_CommandTaskWithKillPolicy_GCRC1U/credential'
3: W1121 14:41:28.502924 19987 process.cpp:2756] Attempted to spawn already running process version@172.17.0.2:37544
3: I1121 14:41:28.503134 19991 slave.cpp:295] Agent using credential for: test-principal
3: I1121 14:41:28.503163 19991 credentials.hpp:37] Loading credentials for authentication from '/tmp/SlaveTest_CommandTaskWithKillPolicy_GCRC1U/http_credentials'
3: I1121 14:41:28.503546 19991 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-executor'
3: I1121 14:41:28.503686 19991 http.cpp:1066] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-executor'
3: I1121 14:41:28.503978 19991 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
3: I1121 14:41:28.504000 19987 sched.cpp:232] Version: 1.5.0
3: I1121 14:41:28.504106 19991 http.cpp:1066] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readonly'
3: I1121 14:41:28.504433 19991 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
3: I1121 14:41:28.504590 19991 http.cpp:1066] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readwrite'
3: I1121 14:41:28.504840 19990 sched.cpp:336] New master detected at master@172.17.0.2:37544
3: I1121 14:41:28.504979 19990 sched.cpp:396] Authenticating with master master@172.17.0.2:37544
3: I1121 14:41:28.505004 19990 sched.cpp:403] Using default CRAM-MD5 authenticatee
3: I1121 14:41:28.505466 19994 authenticatee.cpp:121] Creating new client SASL connection
3: I1121 14:41:28.505931 19989 master.cpp:8306] Authenticating scheduler-4235a07c-c312-4a01-9b59-6b2dfa184788@172.17.0.2:37544
3: I1121 14:41:28.506088 19993 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(372)@172.17.0.2:37544
3: I1121 14:41:28.506407 20006 authenticator.cpp:98] Creating new server SASL connection
3: I1121 14:41:28.506778 20000 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
3: I1121 14:41:28.506829 20000 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
3: I1121 14:41:28.507015 20000 authenticator.cpp:204] Received SASL authentication start
3: I1121 14:41:28.507107 20000 authenticator.cpp:326] Authentication requires more steps
3: I1121 14:41:28.507292 20000 authenticatee.cpp:259] Received SASL authentication step
3: I1121 14:41:28.507490 19992 authenticator.cpp:232] Received SASL authentication step
3: I1121 14:41:28.507544 19992 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '0afbb000a8ae' server FQDN: '0afbb000a8ae' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
3: I1121 14:41:28.507560 19992 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
3: I1121 14:41:28.507613 19992 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
3: I1121 14:41:28.507637 19992 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '0afbb000a8ae' server FQDN: '0afbb000a8ae' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
3: I1121 14:41:28.507649 19992 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
3: I1121 14:41:28.507656 19992 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
3: I1121 14:41:28.507683 19992 authenticator.cpp:318] Authentication success
3: I1121 14:41:28.507426 19991 slave.cpp:593] Agent resources: [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
3: I1121 14:41:28.507760 19991 slave.cpp:601] Agent attributes: [ ]
3: I1121 14:41:28.507772 19991 slave.cpp:610] Agent hostname: 0afbb000a8ae
3: I1121 14:41:28.507822 20005 authenticatee.cpp:299] Authentication success
3: I1121 14:41:28.507906 19996 master.cpp:8336] Successfully authenticated principal 'test-principal' at scheduler-4235a07c-c312-4a01-9b59-6b2dfa184788@172.17.0.2:37544
3: I1121 14:41:28.507983 20005 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(372)@172.17.0.2:37544
3: I1121 14:41:28.508116 19999 task_status_update_manager.cpp:181] Pausing sending task status updates
3: I1121 14:41:28.508281 20001 sched.cpp:502] Successfully authenticated with master master@172.17.0.2:37544
3: I1121 14:41:28.508316 20001 sched.cpp:824] Sending SUBSCRIBE call to master@172.17.0.2:37544
3: I1121 14:41:28.508450 20001 sched.cpp:857] Will retry registration in 1.294775107secs if necessary
3: I1121 14:41:28.508734 20007 master.cpp:2963] Received SUBSCRIBE call for framework 'default' at scheduler-4235a07c-c312-4a01-9b59-6b2dfa184788@172.17.0.2:37544
3: I1121 14:41:28.508841 20007 master.cpp:2280] Authorizing framework principal 'test-principal' to receive offers for roles '{ * }'
3: I1121 14:41:28.509443 19990 master.cpp:3043] Subscribing framework default with checkpointing disabled and capabilities [ RESERVATION_REFINEMENT ]
3: I1121 14:41:28.509989 19993 state.cpp:64] Recovering state from '/tmp/SlaveTest_CommandTaskWithKillPolicy_ASTZHS/meta'
3: I1121 14:41:28.510249 20004 sched.cpp:751] Framework registered with 8cc2598f-1196-4b01-a77b-d7c281d7df33-0000
3: I1121 14:41:28.510313 20004 sched.cpp:765] Scheduler::registered took 32936ns
3: I1121 14:41:28.510349 20006 task_status_update_manager.cpp:207] Recovering task status update manager
3: I1121 14:41:28.510449 19989 hierarchical.cpp:306] Added framework 8cc2598f-1196-4b01-a77b-d7c281d7df33-0000
3: I1121 14:41:28.510547 19996 containerizer.cpp:668] Recovering containerizer
3: I1121 14:41:28.510705 19989 hierarchical.cpp:1457] Performed allocation for 0 agents in 84582ns
3: I1121 14:41:28.512028 19997 provisioner.cpp:455] Provisioner recovery complete
3: I1121 14:41:28.512385 19991 slave.cpp:6443] Finished recovery
3: I1121 14:41:28.513408 20004 task_status_update_manager.cpp:181] Pausing sending task status updates
3: I1121 14:41:28.513438 19991 slave.cpp:1007] New master detected at master@172.17.0.2:37544
3: I1121 14:41:28.513571 19991 slave.cpp:1042] Detecting new master
3: I1121 14:41:28.518009 20008 slave.cpp:1069] Authenticating with master master@172.17.0.2:37544
3: I1121 14:41:28.518092 20008 slave.cpp:1078] Using default CRAM-MD5 authenticatee
3: I1121 14:41:28.518345 19995 authenticatee.cpp:121] Creating new client SASL connection
3: I1121 14:41:28.518646 20006 master.cpp:8306] Authenticating slave(161)@172.17.0.2:37544
3: I1121 14:41:28.518822 19992 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(373)@172.17.0.2:37544
3: I1121 14:41:28.519172 19989 authenticator.cpp:98] Creating new server SASL connection
3: I1121 14:41:28.519475 20011 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
3: I1121 14:41:28.519511 20011 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
3: I1121 14:41:28.519610 20011 authenticator.cpp:204] Received SASL authentication start
3: I1121 14:41:28.519665 20011 authenticator.cpp:326] Authentication requires more steps
3: I1121 14:41:28.519757 20011 authenticatee.cpp:259] Received SASL authentication step
3: I1121 14:41:28.519865 20011 authenticator.cpp:232] Received SASL authentication step
3: I1121 14:41:28.519894 20011 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '0afbb000a8ae' server FQDN: '0afbb000a8ae' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
3: I1121 14:41:28.519904 20011 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
3: I1121 14:41:28.519928 20011 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
3: I1121 14:41:28.519948 20011 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '0afbb000a8ae' server FQDN: '0afbb000a8ae' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
3: I1121 14:41:28.519956 20011 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
3: I1121 14:41:28.519961 20011 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
3: I1121 14:41:28.519975 20011 authenticator.cpp:318] Authentication success
3: I1121 14:41:28.520076 20010 authenticatee.cpp:299] Authentication success
3: I1121 14:41:28.520149 19998 master.cpp:8336] Successfully authenticated principal 'test-principal' at slave(161)@172.17.0.2:37544
3: I1121 14:41:28.520167 20011 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(373)@172.17.0.2:37544
3: I1121 14:41:28.520442 19996 slave.cpp:1161] Successfully authenticated with master master@172.17.0.2:37544
3: I1121 14:41:28.520834 19996 slave.cpp:1682] Will retry registration in 19.502736ms if necessary
3: I1121 14:41:28.521119 19997 master.cpp:6036] Received register agent message from slave(161)@172.17.0.2:37544 (0afbb000a8ae)
3: I1121 14:41:28.521289 19997 master.cpp:3872] Authorizing agent with principal 'test-principal'
3: I1121 14:41:28.521749 19990 master.cpp:6098] Authorized registration of agent at slave(161)@172.17.0.2:37544 (0afbb000a8ae)
3: I1121 14:41:28.521884 19990 master.cpp:6191] Registering agent at slave(161)@172.17.0.2:37544 (0afbb000a8ae) with id 8cc2598f-1196-4b01-a77b-d7c281d7df33-S0
3: I1121 14:41:28.522544 19993 registrar.cpp:495] Applied 1 operations in 108820ns; attempting to update the registry
3: I1121 14:41:28.523422 19993 registrar.cpp:552] Successfully updated the registry in 785920ns
3: I1121 14:41:28.523766 20008 master.cpp:6240] Admitted agent 8cc2598f-1196-4b01-a77b-d7c281d7df33-S0 at slave(161)@172.17.0.2:37544 (0afbb000a8ae)
3: I1121 14:41:28.524822 20006 slave.cpp:1207] Registered with master master@172.17.0.2:37544; given agent ID 8cc2598f-1196-4b01-a77b-d7c281d7df33-S0
3: I1121 14:41:28.525003 20003 task_status_update_manager.cpp:188] Resuming sending task status updates
3: I1121 14:41:28.524716 20008 master.cpp:6273] Registered agent 8cc2598f-1196-4b01-a77b-d7c281d7df33-S0 at slave(161)@172.17.0.2:37544 (0afbb000a8ae) with [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
3: I1121 14:41:28.525136 19992 hierarchical.cpp:600] Added agent 8cc2598f-1196-4b01-a77b-d7c281d7df33-S0 (0afbb000a8ae) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] (allocated: {})
3: I1121 14:41:28.525281 20006 slave.cpp:1227] Checkpointing SlaveInfo to '/tmp/SlaveTest_CommandTaskWithKillPolicy_ASTZHS/meta/slaves/8cc2598f-1196-4b01-a77b-d7c281d7df33-S0/slave.info'
3: I1121 14:41:28.525980 20006 slave.cpp:1295] Forwarding total oversubscribed resources {}
3: I1121 14:41:28.526257 20006 master.cpp:7085] Received update of agent 8cc2598f-1196-4b01-a77b-d7c281d7df33-S0 at slave(161)@172.17.0.2:37544 (0afbb000a8ae) with total oversubscribed resources {}
3: I1121 14:41:28.526468 20006 master.cpp:7103] Ignoring update on agent 8cc2598f-1196-4b01-a77b-d7c281d7df33-S0 at slave(161)@172.17.0.2:37544 (0afbb000a8ae) as it reports no changes
3: I1121 14:41:28.526849 19992 hierarchical.cpp:1457] Performed allocation for 1 agents in 1.515907ms
3: I1121 14:41:28.527524 20005 master.cpp:8136] Sending 1 offers to framework 8cc2598f-1196-4b01-a77b-d7c281d7df33-0000 (default) at scheduler-4235a07c-c312-4a01-9b59-6b2dfa184788@172.17.0.2:37544
3: I1121 14:41:28.528197 20010 sched.cpp:921] Scheduler::resourceOffers took 124685ns
3: I1121 14:41:28.530571 19997 master.cpp:10045] Removing offer 8cc2598f-1196-4b01-a77b-d7c281d7df33-O0
3: I1121 14:41:28.530768 19997 master.cpp:4230] Processing ACCEPT call for offers: [ 8cc2598f-1196-4b01-a77b-d7c281d7df33-O0 ] on agent 8cc2598f-1196-4b01-a77b-d7c281d7df33-S0 at slave(161)@172.17.0.2:37544 (0afbb000a8ae) for framework 8cc2598f-1196-4b01-a77b-d7c281d7df33-0000 (default) at scheduler-4235a07c-c312-4a01-9b59-6b2dfa184788@172.17.0.2:37544
3: I1121 14:41:28.530899 19997 master.cpp:3599] Authorizing framework principal 'test-principal' to launch task 1
3: I1121 14:41:28.533555 20004 master.cpp:10793] Adding task 1 with resources [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}] on agent 8cc2598f-1196-4b01-a77b-d7c281d7df33-S0 at slave(161)@172.17.0.2:37544 (0afbb000a8ae)
3: I1121 14:41:28.534710 20004 master.cpp:4978] Launching task 1 of framework 8cc2598f-1196-4b01-a77b-d7c281d7df33-0000 (default) at scheduler-4235a07c-c312-4a01-9b59-6b2dfa184788@172.17.0.2:37544 with resources [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}] on agent 8cc2598f-1196-4b01-a77b-d7c281d7df33-S0 at slave(161)@172.17.0.2:37544 (0afbb000a8ae)
3: I1121 14:41:28.536375 19988 slave.cpp:1803] Got assigned task '1' for framework 8cc2598f-1196-4b01-a77b-d7c281d7df33-0000
3: I1121 14:41:28.537389 19988 slave.cpp:2071] Authorizing task '1' for framework 8cc2598f-1196-4b01-a77b-d7c281d7df33-0000
3: I1121 14:41:28.537478 19988 slave.cpp:7264] Authorizing framework principal 'test-principal' to launch task 1
3: I1121 14:41:28.538422 19989 slave.cpp:2239] Launching task '1' for framework 8cc2598f-1196-4b01-a77b-d7c281d7df33-0000
3: I1121 14:41:28.539129 19989 paths.cpp:621] Trying to chown '/tmp/SlaveTest_CommandTaskWithKillPolicy_ASTZHS/slaves/8cc2598f-1196-4b01-a77b-d7c281d7df33-S0/frameworks/8cc2598f-1196-4b01-a77b-d7c281d7df33-0000/executors/1/runs/bfaff2b5-4283-499b-ad9d-c584ed587f93' to user 'mesos'
3: I1121 14:41:28.539433 19989 slave.cpp:7738] Launching executor '1' of framework 8cc2598f-1196-4b01-a77b-d7c281d7df33-0000 with resources [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":0.1},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":32.0},"type":"SCALAR"}] in work directory '/tmp/SlaveTest_CommandTaskWithKillPolicy_ASTZHS/slaves/8cc2598f-1196-4b01-a77b-d7c281d7df33-S0/frameworks/8cc2598f-1196-4b01-a77b-d7c281d7df33-0000/executors/1/runs/bfaff2b5-4283-499b-ad9d-c584ed587f93'
3: I1121 14:41:28.540648 19989 slave.cpp:2467] Queued task '1' for executor '1' of framework 8cc2598f-1196-4b01-a77b-d7c281d7df33-0000
3: I1121 14:41:28.540760 19989 slave.cpp:958] Successfully attached '/tmp/SlaveTest_CommandTaskWithKillPolicy_ASTZHS/slaves/8cc2598f-1196-4b01-a77b-d7c281d7df33-S0/frameworks/8cc2598f-1196-4b01-a77b-d7c281d7df33-0000/executors/1/runs/bfaff2b5-4283-499b-ad9d-c584ed587f93' to virtual path '/tmp/SlaveTest_CommandTaskWithKillPolicy_ASTZHS/slaves/8cc2598f-1196-4b01-a77b-d7c281d7df33-S0/frameworks/8cc2598f-1196-4b01-a77b-d7c281d7df33-0000/executors/1/runs/latest'
3: I1121 14:41:28.540819 19989 slave.cpp:958] Successfully attached '/tmp/SlaveTest_CommandTaskWithKillPolicy_ASTZHS/slaves/8cc2598f-1196-4b01-a77b-d7c281d7df33-S0/frameworks/8cc2598f-1196-4b01-a77b-d7c281d7df33-0000/executors/1/runs/bfaff2b5-4283-499b-ad9d-c584ed587f93' to virtual path '/frameworks/8cc2598f-1196-4b01-a77b-d7c281d7df33-0000/executors/1/runs/latest'
3: I1121 14:41:28.541278 19989 slave.cpp:2930] Launching container bfaff2b5-4283-499b-ad9d-c584ed587f93 for executor '1' of framework 8cc2598f-1196-4b01-a77b-d7c281d7df33-0000
3: I1121 14:41:28.541635 19999 containerizer.cpp:1195] Starting container bfaff2b5-4283-499b-ad9d-c584ed587f93
3: I1121 14:41:28.541649 19989 slave.cpp:958] Successfully attached '/tmp/SlaveTest_CommandTaskWithKillPolicy_ASTZHS/slaves/8cc2598f-1196-4b01-a77b-d7c281d7df33-S0/frameworks/8cc2598f-1196-4b01-a77b-d7c281d7df33-0000/executors/1/runs/bfaff2b5-4283-499b-ad9d-c584ed587f93' to virtual path '/tmp/SlaveTest_CommandTaskWithKillPolicy_ASTZHS/slaves/8cc2598f-1196-4b01-a77b-d7c281d7df33-S0/frameworks/8cc2598f-1196-4b01-a77b-d7c281d7df33-0000/executors/1/runs/bfaff2b5-4283-499b-ad9d-c584ed587f93'
3: I1121 14:41:28.542623 19999 containerizer.cpp:1367] Checkpointed ContainerConfig at '/tmp/SlaveTest_CommandTaskWithKillPolicy_GCRC1U/containers/bfaff2b5-4283-499b-ad9d-c584ed587f93/config'
3: I1121 14:41:28.542656 19999 containerizer.cpp:2919] Transitioning the state of container bfaff2b5-4283-499b-ad9d-c584ed587f93 from PROVISIONING to PREPARING
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,ubuntu:14.04,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23) #4485
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=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4485/display/redirect>