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/11/26 17:53:23 UTC
Build failed in Jenkins: Mesos-Reviewbot #23577
See <https://builds.apache.org/job/Mesos-Reviewbot/23577/display/redirect?page=changes>
Changes:
[klueska] Fixed name of task created when running mesos-cli-tests.
[klueska] Replaced CLI test helper function 'running_tasks' by 'wait_for_task'.
[klueska] Added '--all' flag to 'mesos task list'.
------------------------------------------
[...truncated 43.67 MB...]
I1126 17:38:09.202162 19558 slave.cpp:1451] Successfully authenticated with master master@172.17.0.2:45630
I1126 17:38:09.202486 19558 slave.cpp:1882] Will retry registration in 16.347347ms if necessary
I1126 17:38:09.202668 19556 master.cpp:6590] Received register agent message from slave@172.17.0.2:45630 (08f498d6e483)
I1126 17:38:09.203056 19556 master.cpp:3930] Authorizing agent providing resources 'cpus:2; mem:1024; disk:1024; ports:[31000-32000]' with principal 'test-principal'
I1126 17:38:09.203696 19545 master.cpp:6657] Authorized registration of agent at slave@172.17.0.2:45630 (08f498d6e483)
I1126 17:38:09.203799 19545 master.cpp:6772] Registering agent at slave@172.17.0.2:45630 (08f498d6e483) with id 6e587902-7782-45ea-9f8c-d76cc4e4b253-S0
I1126 17:38:09.204398 19537 registrar.cpp:487] Applied 1 operations in 162589ns; attempting to update the registry
I1126 17:38:09.204988 19537 registrar.cpp:544] Successfully updated the registry in 526848ns
I1126 17:38:09.205152 19541 master.cpp:6820] Admitted agent 6e587902-7782-45ea-9f8c-d76cc4e4b253-S0 at slave@172.17.0.2:45630 (08f498d6e483)
I1126 17:38:09.205832 19541 master.cpp:6865] Registered agent 6e587902-7782-45ea-9f8c-d76cc4e4b253-S0 at slave@172.17.0.2:45630 (08f498d6e483) with cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I1126 17:38:09.205911 19548 slave.cpp:1484] Registered with master master@172.17.0.2:45630; given agent ID 6e587902-7782-45ea-9f8c-d76cc4e4b253-S0
I1126 17:38:09.206317 19548 slave.cpp:1504] Checkpointing SlaveInfo to '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_x3RPaL/meta/slaves/6e587902-7782-45ea-9f8c-d76cc4e4b253-S0/slave.info'
I1126 17:38:09.206400 19546 hierarchical.cpp:603] Added agent 6e587902-7782-45ea-9f8c-d76cc4e4b253-S0 (08f498d6e483) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] (allocated: {})
I1126 17:38:09.207123 19540 task_status_update_manager.cpp:188] Resuming sending task status updates
I1126 17:38:09.207501 19548 slave.cpp:1553] Forwarding agent update {"operations":{},"resource_version_uuid":{"value":"AE8x4GaZTcWd0BXUxsp20g=="},"slave_id":{"value":"6e587902-7782-45ea-9f8c-d76cc4e4b253-S0"},"update_oversubscribed_resources":false}
I1126 17:38:09.208034 19546 hierarchical.cpp:1566] Performed allocation for 1 agents in 1.44127ms
I1126 17:38:09.208422 19548 master.cpp:7924] Ignoring update on agent 6e587902-7782-45ea-9f8c-d76cc4e4b253-S0 at slave@172.17.0.2:45630 (08f498d6e483) as it reports no changes
I1126 17:38:09.208889 19548 master.cpp:9454] Sending offers [ 6e587902-7782-45ea-9f8c-d76cc4e4b253-O0 ] to framework 6e587902-7782-45ea-9f8c-d76cc4e4b253-0000 (default) at scheduler-73f9b1fa-8cc1-494f-b0bd-3bdc2befe20f@172.17.0.2:45630
I1126 17:38:09.209499 19548 sched.cpp:914] Scheduler::resourceOffers took 195044ns
I1126 17:38:09.211189 19560 master.cpp:11453] Removing offer 6e587902-7782-45ea-9f8c-d76cc4e4b253-O0
I1126 17:38:09.211500 19560 master.cpp:4451] Processing ACCEPT call for offers: [ 6e587902-7782-45ea-9f8c-d76cc4e4b253-O0 ] on agent 6e587902-7782-45ea-9f8c-d76cc4e4b253-S0 at slave@172.17.0.2:45630 (08f498d6e483) for framework 6e587902-7782-45ea-9f8c-d76cc4e4b253-0000 (default) at scheduler-73f9b1fa-8cc1-494f-b0bd-3bdc2befe20f@172.17.0.2:45630
I1126 17:38:09.211598 19560 master.cpp:3547] Authorizing framework principal 'test-principal' to launch task 48817f74-c4c5-496d-96ab-84109559d8ad
I1126 17:38:09.213433 19539 master.cpp:4028] Adding task 48817f74-c4c5-496d-96ab-84109559d8ad with resources cpus(allocated: *):2; mem(allocated: *):1024; disk(allocated: *):1024; ports(allocated: *):[31000-32000] of framework 6e587902-7782-45ea-9f8c-d76cc4e4b253-0000 (default) at scheduler-73f9b1fa-8cc1-494f-b0bd-3bdc2befe20f@172.17.0.2:45630 on agent 6e587902-7782-45ea-9f8c-d76cc4e4b253-S0 at slave@172.17.0.2:45630 (08f498d6e483)
I1126 17:38:09.214007 19539 master.cpp:5423] Launching task 48817f74-c4c5-496d-96ab-84109559d8ad of framework 6e587902-7782-45ea-9f8c-d76cc4e4b253-0000 (default) at scheduler-73f9b1fa-8cc1-494f-b0bd-3bdc2befe20f@172.17.0.2:45630 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 6e587902-7782-45ea-9f8c-d76cc4e4b253-S0 at slave@172.17.0.2:45630 (08f498d6e483) on new executor
I1126 17:38:09.215215 19541 slave.cpp:2019] Got assigned task '48817f74-c4c5-496d-96ab-84109559d8ad' for framework 6e587902-7782-45ea-9f8c-d76cc4e4b253-0000
I1126 17:38:09.215394 19541 slave.cpp:8895] Checkpointing FrameworkInfo to '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_x3RPaL/meta/slaves/6e587902-7782-45ea-9f8c-d76cc4e4b253-S0/frameworks/6e587902-7782-45ea-9f8c-d76cc4e4b253-0000/framework.info'
I1126 17:38:09.215848 19541 slave.cpp:8906] Checkpointing framework pid 'scheduler-73f9b1fa-8cc1-494f-b0bd-3bdc2befe20f@172.17.0.2:45630' to '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_x3RPaL/meta/slaves/6e587902-7782-45ea-9f8c-d76cc4e4b253-S0/frameworks/6e587902-7782-45ea-9f8c-d76cc4e4b253-0000/framework.pid'
I1126 17:38:09.217206 19541 slave.cpp:2393] Authorizing task '48817f74-c4c5-496d-96ab-84109559d8ad' for framework 6e587902-7782-45ea-9f8c-d76cc4e4b253-0000
I1126 17:38:09.217267 19541 slave.cpp:8471] Authorizing framework principal 'test-principal' to launch task 48817f74-c4c5-496d-96ab-84109559d8ad
I1126 17:38:09.219171 19541 slave.cpp:2836] Launching task '48817f74-c4c5-496d-96ab-84109559d8ad' for framework 6e587902-7782-45ea-9f8c-d76cc4e4b253-0000
I1126 17:38:09.219252 19541 paths.cpp:752] Creating sandbox '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_x3RPaL/slaves/6e587902-7782-45ea-9f8c-d76cc4e4b253-S0/frameworks/6e587902-7782-45ea-9f8c-d76cc4e4b253-0000/executors/48817f74-c4c5-496d-96ab-84109559d8ad/runs/0a848099-9e5e-45c8-b611-4b713ade3c77' for user 'mesos'
I1126 17:38:09.219945 19541 slave.cpp:9681] Checkpointing ExecutorInfo to '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_x3RPaL/meta/slaves/6e587902-7782-45ea-9f8c-d76cc4e4b253-S0/frameworks/6e587902-7782-45ea-9f8c-d76cc4e4b253-0000/executors/48817f74-c4c5-496d-96ab-84109559d8ad/executor.info'
I1126 17:38:09.220501 19541 paths.cpp:755] Creating sandbox '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_x3RPaL/meta/slaves/6e587902-7782-45ea-9f8c-d76cc4e4b253-S0/frameworks/6e587902-7782-45ea-9f8c-d76cc4e4b253-0000/executors/48817f74-c4c5-496d-96ab-84109559d8ad/runs/0a848099-9e5e-45c8-b611-4b713ade3c77'
I1126 17:38:09.220770 19541 slave.cpp:8981] Launching executor '48817f74-c4c5-496d-96ab-84109559d8ad' of framework 6e587902-7782-45ea-9f8c-d76cc4e4b253-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_x3RPaL/slaves/6e587902-7782-45ea-9f8c-d76cc4e4b253-S0/frameworks/6e587902-7782-45ea-9f8c-d76cc4e4b253-0000/executors/48817f74-c4c5-496d-96ab-84109559d8ad/runs/0a848099-9e5e-45c8-b611-4b713ade3c77'
I1126 17:38:09.221500 19541 slave.cpp:3514] Launching container 0a848099-9e5e-45c8-b611-4b713ade3c77 for executor '48817f74-c4c5-496d-96ab-84109559d8ad' of framework 6e587902-7782-45ea-9f8c-d76cc4e4b253-0000
I1126 17:38:09.222020 19541 slave.cpp:9712] Checkpointing TaskInfo to '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_x3RPaL/meta/slaves/6e587902-7782-45ea-9f8c-d76cc4e4b253-S0/frameworks/6e587902-7782-45ea-9f8c-d76cc4e4b253-0000/executors/48817f74-c4c5-496d-96ab-84109559d8ad/runs/0a848099-9e5e-45c8-b611-4b713ade3c77/tasks/48817f74-c4c5-496d-96ab-84109559d8ad/task.info'
I1126 17:38:09.222689 19541 slave.cpp:3033] Queued task '48817f74-c4c5-496d-96ab-84109559d8ad' for executor '48817f74-c4c5-496d-96ab-84109559d8ad' of framework 6e587902-7782-45ea-9f8c-d76cc4e4b253-0000
I1126 17:38:09.222781 19541 slave.cpp:993] Successfully attached '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_x3RPaL/slaves/6e587902-7782-45ea-9f8c-d76cc4e4b253-S0/frameworks/6e587902-7782-45ea-9f8c-d76cc4e4b253-0000/executors/48817f74-c4c5-496d-96ab-84109559d8ad/runs/0a848099-9e5e-45c8-b611-4b713ade3c77' to virtual path '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_x3RPaL/slaves/6e587902-7782-45ea-9f8c-d76cc4e4b253-S0/frameworks/6e587902-7782-45ea-9f8c-d76cc4e4b253-0000/executors/48817f74-c4c5-496d-96ab-84109559d8ad/runs/latest'
I1126 17:38:09.222826 19541 slave.cpp:993] Successfully attached '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_x3RPaL/slaves/6e587902-7782-45ea-9f8c-d76cc4e4b253-S0/frameworks/6e587902-7782-45ea-9f8c-d76cc4e4b253-0000/executors/48817f74-c4c5-496d-96ab-84109559d8ad/runs/0a848099-9e5e-45c8-b611-4b713ade3c77' to virtual path '/frameworks/6e587902-7782-45ea-9f8c-d76cc4e4b253-0000/executors/48817f74-c4c5-496d-96ab-84109559d8ad/runs/latest'
I1126 17:38:09.222863 19541 slave.cpp:993] Successfully attached '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_x3RPaL/slaves/6e587902-7782-45ea-9f8c-d76cc4e4b253-S0/frameworks/6e587902-7782-45ea-9f8c-d76cc4e4b253-0000/executors/48817f74-c4c5-496d-96ab-84109559d8ad/runs/0a848099-9e5e-45c8-b611-4b713ade3c77' to virtual path '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_x3RPaL/slaves/6e587902-7782-45ea-9f8c-d76cc4e4b253-S0/frameworks/6e587902-7782-45ea-9f8c-d76cc4e4b253-0000/executors/48817f74-c4c5-496d-96ab-84109559d8ad/runs/0a848099-9e5e-45c8-b611-4b713ade3c77'
I1126 17:38:09.223482 19557 containerizer.cpp:1288] Starting container 0a848099-9e5e-45c8-b611-4b713ade3c77
I1126 17:38:09.224618 19557 containerizer.cpp:1454] Checkpointed ContainerConfig at '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_gYa0zz/containers/0a848099-9e5e-45c8-b611-4b713ade3c77/config'
I1126 17:38:09.224651 19557 containerizer.cpp:3130] Transitioning the state of container 0a848099-9e5e-45c8-b611-4b713ade3c77 from PROVISIONING to PREPARING
I1126 17:38:09.229061 19560 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:45630"},{"name":"MESOS_CHECKPOINT","type":"VALUE","value":"1"},{"name":"MESOS_DIRECTORY","type":"VALUE","value":"/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_x3RPaL/slaves/6e587902-7782-45ea-9f8c-d76cc4e4b253-S0/frameworks/6e587902-7782-45ea-9f8c-d76cc4e4b253-0000/executors/48817f74-c4c5-496d-96ab-84109559d8ad/runs/0a848099-9e5e-45c8-b611-4b713ade3c77"},{"name":"MESOS_EXECUTOR_ID","type":"VALUE","value":"48817f74-c4c5-496d-96ab-84109559d8ad"},{"name":"MESOS_EXECUTOR_SHUTDOWN_GRACE_PERIOD","type":"VALUE","value":"5secs"},{"name":"MESOS_FRAMEWORK_ID","type":"VALUE","value":"6e587902-7782-45ea-9f8c-d76cc4e4b253-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":"6e587902-7782-45ea-9f8c-d76cc4e4b253-S0"},{"name":"MESOS_SLAVE_PID","type":"VALUE","value":"slave@172.17.0.2:45630"},{"name":"MESOS_SUBSCRIPTION_BACKOFF_MAX","type":"VALUE","value":"15secs"},{"name":"MESOS_SANDBOX","type":"VALUE","value":"/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_x3RPaL/slaves/6e587902-7782-45ea-9f8c-d76cc4e4b253-S0/frameworks/6e587902-7782-45ea-9f8c-d76cc4e4b253-0000/executors/48817f74-c4c5-496d-96ab-84109559d8ad/runs/0a848099-9e5e-45c8-b611-4b713ade3c77"}]},"task_environment":{},"user":"mesos","working_directory":"/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_x3RPaL/slaves/6e587902-7782-45ea-9f8c-d76cc4e4b253-S0/frameworks/6e587902-7782-45ea-9f8c-d76cc4e4b253-0000/executors/48817f74-c4c5-496d-96ab-84109559d8ad/runs/0a848099-9e5e-45c8-b611-4b713ade3c77"}" --pipe_read="24" --pipe_write="27" --runtime_directory="/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_gYa0zz/containers/0a848099-9e5e-45c8-b611-4b713ade3c77" --unshare_namespace_mnt="false"'
I1126 17:38:09.234035 19560 launcher.cpp:145] Forked child with pid '27439' for container '0a848099-9e5e-45c8-b611-4b713ade3c77'
I1126 17:38:09.234172 19560 containerizer.cpp:2052] Checkpointing container's forked pid 27439 to '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_x3RPaL/meta/slaves/6e587902-7782-45ea-9f8c-d76cc4e4b253-S0/frameworks/6e587902-7782-45ea-9f8c-d76cc4e4b253-0000/executors/48817f74-c4c5-496d-96ab-84109559d8ad/runs/0a848099-9e5e-45c8-b611-4b713ade3c77/pids/forked.pid'
I1126 17:38:09.234905 19560 containerizer.cpp:3130] Transitioning the state of container 0a848099-9e5e-45c8-b611-4b713ade3c77 from PREPARING to ISOLATING
I1126 17:38:09.236534 19560 containerizer.cpp:3130] Transitioning the state of container 0a848099-9e5e-45c8-b611-4b713ade3c77 from ISOLATING to FETCHING
I1126 17:38:09.236760 19538 fetcher.cpp:369] Starting to fetch URIs for container: 0a848099-9e5e-45c8-b611-4b713ade3c77, directory: /tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_x3RPaL/slaves/6e587902-7782-45ea-9f8c-d76cc4e4b253-S0/frameworks/6e587902-7782-45ea-9f8c-d76cc4e4b253-0000/executors/48817f74-c4c5-496d-96ab-84109559d8ad/runs/0a848099-9e5e-45c8-b611-4b713ade3c77
I1126 17:38:09.237850 19556 containerizer.cpp:3130] Transitioning the state of container 0a848099-9e5e-45c8-b611-4b713ade3c77 from FETCHING to RUNNING
I1126 17:38:09.685204 27487 exec.cpp:162] Version: 1.8.0
I1126 17:38:09.699491 19549 slave.cpp:4808] Got registration for executor '48817f74-c4c5-496d-96ab-84109559d8ad' of framework 6e587902-7782-45ea-9f8c-d76cc4e4b253-0000 from executor(1)@172.17.0.2:36922
I1126 17:38:09.700101 19549 slave.cpp:4894] Checkpointing executor pid 'executor(1)@172.17.0.2:36922' to '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_x3RPaL/meta/slaves/6e587902-7782-45ea-9f8c-d76cc4e4b253-S0/frameworks/6e587902-7782-45ea-9f8c-d76cc4e4b253-0000/executors/48817f74-c4c5-496d-96ab-84109559d8ad/runs/0a848099-9e5e-45c8-b611-4b713ade3c77/pids/libprocess.pid'
I1126 17:38:09.703173 19555 slave.cpp:3246] Sending queued task '48817f74-c4c5-496d-96ab-84109559d8ad' to executor '48817f74-c4c5-496d-96ab-84109559d8ad' of framework 6e587902-7782-45ea-9f8c-d76cc4e4b253-0000 at executor(1)@172.17.0.2:36922
I1126 17:38:09.703991 27495 exec.cpp:236] Executor registered on agent 6e587902-7782-45ea-9f8c-d76cc4e4b253-S0
I1126 17:38:09.707969 27475 executor.cpp:184] Received SUBSCRIBED event
I1126 17:38:09.709615 27475 executor.cpp:188] Subscribed executor on 08f498d6e483
I1126 17:38:09.709812 27475 executor.cpp:184] Received LAUNCH event
I1126 17:38:09.711877 27475 executor.cpp:687] Starting task 48817f74-c4c5-496d-96ab-84109559d8ad
I1126 17:38:09.715605 19551 slave.cpp:5274] Handling status update TASK_STARTING (Status UUID: 0569d220-68cb-45e1-beae-2c2cd7319ed4) for task 48817f74-c4c5-496d-96ab-84109559d8ad of framework 6e587902-7782-45ea-9f8c-d76cc4e4b253-0000 from executor(1)@172.17.0.2:36922
I1126 17:38:09.718160 19548 task_status_update_manager.cpp:328] Received task status update TASK_STARTING (Status UUID: 0569d220-68cb-45e1-beae-2c2cd7319ed4) for task 48817f74-c4c5-496d-96ab-84109559d8ad of framework 6e587902-7782-45ea-9f8c-d76cc4e4b253-0000
I1126 17:38:09.718236 19548 task_status_update_manager.cpp:507] Creating StatusUpdate stream for task 48817f74-c4c5-496d-96ab-84109559d8ad of framework 6e587902-7782-45ea-9f8c-d76cc4e4b253-0000
I1126 17:38:09.719111 19548 task_status_update_manager.cpp:842] Checkpointing UPDATE for task status update TASK_STARTING (Status UUID: 0569d220-68cb-45e1-beae-2c2cd7319ed4) for task 48817f74-c4c5-496d-96ab-84109559d8ad of framework 6e587902-7782-45ea-9f8c-d76cc4e4b253-0000
I1126 17:38:09.719368 19548 task_status_update_manager.cpp:383] Forwarding task status update TASK_STARTING (Status UUID: 0569d220-68cb-45e1-beae-2c2cd7319ed4) for task 48817f74-c4c5-496d-96ab-84109559d8ad of framework 6e587902-7782-45ea-9f8c-d76cc4e4b253-0000 to the agent
I1126 17:38:09.719558 19560 slave.cpp:5766] Forwarding the update TASK_STARTING (Status UUID: 0569d220-68cb-45e1-beae-2c2cd7319ed4) for task 48817f74-c4c5-496d-96ab-84109559d8ad of framework 6e587902-7782-45ea-9f8c-d76cc4e4b253-0000 to master@172.17.0.2:45630
I1126 17:38:09.719799 19560 slave.cpp:5659] Task status update manager successfully handled status update TASK_STARTING (Status UUID: 0569d220-68cb-45e1-beae-2c2cd7319ed4) for task 48817f74-c4c5-496d-96ab-84109559d8ad of framework 6e587902-7782-45ea-9f8c-d76cc4e4b253-0000
I1126 17:38:09.719852 19560 slave.cpp:5675] Sending acknowledgement for status update TASK_STARTING (Status UUID: 0569d220-68cb-45e1-beae-2c2cd7319ed4) for task 48817f74-c4c5-496d-96ab-84109559d8ad of framework 6e587902-7782-45ea-9f8c-d76cc4e4b253-0000 to executor(1)@172.17.0.2:36922
I1126 17:38:09.720011 19545 master.cpp:8360] Status update TASK_STARTING (Status UUID: 0569d220-68cb-45e1-beae-2c2cd7319ed4) for task 48817f74-c4c5-496d-96ab-84109559d8ad of framework 6e587902-7782-45ea-9f8c-d76cc4e4b253-0000 from agent 6e587902-7782-45ea-9f8c-d76cc4e4b253-S0 at slave@172.17.0.2:45630 (08f498d6e483)
I1126 17:38:09.720093 19545 master.cpp:8417] Forwarding status update TASK_STARTING (Status UUID: 0569d220-68cb-45e1-beae-2c2cd7319ed4) for task 48817f74-c4c5-496d-96ab-84109559d8ad of framework 6e587902-7782-45ea-9f8c-d76cc4e4b253-0000
I1126 17:38:09.720413 19545 master.cpp:10918] Updating the state of task 48817f74-c4c5-496d-96ab-84109559d8ad of framework 6e587902-7782-45ea-9f8c-d76cc4e4b253-0000 (latest state: TASK_STARTING, status update state: TASK_STARTING)
I1126 17:38:09.720753 19557 sched.cpp:1022] Scheduler::statusUpdate took 169119ns
I1126 17:38:09.721577 19555 master.cpp:6226] Processing ACKNOWLEDGE call for status 0569d220-68cb-45e1-beae-2c2cd7319ed4 for task 48817f74-c4c5-496d-96ab-84109559d8ad of framework 6e587902-7782-45ea-9f8c-d76cc4e4b253-0000 (default) at scheduler-73f9b1fa-8cc1-494f-b0bd-3bdc2befe20f@172.17.0.2:45630 on agent 6e587902-7782-45ea-9f8c-d76cc4e4b253-S0
I1126 17:38:09.721983 19539 task_status_update_manager.cpp:401] Received task status update acknowledgement (UUID: 0569d220-68cb-45e1-beae-2c2cd7319ed4) for task 48817f74-c4c5-496d-96ab-84109559d8ad of framework 6e587902-7782-45ea-9f8c-d76cc4e4b253-0000
I1126 17:38:09.722100 19539 task_status_update_manager.cpp:842] Checkpointing ACK for task status update TASK_STARTING (Status UUID: 0569d220-68cb-45e1-beae-2c2cd7319ed4) for task 48817f74-c4c5-496d-96ab-84109559d8ad of framework 6e587902-7782-45ea-9f8c-d76cc4e4b253-0000
I1126 17:38:09.722365 19559 slave.cpp:4510] Task status update manager successfully handled status update acknowledgement (UUID: 0569d220-68cb-45e1-beae-2c2cd7319ed4) for task 48817f74-c4c5-496d-96ab-84109559d8ad of framework 6e587902-7782-45ea-9f8c-d76cc4e4b253-0000
I1126 17:38:09.733567 27475 executor.cpp:502] Running '/mesos/mesos-1.8.0/_build/src/mesos-containerizer launch <POSSIBLY-SENSITIVE-DATA>'
I1126 17:38:09.737406 27475 executor.cpp:702] Forked command at 27502
I1126 17:38:09.742070 19537 slave.cpp:5274] Handling status update TASK_RUNNING (Status UUID: 079a60bd-845c-495f-9246-1aa400c7dff6) for task 48817f74-c4c5-496d-96ab-84109559d8ad of framework 6e587902-7782-45ea-9f8c-d76cc4e4b253-0000 from executor(1)@172.17.0.2:36922
I1126 17:38:09.744366 19558 task_status_update_manager.cpp:328] Received task status update TASK_RUNNING (Status UUID: 079a60bd-845c-495f-9246-1aa400c7dff6) for task 48817f74-c4c5-496d-96ab-84109559d8ad of framework 6e587902-7782-45ea-9f8c-d76cc4e4b253-0000
I1126 17:38:09.744478 19558 task_status_update_manager.cpp:842] Checkpointing UPDATE for task status update TASK_RUNNING (Status UUID: 079a60bd-845c-495f-9246-1aa400c7dff6) for task 48817f74-c4c5-496d-96ab-84109559d8ad of framework 6e587902-7782-45ea-9f8c-d76cc4e4b253-0000
I1126 17:38:09.744732 19558 task_status_update_manager.cpp:383] Forwarding task status update TASK_RUNNING (Status UUID: 079a60bd-845c-495f-9246-1aa400c7dff6) for task 48817f74-c4c5-496d-96ab-84109559d8ad of framework 6e587902-7782-45ea-9f8c-d76cc4e4b253-0000 to the agent
I1126 17:38:09.744954 19556 slave.cpp:5766] Forwarding the update TASK_RUNNING (Status UUID: 079a60bd-845c-495f-9246-1aa400c7dff6) for task 48817f74-c4c5-496d-96ab-84109559d8ad of framework 6e587902-7782-45ea-9f8c-d76cc4e4b253-0000 to master@172.17.0.2:45630
I1126 17:38:09.745164 19556 slave.cpp:5659] Task status update manager successfully handled status update TASK_RUNNING (Status UUID: 079a60bd-845c-495f-9246-1aa400c7dff6) for task 48817f74-c4c5-496d-96ab-84109559d8ad of framework 6e587902-7782-45ea-9f8c-d76cc4e4b253-0000
I1126 17:38:09.745213 19556 slave.cpp:5675] Sending acknowledgement for status update TASK_RUNNING (Status UUID: 079a60bd-845c-495f-9246-1aa400c7dff6) for task 48817f74-c4c5-496d-96ab-84109559d8ad of framework 6e587902-7782-45ea-9f8c-d76cc4e4b253-0000 to executor(1)@172.17.0.2:36922
I1126 17:38:09.745312 19551 master.cpp:8360] Status update TASK_RUNNING (Status UUID: 079a60bd-845c-495f-9246-1aa400c7dff6) for task 48817f74-c4c5-496d-96ab-84109559d8ad of framework 6e587902-7782-45ea-9f8c-d76cc4e4b253-0000 from agent 6e587902-7782-45ea-9f8c-d76cc4e4b253-S0 at slave@172.17.0.2:45630 (08f498d6e483)
I1126 17:38:09.745374 19551 master.cpp:8417] Forwarding status update TASK_RUNNING (Status UUID: 079a60bd-845c-495f-9246-1aa400c7dff6) for task 48817f74-c4c5-496d-96ab-84109559d8ad of framework 6e587902-7782-45ea-9f8c-d76cc4e4b253-0000
I1126 17:38:09.745631 19551 master.cpp:10918] Updating the state of task 48817f74-c4c5-496d-96ab-84109559d8ad of framework 6e587902-7782-45ea-9f8c-d76cc4e4b253-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING)
I1126 17:38:09.745885 19553 sched.cpp:1022] Scheduler::statusUpdate took 107368ns
I1126 17:38:09.746677 19554 master.cpp:6226] Processing ACKNOWLEDGE call for status 079a60bd-845c-495f-9246-1aa400c7dff6 for task 48817f74-c4c5-496d-96ab-84109559d8ad of framework 6e587902-7782-45ea-9f8c-d76cc4e4b253-0000 (default) at scheduler-73f9b1fa-8cc1-494f-b0bd-3bdc2befe20f@172.17.0.2:45630 on agent 6e587902-7782-45ea-9f8c-d76cc4e4b253-S0
I1126 17:38:09.747068 19538 task_status_update_manager.cpp:401] Received task status update acknowledgement (UUID: 079a60bd-845c-495f-9246-1aa400c7dff6) for task 48817f74-c4c5-496d-96ab-84109559d8ad of framework 6e587902-7782-45ea-9f8c-d76cc4e4b253-0000
I1126 17:38:09.747200 19538 task_status_update_manager.cpp:842] Checkpointing ACK for task status update TASK_RUNNING (Status UUID: 079a60bd-845c-495f-9246-1aa400c7dff6) for task 48817f74-c4c5-496d-96ab-84109559d8ad of framework 6e587902-7782-45ea-9f8c-d76cc4e4b253-0000
I1126 17:38:09.747510 19543 slave.cpp:4510] Task status update manager successfully handled status update acknowledgement (UUID: 079a60bd-845c-495f-9246-1aa400c7dff6) for task 48817f74-c4c5-496d-96ab-84109559d8ad of framework 6e587902-7782-45ea-9f8c-d76cc4e4b253-0000
I1126 17:38:09.747723 19548 slave.cpp:914] Agent terminating
I1126 17:38:10.171025 19539 hierarchical.cpp:1566] Performed allocation for 1 agents in 359499ns
I1126 17:38:10.468111 19545 master.cpp:1275] Agent 6e587902-7782-45ea-9f8c-d76cc4e4b253-S0 at slave@172.17.0.2:45630 (08f498d6e483) disconnected
I1126 17:38:10.468159 19545 master.cpp:3273] Disconnecting agent 6e587902-7782-45ea-9f8c-d76cc4e4b253-S0 at slave@172.17.0.2:45630 (08f498d6e483)
I1126 17:38:10.468230 19545 master.cpp:3292] Deactivating agent 6e587902-7782-45ea-9f8c-d76cc4e4b253-S0 at slave@172.17.0.2:45630 (08f498d6e483)
I1126 17:38:10.468351 19543 hierarchical.cpp:801] Agent 6e587902-7782-45ea-9f8c-d76cc4e4b253-S0 deactivated
I1126 17:38:10.468401 19284 containerizer.cpp:305] Using isolation { environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni }
W1126 17:38:10.468931 19284 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W1126 17:38:10.469117 19284 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I1126 17:38:10.469154 19284 provisioner.cpp:298] Using default backend 'copy'
W1126 17:38:10.476522 19284 process.cpp:2829] Attempted to spawn already running process files@172.17.0.2:45630
I1126 17:38:10.477071 19284 cluster.cpp:485] Creating default 'local' authorizer
I1126 17:38:10.478816 19548 slave.cpp:268] Mesos agent started on @172.17.0.2:45630
I1126 17:38:10.478842 19548 slave.cpp:269] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_gYa0zz/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_gYa0zz/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_gYa0zz/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_gYa0zz/fetch" --fetcher_cache_size="2GB" --fetcher_stall_timeout="1mins" --frameworks_home="/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_gYa0zz/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_gYa0zz/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_gYa0zz" --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_x3RPaL" --zk_session_timeout="10secs"
I1126 17:38:10.479396 19548 credentials.hpp:86] Loading credential for authentication from '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_gYa0zz/credential'
I1126 17:38:10.479590 19548 slave.cpp:301] Agent using credential for: test-principal
I1126 17:38:10.479612 19548 credentials.hpp:37] Loading credentials for authentication from '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_gYa0zz/http_credentials'
I1126 17:38:10.479833 19548 http.cpp:1017] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I1126 17:38:10.480242 19548 disk_profile_adaptor.cpp:80] Creating default disk profile adaptor module
I1126 17:38:10.481663 19548 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"}]
I1126 17:38:10.481873 19548 slave.cpp:624] Agent attributes: [ ]
I1126 17:38:10.481889 19548 slave.cpp:633] Agent hostname: 08f498d6e483
I1126 17:38:10.482030 19560 task_status_update_manager.cpp:181] Pausing sending task status updates
I1126 17:38:10.483624 19546 state.cpp:66] Recovering state from '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_x3RPaL/meta'
I1126 17:38:10.483700 19546 state.cpp:711] No committed checkpointed resources found at '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_x3RPaL/meta/resources/resources.info'
I1126 17:38:10.489049 19547 slave.cpp:6914] Finished recovering checkpointed state from '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_x3RPaL/meta', beginning agent recovery
I1126 17:38:10.489892 19547 slave.cpp:7393] Recovering framework 6e587902-7782-45ea-9f8c-d76cc4e4b253-0000
I1126 17:38:10.490105 19547 slave.cpp:9099] Recovering executor '48817f74-c4c5-496d-96ab-84109559d8ad' of framework 6e587902-7782-45ea-9f8c-d76cc4e4b253-0000
I1126 17:38:10.491319 19559 task_status_update_manager.cpp:207] Recovering task status update manager
I1126 17:38:10.491365 19559 task_status_update_manager.cpp:215] Recovering executor '48817f74-c4c5-496d-96ab-84109559d8ad' of framework 6e587902-7782-45ea-9f8c-d76cc4e4b253-0000
I1126 17:38:10.491461 19559 task_status_update_manager.cpp:507] Creating StatusUpdate stream for task 48817f74-c4c5-496d-96ab-84109559d8ad of framework 6e587902-7782-45ea-9f8c-d76cc4e4b253-0000
I1126 17:38:10.492060 19559 task_status_update_manager.cpp:818] Replaying task status update stream for task 48817f74-c4c5-496d-96ab-84109559d8ad
I1126 17:38:10.492828 19542 containerizer.cpp:727] Recovering Mesos containers
I1126 17:38:10.492939 19542 containerizer.cpp:784] Recovering container 0a848099-9e5e-45c8-b611-4b713ade3c77 for executor '48817f74-c4c5-496d-96ab-84109559d8ad' of framework 6e587902-7782-45ea-9f8c-d76cc4e4b253-0000
I1126 17:38:10.494854 19542 containerizer.cpp:1053] Recovering isolators
I1126 17:38:10.495826 19541 containerizer.cpp:1092] Recovering provisioner
I1126 17:38:10.496587 19553 provisioner.cpp:494] Provisioner recovery complete
I1126 17:38:10.498312 19552 composing.cpp:339] Finished recovering all containerizers
I1126 17:38:10.498455 19557 slave.cpp:7143] Recovering executors
I1126 17:38:10.498615 19557 slave.cpp:7167] Sending reconnect request to executor '48817f74-c4c5-496d-96ab-84109559d8ad' of framework 6e587902-7782-45ea-9f8c-d76cc4e4b253-0000 at executor(1)@172.17.0.2:36922
I1126 17:38:10.500504 27494 exec.cpp:282] Received reconnect request from agent 6e587902-7782-45ea-9f8c-d76cc4e4b253-S0
I1126 17:38:10.503602 19558 slave.cpp:4971] Received re-registration message from executor '48817f74-c4c5-496d-96ab-84109559d8ad' of framework 6e587902-7782-45ea-9f8c-d76cc4e4b253-0000
I1126 17:38:10.504999 27497 exec.cpp:259] Executor reregistered on agent 6e587902-7782-45ea-9f8c-d76cc4e4b253-S0
I1126 17:38:10.505575 19560 slave.cpp:5888] No pings from master received within 75secs
I1126 17:38:10.505811 19554 hierarchical.cpp:1566] Performed allocation for 1 agents in 224830ns
F1126 17:38:10.506191 19560 slave.cpp:1232] Check failed: state == DISCONNECTED || state == RUNNING || state == TERMINATING RECOVERING
*** Check failure stack trace: ***
I1126 17:38:10.507704 27498 executor.cpp:184] Received SUBSCRIBED event
I1126 17:38:10.507750 27498 executor.cpp:188] Subscribed executor on 08f498d6e483
@ 0x2b28c4d78b08 google::LogMessage::Fail()
@ 0x2b28c4d78a54 google::LogMessage::SendToLog()
@ 0x2b28c4d78456 google::LogMessage::Flush()
@ 0x2b28c4d7b36a google::LogMessageFatal::~LogMessageFatal()
@ 0x2b28c398583c mesos::internal::slave::Slave::detected()
@ 0x2b28c3a1958c _ZZN7process8dispatchIN5mesos8internal5slave5SlaveERKNS_6FutureI6OptionINS1_10MasterInfoEEEESB_EEvRKNS_3PIDIT_EEMSD_FvT0_EOT1_ENKUlOS9_PNS_11ProcessBaseEE_clESM_SO_
@ 0x2b28c3ac17e5 _ZN5cpp176invokeIZN7process8dispatchIN5mesos8internal5slave5SlaveERKNS1_6FutureI6OptionINS3_10MasterInfoEEEESD_EEvRKNS1_3PIDIT_EEMSF_FvT0_EOT1_EUlOSB_PNS1_11ProcessBaseEE_ISB_SQ_EEEDTclcl7forwardISF_Efp_Espcl7forwardIT0_Efp0_EEEOSF_DpOSS_
@ 0x2b28c3ab8791 _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_
@ 0x2b28c3ab27d1 _ZNO6lambda8internal7PartialIZN7process8dispatchIN5mesos8internal5slave5SlaveERKNS2_6FutureI6OptionINS4_10MasterInfoEEEESE_EEvRKNS2_3PIDIT_EEMSG_FvT0_EOT1_EUlOSC_PNS2_11ProcessBaseEE_JSC_St12_PlaceholderILi1EEEEclIJSR_EEEDTcl13invoke_expandcl4movedtdefpT1fEcl4movedtdefpT10bound_argsEcvN5cpp1416integer_sequenceImJLm0ELm1EEEE_Ecl16forward_as_tuplespcl7forwardIT_Efp_EEEEDpOS10_
@ 0x2b28c3aaf649 _ZN5cpp176invokeIN6lambda8internal7PartialIZN7process8dispatchIN5mesos8internal5slave5SlaveERKNS4_6FutureI6OptionINS6_10MasterInfoEEEESG_EEvRKNS4_3PIDIT_EEMSI_FvT0_EOT1_EUlOSE_PNS4_11ProcessBaseEE_ISE_St12_PlaceholderILi1EEEEEIST_EEEDTclcl7forwardISI_Efp_Espcl7forwardIT0_Efp0_EEEOSI_DpOSY_
@ 0x2b28c3aad957 _ZN6lambda8internal6InvokeIvEclINS0_7PartialIZN7process8dispatchIN5mesos8internal5slave5SlaveERKNS5_6FutureI6OptionINS7_10MasterInfoEEEESH_EEvRKNS5_3PIDIT_EEMSJ_FvT0_EOT1_EUlOSF_PNS5_11ProcessBaseEE_JSF_St12_PlaceholderILi1EEEEEJSU_EEEvOSJ_DpOT0_
@ 0x2b28c3aab811 _ZNO6lambda12CallableOnceIFvPN7process11ProcessBaseEEE10CallableFnINS_8internal7PartialIZNS1_8dispatchIN5mesos8internal5slave5SlaveERKNS1_6FutureI6OptionINSA_10MasterInfoEEEESK_EEvRKNS1_3PIDIT_EEMSM_FvT0_EOT1_EUlOSI_S3_E_ISI_St12_PlaceholderILi1EEEEEEclEOS3_
@ 0x2b28c4c99e49 _ZNO6lambda12CallableOnceIFvPN7process11ProcessBaseEEEclES3_
@ 0x2b28c4c6385f process::ProcessBase::consume()
@ 0x2b28c4c87b82 _ZNO7process13DispatchEvent7consumeEPNS_13EventConsumerE
@ 0x560d939a7578 process::ProcessBase::serve()
@ 0x2b28c4c60b3c process::ProcessManager::resume()
@ 0x2b28c4c5cfb6 _ZZN7process14ProcessManager12init_threadsEvENKUlvE_clEv
@ 0x2b28c4c829ce _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEE9_M_invokeIIEEEvSt12_Index_tupleIIXspT_EEE
@ 0x2b28c4c81cef _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEEclEv
@ 0x2b28c4c80eae _ZNSt6thread5_ImplISt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEEE6_M_runEv
@ 0x2b28c7b39a60 (unknown)
@ 0x2b28c82b0184 start_thread
@ 0x2b28c85c403d (unknown)
I1126 17:38:11.289849 27488 exec.cpp:518] Agent exited, but framework has checkpointing enabled. Waiting 15mins to reconnect with agent 6e587902-7782-45ea-9f8c-d76cc4e4b253-S0
I1126 17:53:11.291188 27487 exec.cpp:499] Recovery timeout of 15mins exceeded; Shutting down
I1126 17:53:11.291378 27487 exec.cpp:445] Executor asked to shutdown
I1126 17:53:11.292151 27496 executor.cpp:184] Received SHUTDOWN event
I1126 17:53:11.292243 27496 executor.cpp:805] Shutting down
I1126 17:53:11.292382 27496 executor.cpp:918] Sending SIGTERM to process tree at pid 27502
I1126 17:53:11.310111 27496 executor.cpp:931] Sent SIGTERM to the following process trees:
[
-+- 27502 sh -c sleep 1000
\--- 27517 sleep 1000
]
I1126 17:53:11.310144 27496 executor.cpp:935] Scheduling escalation to SIGKILL in 3secs from now
I1126 17:53:11.364189 27497 executor.cpp:1003] Command terminated with signal Terminated (pid: 27502)
W1126 17:53:11.369813 27499 process.cpp:1890] Failed to send 'mesos.internal.StatusUpdateMessage' to '172.17.0.2:45630', connect: Failed to connect to 172.17.0.2:45630: Connection refused
I1126 17:53:12.367748 27499 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-1543249871-8012
Untagged: mesos-1543249871-8012:latest
Deleted: sha256:34c83699cfd56838ccaeaacdfe26a9a896f3d3992d06b2dc91f12f87f4422f25
Deleted: sha256:1aed04551d04b33be7b828f56e44d7b0731f23483990dd6e465ac986f0095641
Deleted: sha256:e75cd87c96d6003a91fbf1d6aeecaf1b486ba044ff347157f13e9b89561ebb70
Deleted: sha256:8d2a35e77569cb1ecc4014284f7fcb7a43b61aa58c8a9b0cf698a098e36a5caf
Deleted: sha256:318b0fcf139cbe2fa2d61557d88ae7ac10e9dcda94462866814f3e1259bb4f20
Deleted: sha256:75e89ea0dbcb43d3aa54ce6a4fa01917845511ca2e821f43f731ee1043b2752c
Deleted: sha256:0fff739ab552466489c9aa44c7a64e080bd6e15ecddd96fc092b8ec4974b70f6
Deleted: sha256:3152d0970b9b03e64b32fe249860d7d950da3f06fe4ab70a6da7a55504d27caa
Deleted: sha256:fa1b118746420a867f2b727a58e8cbd561e8a7260004d707aafeb366e6c85508
Deleted: sha256:8a6c17ca6403d90266f7f4e3193c60e4f4fab8110b925b5964c3add152e2bc95
Deleted: sha256:3841127428e80fa9f0158a298aa606ba897307e2cca72476ab74f6b655c59148
Deleted: sha256:b3934bef34c86eb07b5f88096ae3059f405eab1136ac54010d5a26b25dd43e1a
Deleted: sha256:d6b8f1b0faff13f1e60b2cfcca3a4f9c2cbc52c5f80bb341204aff0e71526aac
Deleted: sha256:b67e80f69b72d8b3e5c14e7044d7a162213387a2a6f6dbad4ef4d13646d45758
Deleted: sha256:6ca46938dee9803a77de6acaf07c8caffb061351dad17b0d62a0f9b1489ae1cb
Deleted: sha256:b8a4d66e67dd079785b5b7591f646ad25b59b6cd737eeb0cecb8f4152f934c72
Deleted: sha256:d7525d3913fbbc074d794dab89987232c6a31f48cdc32ad4ad3a6283e06f66aa
Deleted: sha256:260ea00d5222861c7d3d244e5eb58bdf281de57c058c696af86be69b2ae76357
Deleted: sha256:eb242e51275ab3bcdc4d2f76690044b85659b641174c2404dfab89e1115be288
Deleted: sha256:4eb4619a53dd5eafafd7c86adccf5ddf4a66ff133fa84eaa0dcc51ba5f69c8a1
Deleted: sha256:93d98016e96e86a43910b6a414ee079250be871eaf991a4ef693018adbf96558
Deleted: sha256:9fa3f920f5dab79dcd595a64417860dfd4f44f6da322b33eba66613610fe5190
Deleted: sha256:7c7938bf22141674bd9760cd9bd95473e7315bb3f358f3279a6c7882cc2a6ab4
Deleted: sha256:90b5ea53fb931718c533b1eaed6e88f1ce57cc84ed96f32b5e63bda8647a8b6e
Deleted: sha256:e994196760d82f09aeaba00000af43be094c60ad02d4d5a97a359e3885e29fb9
Deleted: sha256:bae1577837fad8ce9cb431bcf8961086eb8d0a56768045756734c79293b3d4a8
Deleted: sha256:b51ffcbfa5ae777dbcca9ff8994bc4c3c0184d0741040a27f198095b21cc23b0
Deleted: sha256:4b3a200ea952ee6d8391e45a0d489b0d4cb3a1652b13d2b5642943fb38b8ac39
Deleted: sha256:734694521a607e4c64333408c59ac7446ba7b7dc138eaa32e3a9b7963381648e
Deleted: sha256:0cc888f0f81fae4fdf9be91e0b5a35de37f0bf125945f2cf318266a2324c878d
11-26-18_16:31:08 - Running <https://builds.apache.org/job/Mesos-Reviewbot/ws/support/verify-reviews.py>
Checking if review 69436 needs verification
Patch never verified, needs verification
Verifying review 69436
Applying review 69436
/usr/bin/python3 support/apply-reviews.py -n -r 69436
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_69436"]' 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 #23578
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Reviewbot/23578/display/redirect?page=changes>