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 2016/05/12 18:50:25 UTC

Build failed in Jenkins: mesos-reviewbot #13029

See <https://builds.apache.org/job/mesos-reviewbot/13029/changes>

Changes:

[toenshoff] Fixed some issues in newbie-guide.md.

[toenshoff] Documented that `slaveLost` and `executorLost` are unreliable.

------------------------------------------
[...truncated 40050 lines...]
I0512 16:38:00.830611 27933 scheduler.cpp:670] Enqueuing event UPDATE received from http://172.17.0.4:58856/master/api/v1/scheduler
I0512 16:38:00.830965 27927 http.cpp:314] HTTP POST for /master/api/v1/scheduler from 172.17.0.4:37358
I0512 16:38:00.831310 27927 master.cpp:4256] Processing ACKNOWLEDGE call 34751aee-d8f3-45f6-8ee3-9b8267b962a4 for task 1 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000 (Event Call Scheduler using libprocess (C++)) on agent 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-S0
I0512 16:38:00.831905 27934 status_update_manager.cpp:392] Received status update acknowledgement (UUID: 34751aee-d8f3-45f6-8ee3-9b8267b962a4) for task 1 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000

Received an UPDATE event
Task 0 is in state TASK_FINISHED
I0512 16:38:00.832993 27934 status_update_manager.cpp:374] Forwarding update TASK_FINISHED (UUID: 0ed519fd-18e7-46da-bda6-5e15f7fa0309) for task 1 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000 to the agent
I0512 16:38:00.832147 27936 scheduler.cpp:236] Sending ACKNOWLEDGE call to http://172.17.0.4:58856/master/api/v1/scheduler
I0512 16:38:00.833890 27937 slave.cpp:3624] Forwarding the update TASK_FINISHED (UUID: 0ed519fd-18e7-46da-bda6-5e15f7fa0309) for task 1 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000 to master@172.17.0.4:58856
I0512 16:38:00.834329 27937 slave.cpp:2636] Status update manager successfully handled status update acknowledgement (UUID: 34751aee-d8f3-45f6-8ee3-9b8267b962a4) for task 1 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000
I0512 16:38:00.834511 27941 master.cpp:5101] Status update TASK_FINISHED (UUID: 0ed519fd-18e7-46da-bda6-5e15f7fa0309) for task 1 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000 from agent 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-S0 at slave(2)@172.17.0.4:58856 (d24d325996e8)
I0512 16:38:00.834575 27941 master.cpp:5149] Forwarding status update TASK_FINISHED (UUID: 0ed519fd-18e7-46da-bda6-5e15f7fa0309) for task 1 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000
I0512 16:38:00.835041 27941 master.cpp:6757] Updating the state of task 1 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000 (latest state: TASK_FINISHED, status update state: TASK_FINISHED)
I0512 16:38:00.836701 27933 process.cpp:3247] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0512 16:38:00.838069 27933 scheduler.cpp:670] Enqueuing event UPDATE received from http://172.17.0.4:58856/master/api/v1/scheduler

