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 2018/12/13 15:11:54 UTC
Build failed in Jenkins: Mesos-Reviewbot #23603
See <https://builds.apache.org/job/Mesos-Reviewbot/23603/display/redirect?page=changes>
Changes:
[bbannier] Fixed SIGINT handling in parallel test runner.
------------------------------------------
[...truncated 45.91 MB...]
I1213 14:56:37.653195 19420 master.cpp:3552] Authorizing framework principal 'test-principal' to launch task 51ef042e-8595-4fc4-a718-aa9e470427b2
I1213 14:56:37.655877 19416 master.cpp:4033] Adding task 51ef042e-8595-4fc4-a718-aa9e470427b2 with resources cpus(allocated: *):2; mem(allocated: *):1024; disk(allocated: *):1024; ports(allocated: *):[31000-32000] of framework 1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-0000 (default) at scheduler-c6a2baf0-8480-43b8-91ba-7f2a50be3cbc@172.17.0.2:37976 on agent 1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-S0 at slave@172.17.0.2:37976 (232d56d69eef)
I1213 14:56:37.656883 19416 master.cpp:5428] Launching task 51ef042e-8595-4fc4-a718-aa9e470427b2 of framework 1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-0000 (default) at scheduler-c6a2baf0-8480-43b8-91ba-7f2a50be3cbc@172.17.0.2:37976 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 1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-S0 at slave@172.17.0.2:37976 (232d56d69eef) on new executor
I1213 14:56:37.658403 19408 slave.cpp:2030] Got assigned task '51ef042e-8595-4fc4-a718-aa9e470427b2' for framework 1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-0000
I1213 14:56:37.658551 19408 slave.cpp:8975] Checkpointing FrameworkInfo to '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_mSX1g2/meta/slaves/1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-S0/frameworks/1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-0000/framework.info'
I1213 14:56:37.658915 19408 slave.cpp:8986] Checkpointing framework pid 'scheduler-c6a2baf0-8480-43b8-91ba-7f2a50be3cbc@172.17.0.2:37976' to '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_mSX1g2/meta/slaves/1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-S0/frameworks/1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-0000/framework.pid'
I1213 14:56:37.660154 19408 slave.cpp:2404] Authorizing task '51ef042e-8595-4fc4-a718-aa9e470427b2' for framework 1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-0000
I1213 14:56:37.660217 19408 slave.cpp:8551] Authorizing framework principal 'test-principal' to launch task 51ef042e-8595-4fc4-a718-aa9e470427b2
I1213 14:56:37.662381 19408 slave.cpp:2847] Launching task '51ef042e-8595-4fc4-a718-aa9e470427b2' for framework 1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-0000
I1213 14:56:37.662456 19408 paths.cpp:752] Creating sandbox '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_mSX1g2/slaves/1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-S0/frameworks/1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-0000/executors/51ef042e-8595-4fc4-a718-aa9e470427b2/runs/329f88ac-5a09-47b2-8e0e-93d089a2d8f6' for user 'mesos'
I1213 14:56:37.663048 19408 slave.cpp:9761] Checkpointing ExecutorInfo to '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_mSX1g2/meta/slaves/1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-S0/frameworks/1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-0000/executors/51ef042e-8595-4fc4-a718-aa9e470427b2/executor.info'
I1213 14:56:37.663548 19408 paths.cpp:755] Creating sandbox '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_mSX1g2/meta/slaves/1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-S0/frameworks/1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-0000/executors/51ef042e-8595-4fc4-a718-aa9e470427b2/runs/329f88ac-5a09-47b2-8e0e-93d089a2d8f6'
I1213 14:56:37.663811 19408 slave.cpp:9061] Launching executor '51ef042e-8595-4fc4-a718-aa9e470427b2' of framework 1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-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/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_mSX1g2/slaves/1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-S0/frameworks/1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-0000/executors/51ef042e-8595-4fc4-a718-aa9e470427b2/runs/329f88ac-5a09-47b2-8e0e-93d089a2d8f6'
I1213 14:56:37.664487 19408 slave.cpp:3525] Launching container 329f88ac-5a09-47b2-8e0e-93d089a2d8f6 for executor '51ef042e-8595-4fc4-a718-aa9e470427b2' of framework 1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-0000
I1213 14:56:37.664978 19408 slave.cpp:9792] Checkpointing TaskInfo to '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_mSX1g2/meta/slaves/1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-S0/frameworks/1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-0000/executors/51ef042e-8595-4fc4-a718-aa9e470427b2/runs/329f88ac-5a09-47b2-8e0e-93d089a2d8f6/tasks/51ef042e-8595-4fc4-a718-aa9e470427b2/task.info'
I1213 14:56:37.665612 19408 slave.cpp:3044] Queued task '51ef042e-8595-4fc4-a718-aa9e470427b2' for executor '51ef042e-8595-4fc4-a718-aa9e470427b2' of framework 1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-0000
I1213 14:56:37.665704 19408 slave.cpp:1001] Successfully attached '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_mSX1g2/slaves/1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-S0/frameworks/1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-0000/executors/51ef042e-8595-4fc4-a718-aa9e470427b2/runs/329f88ac-5a09-47b2-8e0e-93d089a2d8f6' to virtual path '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_mSX1g2/slaves/1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-S0/frameworks/1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-0000/executors/51ef042e-8595-4fc4-a718-aa9e470427b2/runs/latest'
I1213 14:56:37.665757 19408 slave.cpp:1001] Successfully attached '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_mSX1g2/slaves/1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-S0/frameworks/1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-0000/executors/51ef042e-8595-4fc4-a718-aa9e470427b2/runs/329f88ac-5a09-47b2-8e0e-93d089a2d8f6' to virtual path '/frameworks/1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-0000/executors/51ef042e-8595-4fc4-a718-aa9e470427b2/runs/latest'
I1213 14:56:37.665802 19408 slave.cpp:1001] Successfully attached '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_mSX1g2/slaves/1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-S0/frameworks/1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-0000/executors/51ef042e-8595-4fc4-a718-aa9e470427b2/runs/329f88ac-5a09-47b2-8e0e-93d089a2d8f6' to virtual path '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_mSX1g2/slaves/1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-S0/frameworks/1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-0000/executors/51ef042e-8595-4fc4-a718-aa9e470427b2/runs/329f88ac-5a09-47b2-8e0e-93d089a2d8f6'
I1213 14:56:37.666539 19408 containerizer.cpp:1288] Starting container 329f88ac-5a09-47b2-8e0e-93d089a2d8f6
I1213 14:56:37.667765 19408 containerizer.cpp:1454] Checkpointed ContainerConfig at '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_w9YOSJ/containers/329f88ac-5a09-47b2-8e0e-93d089a2d8f6/config'
I1213 14:56:37.667798 19408 containerizer.cpp:3130] Transitioning the state of container 329f88ac-5a09-47b2-8e0e-93d089a2d8f6 from PROVISIONING to PREPARING
I1213 14:56:37.672147 19429 containerizer.cpp:1947] Launching 'mesos-containerizer' with flags '--help="false" --launch_info="{"command":{"arguments":["mesos-executor","--launcher_dir=/mesos/mesos-1.8.0/_build/src"],"shell":false,"value":"/mesos/mesos-1.8.0/_build/src/mesos-executor"},"environment":{"variables":[{"name":"LIBPROCESS_PORT","type":"VALUE","value":"0"},{"name":"MESOS_AGENT_ENDPOINT","type":"VALUE","value":"172.17.0.2:37976"},{"name":"MESOS_CHECKPOINT","type":"VALUE","value":"1"},{"name":"MESOS_DIRECTORY","type":"VALUE","value":"/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_mSX1g2/slaves/1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-S0/frameworks/1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-0000/executors/51ef042e-8595-4fc4-a718-aa9e470427b2/runs/329f88ac-5a09-47b2-8e0e-93d089a2d8f6"},{"name":"MESOS_EXECUTOR_ID","type":"VALUE","value":"51ef042e-8595-4fc4-a718-aa9e470427b2"},{"name":"MESOS_EXECUTOR_SHUTDOWN_GRACE_PERIOD","type":"VALUE","value":"5secs"},{"name":"MESOS_FRAMEWORK_ID","type":"VALUE","value":"1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-0000"},{"name":"MESOS_HTTP_COMMAND_EXECUTOR","type":"VALUE","value":"0"},{"name":"MESOS_RECOVERY_TIMEOUT","type":"VALUE","value":"15mins"},{"name":"MESOS_SLAVE_ID","type":"VALUE","value":"1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-S0"},{"name":"MESOS_SLAVE_PID","type":"VALUE","value":"slave@172.17.0.2:37976"},{"name":"MESOS_SUBSCRIPTION_BACKOFF_MAX","type":"VALUE","value":"15secs"},{"name":"MESOS_SANDBOX","type":"VALUE","value":"/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_mSX1g2/slaves/1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-S0/frameworks/1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-0000/executors/51ef042e-8595-4fc4-a718-aa9e470427b2/runs/329f88ac-5a09-47b2-8e0e-93d089a2d8f6"}]},"task_environment":{},"user":"mesos","working_directory":"/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_mSX1g2/slaves/1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-S0/frameworks/1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-0000/executors/51ef042e-8595-4fc4-a718-aa9e470427b2/runs/329f88ac-5a09-47b2-8e0e-93d089a2d8f6"}" --pipe_read="9" --pipe_write="10" --runtime_directory="/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_w9YOSJ/containers/329f88ac-5a09-47b2-8e0e-93d089a2d8f6" --unshare_namespace_mnt="false"'
I1213 14:56:37.674302 19429 launcher.cpp:145] Forked child with pid '29263' for container '329f88ac-5a09-47b2-8e0e-93d089a2d8f6'
I1213 14:56:37.674424 19429 containerizer.cpp:2052] Checkpointing container's forked pid 29263 to '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_mSX1g2/meta/slaves/1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-S0/frameworks/1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-0000/executors/51ef042e-8595-4fc4-a718-aa9e470427b2/runs/329f88ac-5a09-47b2-8e0e-93d089a2d8f6/pids/forked.pid'
I1213 14:56:37.675129 19429 containerizer.cpp:3130] Transitioning the state of container 329f88ac-5a09-47b2-8e0e-93d089a2d8f6 from PREPARING to ISOLATING
I1213 14:56:37.677556 19447 containerizer.cpp:3130] Transitioning the state of container 329f88ac-5a09-47b2-8e0e-93d089a2d8f6 from ISOLATING to FETCHING
I1213 14:56:37.677789 19435 fetcher.cpp:369] Starting to fetch URIs for container: 329f88ac-5a09-47b2-8e0e-93d089a2d8f6, directory: /tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_mSX1g2/slaves/1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-S0/frameworks/1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-0000/executors/51ef042e-8595-4fc4-a718-aa9e470427b2/runs/329f88ac-5a09-47b2-8e0e-93d089a2d8f6
I1213 14:56:37.678961 19418 containerizer.cpp:3130] Transitioning the state of container 329f88ac-5a09-47b2-8e0e-93d089a2d8f6 from FETCHING to RUNNING
I1213 14:56:38.156026 29364 exec.cpp:162] Version: 1.8.0
I1213 14:56:38.166752 19437 slave.cpp:4838] Got registration for executor '51ef042e-8595-4fc4-a718-aa9e470427b2' of framework 1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-0000 from executor(1)@172.17.0.2:36814
I1213 14:56:38.167263 19437 slave.cpp:4924] Checkpointing executor pid 'executor(1)@172.17.0.2:36814' to '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_mSX1g2/meta/slaves/1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-S0/frameworks/1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-0000/executors/51ef042e-8595-4fc4-a718-aa9e470427b2/runs/329f88ac-5a09-47b2-8e0e-93d089a2d8f6/pids/libprocess.pid'
I1213 14:56:38.170445 19420 slave.cpp:3257] Sending queued task '51ef042e-8595-4fc4-a718-aa9e470427b2' to executor '51ef042e-8595-4fc4-a718-aa9e470427b2' of framework 1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-0000 at executor(1)@172.17.0.2:36814
I1213 14:56:38.172567 29377 exec.cpp:236] Executor registered on agent 1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-S0
I1213 14:56:38.177389 29339 executor.cpp:184] Received SUBSCRIBED event
I1213 14:56:38.178880 29339 executor.cpp:188] Subscribed executor on 232d56d69eef
I1213 14:56:38.179038 29339 executor.cpp:184] Received LAUNCH event
I1213 14:56:38.181044 29339 executor.cpp:687] Starting task 51ef042e-8595-4fc4-a718-aa9e470427b2
I1213 14:56:38.186070 19424 slave.cpp:5304] Handling status update TASK_STARTING (Status UUID: 02318c02-0132-4b5f-a99b-f79bcc5eca05) for task 51ef042e-8595-4fc4-a718-aa9e470427b2 of framework 1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-0000 from executor(1)@172.17.0.2:36814
I1213 14:56:38.188246 19408 task_status_update_manager.cpp:328] Received task status update TASK_STARTING (Status UUID: 02318c02-0132-4b5f-a99b-f79bcc5eca05) for task 51ef042e-8595-4fc4-a718-aa9e470427b2 of framework 1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-0000
I1213 14:56:38.188298 19408 task_status_update_manager.cpp:507] Creating StatusUpdate stream for task 51ef042e-8595-4fc4-a718-aa9e470427b2 of framework 1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-0000
I1213 14:56:38.189023 19408 task_status_update_manager.cpp:842] Checkpointing UPDATE for task status update TASK_STARTING (Status UUID: 02318c02-0132-4b5f-a99b-f79bcc5eca05) for task 51ef042e-8595-4fc4-a718-aa9e470427b2 of framework 1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-0000
I1213 14:56:38.189262 19408 task_status_update_manager.cpp:383] Forwarding task status update TASK_STARTING (Status UUID: 02318c02-0132-4b5f-a99b-f79bcc5eca05) for task 51ef042e-8595-4fc4-a718-aa9e470427b2 of framework 1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-0000 to the agent
I1213 14:56:38.189476 19414 slave.cpp:5796] Forwarding the update TASK_STARTING (Status UUID: 02318c02-0132-4b5f-a99b-f79bcc5eca05) for task 51ef042e-8595-4fc4-a718-aa9e470427b2 of framework 1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-0000 to master@172.17.0.2:37976
I1213 14:56:38.189695 19414 slave.cpp:5689] Task status update manager successfully handled status update TASK_STARTING (Status UUID: 02318c02-0132-4b5f-a99b-f79bcc5eca05) for task 51ef042e-8595-4fc4-a718-aa9e470427b2 of framework 1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-0000
I1213 14:56:38.189748 19414 slave.cpp:5705] Sending acknowledgement for status update TASK_STARTING (Status UUID: 02318c02-0132-4b5f-a99b-f79bcc5eca05) for task 51ef042e-8595-4fc4-a718-aa9e470427b2 of framework 1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-0000 to executor(1)@172.17.0.2:36814
I1213 14:56:38.189810 19418 master.cpp:8375] Status update TASK_STARTING (Status UUID: 02318c02-0132-4b5f-a99b-f79bcc5eca05) for task 51ef042e-8595-4fc4-a718-aa9e470427b2 of framework 1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-0000 from agent 1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-S0 at slave@172.17.0.2:37976 (232d56d69eef)
I1213 14:56:38.189868 19418 master.cpp:8432] Forwarding status update TASK_STARTING (Status UUID: 02318c02-0132-4b5f-a99b-f79bcc5eca05) for task 51ef042e-8595-4fc4-a718-aa9e470427b2 of framework 1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-0000
I1213 14:56:38.190150 19418 master.cpp:10968] Updating the state of task 51ef042e-8595-4fc4-a718-aa9e470427b2 of framework 1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-0000 (latest state: TASK_STARTING, status update state: TASK_STARTING)
I1213 14:56:38.190457 19414 sched.cpp:1022] Scheduler::statusUpdate took 100141ns
I1213 14:56:38.191241 19439 master.cpp:6231] Processing ACKNOWLEDGE call for status 02318c02-0132-4b5f-a99b-f79bcc5eca05 for task 51ef042e-8595-4fc4-a718-aa9e470427b2 of framework 1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-0000 (default) at scheduler-c6a2baf0-8480-43b8-91ba-7f2a50be3cbc@172.17.0.2:37976 on agent 1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-S0
I1213 14:56:38.191608 19412 task_status_update_manager.cpp:401] Received task status update acknowledgement (UUID: 02318c02-0132-4b5f-a99b-f79bcc5eca05) for task 51ef042e-8595-4fc4-a718-aa9e470427b2 of framework 1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-0000
I1213 14:56:38.191735 19412 task_status_update_manager.cpp:842] Checkpointing ACK for task status update TASK_STARTING (Status UUID: 02318c02-0132-4b5f-a99b-f79bcc5eca05) for task 51ef042e-8595-4fc4-a718-aa9e470427b2 of framework 1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-0000
I1213 14:56:38.192000 19441 slave.cpp:4540] Task status update manager successfully handled status update acknowledgement (UUID: 02318c02-0132-4b5f-a99b-f79bcc5eca05) for task 51ef042e-8595-4fc4-a718-aa9e470427b2 of framework 1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-0000
I1213 14:56:38.200819 29339 executor.cpp:502] Running '/mesos/mesos-1.8.0/_build/src/mesos-containerizer launch <POSSIBLY-SENSITIVE-DATA>'
I1213 14:56:38.204340 29339 executor.cpp:702] Forked command at 29396
I1213 14:56:38.208267 19437 slave.cpp:5304] Handling status update TASK_RUNNING (Status UUID: 4bce57c1-eb07-4eb5-ada9-0e511bf18297) for task 51ef042e-8595-4fc4-a718-aa9e470427b2 of framework 1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-0000 from executor(1)@172.17.0.2:36814
I1213 14:56:38.210366 19426 task_status_update_manager.cpp:328] Received task status update TASK_RUNNING (Status UUID: 4bce57c1-eb07-4eb5-ada9-0e511bf18297) for task 51ef042e-8595-4fc4-a718-aa9e470427b2 of framework 1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-0000
I1213 14:56:38.210436 19426 task_status_update_manager.cpp:842] Checkpointing UPDATE for task status update TASK_RUNNING (Status UUID: 4bce57c1-eb07-4eb5-ada9-0e511bf18297) for task 51ef042e-8595-4fc4-a718-aa9e470427b2 of framework 1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-0000
I1213 14:56:38.210665 19426 task_status_update_manager.cpp:383] Forwarding task status update TASK_RUNNING (Status UUID: 4bce57c1-eb07-4eb5-ada9-0e511bf18297) for task 51ef042e-8595-4fc4-a718-aa9e470427b2 of framework 1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-0000 to the agent
I1213 14:56:38.210841 19420 slave.cpp:5796] Forwarding the update TASK_RUNNING (Status UUID: 4bce57c1-eb07-4eb5-ada9-0e511bf18297) for task 51ef042e-8595-4fc4-a718-aa9e470427b2 of framework 1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-0000 to master@172.17.0.2:37976
I1213 14:56:38.211028 19420 slave.cpp:5689] Task status update manager successfully handled status update TASK_RUNNING (Status UUID: 4bce57c1-eb07-4eb5-ada9-0e511bf18297) for task 51ef042e-8595-4fc4-a718-aa9e470427b2 of framework 1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-0000
I1213 14:56:38.211076 19420 slave.cpp:5705] Sending acknowledgement for status update TASK_RUNNING (Status UUID: 4bce57c1-eb07-4eb5-ada9-0e511bf18297) for task 51ef042e-8595-4fc4-a718-aa9e470427b2 of framework 1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-0000 to executor(1)@172.17.0.2:36814
I1213 14:56:38.211158 19416 master.cpp:8375] Status update TASK_RUNNING (Status UUID: 4bce57c1-eb07-4eb5-ada9-0e511bf18297) for task 51ef042e-8595-4fc4-a718-aa9e470427b2 of framework 1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-0000 from agent 1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-S0 at slave@172.17.0.2:37976 (232d56d69eef)
I1213 14:56:38.211215 19416 master.cpp:8432] Forwarding status update TASK_RUNNING (Status UUID: 4bce57c1-eb07-4eb5-ada9-0e511bf18297) for task 51ef042e-8595-4fc4-a718-aa9e470427b2 of framework 1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-0000
I1213 14:56:38.211447 19416 master.cpp:10968] Updating the state of task 51ef042e-8595-4fc4-a718-aa9e470427b2 of framework 1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING)
I1213 14:56:38.211663 19439 sched.cpp:1022] Scheduler::statusUpdate took 90655ns
I1213 14:56:38.212559 19445 master.cpp:6231] Processing ACKNOWLEDGE call for status 4bce57c1-eb07-4eb5-ada9-0e511bf18297 for task 51ef042e-8595-4fc4-a718-aa9e470427b2 of framework 1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-0000 (default) at scheduler-c6a2baf0-8480-43b8-91ba-7f2a50be3cbc@172.17.0.2:37976 on agent 1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-S0
I1213 14:56:38.212975 19445 task_status_update_manager.cpp:401] Received task status update acknowledgement (UUID: 4bce57c1-eb07-4eb5-ada9-0e511bf18297) for task 51ef042e-8595-4fc4-a718-aa9e470427b2 of framework 1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-0000
I1213 14:56:38.213091 19445 task_status_update_manager.cpp:842] Checkpointing ACK for task status update TASK_RUNNING (Status UUID: 4bce57c1-eb07-4eb5-ada9-0e511bf18297) for task 51ef042e-8595-4fc4-a718-aa9e470427b2 of framework 1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-0000
I1213 14:56:38.213385 19435 slave.cpp:4540] Task status update manager successfully handled status update acknowledgement (UUID: 4bce57c1-eb07-4eb5-ada9-0e511bf18297) for task 51ef042e-8595-4fc4-a718-aa9e470427b2 of framework 1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-0000
I1213 14:56:38.213717 19331 slave.cpp:922] Agent terminating
I1213 14:56:38.607362 19412 hierarchical.cpp:1566] Performed allocation for 1 agents in 295326ns
I1213 14:56:38.842402 19331 containerizer.cpp:305] Using isolation { environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni }
W1213 14:56:38.843135 19331 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W1213 14:56:38.843307 19331 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I1213 14:56:38.843346 19331 provisioner.cpp:298] Using default backend 'copy'
I1213 14:56:38.843340 19445 master.cpp:1275] Agent 1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-S0 at slave@172.17.0.2:37976 (232d56d69eef) disconnected
I1213 14:56:38.843390 19445 master.cpp:3278] Disconnecting agent 1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-S0 at slave@172.17.0.2:37976 (232d56d69eef)
I1213 14:56:38.843472 19445 master.cpp:3297] Deactivating agent 1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-S0 at slave@172.17.0.2:37976 (232d56d69eef)
I1213 14:56:38.843632 19418 hierarchical.cpp:801] Agent 1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-S0 deactivated
W1213 14:56:38.853680 19331 process.cpp:2829] Attempted to spawn already running process files@172.17.0.2:37976
I1213 14:56:38.856900 19331 cluster.cpp:485] Creating default 'local' authorizer
I1213 14:56:38.859349 19418 slave.cpp:268] Mesos agent started on @172.17.0.2:37976
I1213 14:56:38.859377 19418 slave.cpp:269] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_w9YOSJ/store/appc" --authenticate_http_readonly="true" --authenticate_http_readwrite="false" --authenticatee="crammd5" --authentication_backoff_factor="1secs" --authentication_timeout_max="1mins" --authentication_timeout_min="5secs" --authorizer="local" --cgroups_cpu_enable_pids_and_tids_count="false" --cgroups_destroy_timeout="1mins" --cgroups_enable_cfs="false" --cgroups_hierarchy="/sys/fs/cgroup" --cgroups_limit_swap="false" --cgroups_root="mesos" --container_disk_watch_interval="15secs" --containerizers="mesos" --credential="/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_w9YOSJ/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/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_w9YOSJ/store/docker" --docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_reregistration_timeout="15secs" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_w9YOSJ/fetch" --fetcher_cache_size="2GB" --fetcher_stall_timeout="1mins" --frameworks_home="/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_w9YOSJ/frameworks" --gc_delay="1weeks" --gc_disk_headroom="0.1" --gc_non_executor_container_sandboxes="false" --help="false" --hostname_lookup="true" --http_command_executor="false" --http_credentials="/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_w9YOSJ/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.8.0/_build/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --memory_profiling="false" --network_cni_metrics="true" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --port="5051" --qos_correction_interval_min="0ns" --quiet="false" --reconfiguration_policy="equal" --recover="reconnect" --recovery_timeout="15mins" --registration_backoff_factor="10ms" --resources="cpus:2;gpus:0;mem:1024;disk:1024;ports:[31000-32000]" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_w9YOSJ" --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/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_mSX1g2" --zk_session_timeout="10secs"
I1213 14:56:38.859848 19418 credentials.hpp:86] Loading credential for authentication from '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_w9YOSJ/credential'
I1213 14:56:38.860023 19418 slave.cpp:301] Agent using credential for: test-principal
I1213 14:56:38.860044 19418 credentials.hpp:37] Loading credentials for authentication from '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_w9YOSJ/http_credentials'
I1213 14:56:38.860299 19418 http.cpp:1017] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I1213 14:56:38.860702 19418 disk_profile_adaptor.cpp:80] Creating default disk profile adaptor module
I1213 14:56:38.862113 19418 slave.cpp:616] 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"}]
I1213 14:56:38.862413 19418 slave.cpp:624] Agent attributes: [ ]
I1213 14:56:38.862438 19418 slave.cpp:633] Agent hostname: 232d56d69eef
I1213 14:56:38.862676 19437 status_update_manager_process.hpp:379] Pausing operation status update manager
I1213 14:56:38.862720 19412 task_status_update_manager.cpp:181] Pausing sending task status updates
I1213 14:56:38.865172 19410 state.cpp:66] Recovering state from '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_mSX1g2/meta'
I1213 14:56:38.865240 19410 state.cpp:711] No committed checkpointed resources found at '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_mSX1g2/meta/resources/resources.info'
I1213 14:56:38.870162 19439 slave.cpp:6992] Finished recovering checkpointed state from '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_mSX1g2/meta', beginning agent recovery
I1213 14:56:38.870868 19439 slave.cpp:7471] Recovering framework 1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-0000
I1213 14:56:38.870988 19439 slave.cpp:9179] Recovering executor '51ef042e-8595-4fc4-a718-aa9e470427b2' of framework 1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-0000
I1213 14:56:38.872167 19439 task_status_update_manager.cpp:207] Recovering task status update manager
I1213 14:56:38.872197 19439 task_status_update_manager.cpp:215] Recovering executor '51ef042e-8595-4fc4-a718-aa9e470427b2' of framework 1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-0000
I1213 14:56:38.872287 19439 task_status_update_manager.cpp:507] Creating StatusUpdate stream for task 51ef042e-8595-4fc4-a718-aa9e470427b2 of framework 1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-0000
I1213 14:56:38.872974 19439 task_status_update_manager.cpp:818] Replaying task status update stream for task 51ef042e-8595-4fc4-a718-aa9e470427b2
I1213 14:56:38.874004 19448 containerizer.cpp:727] Recovering Mesos containers
I1213 14:56:38.874173 19448 containerizer.cpp:784] Recovering container 329f88ac-5a09-47b2-8e0e-93d089a2d8f6 for executor '51ef042e-8595-4fc4-a718-aa9e470427b2' of framework 1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-0000
I1213 14:56:38.876494 19448 containerizer.cpp:1053] Recovering isolators
I1213 14:56:38.877713 19414 containerizer.cpp:1092] Recovering provisioner
I1213 14:56:38.878366 19420 provisioner.cpp:494] Provisioner recovery complete
I1213 14:56:38.880281 19424 composing.cpp:339] Finished recovering all containerizers
I1213 14:56:38.880533 19424 slave.cpp:7221] Recovering executors
I1213 14:56:38.880654 19424 slave.cpp:7245] Sending reconnect request to executor '51ef042e-8595-4fc4-a718-aa9e470427b2' of framework 1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-0000 at executor(1)@172.17.0.2:36814
I1213 14:56:38.882704 29373 exec.cpp:282] Received reconnect request from agent 1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-S0
I1213 14:56:38.886245 19435 slave.cpp:5001] Received re-registration message from executor '51ef042e-8595-4fc4-a718-aa9e470427b2' of framework 1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-0000
I1213 14:56:38.888080 29379 exec.cpp:259] Executor reregistered on agent 1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-S0
I1213 14:56:38.889910 19408 slave.cpp:5966] No pings from master received within 75secs
I1213 14:56:38.890395 19431 hierarchical.cpp:1566] Performed allocation for 1 agents in 251711ns
F1213 14:56:38.892621 19414 slave.cpp:1240] Check failed: state == DISCONNECTED || state == RUNNING || state == TERMINATING RECOVERING
I1213 14:56:38.892740 29381 executor.cpp:184] Received SUBSCRIBED event
I1213 14:56:38.892783 29381 executor.cpp:188] Subscribed executor on 232d56d69eef
*** Check failure stack trace: ***
@ 0x2ab35fe099c6 google::LogMessage::Fail()
@ 0x2ab35fe09912 google::LogMessage::SendToLog()
@ 0x2ab35fe09314 google::LogMessage::Flush()
@ 0x2ab35fe0c228 google::LogMessageFatal::~LogMessageFatal()
@ 0x2ab35ea0e7f0 mesos::internal::slave::Slave::detected()
@ 0x2ab35eaa47bc _ZZN7process8dispatchIN5mesos8internal5slave5SlaveERKNS_6FutureI6OptionINS1_10MasterInfoEEEESB_EEvRKNS_3PIDIT_EEMSD_FvT0_EOT1_ENKUlOS9_PNS_11ProcessBaseEE_clESM_SO_
@ 0x2ab35eb4e4f7 _ZN5cpp176invokeIZN7process8dispatchIN5mesos8internal5slave5SlaveERKNS1_6FutureI6OptionINS3_10MasterInfoEEEESD_EEvRKNS1_3PIDIT_EEMSF_FvT0_EOT1_EUlOSB_PNS1_11ProcessBaseEE_ISB_SQ_EEEDTclcl7forwardISF_Efp_Espcl7forwardIT0_Efp0_EEEOSF_DpOSS_
@ 0x2ab35eb45407 _ZN6lambda8internal7PartialIZN7process8dispatchIN5mesos8internal5slave5SlaveERKNS2_6FutureI6OptionINS4_10MasterInfoEEEESE_EEvRKNS2_3PIDIT_EEMSG_FvT0_EOT1_EUlOSC_PNS2_11ProcessBaseEE_ISC_St12_PlaceholderILi1EEEE13invoke_expandISS_St5tupleIISC_SU_EESX_IIOSR_EEILm0ELm1EEEEDTcl6invokecl7forwardIT_Efp_Espcl6expandcl3getIXT2_EEcl7forwardIT0_Efp0_EEcl7forwardIT1_Efp2_EEEEOS11_OS12_N5cpp1416integer_sequenceImIXspT2_EEEEOS13_
@ 0x2ab35eb3f3db _ZNO6lambda8internal7PartialIZN7process8dispatchIN5mesos8internal5slave5SlaveERKNS2_6FutureI6OptionINS4_10MasterInfoEEEESE_EEvRKNS2_3PIDIT_EEMSG_FvT0_EOT1_EUlOSC_PNS2_11ProcessBaseEE_JSC_St12_PlaceholderILi1EEEEclIJSR_EEEDTcl13invoke_expandcl4movedtdefpT1fEcl4movedtdefpT10bound_argsEcvN5cpp1416integer_sequenceImJLm0ELm1EEEE_Ecl16forward_as_tuplespcl7forwardIT_Efp_EEEEDpOS10_
@ 0x2ab35eb3c215 _ZN5cpp176invokeIN6lambda8internal7PartialIZN7process8dispatchIN5mesos8internal5slave5SlaveERKNS4_6FutureI6OptionINS6_10MasterInfoEEEESG_EEvRKNS4_3PIDIT_EEMSI_FvT0_EOT1_EUlOSE_PNS4_11ProcessBaseEE_ISE_St12_PlaceholderILi1EEEEEIST_EEEDTclcl7forwardISI_Efp_Espcl7forwardIT0_Efp0_EEEOSI_DpOSY_
@ 0x2ab35eb3a4e1 _ZN6lambda8internal6InvokeIvEclINS0_7PartialIZN7process8dispatchIN5mesos8internal5slave5SlaveERKNS5_6FutureI6OptionINS7_10MasterInfoEEEESH_EEvRKNS5_3PIDIT_EEMSJ_FvT0_EOT1_EUlOSF_PNS5_11ProcessBaseEE_JSF_St12_PlaceholderILi1EEEEEJSU_EEEvOSJ_DpOT0_
@ 0x2ab35eb3834f _ZNO6lambda12CallableOnceIFvPN7process11ProcessBaseEEE10CallableFnINS_8internal7PartialIZNS1_8dispatchIN5mesos8internal5slave5SlaveERKNS1_6FutureI6OptionINSA_10MasterInfoEEEESK_EEvRKNS1_3PIDIT_EEMSM_FvT0_EOT1_EUlOSI_S3_E_ISI_St12_PlaceholderILi1EEEEEEclEOS3_
@ 0x2ab35fd2e4d1 _ZNO6lambda12CallableOnceIFvPN7process11ProcessBaseEEEclES3_
@ 0x2ab35fcf7f01 process::ProcessBase::consume()
@ 0x2ab35fd1c224 _ZNO7process13DispatchEvent7consumeEPNS_13EventConsumerE
@ 0x561fac37e9c8 process::ProcessBase::serve()
@ 0x2ab35fcf51de process::ProcessManager::resume()
@ 0x2ab35fcf1658 _ZZN7process14ProcessManager12init_threadsEvENKUlvE_clEv
@ 0x2ab35fd17070 _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEE9_M_invokeIIEEEvSt12_Index_tupleIIXspT_EEE
@ 0x2ab35fd16391 _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEEclEv
@ 0x2ab35fd15550 _ZNSt6thread5_ImplISt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEEE6_M_runEv
@ 0x2ab362bcda60 (unknown)
@ 0x2ab363344184 start_thread
@ 0x2ab36365803d (unknown)
I1213 14:56:39.601336 29360 exec.cpp:518] Agent exited, but framework has checkpointing enabled. Waiting 15mins to reconnect with agent 1a67b9d0-d717-48dd-a846-2ceaf1dd0ccd-S0
I1213 15:11:39.602963 29371 exec.cpp:499] Recovery timeout of 15mins exceeded; Shutting down
I1213 15:11:39.603190 29371 exec.cpp:445] Executor asked to shutdown
I1213 15:11:39.604267 29375 executor.cpp:184] Received SHUTDOWN event
I1213 15:11:39.604364 29375 executor.cpp:805] Shutting down
I1213 15:11:39.604452 29375 executor.cpp:918] Sending SIGTERM to process tree at pid 29396
I1213 15:11:39.620218 29375 executor.cpp:931] Sent SIGTERM to the following process trees:
[
-+- 29396 sh -c sleep 1000
\--- 29432 sleep 1000
]
I1213 15:11:39.620247 29375 executor.cpp:935] Scheduling escalation to SIGKILL in 3secs from now
I1213 15:11:39.683349 29383 executor.cpp:1003] Command terminated with signal Terminated (pid: 29396)
W1213 15:11:39.688135 29386 process.cpp:1890] Failed to send 'mesos.internal.StatusUpdateMessage' to '172.17.0.2:37976', connect: Failed to connect to 172.17.0.2:37976: Connection refused
I1213 15:11:40.689307 29386 process.cpp:927] Stopped the socket accept loop
[FAIL]: 1 shard(s) have failed tests
make[4]: *** [check-local] Error 1
make[4]: Leaving directory `/mesos/mesos-1.8.0/_build/src'
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/mesos/mesos-1.8.0/_build/src'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/mesos/mesos-1.8.0/_build/src'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-1.8.0/_build'
make: *** [distcheck] Error 1
+ docker rmi --force mesos-1544708924-14806
Untagged: mesos-1544708924-14806:latest
Deleted: sha256:967ba03fe9adc2bcd1d8a6fd865bf5ec218e33e7a75f4d4e7fe0896888d2a577
Deleted: sha256:8e77b85ab6537c73d1011ce22c36b74a9883ee7413670c3efd1644232397f5fc
Deleted: sha256:1d5faee841b6d74c84e5626e26c8e7e03bbb090794d151ad82beae6df5d824cb
Deleted: sha256:dce7049503088a82c1bee25540b1a7d11705de7e088102fe2af496ba5f6997d0
Deleted: sha256:4291521edee549fef46ab77ca8df0ed83f1180ada66bbc784e677ae8af9ae730
Deleted: sha256:660851e49a885b41569188ca92586ff36fd24e1b7287c450f850399d94d10955
Deleted: sha256:2ef01db6fa6fb55dfc755db83ad754c98a11de5ff0607fd71a8aa06843c23b44
Deleted: sha256:4cbe9ff8b7bfa11b97cb27ebef9815a303a81b71e1d1bd51b32e11e9c3ce71a9
Deleted: sha256:936f38e90e3607842e28f12f2d6c0b90a2a811231541300900660763cbe63fba
Deleted: sha256:bf7284650b8cc0f454410ec535f436b468c9e6d3f24de30caf1b72291d54faee
Deleted: sha256:8c1ec6b16b68313c25c3fdb2b3a7ca75d0056218702e562a6f03a26915248166
Deleted: sha256:ddddae36bd0506ddac7a342747de18960198de59d65c9b37e5d8d6b13c5cde8e
Deleted: sha256:0e7069de6ff1470b76685fb01aa3e0295f506d808135f044c8ece3f1b2e18194
Deleted: sha256:0afbb74326e97efdaf7f11da0a31f098f703756da1df9745a3b3eda1c7560d06
Deleted: sha256:3f0a1b9be7c02b4388f256f1d37ee9ff34b460b1205f41eb992d72078fdf23b5
Deleted: sha256:001faa59f80a23713828609a6ca4ba4a79813e8f147c1597efb1ce8dd635a413
Deleted: sha256:e740ad76769a5b2614f0ba4f11fac8d9478d4c46e53fb400472ff2a7231145d3
Deleted: sha256:44bb35438c30c3ebc8ea2af1ba067352c51c44bc201677d73bc2e36aff20ee30
Deleted: sha256:beae1eabb55f5f4fe66538f0159c428674560f496d679683f442a94f4da6ffa3
Deleted: sha256:0835d495dc4930e8b73926f625bd4857a1199c572baed76d7773475855e0abc7
Deleted: sha256:96a34aa5ce33915a6dd18289048697d16288fe0e053f50ec3ee6628eee062818
Deleted: sha256:0813080027e0304f48dad30e017f13c073a3baf9605b54edda29a5e5fd7dedcb
Deleted: sha256:a96fc985df31fa1bca91c0e6c8a86a22336ffa5d5b53ba7e233acfac2519376e
Deleted: sha256:47cb92a9508ac57a8e50b094f305d54b336ecf53c9b290b21033d9adedfd6c52
Deleted: sha256:ae6eee2fd2c1b0ca38dfa65f556ffbd9c716c66aa9ed185d8c902aedfe0dcc56
Deleted: sha256:ffd7c608da9a2ded6412dc51b80cdc21ef349d81b9d92cce38f2d2e3c9d8e1d6
Deleted: sha256:1415d3e7bc4ed86fa2dbbe43d7baedad85156e474e986def4ab01ad9be44f012
Deleted: sha256:a7672e12c8c34552d1f09d2210b104d8783acd2138df114ee14ad70a3542b557
Deleted: sha256:21c4c6fbf698b1fa1a7b0fe58407eaf0a1fa2d1917a70bda6d06521256603613
Deleted: sha256:0444b7e1a3cbc8db013c34f13cc47a9f3bbbb6f28d08cdae2d12d1d7fa88084c
12-13-18_13:48:38 - Running <https://builds.apache.org/job/Mesos-Reviewbot/ws/support/verify-reviews.py>
Checking if review 68017 needs verification
Skipping blocking review 68017
Checking if review 69037 needs verification
Skipping blocking review 69037
Checking if review 69520 needs verification
Skipping blocking review 69520
Checking if review 69521 needs verification
Skipping blocking review 69521
Checking if review 69522 needs verification
Patch never verified, needs verification
Dependent review: https://reviews.apache.org/api/review-requests/69521/
Dependent review: https://reviews.apache.org/api/review-requests/69520/
Dependent review: https://reviews.apache.org/api/review-requests/69037/
Verifying review 69522
Dependent review: https://reviews.apache.org/api/review-requests/69521/
Dependent review: https://reviews.apache.org/api/review-requests/69520/
Dependent review: https://reviews.apache.org/api/review-requests/69037/
Applying review 69037
/usr/bin/python3 support/apply-reviews.py -n -r 69037
Applying review 69520
/usr/bin/python3 support/apply-reviews.py -n -r 69520
Applying review 69521
/usr/bin/python3 support/apply-reviews.py -n -r 69521
Applying review 69522
/usr/bin/python3 support/apply-reviews.py -n -r 69522
Traceback (most recent call last):
File "<https://builds.apache.org/job/Mesos-Reviewbot/ws/support/verify-reviews.py",> line 186, in verify_review
% (command, build_output)])
File "/usr/lib/python3.5/subprocess.py", line 581, in check_call
raise CalledProcessError(retcode, cmd)
subprocess.CalledProcessError: Command '['bash', '-c', "set -o pipefail; export OS='ubuntu:14.04' BUILDTOOL='autotools' COMPILER='gcc' CONFIGURATION='--verbose --disable-libtool-wrappers' ENVIRONMENT='GLOG_v=1 MESOS_VERBOSE=1'; ./support/docker-build.sh 2>&1 | tee build_69522"]' returned non-zero exit status 2
During handling of the above exception, another exception occurred:
Traceback (most recent call last):
File "<https://builds.apache.org/job/Mesos-Reviewbot/ws/support/verify-reviews.py",> line 294, in <module>
main()
File "<https://builds.apache.org/job/Mesos-Reviewbot/ws/support/verify-reviews.py",> line 288, in main
verify_review(review_request, handler)
File "<https://builds.apache.org/job/Mesos-Reviewbot/ws/support/verify-reviews.py",> line 201, in verify_review
output = open(build_output).read().decode(sys.stdout.encoding)
AttributeError: 'str' object has no attribute 'decode'
Build step 'Execute shell' marked build as failure
Jenkins build is back to normal : Mesos-Reviewbot #23605
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Reviewbot/23605/display/redirect>
Build failed in Jenkins: Mesos-Reviewbot #23604
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Reviewbot/23604/display/redirect?page=changes>
Changes:
[chhsiao] Added an optional `vendor` field to `Resource.DiskInfo.Source`.
[chhsiao] Set up the `Resource.DiskInfo.Source.vendor` field in SLRP.
[chhsiao] Fixed `AgentResourceProviderConfigApiTest.Update` for `vendor` field.
[chhsiao] Added MESOS-9321 to the 1.7.1 CHANGELOG.
[chhsiao] Tested the `Resource.DiskInfo.Source.vendor` field in related tests.
[chhsiao] Added the missing MESOS-7947 to the 1.7.0 CHANGELOG.
------------------------------------------
[...truncated 46.02 MB...]
I1214 04:21:47.389428 20001 master.cpp:6880] Registered agent 52dc37bb-1f20-49d0-8aa5-314eb09037c6-S0 at slave@172.17.0.2:38996 (a21943ae6967) with cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I1214 04:21:47.389510 20024 slave.cpp:1493] Registered with master master@172.17.0.2:38996; given agent ID 52dc37bb-1f20-49d0-8aa5-314eb09037c6-S0
I1214 04:21:47.389724 20001 master.cpp:6605] Received register agent message from slave@172.17.0.2:38996 (a21943ae6967)
I1214 04:21:47.389832 20006 task_status_update_manager.cpp:188] Resuming sending task status updates
I1214 04:21:47.389894 20006 status_update_manager_process.hpp:385] Resuming operation status update manager
I1214 04:21:47.389998 20024 slave.cpp:1514] Checkpointing SlaveInfo to '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_l1Tip8/meta/slaves/52dc37bb-1f20-49d0-8aa5-314eb09037c6-S0/slave.info'
I1214 04:21:47.390117 20001 master.cpp:3935] Authorizing agent providing resources 'cpus:2; mem:1024; disk:1024; ports:[31000-32000]' with principal 'test-principal'
I1214 04:21:47.390410 20011 hierarchical.cpp:603] Added agent 52dc37bb-1f20-49d0-8aa5-314eb09037c6-S0 (a21943ae6967) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] (allocated: {})
I1214 04:21:47.390723 20023 master.cpp:6672] Authorized registration of agent at slave@172.17.0.2:38996 (a21943ae6967)
I1214 04:21:47.390838 20023 master.cpp:6766] Agent 52dc37bb-1f20-49d0-8aa5-314eb09037c6-S0 at slave@172.17.0.2:38996 (a21943ae6967) already registered, resending acknowledgement
I1214 04:21:47.391296 20024 slave.cpp:1563] Forwarding agent update {"operations":{},"resource_version_uuid":{"value":"j1/4uVULSXyOp+fQXj8FHg=="},"slave_id":{"value":"52dc37bb-1f20-49d0-8aa5-314eb09037c6-S0"},"update_oversubscribed_resources":false}
W1214 04:21:47.391760 20024 slave.cpp:1545] Already registered with master master@172.17.0.2:38996
I1214 04:21:47.391865 20024 slave.cpp:1563] Forwarding agent update {"operations":{},"resource_version_uuid":{"value":"j1/4uVULSXyOp+fQXj8FHg=="},"slave_id":{"value":"52dc37bb-1f20-49d0-8aa5-314eb09037c6-S0"},"update_oversubscribed_resources":false}
I1214 04:21:47.392256 20021 master.cpp:7939] Ignoring update on agent 52dc37bb-1f20-49d0-8aa5-314eb09037c6-S0 at slave@172.17.0.2:38996 (a21943ae6967) as it reports no changes
I1214 04:21:47.392314 20011 hierarchical.cpp:1566] Performed allocation for 1 agents in 1.687645ms
I1214 04:21:47.393995 20021 master.cpp:7939] Ignoring update on agent 52dc37bb-1f20-49d0-8aa5-314eb09037c6-S0 at slave@172.17.0.2:38996 (a21943ae6967) as it reports no changes
I1214 04:21:47.395380 20021 master.cpp:9504] Sending offers [ 52dc37bb-1f20-49d0-8aa5-314eb09037c6-O0 ] to framework 52dc37bb-1f20-49d0-8aa5-314eb09037c6-0000 (default) at scheduler-1b8b7f11-7028-4aa3-9f2d-010684c42ce5@172.17.0.2:38996
I1214 04:21:47.396199 20021 sched.cpp:914] Scheduler::resourceOffers took 151349ns
I1214 04:21:47.398469 19988 master.cpp:11507] Removing offer 52dc37bb-1f20-49d0-8aa5-314eb09037c6-O0
I1214 04:21:47.399032 19988 master.cpp:4456] Processing ACCEPT call for offers: [ 52dc37bb-1f20-49d0-8aa5-314eb09037c6-O0 ] on agent 52dc37bb-1f20-49d0-8aa5-314eb09037c6-S0 at slave@172.17.0.2:38996 (a21943ae6967) for framework 52dc37bb-1f20-49d0-8aa5-314eb09037c6-0000 (default) at scheduler-1b8b7f11-7028-4aa3-9f2d-010684c42ce5@172.17.0.2:38996
I1214 04:21:47.399209 19988 master.cpp:3552] Authorizing framework principal 'test-principal' to launch task 139ed374-1d91-4d68-bb4f-93e505e5335a
I1214 04:21:47.401973 20004 master.cpp:4033] Adding task 139ed374-1d91-4d68-bb4f-93e505e5335a with resources cpus(allocated: *):2; mem(allocated: *):1024; disk(allocated: *):1024; ports(allocated: *):[31000-32000] of framework 52dc37bb-1f20-49d0-8aa5-314eb09037c6-0000 (default) at scheduler-1b8b7f11-7028-4aa3-9f2d-010684c42ce5@172.17.0.2:38996 on agent 52dc37bb-1f20-49d0-8aa5-314eb09037c6-S0 at slave@172.17.0.2:38996 (a21943ae6967)
I1214 04:21:47.402804 20004 master.cpp:5428] Launching task 139ed374-1d91-4d68-bb4f-93e505e5335a of framework 52dc37bb-1f20-49d0-8aa5-314eb09037c6-0000 (default) at scheduler-1b8b7f11-7028-4aa3-9f2d-010684c42ce5@172.17.0.2:38996 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 52dc37bb-1f20-49d0-8aa5-314eb09037c6-S0 at slave@172.17.0.2:38996 (a21943ae6967) on new executor
I1214 04:21:47.404812 20004 slave.cpp:2030] Got assigned task '139ed374-1d91-4d68-bb4f-93e505e5335a' for framework 52dc37bb-1f20-49d0-8aa5-314eb09037c6-0000
I1214 04:21:47.405072 20004 slave.cpp:8975] Checkpointing FrameworkInfo to '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_l1Tip8/meta/slaves/52dc37bb-1f20-49d0-8aa5-314eb09037c6-S0/frameworks/52dc37bb-1f20-49d0-8aa5-314eb09037c6-0000/framework.info'
I1214 04:21:47.405634 20004 slave.cpp:8986] Checkpointing framework pid 'scheduler-1b8b7f11-7028-4aa3-9f2d-010684c42ce5@172.17.0.2:38996' to '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_l1Tip8/meta/slaves/52dc37bb-1f20-49d0-8aa5-314eb09037c6-S0/frameworks/52dc37bb-1f20-49d0-8aa5-314eb09037c6-0000/framework.pid'
I1214 04:21:47.407641 20004 slave.cpp:2404] Authorizing task '139ed374-1d91-4d68-bb4f-93e505e5335a' for framework 52dc37bb-1f20-49d0-8aa5-314eb09037c6-0000
I1214 04:21:47.407775 20004 slave.cpp:8551] Authorizing framework principal 'test-principal' to launch task 139ed374-1d91-4d68-bb4f-93e505e5335a
I1214 04:21:47.410432 20020 slave.cpp:2847] Launching task '139ed374-1d91-4d68-bb4f-93e505e5335a' for framework 52dc37bb-1f20-49d0-8aa5-314eb09037c6-0000
I1214 04:21:47.410554 20020 paths.cpp:752] Creating sandbox '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_l1Tip8/slaves/52dc37bb-1f20-49d0-8aa5-314eb09037c6-S0/frameworks/52dc37bb-1f20-49d0-8aa5-314eb09037c6-0000/executors/139ed374-1d91-4d68-bb4f-93e505e5335a/runs/4d366ff3-e202-449a-8640-97b2bd13e5e7' for user 'mesos'
I1214 04:21:47.413152 20020 slave.cpp:9761] Checkpointing ExecutorInfo to '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_l1Tip8/meta/slaves/52dc37bb-1f20-49d0-8aa5-314eb09037c6-S0/frameworks/52dc37bb-1f20-49d0-8aa5-314eb09037c6-0000/executors/139ed374-1d91-4d68-bb4f-93e505e5335a/executor.info'
I1214 04:21:47.414191 20020 paths.cpp:755] Creating sandbox '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_l1Tip8/meta/slaves/52dc37bb-1f20-49d0-8aa5-314eb09037c6-S0/frameworks/52dc37bb-1f20-49d0-8aa5-314eb09037c6-0000/executors/139ed374-1d91-4d68-bb4f-93e505e5335a/runs/4d366ff3-e202-449a-8640-97b2bd13e5e7'
I1214 04:21:47.414651 20020 slave.cpp:9061] Launching executor '139ed374-1d91-4d68-bb4f-93e505e5335a' of framework 52dc37bb-1f20-49d0-8aa5-314eb09037c6-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/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_l1Tip8/slaves/52dc37bb-1f20-49d0-8aa5-314eb09037c6-S0/frameworks/52dc37bb-1f20-49d0-8aa5-314eb09037c6-0000/executors/139ed374-1d91-4d68-bb4f-93e505e5335a/runs/4d366ff3-e202-449a-8640-97b2bd13e5e7'
I1214 04:21:47.415972 20020 slave.cpp:3525] Launching container 4d366ff3-e202-449a-8640-97b2bd13e5e7 for executor '139ed374-1d91-4d68-bb4f-93e505e5335a' of framework 52dc37bb-1f20-49d0-8aa5-314eb09037c6-0000
I1214 04:21:47.416864 20020 slave.cpp:9792] Checkpointing TaskInfo to '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_l1Tip8/meta/slaves/52dc37bb-1f20-49d0-8aa5-314eb09037c6-S0/frameworks/52dc37bb-1f20-49d0-8aa5-314eb09037c6-0000/executors/139ed374-1d91-4d68-bb4f-93e505e5335a/runs/4d366ff3-e202-449a-8640-97b2bd13e5e7/tasks/139ed374-1d91-4d68-bb4f-93e505e5335a/task.info'
I1214 04:21:47.417898 20020 slave.cpp:3044] Queued task '139ed374-1d91-4d68-bb4f-93e505e5335a' for executor '139ed374-1d91-4d68-bb4f-93e505e5335a' of framework 52dc37bb-1f20-49d0-8aa5-314eb09037c6-0000
I1214 04:21:47.418195 20020 slave.cpp:1001] Successfully attached '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_l1Tip8/slaves/52dc37bb-1f20-49d0-8aa5-314eb09037c6-S0/frameworks/52dc37bb-1f20-49d0-8aa5-314eb09037c6-0000/executors/139ed374-1d91-4d68-bb4f-93e505e5335a/runs/4d366ff3-e202-449a-8640-97b2bd13e5e7' to virtual path '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_l1Tip8/slaves/52dc37bb-1f20-49d0-8aa5-314eb09037c6-S0/frameworks/52dc37bb-1f20-49d0-8aa5-314eb09037c6-0000/executors/139ed374-1d91-4d68-bb4f-93e505e5335a/runs/latest'
I1214 04:21:47.418251 20020 slave.cpp:1001] Successfully attached '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_l1Tip8/slaves/52dc37bb-1f20-49d0-8aa5-314eb09037c6-S0/frameworks/52dc37bb-1f20-49d0-8aa5-314eb09037c6-0000/executors/139ed374-1d91-4d68-bb4f-93e505e5335a/runs/4d366ff3-e202-449a-8640-97b2bd13e5e7' to virtual path '/frameworks/52dc37bb-1f20-49d0-8aa5-314eb09037c6-0000/executors/139ed374-1d91-4d68-bb4f-93e505e5335a/runs/latest'
I1214 04:21:47.418289 20020 slave.cpp:1001] Successfully attached '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_l1Tip8/slaves/52dc37bb-1f20-49d0-8aa5-314eb09037c6-S0/frameworks/52dc37bb-1f20-49d0-8aa5-314eb09037c6-0000/executors/139ed374-1d91-4d68-bb4f-93e505e5335a/runs/4d366ff3-e202-449a-8640-97b2bd13e5e7' to virtual path '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_l1Tip8/slaves/52dc37bb-1f20-49d0-8aa5-314eb09037c6-S0/frameworks/52dc37bb-1f20-49d0-8aa5-314eb09037c6-0000/executors/139ed374-1d91-4d68-bb4f-93e505e5335a/runs/4d366ff3-e202-449a-8640-97b2bd13e5e7'
I1214 04:21:47.418781 20016 containerizer.cpp:1288] Starting container 4d366ff3-e202-449a-8640-97b2bd13e5e7
I1214 04:21:47.420619 20016 containerizer.cpp:1454] Checkpointed ContainerConfig at '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_oQqeYg/containers/4d366ff3-e202-449a-8640-97b2bd13e5e7/config'
I1214 04:21:47.420663 20016 containerizer.cpp:3130] Transitioning the state of container 4d366ff3-e202-449a-8640-97b2bd13e5e7 from PROVISIONING to PREPARING
I1214 04:21:47.427032 19994 containerizer.cpp:1947] Launching 'mesos-containerizer' with flags '--help="false" --launch_info="{"command":{"arguments":["mesos-executor","--launcher_dir=/mesos/mesos-1.8.0/_build/src"],"shell":false,"value":"/mesos/mesos-1.8.0/_build/src/mesos-executor"},"environment":{"variables":[{"name":"LIBPROCESS_PORT","type":"VALUE","value":"0"},{"name":"MESOS_AGENT_ENDPOINT","type":"VALUE","value":"172.17.0.2:38996"},{"name":"MESOS_CHECKPOINT","type":"VALUE","value":"1"},{"name":"MESOS_DIRECTORY","type":"VALUE","value":"/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_l1Tip8/slaves/52dc37bb-1f20-49d0-8aa5-314eb09037c6-S0/frameworks/52dc37bb-1f20-49d0-8aa5-314eb09037c6-0000/executors/139ed374-1d91-4d68-bb4f-93e505e5335a/runs/4d366ff3-e202-449a-8640-97b2bd13e5e7"},{"name":"MESOS_EXECUTOR_ID","type":"VALUE","value":"139ed374-1d91-4d68-bb4f-93e505e5335a"},{"name":"MESOS_EXECUTOR_SHUTDOWN_GRACE_PERIOD","type":"VALUE","value":"5secs"},{"name":"MESOS_FRAMEWORK_ID","type":"VALUE","value":"52dc37bb-1f20-49d0-8aa5-314eb09037c6-0000"},{"name":"MESOS_HTTP_COMMAND_EXECUTOR","type":"VALUE","value":"0"},{"name":"MESOS_RECOVERY_TIMEOUT","type":"VALUE","value":"15mins"},{"name":"MESOS_SLAVE_ID","type":"VALUE","value":"52dc37bb-1f20-49d0-8aa5-314eb09037c6-S0"},{"name":"MESOS_SLAVE_PID","type":"VALUE","value":"slave@172.17.0.2:38996"},{"name":"MESOS_SUBSCRIPTION_BACKOFF_MAX","type":"VALUE","value":"15secs"},{"name":"MESOS_SANDBOX","type":"VALUE","value":"/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_l1Tip8/slaves/52dc37bb-1f20-49d0-8aa5-314eb09037c6-S0/frameworks/52dc37bb-1f20-49d0-8aa5-314eb09037c6-0000/executors/139ed374-1d91-4d68-bb4f-93e505e5335a/runs/4d366ff3-e202-449a-8640-97b2bd13e5e7"}]},"task_environment":{},"user":"mesos","working_directory":"/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_l1Tip8/slaves/52dc37bb-1f20-49d0-8aa5-314eb09037c6-S0/frameworks/52dc37bb-1f20-49d0-8aa5-314eb09037c6-0000/executors/139ed374-1d91-4d68-bb4f-93e505e5335a/runs/4d366ff3-e202-449a-8640-97b2bd13e5e7"}" --pipe_read="9" --pipe_write="10" --runtime_directory="/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_oQqeYg/containers/4d366ff3-e202-449a-8640-97b2bd13e5e7" --unshare_namespace_mnt="false"'
I1214 04:21:47.429486 19994 launcher.cpp:145] Forked child with pid '29684' for container '4d366ff3-e202-449a-8640-97b2bd13e5e7'
I1214 04:21:47.429644 19994 containerizer.cpp:2052] Checkpointing container's forked pid 29684 to '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_l1Tip8/meta/slaves/52dc37bb-1f20-49d0-8aa5-314eb09037c6-S0/frameworks/52dc37bb-1f20-49d0-8aa5-314eb09037c6-0000/executors/139ed374-1d91-4d68-bb4f-93e505e5335a/runs/4d366ff3-e202-449a-8640-97b2bd13e5e7/pids/forked.pid'
I1214 04:21:47.430585 19994 containerizer.cpp:3130] Transitioning the state of container 4d366ff3-e202-449a-8640-97b2bd13e5e7 from PREPARING to ISOLATING
I1214 04:21:47.432651 19990 containerizer.cpp:3130] Transitioning the state of container 4d366ff3-e202-449a-8640-97b2bd13e5e7 from ISOLATING to FETCHING
I1214 04:21:47.432971 20001 fetcher.cpp:369] Starting to fetch URIs for container: 4d366ff3-e202-449a-8640-97b2bd13e5e7, directory: /tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_l1Tip8/slaves/52dc37bb-1f20-49d0-8aa5-314eb09037c6-S0/frameworks/52dc37bb-1f20-49d0-8aa5-314eb09037c6-0000/executors/139ed374-1d91-4d68-bb4f-93e505e5335a/runs/4d366ff3-e202-449a-8640-97b2bd13e5e7
I1214 04:21:47.434581 20001 containerizer.cpp:3130] Transitioning the state of container 4d366ff3-e202-449a-8640-97b2bd13e5e7 from FETCHING to RUNNING
I1214 04:21:47.915544 29766 exec.cpp:162] Version: 1.8.0
I1214 04:21:47.928161 19997 slave.cpp:4838] Got registration for executor '139ed374-1d91-4d68-bb4f-93e505e5335a' of framework 52dc37bb-1f20-49d0-8aa5-314eb09037c6-0000 from executor(1)@172.17.0.2:37461
I1214 04:21:47.928886 19997 slave.cpp:4924] Checkpointing executor pid 'executor(1)@172.17.0.2:37461' to '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_l1Tip8/meta/slaves/52dc37bb-1f20-49d0-8aa5-314eb09037c6-S0/frameworks/52dc37bb-1f20-49d0-8aa5-314eb09037c6-0000/executors/139ed374-1d91-4d68-bb4f-93e505e5335a/runs/4d366ff3-e202-449a-8640-97b2bd13e5e7/pids/libprocess.pid'
I1214 04:21:47.932601 20023 slave.cpp:3257] Sending queued task '139ed374-1d91-4d68-bb4f-93e505e5335a' to executor '139ed374-1d91-4d68-bb4f-93e505e5335a' of framework 52dc37bb-1f20-49d0-8aa5-314eb09037c6-0000 at executor(1)@172.17.0.2:37461
I1214 04:21:47.935004 29773 exec.cpp:236] Executor registered on agent 52dc37bb-1f20-49d0-8aa5-314eb09037c6-S0
I1214 04:21:47.940012 29750 executor.cpp:184] Received SUBSCRIBED event
I1214 04:21:47.941709 29750 executor.cpp:188] Subscribed executor on a21943ae6967
I1214 04:21:47.941963 29750 executor.cpp:184] Received LAUNCH event
I1214 04:21:47.944339 29750 executor.cpp:687] Starting task 139ed374-1d91-4d68-bb4f-93e505e5335a
I1214 04:21:47.948521 20022 slave.cpp:5304] Handling status update TASK_STARTING (Status UUID: 0fdad5ee-d41b-44e6-af4d-104335a745fc) for task 139ed374-1d91-4d68-bb4f-93e505e5335a of framework 52dc37bb-1f20-49d0-8aa5-314eb09037c6-0000 from executor(1)@172.17.0.2:37461
I1214 04:21:47.950886 19994 task_status_update_manager.cpp:328] Received task status update TASK_STARTING (Status UUID: 0fdad5ee-d41b-44e6-af4d-104335a745fc) for task 139ed374-1d91-4d68-bb4f-93e505e5335a of framework 52dc37bb-1f20-49d0-8aa5-314eb09037c6-0000
I1214 04:21:47.950953 19994 task_status_update_manager.cpp:507] Creating StatusUpdate stream for task 139ed374-1d91-4d68-bb4f-93e505e5335a of framework 52dc37bb-1f20-49d0-8aa5-314eb09037c6-0000
I1214 04:21:47.951902 19994 task_status_update_manager.cpp:842] Checkpointing UPDATE for task status update TASK_STARTING (Status UUID: 0fdad5ee-d41b-44e6-af4d-104335a745fc) for task 139ed374-1d91-4d68-bb4f-93e505e5335a of framework 52dc37bb-1f20-49d0-8aa5-314eb09037c6-0000
I1214 04:21:47.952208 19994 task_status_update_manager.cpp:383] Forwarding task status update TASK_STARTING (Status UUID: 0fdad5ee-d41b-44e6-af4d-104335a745fc) for task 139ed374-1d91-4d68-bb4f-93e505e5335a of framework 52dc37bb-1f20-49d0-8aa5-314eb09037c6-0000 to the agent
I1214 04:21:47.952435 20006 slave.cpp:5796] Forwarding the update TASK_STARTING (Status UUID: 0fdad5ee-d41b-44e6-af4d-104335a745fc) for task 139ed374-1d91-4d68-bb4f-93e505e5335a of framework 52dc37bb-1f20-49d0-8aa5-314eb09037c6-0000 to master@172.17.0.2:38996
I1214 04:21:47.952711 20006 slave.cpp:5689] Task status update manager successfully handled status update TASK_STARTING (Status UUID: 0fdad5ee-d41b-44e6-af4d-104335a745fc) for task 139ed374-1d91-4d68-bb4f-93e505e5335a of framework 52dc37bb-1f20-49d0-8aa5-314eb09037c6-0000
I1214 04:21:47.952777 20006 slave.cpp:5705] Sending acknowledgement for status update TASK_STARTING (Status UUID: 0fdad5ee-d41b-44e6-af4d-104335a745fc) for task 139ed374-1d91-4d68-bb4f-93e505e5335a of framework 52dc37bb-1f20-49d0-8aa5-314eb09037c6-0000 to executor(1)@172.17.0.2:37461
I1214 04:21:47.952888 20017 master.cpp:8375] Status update TASK_STARTING (Status UUID: 0fdad5ee-d41b-44e6-af4d-104335a745fc) for task 139ed374-1d91-4d68-bb4f-93e505e5335a of framework 52dc37bb-1f20-49d0-8aa5-314eb09037c6-0000 from agent 52dc37bb-1f20-49d0-8aa5-314eb09037c6-S0 at slave@172.17.0.2:38996 (a21943ae6967)
I1214 04:21:47.952958 20017 master.cpp:8432] Forwarding status update TASK_STARTING (Status UUID: 0fdad5ee-d41b-44e6-af4d-104335a745fc) for task 139ed374-1d91-4d68-bb4f-93e505e5335a of framework 52dc37bb-1f20-49d0-8aa5-314eb09037c6-0000
I1214 04:21:47.953282 20017 master.cpp:10968] Updating the state of task 139ed374-1d91-4d68-bb4f-93e505e5335a of framework 52dc37bb-1f20-49d0-8aa5-314eb09037c6-0000 (latest state: TASK_STARTING, status update state: TASK_STARTING)
I1214 04:21:47.953591 20001 sched.cpp:1022] Scheduler::statusUpdate took 145463ns
I1214 04:21:47.954785 20022 master.cpp:6231] Processing ACKNOWLEDGE call for status 0fdad5ee-d41b-44e6-af4d-104335a745fc for task 139ed374-1d91-4d68-bb4f-93e505e5335a of framework 52dc37bb-1f20-49d0-8aa5-314eb09037c6-0000 (default) at scheduler-1b8b7f11-7028-4aa3-9f2d-010684c42ce5@172.17.0.2:38996 on agent 52dc37bb-1f20-49d0-8aa5-314eb09037c6-S0
I1214 04:21:47.955246 20012 task_status_update_manager.cpp:401] Received task status update acknowledgement (UUID: 0fdad5ee-d41b-44e6-af4d-104335a745fc) for task 139ed374-1d91-4d68-bb4f-93e505e5335a of framework 52dc37bb-1f20-49d0-8aa5-314eb09037c6-0000
I1214 04:21:47.955381 20012 task_status_update_manager.cpp:842] Checkpointing ACK for task status update TASK_STARTING (Status UUID: 0fdad5ee-d41b-44e6-af4d-104335a745fc) for task 139ed374-1d91-4d68-bb4f-93e505e5335a of framework 52dc37bb-1f20-49d0-8aa5-314eb09037c6-0000
I1214 04:21:47.955698 19998 slave.cpp:4540] Task status update manager successfully handled status update acknowledgement (UUID: 0fdad5ee-d41b-44e6-af4d-104335a745fc) for task 139ed374-1d91-4d68-bb4f-93e505e5335a of framework 52dc37bb-1f20-49d0-8aa5-314eb09037c6-0000
I1214 04:21:47.982473 29750 executor.cpp:502] Running '/mesos/mesos-1.8.0/_build/src/mesos-containerizer launch <POSSIBLY-SENSITIVE-DATA>'
I1214 04:21:47.987602 29750 executor.cpp:702] Forked command at 29781
I1214 04:21:47.991948 20025 slave.cpp:5304] Handling status update TASK_RUNNING (Status UUID: f13fc729-c6fd-4f8e-8258-23dbff821f2e) for task 139ed374-1d91-4d68-bb4f-93e505e5335a of framework 52dc37bb-1f20-49d0-8aa5-314eb09037c6-0000 from executor(1)@172.17.0.2:37461
I1214 04:21:47.995160 19986 task_status_update_manager.cpp:328] Received task status update TASK_RUNNING (Status UUID: f13fc729-c6fd-4f8e-8258-23dbff821f2e) for task 139ed374-1d91-4d68-bb4f-93e505e5335a of framework 52dc37bb-1f20-49d0-8aa5-314eb09037c6-0000
I1214 04:21:47.995303 19986 task_status_update_manager.cpp:842] Checkpointing UPDATE for task status update TASK_RUNNING (Status UUID: f13fc729-c6fd-4f8e-8258-23dbff821f2e) for task 139ed374-1d91-4d68-bb4f-93e505e5335a of framework 52dc37bb-1f20-49d0-8aa5-314eb09037c6-0000
I1214 04:21:47.995694 19986 task_status_update_manager.cpp:383] Forwarding task status update TASK_RUNNING (Status UUID: f13fc729-c6fd-4f8e-8258-23dbff821f2e) for task 139ed374-1d91-4d68-bb4f-93e505e5335a of framework 52dc37bb-1f20-49d0-8aa5-314eb09037c6-0000 to the agent
I1214 04:21:47.995959 20023 slave.cpp:5796] Forwarding the update TASK_RUNNING (Status UUID: f13fc729-c6fd-4f8e-8258-23dbff821f2e) for task 139ed374-1d91-4d68-bb4f-93e505e5335a of framework 52dc37bb-1f20-49d0-8aa5-314eb09037c6-0000 to master@172.17.0.2:38996
I1214 04:21:47.996248 20023 slave.cpp:5689] Task status update manager successfully handled status update TASK_RUNNING (Status UUID: f13fc729-c6fd-4f8e-8258-23dbff821f2e) for task 139ed374-1d91-4d68-bb4f-93e505e5335a of framework 52dc37bb-1f20-49d0-8aa5-314eb09037c6-0000
I1214 04:21:47.996320 20023 slave.cpp:5705] Sending acknowledgement for status update TASK_RUNNING (Status UUID: f13fc729-c6fd-4f8e-8258-23dbff821f2e) for task 139ed374-1d91-4d68-bb4f-93e505e5335a of framework 52dc37bb-1f20-49d0-8aa5-314eb09037c6-0000 to executor(1)@172.17.0.2:37461
I1214 04:21:47.996423 20008 master.cpp:8375] Status update TASK_RUNNING (Status UUID: f13fc729-c6fd-4f8e-8258-23dbff821f2e) for task 139ed374-1d91-4d68-bb4f-93e505e5335a of framework 52dc37bb-1f20-49d0-8aa5-314eb09037c6-0000 from agent 52dc37bb-1f20-49d0-8aa5-314eb09037c6-S0 at slave@172.17.0.2:38996 (a21943ae6967)
I1214 04:21:47.996496 20008 master.cpp:8432] Forwarding status update TASK_RUNNING (Status UUID: f13fc729-c6fd-4f8e-8258-23dbff821f2e) for task 139ed374-1d91-4d68-bb4f-93e505e5335a of framework 52dc37bb-1f20-49d0-8aa5-314eb09037c6-0000
I1214 04:21:47.996807 20008 master.cpp:10968] Updating the state of task 139ed374-1d91-4d68-bb4f-93e505e5335a of framework 52dc37bb-1f20-49d0-8aa5-314eb09037c6-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING)
I1214 04:21:47.997128 20022 sched.cpp:1022] Scheduler::statusUpdate took 109497ns
I1214 04:21:47.998128 20025 master.cpp:6231] Processing ACKNOWLEDGE call for status f13fc729-c6fd-4f8e-8258-23dbff821f2e for task 139ed374-1d91-4d68-bb4f-93e505e5335a of framework 52dc37bb-1f20-49d0-8aa5-314eb09037c6-0000 (default) at scheduler-1b8b7f11-7028-4aa3-9f2d-010684c42ce5@172.17.0.2:38996 on agent 52dc37bb-1f20-49d0-8aa5-314eb09037c6-S0
I1214 04:21:47.998639 20016 task_status_update_manager.cpp:401] Received task status update acknowledgement (UUID: f13fc729-c6fd-4f8e-8258-23dbff821f2e) for task 139ed374-1d91-4d68-bb4f-93e505e5335a of framework 52dc37bb-1f20-49d0-8aa5-314eb09037c6-0000
I1214 04:21:47.998788 20016 task_status_update_manager.cpp:842] Checkpointing ACK for task status update TASK_RUNNING (Status UUID: f13fc729-c6fd-4f8e-8258-23dbff821f2e) for task 139ed374-1d91-4d68-bb4f-93e505e5335a of framework 52dc37bb-1f20-49d0-8aa5-314eb09037c6-0000
I1214 04:21:47.999178 20014 slave.cpp:4540] Task status update manager successfully handled status update acknowledgement (UUID: f13fc729-c6fd-4f8e-8258-23dbff821f2e) for task 139ed374-1d91-4d68-bb4f-93e505e5335a of framework 52dc37bb-1f20-49d0-8aa5-314eb09037c6-0000
I1214 04:21:47.999758 20017 slave.cpp:922] Agent terminating
I1214 04:21:48.332859 20003 hierarchical.cpp:1566] Performed allocation for 1 agents in 361184ns
I1214 04:21:48.408609 20017 master.cpp:1275] Agent 52dc37bb-1f20-49d0-8aa5-314eb09037c6-S0 at slave@172.17.0.2:38996 (a21943ae6967) disconnected
I1214 04:21:48.408668 20017 master.cpp:3278] Disconnecting agent 52dc37bb-1f20-49d0-8aa5-314eb09037c6-S0 at slave@172.17.0.2:38996 (a21943ae6967)
I1214 04:21:48.408746 20017 master.cpp:3297] Deactivating agent 52dc37bb-1f20-49d0-8aa5-314eb09037c6-S0 at slave@172.17.0.2:38996 (a21943ae6967)
I1214 04:21:48.408910 20020 hierarchical.cpp:801] Agent 52dc37bb-1f20-49d0-8aa5-314eb09037c6-S0 deactivated
I1214 04:21:48.409493 19625 containerizer.cpp:305] Using isolation { environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni }
W1214 04:21:48.410259 19625 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W1214 04:21:48.410441 19625 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I1214 04:21:48.410485 19625 provisioner.cpp:298] Using default backend 'copy'
W1214 04:21:48.426771 19625 process.cpp:2829] Attempted to spawn already running process files@172.17.0.2:38996
I1214 04:21:48.427829 19625 cluster.cpp:485] Creating default 'local' authorizer
I1214 04:21:48.432866 20024 slave.cpp:268] Mesos agent started on @172.17.0.2:38996
I1214 04:21:48.432940 20024 slave.cpp:269] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_oQqeYg/store/appc" --authenticate_http_readonly="true" --authenticate_http_readwrite="false" --authenticatee="crammd5" --authentication_backoff_factor="1secs" --authentication_timeout_max="1mins" --authentication_timeout_min="5secs" --authorizer="local" --cgroups_cpu_enable_pids_and_tids_count="false" --cgroups_destroy_timeout="1mins" --cgroups_enable_cfs="false" --cgroups_hierarchy="/sys/fs/cgroup" --cgroups_limit_swap="false" --cgroups_root="mesos" --container_disk_watch_interval="15secs" --containerizers="mesos" --credential="/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_oQqeYg/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/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_oQqeYg/store/docker" --docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_reregistration_timeout="15secs" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_oQqeYg/fetch" --fetcher_cache_size="2GB" --fetcher_stall_timeout="1mins" --frameworks_home="/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_oQqeYg/frameworks" --gc_delay="1weeks" --gc_disk_headroom="0.1" --gc_non_executor_container_sandboxes="false" --help="false" --hostname_lookup="true" --http_command_executor="false" --http_credentials="/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_oQqeYg/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.8.0/_build/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --memory_profiling="false" --network_cni_metrics="true" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --port="5051" --qos_correction_interval_min="0ns" --quiet="false" --reconfiguration_policy="equal" --recover="reconnect" --recovery_timeout="15mins" --registration_backoff_factor="10ms" --resources="cpus:2;gpus:0;mem:1024;disk:1024;ports:[31000-32000]" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_oQqeYg" --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/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_l1Tip8" --zk_session_timeout="10secs"
I1214 04:21:48.433867 20024 credentials.hpp:86] Loading credential for authentication from '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_oQqeYg/credential'
I1214 04:21:48.434157 20024 slave.cpp:301] Agent using credential for: test-principal
I1214 04:21:48.434352 20024 credentials.hpp:37] Loading credentials for authentication from '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_oQqeYg/http_credentials'
I1214 04:21:48.434728 20024 http.cpp:1017] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I1214 04:21:48.442337 20024 disk_profile_adaptor.cpp:80] Creating default disk profile adaptor module
I1214 04:21:48.444270 20024 slave.cpp:616] 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"}]
I1214 04:21:48.444536 20024 slave.cpp:624] Agent attributes: [ ]
I1214 04:21:48.444562 20024 slave.cpp:633] Agent hostname: a21943ae6967
I1214 04:21:48.444780 20023 task_status_update_manager.cpp:181] Pausing sending task status updates
I1214 04:21:48.444864 20023 status_update_manager_process.hpp:379] Pausing operation status update manager
I1214 04:21:48.458024 19988 state.cpp:66] Recovering state from '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_l1Tip8/meta'
I1214 04:21:48.458138 19988 state.cpp:711] No committed checkpointed resources found at '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_l1Tip8/meta/resources/resources.info'
I1214 04:21:48.465199 20023 slave.cpp:6992] Finished recovering checkpointed state from '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_l1Tip8/meta', beginning agent recovery
I1214 04:21:48.465960 20023 slave.cpp:7471] Recovering framework 52dc37bb-1f20-49d0-8aa5-314eb09037c6-0000
I1214 04:21:48.466099 20023 slave.cpp:9179] Recovering executor '139ed374-1d91-4d68-bb4f-93e505e5335a' of framework 52dc37bb-1f20-49d0-8aa5-314eb09037c6-0000
I1214 04:21:48.467370 20023 task_status_update_manager.cpp:207] Recovering task status update manager
I1214 04:21:48.467416 20023 task_status_update_manager.cpp:215] Recovering executor '139ed374-1d91-4d68-bb4f-93e505e5335a' of framework 52dc37bb-1f20-49d0-8aa5-314eb09037c6-0000
I1214 04:21:48.467527 20023 task_status_update_manager.cpp:507] Creating StatusUpdate stream for task 139ed374-1d91-4d68-bb4f-93e505e5335a of framework 52dc37bb-1f20-49d0-8aa5-314eb09037c6-0000
I1214 04:21:48.468334 20023 task_status_update_manager.cpp:818] Replaying task status update stream for task 139ed374-1d91-4d68-bb4f-93e505e5335a
I1214 04:21:48.469755 19998 containerizer.cpp:727] Recovering Mesos containers
I1214 04:21:48.469995 19998 containerizer.cpp:784] Recovering container 4d366ff3-e202-449a-8640-97b2bd13e5e7 for executor '139ed374-1d91-4d68-bb4f-93e505e5335a' of framework 52dc37bb-1f20-49d0-8aa5-314eb09037c6-0000
I1214 04:21:48.474176 19998 containerizer.cpp:1053] Recovering isolators
I1214 04:21:48.476382 19998 containerizer.cpp:1092] Recovering provisioner
I1214 04:21:48.477511 20004 provisioner.cpp:494] Provisioner recovery complete
I1214 04:21:48.480697 19997 composing.cpp:339] Finished recovering all containerizers
I1214 04:21:48.480959 19990 slave.cpp:7221] Recovering executors
I1214 04:21:48.481189 19990 slave.cpp:7245] Sending reconnect request to executor '139ed374-1d91-4d68-bb4f-93e505e5335a' of framework 52dc37bb-1f20-49d0-8aa5-314eb09037c6-0000 at executor(1)@172.17.0.2:37461
I1214 04:21:48.484555 29768 exec.cpp:282] Received reconnect request from agent 52dc37bb-1f20-49d0-8aa5-314eb09037c6-S0
I1214 04:21:48.491564 19986 slave.cpp:5001] Received re-registration message from executor '139ed374-1d91-4d68-bb4f-93e505e5335a' of framework 52dc37bb-1f20-49d0-8aa5-314eb09037c6-0000
I1214 04:21:48.493894 29766 exec.cpp:259] Executor reregistered on agent 52dc37bb-1f20-49d0-8aa5-314eb09037c6-S0
I1214 04:21:48.494300 20003 slave.cpp:5966] No pings from master received within 75secs
I1214 04:21:48.494333 19994 hierarchical.cpp:1566] Performed allocation for 1 agents in 311962ns
F1214 04:21:48.495267 20021 slave.cpp:1240] Check failed: state == DISCONNECTED || state == RUNNING || state == TERMINATING RECOVERING
*** Check failure stack trace: ***
I1214 04:21:48.496450 29771 executor.cpp:184] Received SUBSCRIBED event
I1214 04:21:48.496515 29771 executor.cpp:188] Subscribed executor on a21943ae6967
@ 0x2ab9866d4628 google::LogMessage::Fail()
@ 0x2ab9866d4574 google::LogMessage::SendToLog()
@ 0x2ab9866d3f76 google::LogMessage::Flush()
@ 0x2ab9866d6e8a google::LogMessageFatal::~LogMessageFatal()
@ 0x2ab9852d9590 mesos::internal::slave::Slave::detected()
@ 0x2ab98536f55c _ZZN7process8dispatchIN5mesos8internal5slave5SlaveERKNS_6FutureI6OptionINS1_10MasterInfoEEEESB_EEvRKNS_3PIDIT_EEMSD_FvT0_EOT1_ENKUlOS9_PNS_11ProcessBaseEE_clESM_SO_
@ 0x2ab985419297 _ZN5cpp176invokeIZN7process8dispatchIN5mesos8internal5slave5SlaveERKNS1_6FutureI6OptionINS3_10MasterInfoEEEESD_EEvRKNS1_3PIDIT_EEMSF_FvT0_EOT1_EUlOSB_PNS1_11ProcessBaseEE_ISB_SQ_EEEDTclcl7forwardISF_Efp_Espcl7forwardIT0_Efp0_EEEOSF_DpOSS_
@ 0x2ab9854101a7 _ZN6lambda8internal7PartialIZN7process8dispatchIN5mesos8internal5slave5SlaveERKNS2_6FutureI6OptionINS4_10MasterInfoEEEESE_EEvRKNS2_3PIDIT_EEMSG_FvT0_EOT1_EUlOSC_PNS2_11ProcessBaseEE_ISC_St12_PlaceholderILi1EEEE13invoke_expandISS_St5tupleIISC_SU_EESX_IIOSR_EEILm0ELm1EEEEDTcl6invokecl7forwardIT_Efp_Espcl6expandcl3getIXT2_EEcl7forwardIT0_Efp0_EEcl7forwardIT1_Efp2_EEEEOS11_OS12_N5cpp1416integer_sequenceImIXspT2_EEEEOS13_
@ 0x2ab98540a17b _ZNO6lambda8internal7PartialIZN7process8dispatchIN5mesos8internal5slave5SlaveERKNS2_6FutureI6OptionINS4_10MasterInfoEEEESE_EEvRKNS2_3PIDIT_EEMSG_FvT0_EOT1_EUlOSC_PNS2_11ProcessBaseEE_JSC_St12_PlaceholderILi1EEEEclIJSR_EEEDTcl13invoke_expandcl4movedtdefpT1fEcl4movedtdefpT10bound_argsEcvN5cpp1416integer_sequenceImJLm0ELm1EEEE_Ecl16forward_as_tuplespcl7forwardIT_Efp_EEEEDpOS10_
@ 0x2ab985406fb5 _ZN5cpp176invokeIN6lambda8internal7PartialIZN7process8dispatchIN5mesos8internal5slave5SlaveERKNS4_6FutureI6OptionINS6_10MasterInfoEEEESG_EEvRKNS4_3PIDIT_EEMSI_FvT0_EOT1_EUlOSE_PNS4_11ProcessBaseEE_ISE_St12_PlaceholderILi1EEEEEIST_EEEDTclcl7forwardISI_Efp_Espcl7forwardIT0_Efp0_EEEOSI_DpOSY_
@ 0x2ab985405281 _ZN6lambda8internal6InvokeIvEclINS0_7PartialIZN7process8dispatchIN5mesos8internal5slave5SlaveERKNS5_6FutureI6OptionINS7_10MasterInfoEEEESH_EEvRKNS5_3PIDIT_EEMSJ_FvT0_EOT1_EUlOSF_PNS5_11ProcessBaseEE_JSF_St12_PlaceholderILi1EEEEEJSU_EEEvOSJ_DpOT0_
@ 0x2ab9854030ef _ZNO6lambda12CallableOnceIFvPN7process11ProcessBaseEEE10CallableFnINS_8internal7PartialIZNS1_8dispatchIN5mesos8internal5slave5SlaveERKNS1_6FutureI6OptionINSA_10MasterInfoEEEESK_EEvRKNS1_3PIDIT_EEMSM_FvT0_EOT1_EUlOSI_S3_E_ISI_St12_PlaceholderILi1EEEEEEclEOS3_
@ 0x2ab9865f9133 _ZNO6lambda12CallableOnceIFvPN7process11ProcessBaseEEEclES3_
@ 0x2ab9865c2b63 process::ProcessBase::consume()
@ 0x2ab9865e6e86 _ZNO7process13DispatchEvent7consumeEPNS_13EventConsumerE
@ 0x56439a7ed828 process::ProcessBase::serve()
@ 0x2ab9865bfe40 process::ProcessManager::resume()
@ 0x2ab9865bc2ba _ZZN7process14ProcessManager12init_threadsEvENKUlvE_clEv
@ 0x2ab9865e1cd2 _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEE9_M_invokeIIEEEvSt12_Index_tupleIIXspT_EEE
@ 0x2ab9865e0ff3 _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEEclEv
@ 0x2ab9865e01b2 _ZNSt6thread5_ImplISt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEEE6_M_runEv
@ 0x2ab989497a60 (unknown)
@ 0x2ab989c0e184 start_thread
@ 0x2ab989f2203d (unknown)
I1214 04:21:49.288971 29761 exec.cpp:518] Agent exited, but framework has checkpointing enabled. Waiting 15mins to reconnect with agent 52dc37bb-1f20-49d0-8aa5-314eb09037c6-S0
I1214 04:36:49.290410 29762 exec.cpp:499] Recovery timeout of 15mins exceeded; Shutting down
I1214 04:36:49.290647 29762 exec.cpp:445] Executor asked to shutdown
I1214 04:36:49.291764 29765 executor.cpp:184] Received SHUTDOWN event
I1214 04:36:49.291882 29765 executor.cpp:805] Shutting down
I1214 04:36:49.291982 29765 executor.cpp:918] Sending SIGTERM to process tree at pid 29781
I1214 04:36:49.309928 29765 executor.cpp:931] Sent SIGTERM to the following process trees:
[
-+- 29781 sh -c sleep 1000
\--- 29823 sleep 1000
]
I1214 04:36:49.309962 29765 executor.cpp:935] Scheduling escalation to SIGKILL in 3secs from now
I1214 04:36:49.407408 29768 executor.cpp:1003] Command terminated with signal Terminated (pid: 29781)
W1214 04:36:49.416296 29774 process.cpp:1890] Failed to send 'mesos.internal.StatusUpdateMessage' to '172.17.0.2:38996', connect: Failed to connect to 172.17.0.2:38996: Connection refused
I1214 04:36:50.414160 29774 process.cpp:927] Stopped the socket accept loop
[FAIL]: 1 shard(s) have failed tests
make[4]: *** [check-local] Error 1
make[4]: Leaving directory `/mesos/mesos-1.8.0/_build/src'
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/mesos/mesos-1.8.0/_build/src'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/mesos/mesos-1.8.0/_build/src'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-1.8.0/_build'
make: *** [distcheck] Error 1
+ docker rmi --force mesos-1544756494-7162
Untagged: mesos-1544756494-7162:latest
Deleted: sha256:8d988cce90a32c76d238e59ab4a702c25370432060df89ba7a80b0cf1ef8cfd1
Deleted: sha256:c5ecd0d4d79e828e01bee339174f32958d6ab93cda5ef2b28832b8c12f2da657
Deleted: sha256:7ddb870faef6d9ab6eb36db93a6671e47e10f7ca2577c9e67fc1f80a92995f96
Deleted: sha256:a0743b0ec571d3e420156ecfc8314f1d02059ee7d2417a104d34dd1b44a77325
Deleted: sha256:9687694f0509e7ea75a43beedc4027266c4db9e5fd246f831eccdbc0b11c47c8
Deleted: sha256:93fdc13e1e4f55f3087e4e7a410157ae1ddab335708aecbd0c90aaf65158c3a1
Deleted: sha256:514a454e80fc6cde0363c71510d6fc9449c6b82cab94967ca5f3fe6affe771ce
Deleted: sha256:d60f02c2add8e7273d4f5c6baec330104b5d75b37f34268eccc91cc89f1801f7
Deleted: sha256:6d0d5bf2d341b972c2f5a76e8587eb02bb683f39440a19b48b05107124797ae0
Deleted: sha256:f358a1b2ce0153bafbad98cb93ae8827f2ebb597d2574d7d5c68e6d770510994
Deleted: sha256:4b6744b2f5673f3c0e9eb124aafa890ca52808f6c9e346be095c650b47c10eff
Deleted: sha256:7be570a76b29265b692c6ea1ab58985692e703d3dd9fe352e63def68e8a890be
Deleted: sha256:f1349884795aebea2b9f12e255ae6f877a466cf4b304042f9667cd2cb7cdb490
Deleted: sha256:bb21bab50452282565e260a66f1fec5452da19a4f32b308e6cd31c690f78c0a5
Deleted: sha256:ae299ba64d1039b9bac49a1007e5fc4375e182549b7bccb500a7ef17d9866612
Deleted: sha256:78e8c9b4ad647595eabcf3dfd21247b64cf0dd803b4aaab21b1a3e8fe04b0c85
Deleted: sha256:60b6ee6201da19ae935509bc0cb551544cbe47d0a59e0c929ff81b97591e1d57
Deleted: sha256:8e86e347dc985a4fa959bbb3eb9ad81c3a17c4d932ac2e6cedab9aff598f3940
Deleted: sha256:be3cea2106152f85336a061fe66162645307cb5c1b8fd7dcc09a4b433cc0b6a7
Deleted: sha256:202529ecf9d5e94c26d893a7495c6b9d8485819fef920a188c6425de706a95cf
Deleted: sha256:3b86c2ca2956a8b3279697bc1f2a20f5fd3cc0a7e9b81e6d7229998ee96ff3d0
Deleted: sha256:7ef6305e449f6a43d50bf43c0af8d7bcbfe17596073cd4e9225c92583e131c10
Deleted: sha256:f0ed5073e275b5a14fd33f987092ad903cac89b2b0c4c2982aacf9b490e22a52
Deleted: sha256:1a3e967d037a232a682659f21c3f96fec37d7a6b0d4f0a0bb77a07f9716507c6
Deleted: sha256:2c1e1f8d976c297f4a3cb2acdee0cc80513d11a68dfbb3b8eed10ed5148cd550
Deleted: sha256:733a13e7a384392a3ad8a9dede0ad0a1ce466c45b250ba39a61010f553b3c914
Deleted: sha256:a55dd712a599e1771387f13022c50dd58b1722eb0e7d5adb3d03c0f674d2d61f
Deleted: sha256:4a001a3f15484fd8a4cb213f45ff532289621eaad59b0abc5a0128f9e9e6fe27
Deleted: sha256:e179e87a33c0bd81cd8355f468eaabe814498ac06f9d28cf882c98bae975cd96
Deleted: sha256:c4bd5bbb71117b9de46ae077622d28bf3818a01eb8eeb172de02edf100db563c
12-14-18_03:01:32 - Running <https://builds.apache.org/job/Mesos-Reviewbot/ws/support/verify-reviews.py>
Checking if review 69559 needs verification
Patch never verified, needs verification
Verifying review 69559
Applying review 69559
/usr/bin/python3 support/apply-reviews.py -n -r 69559
Traceback (most recent call last):
File "<https://builds.apache.org/job/Mesos-Reviewbot/ws/support/verify-reviews.py",> line 186, in verify_review
File "/usr/lib/python3.5/subprocess.py", line 581, in check_call
raise CalledProcessError(retcode, cmd)
subprocess.CalledProcessError: Command '['bash', '-c', "set -o pipefail; export OS='ubuntu:14.04' BUILDTOOL='autotools' COMPILER='gcc' CONFIGURATION='--verbose --disable-libtool-wrappers' ENVIRONMENT='GLOG_v=1 MESOS_VERBOSE=1'; ./support/docker-build.sh 2>&1 | tee build_69559"]' returned non-zero exit status 2
During handling of the above exception, another exception occurred:
Traceback (most recent call last):
File "<https://builds.apache.org/job/Mesos-Reviewbot/ws/support/verify-reviews.py",> line 294, in <module>
File "<https://builds.apache.org/job/Mesos-Reviewbot/ws/support/verify-reviews.py",> line 288, in main
for review in reversed(reviews["reviews"]):
File "<https://builds.apache.org/job/Mesos-Reviewbot/ws/support/verify-reviews.py",> line 201, in verify_review
configuration = ("export "
AttributeError: 'str' object has no attribute 'decode'
Build step 'Execute shell' marked build as failure