Received an UPDATE event
Task 1 is in state TASK_FINISHED
I0512 16:38:00.839257 27933 http.cpp:314] HTTP POST for /master/api/v1/scheduler from 172.17.0.4:37358
I0512 16:38:00.839483 27931 scheduler.cpp:236] Sending ACKNOWLEDGE call to http://172.17.0.4:58856/master/api/v1/scheduler
I0512 16:38:00.839507 27933 master.cpp:4256] Processing ACKNOWLEDGE call 98e18493-c583-4c02-be76-5ed7f3e177f7 for task 0 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000 (Event Call Scheduler using libprocess (C++)) on agent 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-S0
I0512 16:38:00.839653 27933 master.cpp:6823] Removing task 0 with resources cpus(*):1; mem(*):128 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000 on agent 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-S0 at slave(2)@172.17.0.4:58856 (d24d325996e8)
I0512 16:38:00.840337 27931 status_update_manager.cpp:392] Received status update acknowledgement (UUID: 98e18493-c583-4c02-be76-5ed7f3e177f7) for task 0 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000
I0512 16:38:00.840598 27931 status_update_manager.cpp:528] Cleaning up status update stream for task 0 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000
I0512 16:38:00.841066 27933 slave.cpp:2636] Status update manager successfully handled status update acknowledgement (UUID: 98e18493-c583-4c02-be76-5ed7f3e177f7) for task 0 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000
I0512 16:38:00.841159 27933 slave.cpp:5987] Completing task 0
I0512 16:38:00.842257 27931 process.cpp:3247] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0512 16:38:00.843387 27937 http.cpp:314] HTTP POST for /master/api/v1/scheduler from 172.17.0.4:37358
I0512 16:38:00.843678 27937 master.cpp:4256] Processing ACKNOWLEDGE call 0ed519fd-18e7-46da-bda6-5e15f7fa0309 for task 1 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000 (Event Call Scheduler using libprocess (C++)) on agent 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-S0
I0512 16:38:00.843763 27937 master.cpp:6823] Removing task 1 with resources cpus(*):1; mem(*):128 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000 on agent 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-S0 at slave(2)@172.17.0.4:58856 (d24d325996e8)
I0512 16:38:00.844463 27928 status_update_manager.cpp:392] Received status update acknowledgement (UUID: 0ed519fd-18e7-46da-bda6-5e15f7fa0309) for task 1 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000
I0512 16:38:00.844709 27928 status_update_manager.cpp:528] Cleaning up status update stream for task 1 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000
I0512 16:38:00.845260 27934 slave.cpp:2636] Status update manager successfully handled status update acknowledgement (UUID: 0ed519fd-18e7-46da-bda6-5e15f7fa0309) for task 1 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000
I0512 16:38:00.845412 27934 slave.cpp:5987] Completing task 1
WARNING: Logging before InitGoogleLogging() is written to STDERR
I0512 16:38:01.214717 27994 process.cpp:1057] libprocess is initialized on 172.17.0.4:48564 with 16 worker threads
I0512 16:38:01.216193 27994 logging.cpp:195] Logging to STDERR
I0512 16:38:01.216223 27994 executor.cpp:174] Version: 0.29.0
I0512 16:38:01.221467 28071 executor.cpp:375] Connected with the agent
I0512 16:38:01.223160 28072 executor.cpp:276] Sending SUBSCRIBE call to http://172.17.0.4:58856/slave(3)/api/v1/executor
I0512 16:38:01.238245 27936 process.cpp:3247] Handling HTTP event for process 'slave(3)' with path: '/slave(3)/api/v1/executor'
I0512 16:38:01.239356 27939 http.cpp:189] HTTP POST for /slave(3)/api/v1/executor from 172.17.0.4:37361
I0512 16:38:01.239714 27939 slave.cpp:2700] Received Subscribe request for HTTP executor 'default' of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000
I0512 16:38:01.241704 27930 slave.cpp:2030] Sending queued task '2' to executor 'default' of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000 (via HTTP)
I0512 16:38:01.242271 27930 slave.cpp:2030] Sending queued task '3' to executor 'default' of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000 (via HTTP)
I0512 16:38:01.247550 28074 executor.cpp:690] Enqueuing event SUBSCRIBED received from http://172.17.0.4:58856/slave(3)/api/v1/executor
Received a SUBSCRIBED event
I0512 16:38:01.249403 28080 executor.cpp:690] Enqueuing event LAUNCH received from http://172.17.0.4:58856/slave(3)/api/v1/executor
Starting task 2
I0512 16:38:01.250905 28077 executor.cpp:690] Enqueuing event LAUNCH received from http://172.17.0.4:58856/slave(3)/api/v1/executor
Finishing task 2
Starting task 3
Finishing task 3
I0512 16:38:01.252892 28077 executor.cpp:276] Sending UPDATE call to http://172.17.0.4:58856/slave(3)/api/v1/executor
I0512 16:38:01.253310 28077 executor.cpp:276] Sending UPDATE call to http://172.17.0.4:58856/slave(3)/api/v1/executor
I0512 16:38:01.253535 28077 executor.cpp:276] Sending UPDATE call to http://172.17.0.4:58856/slave(3)/api/v1/executor
I0512 16:38:01.253741 28077 executor.cpp:276] Sending UPDATE call to http://172.17.0.4:58856/slave(3)/api/v1/executor
I0512 16:38:01.254813 27934 process.cpp:3247] Handling HTTP event for process 'slave(3)' with path: '/slave(3)/api/v1/executor'
I0512 16:38:01.255180 27934 process.cpp:3247] Handling HTTP event for process 'slave(3)' with path: '/slave(3)/api/v1/executor'
I0512 16:38:01.255416 27934 process.cpp:3247] Handling HTTP event for process 'slave(3)' with path: '/slave(3)/api/v1/executor'
I0512 16:38:01.255743 27934 process.cpp:3247] Handling HTTP event for process 'slave(3)' with path: '/slave(3)/api/v1/executor'
I0512 16:38:01.256789 27937 http.cpp:189] HTTP POST for /slave(3)/api/v1/executor from 172.17.0.4:37362
I0512 16:38:01.257088 27937 slave.cpp:3226] Handling status update TASK_RUNNING (UUID: 04152d4d-ed61-4957-8456-a8910081ff8c) for task 2 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000
I0512 16:38:01.257916 27937 http.cpp:189] HTTP POST for /slave(3)/api/v1/executor from 172.17.0.4:37362
I0512 16:38:01.258036 27937 slave.cpp:3226] Handling status update TASK_FINISHED (UUID: 3accb63a-5b77-4709-88e6-cca00ce9646f) for task 2 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000
I0512 16:38:01.258739 27937 http.cpp:189] HTTP POST for /slave(3)/api/v1/executor from 172.17.0.4:37362
I0512 16:38:01.258908 27937 slave.cpp:3226] Handling status update TASK_RUNNING (UUID: 280f08ee-e664-479e-b888-fcbd6c1758a3) for task 3 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000
I0512 16:38:01.259402 27937 http.cpp:189] HTTP POST for /slave(3)/api/v1/executor from 172.17.0.4:37362
I0512 16:38:01.260378 27937 slave.cpp:3226] Handling status update TASK_FINISHED (UUID: ddf4270d-c263-4f7d-9739-3d00e8f56aa2) for task 3 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000
I0512 16:38:01.263284 27928 status_update_manager.cpp:320] Received status update TASK_RUNNING (UUID: 04152d4d-ed61-4957-8456-a8910081ff8c) for task 2 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000
I0512 16:38:01.263375 27928 status_update_manager.cpp:497] Creating StatusUpdate stream for task 2 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000
I0512 16:38:01.263442 27937 slave.cpp:5946] Terminating task 2
I0512 16:38:01.263999 27928 status_update_manager.cpp:374] Forwarding update TASK_RUNNING (UUID: 04152d4d-ed61-4957-8456-a8910081ff8c) for task 2 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000 to the agent
I0512 16:38:01.264294 27937 slave.cpp:5946] Terminating task 3
I0512 16:38:01.264484 27928 status_update_manager.cpp:320] Received status update TASK_RUNNING (UUID: 280f08ee-e664-479e-b888-fcbd6c1758a3) for task 3 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000
I0512 16:38:01.264585 27928 status_update_manager.cpp:497] Creating StatusUpdate stream for task 3 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000
I0512 16:38:01.264925 27928 status_update_manager.cpp:374] Forwarding update TASK_RUNNING (UUID: 280f08ee-e664-479e-b888-fcbd6c1758a3) for task 3 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000 to the agent
I0512 16:38:01.265817 27937 slave.cpp:3624] Forwarding the update TASK_RUNNING (UUID: 04152d4d-ed61-4957-8456-a8910081ff8c) for task 2 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000 to master@172.17.0.4:58856
I0512 16:38:01.266029 27937 slave.cpp:3518] Status update manager successfully handled status update TASK_RUNNING (UUID: 04152d4d-ed61-4957-8456-a8910081ff8c) for task 2 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000
I0512 16:38:01.266366 27939 master.cpp:5101] Status update TASK_RUNNING (UUID: 04152d4d-ed61-4957-8456-a8910081ff8c) for task 2 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000 from agent 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-S1 at slave(3)@172.17.0.4:58856 (d24d325996e8)
I0512 16:38:01.266410 27939 master.cpp:5149] Forwarding status update TASK_RUNNING (UUID: 04152d4d-ed61-4957-8456-a8910081ff8c) for task 2 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000
I0512 16:38:01.266419 27937 slave.cpp:3624] Forwarding the update TASK_RUNNING (UUID: 280f08ee-e664-479e-b888-fcbd6c1758a3) for task 3 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000 to master@172.17.0.4:58856
I0512 16:38:01.266861 27939 master.cpp:6757] Updating the state of task 2 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000 (latest state: TASK_FINISHED, status update state: TASK_RUNNING)
I0512 16:38:01.267062 27937 slave.cpp:3518] Status update manager successfully handled status update TASK_RUNNING (UUID: 280f08ee-e664-479e-b888-fcbd6c1758a3) for task 3 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000
I0512 16:38:01.267379 27939 master.cpp:5101] Status update TASK_RUNNING (UUID: 280f08ee-e664-479e-b888-fcbd6c1758a3) for task 3 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000 from agent 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-S1 at slave(3)@172.17.0.4:58856 (d24d325996e8)
I0512 16:38:01.267426 27939 master.cpp:5149] Forwarding status update TASK_RUNNING (UUID: 280f08ee-e664-479e-b888-fcbd6c1758a3) for task 3 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000
I0512 16:38:01.267487 27941 hierarchical.cpp:891] Recovered cpus(*):1; mem(*):128 (total: cpus(*):2; mem(*):10240; disk(*):3.70122e+06; ports(*):[31000-32000], allocated: cpus(*):1; mem(*):128) on agent 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-S1 from framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000
I0512 16:38:01.267561 27932 status_update_manager.cpp:320] Received status update TASK_FINISHED (UUID: ddf4270d-c263-4f7d-9739-3d00e8f56aa2) for task 3 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000
I0512 16:38:01.267808 27939 master.cpp:6757] Updating the state of task 3 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000 (latest state: TASK_FINISHED, status update state: TASK_RUNNING)
I0512 16:38:01.268060 27939 status_update_manager.cpp:320] Received status update TASK_FINISHED (UUID: 3accb63a-5b77-4709-88e6-cca00ce9646f) for task 2 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000
I0512 16:38:01.268121 27937 slave.cpp:3518] Status update manager successfully handled status update TASK_FINISHED (UUID: ddf4270d-c263-4f7d-9739-3d00e8f56aa2) for task 3 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000
I0512 16:38:01.268406 27940 hierarchical.cpp:891] Recovered cpus(*):1; mem(*):128 (total: cpus(*):2; mem(*):10240; disk(*):3.70122e+06; ports(*):[31000-32000], allocated: ) on agent 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-S1 from framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000
I0512 16:38:01.268446 27937 slave.cpp:3518] Status update manager successfully handled status update TASK_FINISHED (UUID: 3accb63a-5b77-4709-88e6-cca00ce9646f) for task 2 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000
I0512 16:38:01.271755 28079 executor.cpp:690] Enqueuing event ACKNOWLEDGED received from http://172.17.0.4:58856/slave(3)/api/v1/executor
I0512 16:38:01.272550 28067 executor.cpp:690] Enqueuing event ACKNOWLEDGED received from http://172.17.0.4:58856/slave(3)/api/v1/executor
I0512 16:38:01.272591 27942 scheduler.cpp:670] Enqueuing event UPDATE received from http://172.17.0.4:58856/master/api/v1/scheduler
I0512 16:38:01.272959 28067 executor.cpp:690] Enqueuing event ACKNOWLEDGED received from http://172.17.0.4:58856/slave(3)/api/v1/executor
I0512 16:38:01.273211 28067 executor.cpp:690] Enqueuing event ACKNOWLEDGED received from http://172.17.0.4:58856/slave(3)/api/v1/executor
Received an ACKNOWLEDGED event
Received an ACKNOWLEDGED event

Received an UPDATE event
Task 2 is in state TASK_RUNNING
Received an ACKNOWLEDGED event
Received an ACKNOWLEDGED event
I0512 16:38:01.274317 27942 scheduler.cpp:236] Sending ACKNOWLEDGE call to http://172.17.0.4:58856/master/api/v1/scheduler
I0512 16:38:01.275235 27932 scheduler.cpp:670] Enqueuing event UPDATE received from http://172.17.0.4:58856/master/api/v1/scheduler

Received an UPDATE event
Task 3 is in state TASK_RUNNING
I0512 16:38:01.276319 27938 process.cpp:3247] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0512 16:38:01.276716 27927 scheduler.cpp:236] Sending ACKNOWLEDGE call to http://172.17.0.4:58856/master/api/v1/scheduler
I0512 16:38:01.277550 27934 http.cpp:314] HTTP POST for /master/api/v1/scheduler from 172.17.0.4:37358
I0512 16:38:01.277779 27934 master.cpp:4256] Processing ACKNOWLEDGE call 04152d4d-ed61-4957-8456-a8910081ff8c for task 2 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000 (Event Call Scheduler using libprocess (C++)) on agent 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-S1
I0512 16:38:01.278149 27940 status_update_manager.cpp:392] Received status update acknowledgement (UUID: 04152d4d-ed61-4957-8456-a8910081ff8c) for task 2 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000
I0512 16:38:01.278589 27940 status_update_manager.cpp:374] Forwarding update TASK_FINISHED (UUID: 3accb63a-5b77-4709-88e6-cca00ce9646f) for task 2 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000 to the agent
I0512 16:38:01.278952 27935 slave.cpp:3624] Forwarding the update TASK_FINISHED (UUID: 3accb63a-5b77-4709-88e6-cca00ce9646f) for task 2 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000 to master@172.17.0.4:58856
I0512 16:38:01.279114 27940 process.cpp:3247] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0512 16:38:01.279445 27935 slave.cpp:2636] Status update manager successfully handled status update acknowledgement (UUID: 04152d4d-ed61-4957-8456-a8910081ff8c) for task 2 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000
I0512 16:38:01.279731 27940 master.cpp:5101] Status update TASK_FINISHED (UUID: 3accb63a-5b77-4709-88e6-cca00ce9646f) for task 2 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000 from agent 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-S1 at slave(3)@172.17.0.4:58856 (d24d325996e8)
I0512 16:38:01.279788 27940 master.cpp:5149] Forwarding status update TASK_FINISHED (UUID: 3accb63a-5b77-4709-88e6-cca00ce9646f) for task 2 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000
I0512 16:38:01.280254 27940 master.cpp:6757] Updating the state of task 2 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000 (latest state: TASK_FINISHED, status update state: TASK_FINISHED)
I0512 16:38:01.280702 27940 http.cpp:314] HTTP POST for /master/api/v1/scheduler from 172.17.0.4:37358
I0512 16:38:01.280889 27940 master.cpp:4256] Processing ACKNOWLEDGE call 280f08ee-e664-479e-b888-fcbd6c1758a3 for task 3 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000 (Event Call Scheduler using libprocess (C++)) on agent 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-S1
I0512 16:38:01.281577 27938 status_update_manager.cpp:392] Received status update acknowledgement (UUID: 280f08ee-e664-479e-b888-fcbd6c1758a3) for task 3 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000
I0512 16:38:01.281734 27931 scheduler.cpp:670] Enqueuing event UPDATE received from http://172.17.0.4:58856/master/api/v1/scheduler
I0512 16:38:01.282039 27938 status_update_manager.cpp:374] Forwarding update TASK_FINISHED (UUID: ddf4270d-c263-4f7d-9739-3d00e8f56aa2) for task 3 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000 to the agent
I0512 16:38:01.282476 27935 slave.cpp:3624] Forwarding the update TASK_FINISHED (UUID: ddf4270d-c263-4f7d-9739-3d00e8f56aa2) for task 3 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000 to master@172.17.0.4:58856
I0512 16:38:01.282727 27935 slave.cpp:2636] Status update manager successfully handled status update acknowledgement (UUID: 280f08ee-e664-479e-b888-fcbd6c1758a3) for task 3 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000
I0512 16:38:01.282968 27939 master.cpp:5101] Status update TASK_FINISHED (UUID: ddf4270d-c263-4f7d-9739-3d00e8f56aa2) for task 3 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000 from agent 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-S1 at slave(3)@172.17.0.4:58856 (d24d325996e8)

Received an UPDATE event
Task 2 is in state TASK_FINISHED
I0512 16:38:01.284713 27939 master.cpp:5149] Forwarding status update TASK_FINISHED (UUID: ddf4270d-c263-4f7d-9739-3d00e8f56aa2) for task 3 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000
I0512 16:38:01.284652 27941 scheduler.cpp:236] Sending ACKNOWLEDGE call to http://172.17.0.4:58856/master/api/v1/scheduler
I0512 16:38:01.286895 27939 master.cpp:6757] Updating the state of task 3 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000 (latest state: TASK_FINISHED, status update state: TASK_FINISHED)
I0512 16:38:01.287148 27939 process.cpp:3247] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0512 16:38:01.288435 27936 scheduler.cpp:670] Enqueuing event UPDATE received from http://172.17.0.4:58856/master/api/v1/scheduler
I0512 16:38:01.288965 27939 http.cpp:314] HTTP POST for /master/api/v1/scheduler from 172.17.0.4:37358
I0512 16:38:01.289321 27939 master.cpp:4256] Processing ACKNOWLEDGE call 3accb63a-5b77-4709-88e6-cca00ce9646f for task 2 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000 (Event Call Scheduler using libprocess (C++)) on agent 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-S1

Received an UPDATE event
Task 3 is in state TASK_FINISHED
I0512 16:38:01.289577 27939 master.cpp:6823] Removing task 2 with resources cpus(*):1; mem(*):128 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000 on agent 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-S1 at slave(3)@172.17.0.4:58856 (d24d325996e8)
I0512 16:38:01.289870 27933 scheduler.cpp:236] Sending ACKNOWLEDGE call to http://172.17.0.4:58856/master/api/v1/scheduler
I0512 16:38:01.290578 27938 status_update_manager.cpp:392] Received status update acknowledgement (UUID: 3accb63a-5b77-4709-88e6-cca00ce9646f) for task 2 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000
I0512 16:38:01.290834 27938 status_update_manager.cpp:528] Cleaning up status update stream for task 2 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000
I0512 16:38:01.291225 27929 slave.cpp:2636] Status update manager successfully handled status update acknowledgement (UUID: 3accb63a-5b77-4709-88e6-cca00ce9646f) for task 2 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000
I0512 16:38:01.291373 27929 slave.cpp:5987] Completing task 2
I0512 16:38:01.291846 27942 process.cpp:3247] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0512 16:38:01.293084 27940 http.cpp:314] HTTP POST for /master/api/v1/scheduler from 172.17.0.4:37358
I0512 16:38:01.293325 27940 master.cpp:4256] Processing ACKNOWLEDGE call ddf4270d-c263-4f7d-9739-3d00e8f56aa2 for task 3 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000 (Event Call Scheduler using libprocess (C++)) on agent 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-S1
I0512 16:38:01.293409 27940 master.cpp:6823] Removing task 3 with resources cpus(*):1; mem(*):128 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000 on agent 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-S1 at slave(3)@172.17.0.4:58856 (d24d325996e8)
I0512 16:38:01.293931 27935 status_update_manager.cpp:392] Received status update acknowledgement (UUID: ddf4270d-c263-4f7d-9739-3d00e8f56aa2) for task 3 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000
I0512 16:38:01.294325 27935 status_update_manager.cpp:528] Cleaning up status update stream for task 3 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000
I0512 16:38:01.294724 27940 slave.cpp:2636] Status update manager successfully handled status update acknowledgement (UUID: ddf4270d-c263-4f7d-9739-3d00e8f56aa2) for task 3 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000
I0512 16:38:01.294798 27940 slave.cpp:5987] Completing task 3
I0512 16:38:01.401597 27941 hierarchical.cpp:1674] Filtered offer with cpus(*):1; mem(*):10112; disk(*):3.70122e+06; ports(*):[31000-32000] on agent 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-S2 for framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000
I0512 16:38:01.402348 27941 hierarchical.cpp:1583] No inverse offers to send out!
I0512 16:38:01.402675 27941 hierarchical.cpp:1139] Performed allocation for 3 agents in 3.454287ms
I0512 16:38:01.403553 27940 master.cpp:5662] Sending 2 offers to framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000 (Event Call Scheduler using libprocess (C++))
I0512 16:38:01.408095 27931 scheduler.cpp:670] Enqueuing event OFFERS received from http://172.17.0.4:58856/master/api/v1/scheduler

Received an OFFERS event
Received offer 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-O3 with cpus(*):2; mem(*):10240; disk(*):3.70122e+06; ports(*):[31000-32000]
Received offer 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-O4 with cpus(*):2; mem(*):10240; disk(*):3.70122e+06; ports(*):[31000-32000]
I0512 16:38:01.410357 27927 scheduler.cpp:236] Sending ACCEPT call to http://172.17.0.4:58856/master/api/v1/scheduler
I0512 16:38:01.410902 27927 scheduler.cpp:236] Sending ACCEPT call to http://172.17.0.4:58856/master/api/v1/scheduler
I0512 16:38:01.411857 27928 process.cpp:3247] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0512 16:38:01.413352 27939 http.cpp:314] HTTP POST for /master/api/v1/scheduler from 172.17.0.4:37358
I0512 16:38:01.414333 27939 master.cpp:3436] Processing ACCEPT call for offers: [ 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-O3 ] on agent 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-S1 at slave(3)@172.17.0.4:58856 (d24d325996e8) for framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000 (Event Call Scheduler using libprocess (C++))
I0512 16:38:01.415701 27928 hierarchical.cpp:891] Recovered cpus(*):2; mem(*):10240; disk(*):3.70122e+06; ports(*):[31000-32000] (total: cpus(*):2; mem(*):10240; disk(*):3.70122e+06; ports(*):[31000-32000], allocated: ) on agent 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-S1 from framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000
I0512 16:38:01.415791 27928 hierarchical.cpp:928] Framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000 filtered agent 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-S1 for 5secs
I0512 16:38:01.416231 27940 process.cpp:3247] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0512 16:38:01.417268 27930 http.cpp:314] HTTP POST for /master/api/v1/scheduler from 172.17.0.4:37358
I0512 16:38:01.417917 27930 master.cpp:3436] Processing ACCEPT call for offers: [ 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-O4 ] on agent 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-S0 at slave(2)@172.17.0.4:58856 (d24d325996e8) for framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000 (Event Call Scheduler using libprocess (C++))
I0512 16:38:01.419101 27929 hierarchical.cpp:891] Recovered cpus(*):2; mem(*):10240; disk(*):3.70122e+06; ports(*):[31000-32000] (total: cpus(*):2; mem(*):10240; disk(*):3.70122e+06; ports(*):[31000-32000], allocated: ) on agent 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-S0 from framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000
I0512 16:38:01.419183 27929 hierarchical.cpp:928] Framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000 filtered agent 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-S0 for 5secs
WARNING: Logging before InitGoogleLogging() is written to STDERR
I0512 16:38:01.496928 28027 process.cpp:1057] libprocess is initialized on 172.17.0.4:42042 with 16 worker threads
I0512 16:38:01.498179 28098 logging.cpp:195] Logging to STDERR
I0512 16:38:01.498203 28098 executor.cpp:174] Version: 0.29.0
I0512 16:38:01.502007 28093 executor.cpp:375] Connected with the agent
I0512 16:38:01.503170 28099 executor.cpp:276] Sending SUBSCRIBE call to http://172.17.0.4:58856/slave(1)/api/v1/executor
I0512 16:38:01.513348 27930 process.cpp:3247] Handling HTTP event for process 'slave(1)' with path: '/slave(1)/api/v1/executor'
I0512 16:38:01.514366 27928 http.cpp:189] HTTP POST for /slave(1)/api/v1/executor from 172.17.0.4:37363
I0512 16:38:01.514708 27928 slave.cpp:2700] Received Subscribe request for HTTP executor 'default' of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000
I0512 16:38:01.516271 27928 slave.cpp:2030] Sending queued task '4' to executor 'default' of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000 (via HTTP)
I0512 16:38:01.520452 28092 executor.cpp:690] Enqueuing event SUBSCRIBED received from http://172.17.0.4:58856/slave(1)/api/v1/executor
I0512 16:38:01.521339 28092 executor.cpp:690] Enqueuing event LAUNCH received from http://172.17.0.4:58856/slave(1)/api/v1/executor
Received a SUBSCRIBED event
Starting task 4
Finishing task 4
I0512 16:38:01.524014 28085 executor.cpp:276] Sending UPDATE call to http://172.17.0.4:58856/slave(1)/api/v1/executor
I0512 16:38:01.524415 28085 executor.cpp:276] Sending UPDATE call to http://172.17.0.4:58856/slave(1)/api/v1/executor
I0512 16:38:01.525602 27932 process.cpp:3247] Handling HTTP event for process 'slave(1)' with path: '/slave(1)/api/v1/executor'
I0512 16:38:01.526330 27938 process.cpp:3247] Handling HTTP event for process 'slave(1)' with path: '/slave(1)/api/v1/executor'
I0512 16:38:01.526609 27938 http.cpp:189] HTTP POST for /slave(1)/api/v1/executor from 172.17.0.4:37364
I0512 16:38:01.526821 27938 slave.cpp:3226] Handling status update TASK_RUNNING (UUID: 09e6d2ef-f11c-4200-9e35-90b0089c7ee0) for task 4 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000
I0512 16:38:01.527753 27931 http.cpp:189] HTTP POST for /slave(1)/api/v1/executor from 172.17.0.4:37364
I0512 16:38:01.527904 27931 slave.cpp:3226] Handling status update TASK_FINISHED (UUID: 532e9b25-b226-404c-a0b2-baf650bd4778) for task 4 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000
I0512 16:38:01.528879 27931 status_update_manager.cpp:320] Received status update TASK_RUNNING (UUID: 09e6d2ef-f11c-4200-9e35-90b0089c7ee0) for task 4 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000
I0512 16:38:01.528926 27931 status_update_manager.cpp:497] Creating StatusUpdate stream for task 4 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000
I0512 16:38:01.529538 27931 status_update_manager.cpp:374] Forwarding update TASK_RUNNING (UUID: 09e6d2ef-f11c-4200-9e35-90b0089c7ee0) for task 4 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000 to the agent
I0512 16:38:01.529650 27932 slave.cpp:5946] Terminating task 4
I0512 16:38:01.530102 27932 slave.cpp:3624] Forwarding the update TASK_RUNNING (UUID: 09e6d2ef-f11c-4200-9e35-90b0089c7ee0) for task 4 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000 to master@172.17.0.4:58856
I0512 16:38:01.531008 27932 slave.cpp:3518] Status update manager successfully handled status update TASK_RUNNING (UUID: 09e6d2ef-f11c-4200-9e35-90b0089c7ee0) for task 4 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000
I0512 16:38:01.531189 27935 master.cpp:5101] Status update TASK_RUNNING (UUID: 09e6d2ef-f11c-4200-9e35-90b0089c7ee0) for task 4 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000 from agent 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-S2 at slave(1)@172.17.0.4:58856 (d24d325996e8)
I0512 16:38:01.531249 27935 master.cpp:5149] Forwarding status update TASK_RUNNING (UUID: 09e6d2ef-f11c-4200-9e35-90b0089c7ee0) for task 4 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000
I0512 16:38:01.531700 27935 master.cpp:6757] Updating the state of task 4 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000 (latest state: TASK_FINISHED, status update state: TASK_RUNNING)
I0512 16:38:01.531494 27941 status_update_manager.cpp:320] Received status update TASK_FINISHED (UUID: 532e9b25-b226-404c-a0b2-baf650bd4778) for task 4 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000
I0512 16:38:01.532310 27937 hierarchical.cpp:891] Recovered cpus(*):1; mem(*):128 (total: cpus(*):2; mem(*):10240; disk(*):3.70122e+06; ports(*):[31000-32000], allocated: ) on agent 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-S2 from framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000
I0512 16:38:01.532487 27936 slave.cpp:3518] Status update manager successfully handled status update TASK_FINISHED (UUID: 532e9b25-b226-404c-a0b2-baf650bd4778) for task 4 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000
I0512 16:38:01.532798 28095 executor.cpp:690] Enqueuing event ACKNOWLEDGED received from http://172.17.0.4:58856/slave(1)/api/v1/executor
I0512 16:38:01.533694 27928 scheduler.cpp:670] Enqueuing event UPDATE received from http://172.17.0.4:58856/master/api/v1/scheduler
Received an ACKNOWLEDGED event
I0512 16:38:01.534415 28091 executor.cpp:690] Enqueuing event ACKNOWLEDGED received from http://172.17.0.4:58856/slave(1)/api/v1/executor

Received an UPDATE event
Task 4 is in state TASK_RUNNING
I0512 16:38:01.534898 27928 scheduler.cpp:236] Sending ACKNOWLEDGE call to http://172.17.0.4:58856/master/api/v1/scheduler
Received an ACKNOWLEDGED event
I0512 16:38:01.536115 27928 process.cpp:3247] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0512 16:38:01.536988 27939 http.cpp:314] HTTP POST for /master/api/v1/scheduler from 172.17.0.4:37358
I0512 16:38:01.537129 27939 master.cpp:4256] Processing ACKNOWLEDGE call 09e6d2ef-f11c-4200-9e35-90b0089c7ee0 for task 4 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000 (Event Call Scheduler using libprocess (C++)) on agent 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-S2
I0512 16:38:01.537444 27939 status_update_manager.cpp:392] Received status update acknowledgement (UUID: 09e6d2ef-f11c-4200-9e35-90b0089c7ee0) for task 4 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000
I0512 16:38:01.537717 27939 status_update_manager.cpp:374] Forwarding update TASK_FINISHED (UUID: 532e9b25-b226-404c-a0b2-baf650bd4778) for task 4 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000 to the agent
I0512 16:38:01.537957 27934 slave.cpp:3624] Forwarding the update TASK_FINISHED (UUID: 532e9b25-b226-404c-a0b2-baf650bd4778) for task 4 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000 to master@172.17.0.4:58856
I0512 16:38:01.538120 27934 slave.cpp:2636] Status update manager successfully handled status update acknowledgement (UUID: 09e6d2ef-f11c-4200-9e35-90b0089c7ee0) for task 4 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000
I0512 16:38:01.538391 27934 master.cpp:5101] Status update TASK_FINISHED (UUID: 532e9b25-b226-404c-a0b2-baf650bd4778) for task 4 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000 from agent 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-S2 at slave(1)@172.17.0.4:58856 (d24d325996e8)
I0512 16:38:01.538451 27934 master.cpp:5149] Forwarding status update TASK_FINISHED (UUID: 532e9b25-b226-404c-a0b2-baf650bd4778) for task 4 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000
I0512 16:38:01.538873 27934 master.cpp:6757] Updating the state of task 4 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000 (latest state: TASK_FINISHED, status update state: TASK_FINISHED)
I0512 16:38:01.539995 27929 scheduler.cpp:670] Enqueuing event UPDATE received from http://172.17.0.4:58856/master/api/v1/scheduler

Received an UPDATE event
Task 4 is in state TASK_FINISHED
I0512 16:38:01.540920 27934 scheduler.cpp:236] Sending ACKNOWLEDGE call to http://172.17.0.4:58856/master/api/v1/scheduler
I0512 16:38:01.542234 27903 master.cpp:1190] Master terminating
I0512 16:38:01.542461 27903 master.cpp:6823] Removing task 4 with resources cpus(*):1; mem(*):128 of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000 on agent 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-S2 at slave(1)@172.17.0.4:58856 (d24d325996e8)
I0512 16:38:01.542953 27903 master.cpp:6852] Removing executor 'default' with resources  of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000 on agent 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-S2 at slave(1)@172.17.0.4:58856 (d24d325996e8)
I0512 16:38:01.543692 27939 hierarchical.cpp:505] Removed agent 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-S2
I0512 16:38:01.543962 27903 master.cpp:6852] Removing executor 'default' with resources  of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000 on agent 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-S1 at slave(3)@172.17.0.4:58856 (d24d325996e8)
I0512 16:38:01.544319 27934 hierarchical.cpp:505] Removed agent 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-S1
I0512 16:38:01.544497 27903 master.cpp:6852] Removing executor 'default' with resources  of framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000 on agent 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-S0 at slave(2)@172.17.0.4:58856 (d24d325996e8)
I0512 16:38:01.544883 27934 hierarchical.cpp:505] Removed agent 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-S0
I0512 16:38:01.546187 27934 hierarchical.cpp:326] Removed framework 350bb09d-8e79-4cf3-99e3-1fd9d82e7a75-0000
Build timed out (after 180 minutes). Marking the build as failed.
Build was aborted

Jenkins build is back to normal : mesos-reviewbot #13030

Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/mesos-reviewbot/13030/changes>