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/19 07:54:26 UTC
Build failed in Jenkins: Mesos-Reviewbot #23560
See <https://builds.apache.org/job/Mesos-Reviewbot/23560/display/redirect?page=changes>
Changes:
[yujie.jay] Added Stout `path::normalize` function for POSIX paths.
[yujie.jay] Added unit tests for Stout `path::normalize` function in POSIX.
------------------------------------------
[...truncated 43.63 MB...]
I1119 07:39:12.382982 19874 slave.cpp:2018] Got assigned task '36451a7b-9b98-4439-9bd4-95126354b009' for framework 487158f5-de70-4cad-b317-c6fbdeb81991-0000
I1119 07:39:12.383143 19874 slave.cpp:8894] Checkpointing FrameworkInfo to '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_bfM960/meta/slaves/487158f5-de70-4cad-b317-c6fbdeb81991-S0/frameworks/487158f5-de70-4cad-b317-c6fbdeb81991-0000/framework.info'
I1119 07:39:12.383530 19874 slave.cpp:8905] Checkpointing framework pid 'scheduler-01a108bc-9648-4bcf-8a02-85704699c8fb@172.17.0.2:35669' to '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_bfM960/meta/slaves/487158f5-de70-4cad-b317-c6fbdeb81991-S0/frameworks/487158f5-de70-4cad-b317-c6fbdeb81991-0000/framework.pid'
I1119 07:39:12.384800 19874 slave.cpp:2392] Authorizing task '36451a7b-9b98-4439-9bd4-95126354b009' for framework 487158f5-de70-4cad-b317-c6fbdeb81991-0000
I1119 07:39:12.384855 19874 slave.cpp:8470] Authorizing framework principal 'test-principal' to launch task 36451a7b-9b98-4439-9bd4-95126354b009
I1119 07:39:12.386512 19860 slave.cpp:2835] Launching task '36451a7b-9b98-4439-9bd4-95126354b009' for framework 487158f5-de70-4cad-b317-c6fbdeb81991-0000
I1119 07:39:12.386584 19860 paths.cpp:752] Creating sandbox '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_bfM960/slaves/487158f5-de70-4cad-b317-c6fbdeb81991-S0/frameworks/487158f5-de70-4cad-b317-c6fbdeb81991-0000/executors/36451a7b-9b98-4439-9bd4-95126354b009/runs/981a54ee-b751-49cf-8627-ea025b06269e' for user 'mesos'
I1119 07:39:12.387207 19860 slave.cpp:9680] Checkpointing ExecutorInfo to '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_bfM960/meta/slaves/487158f5-de70-4cad-b317-c6fbdeb81991-S0/frameworks/487158f5-de70-4cad-b317-c6fbdeb81991-0000/executors/36451a7b-9b98-4439-9bd4-95126354b009/executor.info'
I1119 07:39:12.387712 19860 paths.cpp:755] Creating sandbox '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_bfM960/meta/slaves/487158f5-de70-4cad-b317-c6fbdeb81991-S0/frameworks/487158f5-de70-4cad-b317-c6fbdeb81991-0000/executors/36451a7b-9b98-4439-9bd4-95126354b009/runs/981a54ee-b751-49cf-8627-ea025b06269e'
I1119 07:39:12.387979 19860 slave.cpp:8980] Launching executor '36451a7b-9b98-4439-9bd4-95126354b009' of framework 487158f5-de70-4cad-b317-c6fbdeb81991-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_bfM960/slaves/487158f5-de70-4cad-b317-c6fbdeb81991-S0/frameworks/487158f5-de70-4cad-b317-c6fbdeb81991-0000/executors/36451a7b-9b98-4439-9bd4-95126354b009/runs/981a54ee-b751-49cf-8627-ea025b06269e'
I1119 07:39:12.388674 19860 slave.cpp:3513] Launching container 981a54ee-b751-49cf-8627-ea025b06269e for executor '36451a7b-9b98-4439-9bd4-95126354b009' of framework 487158f5-de70-4cad-b317-c6fbdeb81991-0000
I1119 07:39:12.389183 19860 slave.cpp:9711] Checkpointing TaskInfo to '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_bfM960/meta/slaves/487158f5-de70-4cad-b317-c6fbdeb81991-S0/frameworks/487158f5-de70-4cad-b317-c6fbdeb81991-0000/executors/36451a7b-9b98-4439-9bd4-95126354b009/runs/981a54ee-b751-49cf-8627-ea025b06269e/tasks/36451a7b-9b98-4439-9bd4-95126354b009/task.info'
I1119 07:39:12.389814 19860 slave.cpp:3032] Queued task '36451a7b-9b98-4439-9bd4-95126354b009' for executor '36451a7b-9b98-4439-9bd4-95126354b009' of framework 487158f5-de70-4cad-b317-c6fbdeb81991-0000
I1119 07:39:12.389897 19860 slave.cpp:992] Successfully attached '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_bfM960/slaves/487158f5-de70-4cad-b317-c6fbdeb81991-S0/frameworks/487158f5-de70-4cad-b317-c6fbdeb81991-0000/executors/36451a7b-9b98-4439-9bd4-95126354b009/runs/981a54ee-b751-49cf-8627-ea025b06269e' to virtual path '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_bfM960/slaves/487158f5-de70-4cad-b317-c6fbdeb81991-S0/frameworks/487158f5-de70-4cad-b317-c6fbdeb81991-0000/executors/36451a7b-9b98-4439-9bd4-95126354b009/runs/latest'
I1119 07:39:12.389940 19860 slave.cpp:992] Successfully attached '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_bfM960/slaves/487158f5-de70-4cad-b317-c6fbdeb81991-S0/frameworks/487158f5-de70-4cad-b317-c6fbdeb81991-0000/executors/36451a7b-9b98-4439-9bd4-95126354b009/runs/981a54ee-b751-49cf-8627-ea025b06269e' to virtual path '/frameworks/487158f5-de70-4cad-b317-c6fbdeb81991-0000/executors/36451a7b-9b98-4439-9bd4-95126354b009/runs/latest'
I1119 07:39:12.389976 19860 slave.cpp:992] Successfully attached '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_bfM960/slaves/487158f5-de70-4cad-b317-c6fbdeb81991-S0/frameworks/487158f5-de70-4cad-b317-c6fbdeb81991-0000/executors/36451a7b-9b98-4439-9bd4-95126354b009/runs/981a54ee-b751-49cf-8627-ea025b06269e' to virtual path '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_bfM960/slaves/487158f5-de70-4cad-b317-c6fbdeb81991-S0/frameworks/487158f5-de70-4cad-b317-c6fbdeb81991-0000/executors/36451a7b-9b98-4439-9bd4-95126354b009/runs/981a54ee-b751-49cf-8627-ea025b06269e'
I1119 07:39:12.390503 19861 containerizer.cpp:1288] Starting container 981a54ee-b751-49cf-8627-ea025b06269e
I1119 07:39:12.391542 19861 containerizer.cpp:1454] Checkpointed ContainerConfig at '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_5KbAbI/containers/981a54ee-b751-49cf-8627-ea025b06269e/config'
I1119 07:39:12.391574 19861 containerizer.cpp:3130] Transitioning the state of container 981a54ee-b751-49cf-8627-ea025b06269e from PROVISIONING to PREPARING
I1119 07:39:12.395596 19875 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:35669"},{"name":"MESOS_CHECKPOINT","type":"VALUE","value":"1"},{"name":"MESOS_DIRECTORY","type":"VALUE","value":"/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_bfM960/slaves/487158f5-de70-4cad-b317-c6fbdeb81991-S0/frameworks/487158f5-de70-4cad-b317-c6fbdeb81991-0000/executors/36451a7b-9b98-4439-9bd4-95126354b009/runs/981a54ee-b751-49cf-8627-ea025b06269e"},{"name":"MESOS_EXECUTOR_ID","type":"VALUE","value":"36451a7b-9b98-4439-9bd4-95126354b009"},{"name":"MESOS_EXECUTOR_SHUTDOWN_GRACE_PERIOD","type":"VALUE","value":"5secs"},{"name":"MESOS_FRAMEWORK_ID","type":"VALUE","value":"487158f5-de70-4cad-b317-c6fbdeb81991-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":"487158f5-de70-4cad-b317-c6fbdeb81991-S0"},{"name":"MESOS_SLAVE_PID","type":"VALUE","value":"slave@172.17.0.2:35669"},{"name":"MESOS_SUBSCRIPTION_BACKOFF_MAX","type":"VALUE","value":"15secs"},{"name":"MESOS_SANDBOX","type":"VALUE","value":"/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_bfM960/slaves/487158f5-de70-4cad-b317-c6fbdeb81991-S0/frameworks/487158f5-de70-4cad-b317-c6fbdeb81991-0000/executors/36451a7b-9b98-4439-9bd4-95126354b009/runs/981a54ee-b751-49cf-8627-ea025b06269e"}]},"task_environment":{},"user":"mesos","working_directory":"/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_bfM960/slaves/487158f5-de70-4cad-b317-c6fbdeb81991-S0/frameworks/487158f5-de70-4cad-b317-c6fbdeb81991-0000/executors/36451a7b-9b98-4439-9bd4-95126354b009/runs/981a54ee-b751-49cf-8627-ea025b06269e"}" --pipe_read="24" --pipe_write="26" --runtime_directory="/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_5KbAbI/containers/981a54ee-b751-49cf-8627-ea025b06269e" --unshare_namespace_mnt="false"'
I1119 07:39:12.400179 19875 launcher.cpp:145] Forked child with pid '27363' for container '981a54ee-b751-49cf-8627-ea025b06269e'
I1119 07:39:12.400301 19875 containerizer.cpp:2052] Checkpointing container's forked pid 27363 to '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_bfM960/meta/slaves/487158f5-de70-4cad-b317-c6fbdeb81991-S0/frameworks/487158f5-de70-4cad-b317-c6fbdeb81991-0000/executors/36451a7b-9b98-4439-9bd4-95126354b009/runs/981a54ee-b751-49cf-8627-ea025b06269e/pids/forked.pid'
I1119 07:39:12.401114 19875 containerizer.cpp:3130] Transitioning the state of container 981a54ee-b751-49cf-8627-ea025b06269e from PREPARING to ISOLATING
I1119 07:39:12.403841 19863 containerizer.cpp:3130] Transitioning the state of container 981a54ee-b751-49cf-8627-ea025b06269e from ISOLATING to FETCHING
I1119 07:39:12.404132 19867 fetcher.cpp:369] Starting to fetch URIs for container: 981a54ee-b751-49cf-8627-ea025b06269e, directory: /tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_bfM960/slaves/487158f5-de70-4cad-b317-c6fbdeb81991-S0/frameworks/487158f5-de70-4cad-b317-c6fbdeb81991-0000/executors/36451a7b-9b98-4439-9bd4-95126354b009/runs/981a54ee-b751-49cf-8627-ea025b06269e
I1119 07:39:12.405213 19882 containerizer.cpp:3130] Transitioning the state of container 981a54ee-b751-49cf-8627-ea025b06269e from FETCHING to RUNNING
I1119 07:39:12.836520 27384 exec.cpp:162] Version: 1.8.0
I1119 07:39:12.847929 19877 slave.cpp:4807] Got registration for executor '36451a7b-9b98-4439-9bd4-95126354b009' of framework 487158f5-de70-4cad-b317-c6fbdeb81991-0000 from executor(1)@172.17.0.2:39315
I1119 07:39:12.848541 19877 slave.cpp:4893] Checkpointing executor pid 'executor(1)@172.17.0.2:39315' to '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_bfM960/meta/slaves/487158f5-de70-4cad-b317-c6fbdeb81991-S0/frameworks/487158f5-de70-4cad-b317-c6fbdeb81991-0000/executors/36451a7b-9b98-4439-9bd4-95126354b009/runs/981a54ee-b751-49cf-8627-ea025b06269e/pids/libprocess.pid'
I1119 07:39:12.851908 19882 slave.cpp:3245] Sending queued task '36451a7b-9b98-4439-9bd4-95126354b009' to executor '36451a7b-9b98-4439-9bd4-95126354b009' of framework 487158f5-de70-4cad-b317-c6fbdeb81991-0000 at executor(1)@172.17.0.2:39315
I1119 07:39:12.853982 27392 exec.cpp:236] Executor registered on agent 487158f5-de70-4cad-b317-c6fbdeb81991-S0
I1119 07:39:12.858089 27396 executor.cpp:184] Received SUBSCRIBED event
I1119 07:39:12.860132 27396 executor.cpp:188] Subscribed executor on 19ea403d63c0
I1119 07:39:12.860391 27396 executor.cpp:184] Received LAUNCH event
I1119 07:39:12.863281 27396 executor.cpp:687] Starting task 36451a7b-9b98-4439-9bd4-95126354b009
I1119 07:39:12.866588 19865 slave.cpp:5273] Handling status update TASK_STARTING (Status UUID: d5a14617-072d-4203-ba82-eeb35f79593c) for task 36451a7b-9b98-4439-9bd4-95126354b009 of framework 487158f5-de70-4cad-b317-c6fbdeb81991-0000 from executor(1)@172.17.0.2:39315
I1119 07:39:12.868803 19861 task_status_update_manager.cpp:328] Received task status update TASK_STARTING (Status UUID: d5a14617-072d-4203-ba82-eeb35f79593c) for task 36451a7b-9b98-4439-9bd4-95126354b009 of framework 487158f5-de70-4cad-b317-c6fbdeb81991-0000
I1119 07:39:12.868875 19861 task_status_update_manager.cpp:507] Creating StatusUpdate stream for task 36451a7b-9b98-4439-9bd4-95126354b009 of framework 487158f5-de70-4cad-b317-c6fbdeb81991-0000
I1119 07:39:12.869603 19861 task_status_update_manager.cpp:842] Checkpointing UPDATE for task status update TASK_STARTING (Status UUID: d5a14617-072d-4203-ba82-eeb35f79593c) for task 36451a7b-9b98-4439-9bd4-95126354b009 of framework 487158f5-de70-4cad-b317-c6fbdeb81991-0000
I1119 07:39:12.869882 19861 task_status_update_manager.cpp:383] Forwarding task status update TASK_STARTING (Status UUID: d5a14617-072d-4203-ba82-eeb35f79593c) for task 36451a7b-9b98-4439-9bd4-95126354b009 of framework 487158f5-de70-4cad-b317-c6fbdeb81991-0000 to the agent
I1119 07:39:12.870097 19863 slave.cpp:5765] Forwarding the update TASK_STARTING (Status UUID: d5a14617-072d-4203-ba82-eeb35f79593c) for task 36451a7b-9b98-4439-9bd4-95126354b009 of framework 487158f5-de70-4cad-b317-c6fbdeb81991-0000 to master@172.17.0.2:35669
I1119 07:39:12.870355 19863 slave.cpp:5658] Task status update manager successfully handled status update TASK_STARTING (Status UUID: d5a14617-072d-4203-ba82-eeb35f79593c) for task 36451a7b-9b98-4439-9bd4-95126354b009 of framework 487158f5-de70-4cad-b317-c6fbdeb81991-0000
I1119 07:39:12.870421 19863 slave.cpp:5674] Sending acknowledgement for status update TASK_STARTING (Status UUID: d5a14617-072d-4203-ba82-eeb35f79593c) for task 36451a7b-9b98-4439-9bd4-95126354b009 of framework 487158f5-de70-4cad-b317-c6fbdeb81991-0000 to executor(1)@172.17.0.2:39315
I1119 07:39:12.870522 19878 master.cpp:8359] Status update TASK_STARTING (Status UUID: d5a14617-072d-4203-ba82-eeb35f79593c) for task 36451a7b-9b98-4439-9bd4-95126354b009 of framework 487158f5-de70-4cad-b317-c6fbdeb81991-0000 from agent 487158f5-de70-4cad-b317-c6fbdeb81991-S0 at slave@172.17.0.2:35669 (19ea403d63c0)
I1119 07:39:12.870586 19878 master.cpp:8416] Forwarding status update TASK_STARTING (Status UUID: d5a14617-072d-4203-ba82-eeb35f79593c) for task 36451a7b-9b98-4439-9bd4-95126354b009 of framework 487158f5-de70-4cad-b317-c6fbdeb81991-0000
I1119 07:39:12.870816 19878 master.cpp:10917] Updating the state of task 36451a7b-9b98-4439-9bd4-95126354b009 of framework 487158f5-de70-4cad-b317-c6fbdeb81991-0000 (latest state: TASK_STARTING, status update state: TASK_STARTING)
I1119 07:39:12.871048 19871 sched.cpp:1022] Scheduler::statusUpdate took 111273ns
I1119 07:39:12.871825 19862 master.cpp:6225] Processing ACKNOWLEDGE call for status d5a14617-072d-4203-ba82-eeb35f79593c for task 36451a7b-9b98-4439-9bd4-95126354b009 of framework 487158f5-de70-4cad-b317-c6fbdeb81991-0000 (default) at scheduler-01a108bc-9648-4bcf-8a02-85704699c8fb@172.17.0.2:35669 on agent 487158f5-de70-4cad-b317-c6fbdeb81991-S0
I1119 07:39:12.872187 19869 task_status_update_manager.cpp:401] Received task status update acknowledgement (UUID: d5a14617-072d-4203-ba82-eeb35f79593c) for task 36451a7b-9b98-4439-9bd4-95126354b009 of framework 487158f5-de70-4cad-b317-c6fbdeb81991-0000
I1119 07:39:12.872336 19869 task_status_update_manager.cpp:842] Checkpointing ACK for task status update TASK_STARTING (Status UUID: d5a14617-072d-4203-ba82-eeb35f79593c) for task 36451a7b-9b98-4439-9bd4-95126354b009 of framework 487158f5-de70-4cad-b317-c6fbdeb81991-0000
I1119 07:39:12.872637 19864 slave.cpp:4509] Task status update manager successfully handled status update acknowledgement (UUID: d5a14617-072d-4203-ba82-eeb35f79593c) for task 36451a7b-9b98-4439-9bd4-95126354b009 of framework 487158f5-de70-4cad-b317-c6fbdeb81991-0000
I1119 07:39:12.894620 27396 executor.cpp:502] Running '/mesos/mesos-1.8.0/_build/src/mesos-containerizer launch <POSSIBLY-SENSITIVE-DATA>'
I1119 07:39:12.898916 27396 executor.cpp:702] Forked command at 27401
I1119 07:39:12.903600 19868 slave.cpp:5273] Handling status update TASK_RUNNING (Status UUID: ceb425e1-d2db-4a79-ad4e-b621f8570520) for task 36451a7b-9b98-4439-9bd4-95126354b009 of framework 487158f5-de70-4cad-b317-c6fbdeb81991-0000 from executor(1)@172.17.0.2:39315
I1119 07:39:12.905527 19871 task_status_update_manager.cpp:328] Received task status update TASK_RUNNING (Status UUID: ceb425e1-d2db-4a79-ad4e-b621f8570520) for task 36451a7b-9b98-4439-9bd4-95126354b009 of framework 487158f5-de70-4cad-b317-c6fbdeb81991-0000
I1119 07:39:12.905617 19871 task_status_update_manager.cpp:842] Checkpointing UPDATE for task status update TASK_RUNNING (Status UUID: ceb425e1-d2db-4a79-ad4e-b621f8570520) for task 36451a7b-9b98-4439-9bd4-95126354b009 of framework 487158f5-de70-4cad-b317-c6fbdeb81991-0000
I1119 07:39:12.905899 19871 task_status_update_manager.cpp:383] Forwarding task status update TASK_RUNNING (Status UUID: ceb425e1-d2db-4a79-ad4e-b621f8570520) for task 36451a7b-9b98-4439-9bd4-95126354b009 of framework 487158f5-de70-4cad-b317-c6fbdeb81991-0000 to the agent
I1119 07:39:12.906165 19876 slave.cpp:5765] Forwarding the update TASK_RUNNING (Status UUID: ceb425e1-d2db-4a79-ad4e-b621f8570520) for task 36451a7b-9b98-4439-9bd4-95126354b009 of framework 487158f5-de70-4cad-b317-c6fbdeb81991-0000 to master@172.17.0.2:35669
I1119 07:39:12.906406 19876 slave.cpp:5658] Task status update manager successfully handled status update TASK_RUNNING (Status UUID: ceb425e1-d2db-4a79-ad4e-b621f8570520) for task 36451a7b-9b98-4439-9bd4-95126354b009 of framework 487158f5-de70-4cad-b317-c6fbdeb81991-0000
I1119 07:39:12.906466 19876 slave.cpp:5674] Sending acknowledgement for status update TASK_RUNNING (Status UUID: ceb425e1-d2db-4a79-ad4e-b621f8570520) for task 36451a7b-9b98-4439-9bd4-95126354b009 of framework 487158f5-de70-4cad-b317-c6fbdeb81991-0000 to executor(1)@172.17.0.2:39315
I1119 07:39:12.906566 19882 master.cpp:8359] Status update TASK_RUNNING (Status UUID: ceb425e1-d2db-4a79-ad4e-b621f8570520) for task 36451a7b-9b98-4439-9bd4-95126354b009 of framework 487158f5-de70-4cad-b317-c6fbdeb81991-0000 from agent 487158f5-de70-4cad-b317-c6fbdeb81991-S0 at slave@172.17.0.2:35669 (19ea403d63c0)
I1119 07:39:12.906638 19882 master.cpp:8416] Forwarding status update TASK_RUNNING (Status UUID: ceb425e1-d2db-4a79-ad4e-b621f8570520) for task 36451a7b-9b98-4439-9bd4-95126354b009 of framework 487158f5-de70-4cad-b317-c6fbdeb81991-0000
I1119 07:39:12.906924 19882 master.cpp:10917] Updating the state of task 36451a7b-9b98-4439-9bd4-95126354b009 of framework 487158f5-de70-4cad-b317-c6fbdeb81991-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING)
I1119 07:39:12.907166 19865 sched.cpp:1022] Scheduler::statusUpdate took 112312ns
I1119 07:39:12.907855 19868 master.cpp:6225] Processing ACKNOWLEDGE call for status ceb425e1-d2db-4a79-ad4e-b621f8570520 for task 36451a7b-9b98-4439-9bd4-95126354b009 of framework 487158f5-de70-4cad-b317-c6fbdeb81991-0000 (default) at scheduler-01a108bc-9648-4bcf-8a02-85704699c8fb@172.17.0.2:35669 on agent 487158f5-de70-4cad-b317-c6fbdeb81991-S0
I1119 07:39:12.908206 19866 task_status_update_manager.cpp:401] Received task status update acknowledgement (UUID: ceb425e1-d2db-4a79-ad4e-b621f8570520) for task 36451a7b-9b98-4439-9bd4-95126354b009 of framework 487158f5-de70-4cad-b317-c6fbdeb81991-0000
I1119 07:39:12.908330 19866 task_status_update_manager.cpp:842] Checkpointing ACK for task status update TASK_RUNNING (Status UUID: ceb425e1-d2db-4a79-ad4e-b621f8570520) for task 36451a7b-9b98-4439-9bd4-95126354b009 of framework 487158f5-de70-4cad-b317-c6fbdeb81991-0000
I1119 07:39:12.908630 19861 slave.cpp:4509] Task status update manager successfully handled status update acknowledgement (UUID: ceb425e1-d2db-4a79-ad4e-b621f8570520) for task 36451a7b-9b98-4439-9bd4-95126354b009 of framework 487158f5-de70-4cad-b317-c6fbdeb81991-0000
I1119 07:39:12.908896 19277 slave.cpp:913] Agent terminating
I1119 07:39:13.347081 19877 hierarchical.cpp:1566] Performed allocation for 1 agents in 379118ns
I1119 07:39:13.634182 19879 master.cpp:1274] Agent 487158f5-de70-4cad-b317-c6fbdeb81991-S0 at slave@172.17.0.2:35669 (19ea403d63c0) disconnected
I1119 07:39:13.634229 19879 master.cpp:3272] Disconnecting agent 487158f5-de70-4cad-b317-c6fbdeb81991-S0 at slave@172.17.0.2:35669 (19ea403d63c0)
I1119 07:39:13.634300 19879 master.cpp:3291] Deactivating agent 487158f5-de70-4cad-b317-c6fbdeb81991-S0 at slave@172.17.0.2:35669 (19ea403d63c0)
I1119 07:39:13.634421 19881 hierarchical.cpp:801] Agent 487158f5-de70-4cad-b317-c6fbdeb81991-S0 deactivated
I1119 07:39:13.634476 19277 containerizer.cpp:305] Using isolation { environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni }
W1119 07:39:13.634974 19277 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W1119 07:39:13.635126 19277 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I1119 07:39:13.635166 19277 provisioner.cpp:298] Using default backend 'copy'
W1119 07:39:13.642606 19277 process.cpp:2829] Attempted to spawn already running process files@172.17.0.2:35669
I1119 07:39:13.643342 19277 cluster.cpp:485] Creating default 'local' authorizer
I1119 07:39:13.645962 19866 slave.cpp:268] Mesos agent started on @172.17.0.2:35669
I1119 07:39:13.645992 19866 slave.cpp:269] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_5KbAbI/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_5KbAbI/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_5KbAbI/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_5KbAbI/fetch" --fetcher_cache_size="2GB" --fetcher_stall_timeout="1mins" --frameworks_home="/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_5KbAbI/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_5KbAbI/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_5KbAbI" --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_bfM960" --zk_session_timeout="10secs"
I1119 07:39:13.646687 19866 credentials.hpp:86] Loading credential for authentication from '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_5KbAbI/credential'
I1119 07:39:13.646916 19866 slave.cpp:301] Agent using credential for: test-principal
I1119 07:39:13.646945 19866 credentials.hpp:37] Loading credentials for authentication from '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_5KbAbI/http_credentials'
I1119 07:39:13.647248 19866 http.cpp:1017] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I1119 07:39:13.647662 19866 disk_profile_adaptor.cpp:80] Creating default disk profile adaptor module
I1119 07:39:13.649611 19866 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"}]
I1119 07:39:13.649914 19866 slave.cpp:624] Agent attributes: [ ]
I1119 07:39:13.649941 19866 slave.cpp:633] Agent hostname: 19ea403d63c0
I1119 07:39:13.650171 19865 task_status_update_manager.cpp:181] Pausing sending task status updates
I1119 07:39:13.652189 19878 state.cpp:66] Recovering state from '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_bfM960/meta'
I1119 07:39:13.652303 19878 state.cpp:711] No committed checkpointed resources found at '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_bfM960/meta/resources/resources.info'
I1119 07:39:13.659994 19859 slave.cpp:6913] Finished recovering checkpointed state from '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_bfM960/meta', beginning agent recovery
I1119 07:39:13.660722 19859 slave.cpp:7392] Recovering framework 487158f5-de70-4cad-b317-c6fbdeb81991-0000
I1119 07:39:13.660924 19859 slave.cpp:9098] Recovering executor '36451a7b-9b98-4439-9bd4-95126354b009' of framework 487158f5-de70-4cad-b317-c6fbdeb81991-0000
I1119 07:39:13.662140 19877 task_status_update_manager.cpp:207] Recovering task status update manager
I1119 07:39:13.662186 19877 task_status_update_manager.cpp:215] Recovering executor '36451a7b-9b98-4439-9bd4-95126354b009' of framework 487158f5-de70-4cad-b317-c6fbdeb81991-0000
I1119 07:39:13.662278 19877 task_status_update_manager.cpp:507] Creating StatusUpdate stream for task 36451a7b-9b98-4439-9bd4-95126354b009 of framework 487158f5-de70-4cad-b317-c6fbdeb81991-0000
I1119 07:39:13.662922 19877 task_status_update_manager.cpp:818] Replaying task status update stream for task 36451a7b-9b98-4439-9bd4-95126354b009
I1119 07:39:13.663596 19862 containerizer.cpp:727] Recovering Mesos containers
I1119 07:39:13.663686 19862 containerizer.cpp:784] Recovering container 981a54ee-b751-49cf-8627-ea025b06269e for executor '36451a7b-9b98-4439-9bd4-95126354b009' of framework 487158f5-de70-4cad-b317-c6fbdeb81991-0000
I1119 07:39:13.665554 19862 containerizer.cpp:1053] Recovering isolators
I1119 07:39:13.666651 19871 containerizer.cpp:1092] Recovering provisioner
I1119 07:39:13.667300 19867 provisioner.cpp:494] Provisioner recovery complete
I1119 07:39:13.668879 19863 composing.cpp:339] Finished recovering all containerizers
I1119 07:39:13.669005 19870 slave.cpp:7142] Recovering executors
I1119 07:39:13.669162 19870 slave.cpp:7166] Sending reconnect request to executor '36451a7b-9b98-4439-9bd4-95126354b009' of framework 487158f5-de70-4cad-b317-c6fbdeb81991-0000 at executor(1)@172.17.0.2:39315
I1119 07:39:13.670938 27390 exec.cpp:282] Received reconnect request from agent 487158f5-de70-4cad-b317-c6fbdeb81991-S0
I1119 07:39:13.674751 19881 slave.cpp:4970] Received re-registration message from executor '36451a7b-9b98-4439-9bd4-95126354b009' of framework 487158f5-de70-4cad-b317-c6fbdeb81991-0000
I1119 07:39:13.676573 27393 exec.cpp:259] Executor reregistered on agent 487158f5-de70-4cad-b317-c6fbdeb81991-S0
I1119 07:39:13.677536 19870 slave.cpp:5887] No pings from master received within 75secs
I1119 07:39:13.677829 19862 hierarchical.cpp:1566] Performed allocation for 1 agents in 281741ns
F1119 07:39:13.678194 19872 slave.cpp:1231] Check failed: state == DISCONNECTED || state == RUNNING || state == TERMINATING RECOVERING
I1119 07:39:13.680205 27394 executor.cpp:184] Received SUBSCRIBED event
I1119 07:39:13.680246 27394 executor.cpp:188] Subscribed executor on 19ea403d63c0
*** Check failure stack trace: ***
@ 0x2b040e2bec7a google::LogMessage::Fail()
@ 0x2b040e2bebc6 google::LogMessage::SendToLog()
@ 0x2b040e2be5c8 google::LogMessage::Flush()
@ 0x2b040e2c14dc google::LogMessageFatal::~LogMessageFatal()
@ 0x2b040c9f5db4 mesos::internal::slave::Slave::detected()
@ 0x2b040ca8e9f8 _ZZN7process8dispatchIN5mesos8internal5slave5SlaveERKNS_6FutureI6OptionINS1_10MasterInfoEEEESB_EEvRKNS_3PIDIT_EEMSD_FvT0_EOT1_ENKUlOS9_PNS_11ProcessBaseEE_clESM_SO_
@ 0x2b040cb48971 _ZN5cpp176invokeIZN7process8dispatchIN5mesos8internal5slave5SlaveERKNS1_6FutureI6OptionINS3_10MasterInfoEEEESD_EEvRKNS1_3PIDIT_EEMSF_FvT0_EOT1_EUlOSB_PNS1_11ProcessBaseEE_ISB_SQ_EEEDTclcl7forwardISF_Efp_Espcl7forwardIT0_Efp0_EEEOSF_DpOSS_
@ 0x2b040cb3f079 _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_
@ 0x2b040cb38ae1 _ZNO6lambda8internal7PartialIZN7process8dispatchIN5mesos8internal5slave5SlaveERKNS2_6FutureI6OptionINS4_10MasterInfoEEEESE_EEvRKNS2_3PIDIT_EEMSG_FvT0_EOT1_EUlOSC_PNS2_11ProcessBaseEE_JSC_St12_PlaceholderILi1EEEEclIJSR_EEEDTcl13invoke_expandcl4movedtdefpT1fEcl4movedtdefpT10bound_argsEcvN5cpp1416integer_sequenceImJLm0ELm1EEEE_Ecl16forward_as_tuplespcl7forwardIT_Efp_EEEEDpOS10_
@ 0x2b040cb354fb _ZN5cpp176invokeIN6lambda8internal7PartialIZN7process8dispatchIN5mesos8internal5slave5SlaveERKNS4_6FutureI6OptionINS6_10MasterInfoEEEESG_EEvRKNS4_3PIDIT_EEMSI_FvT0_EOT1_EUlOSE_PNS4_11ProcessBaseEE_ISE_St12_PlaceholderILi1EEEEEIST_EEEDTclcl7forwardISI_Efp_Espcl7forwardIT0_Efp0_EEEOSI_DpOSY_
@ 0x2b040cb335d3 _ZN6lambda8internal6InvokeIvEclINS0_7PartialIZN7process8dispatchIN5mesos8internal5slave5SlaveERKNS5_6FutureI6OptionINS7_10MasterInfoEEEESH_EEvRKNS5_3PIDIT_EEMSJ_FvT0_EOT1_EUlOSF_PNS5_11ProcessBaseEE_JSF_St12_PlaceholderILi1EEEEEJSU_EEEvOSJ_DpOT0_
@ 0x2b040cb31227 _ZNO6lambda12CallableOnceIFvPN7process11ProcessBaseEEE10CallableFnINS_8internal7PartialIZNS1_8dispatchIN5mesos8internal5slave5SlaveERKNS1_6FutureI6OptionINSA_10MasterInfoEEEESK_EEvRKNS1_3PIDIT_EEMSM_FvT0_EOT1_EUlOSI_S3_E_ISI_St12_PlaceholderILi1EEEEEEclEOS3_
@ 0x2b040e1dffbb _ZNO6lambda12CallableOnceIFvPN7process11ProcessBaseEEEclES3_
@ 0x2b040e1a99d1 process::ProcessBase::consume()
@ 0x2b040e1cdcf4 _ZNO7process13DispatchEvent7consumeEPNS_13EventConsumerE
@ 0x5648e7508ba8 process::ProcessBase::serve()
@ 0x2b040e1a6cae process::ProcessManager::resume()
@ 0x2b040e1a3128 _ZZN7process14ProcessManager12init_threadsEvENKUlvE_clEv
@ 0x2b040e1c8b40 _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEE9_M_invokeIIEEEvSt12_Index_tupleIIXspT_EEE
@ 0x2b040e1c7e61 _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEEclEv
@ 0x2b040e1c7020 _ZNSt6thread5_ImplISt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEEE6_M_runEv
@ 0x2b041107fa60 (unknown)
@ 0x2b04117f6184 start_thread
@ 0x2b0411b0a03d (unknown)
I1119 07:39:14.641636 27383 exec.cpp:518] Agent exited, but framework has checkpointing enabled. Waiting 15mins to reconnect with agent 487158f5-de70-4cad-b317-c6fbdeb81991-S0
I1119 07:54:14.642884 27387 exec.cpp:499] Recovery timeout of 15mins exceeded; Shutting down
I1119 07:54:14.643146 27387 exec.cpp:445] Executor asked to shutdown
I1119 07:54:14.644292 27390 executor.cpp:184] Received SHUTDOWN event
I1119 07:54:14.644384 27390 executor.cpp:805] Shutting down
I1119 07:54:14.644484 27390 executor.cpp:918] Sending SIGTERM to process tree at pid 27401
I1119 07:54:14.662801 27390 executor.cpp:931] Sent SIGTERM to the following process trees:
[
-+- 27401 sh -c sleep 1000
\--- 27404 sleep 1000
]
I1119 07:54:14.662832 27390 executor.cpp:935] Scheduling escalation to SIGKILL in 3secs from now
I1119 07:54:14.694025 27395 executor.cpp:1003] Command terminated with signal Terminated (pid: 27401)
W1119 07:54:14.699277 27397 process.cpp:1890] Failed to send 'mesos.internal.StatusUpdateMessage' to '172.17.0.2:35669', connect: Failed to connect to 172.17.0.2:35669: Connection refused
I1119 07:54:15.700608 27397 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-1542609074-4908
Untagged: mesos-1542609074-4908:latest
Deleted: sha256:9f80d599d7b05ee8bb1c10c1170bf14ee64baceb9c97da680b27cdf5d9784560
Deleted: sha256:90e6a1292a09c0a748e6c7b46bac32c0973c8795244544fa6fa0a34c9bbcb502
Deleted: sha256:48bdd3e9cbd581b297612a14e1d53303e7330d361ecfea69910de838bbe0cde8
Deleted: sha256:e2141fb98961ed8c70063666bbb91ed870a1c418299391406f1870485f99c7ec
Deleted: sha256:430e0fe7abc1591c074f7bf22e39c02da40b0eb42d126fabd97109693e855bfa
Deleted: sha256:34a72655eb6829caa0633a71b626c4bb33c9f3f099edc70953e4ace155169ec1
Deleted: sha256:2097105f18052a3b27f1b101feceebd5c87f42f50617e664013dd6eb005e5f4d
Deleted: sha256:0f33876625e3bfd4ea863311e8b27012db612602f9b1567bed6a66b2a1c387cb
Deleted: sha256:721def75b1dbc5b8a3dc9a936318c3f81edd901a04b0988cfc7dae95414076ab
Deleted: sha256:b45522bf0133d75e4e82ebb6b6512c108053b55f53bc6b7d63f813763ddb6950
Deleted: sha256:cb9e776f67831d331bb238fde235e9e847491dcd56659dffd91c114dc432ac8c
Deleted: sha256:3b8995cb3441e2bb57c5b380608be678022765869a767c6a2127f4b2bc0dac6e
Deleted: sha256:bda280d05e2ff8a50f38e9675ca3a3cb15b5074cb843a44fd3357be3d2215aa2
Deleted: sha256:123110583d0d15b555e5db0c115e5acf99c20092bbe099efe3e628bd18e28d05
Deleted: sha256:afaa3e5bbc85ea1212269d8041abf79d9202569cba087db217beab9d2e9a6c69
Deleted: sha256:d7d7ac6c6aa2b18afc4295f542b2f46c21dc37bcb003fd4e271e689b2d3aac6c
Deleted: sha256:e90cce30275df58e05c9a76fe3b3818e1e5eba2a28104ba35c68b7b7e3e02ab8
Deleted: sha256:621ba53614eb99d473410d6c1b5aefc1d65fe2b4c1f55a9540c089f5ea898686
Deleted: sha256:ab8ee8aac68bb955fdf8c332905e71ec27c2b333d5054f69f995be24a7e9463d
Deleted: sha256:0b395419acfb80f2915ad0f38215de29698098606a35626fa383566fda602663
Deleted: sha256:a9218c32e0303d0828dffc907314a04ef3ef15c42b9de9e13450164ea77d5d3d
Deleted: sha256:b5f767cfb139efe24b7c8403c2e8f8915537884124ad6027da110453f663a7f7
Deleted: sha256:5717cc84e341962de7691fb17564f4449e950450cdbfa9e49f605ebd08cc3fdc
Deleted: sha256:9a94b7adae9c6a1ed510d5dcfe69621353122783280232d40b52d7de54c061bc
Deleted: sha256:168ecf49b4f51b6276e0374465fef7125e1375cfed08d88346fed5ccc3673800
Deleted: sha256:43dea870ef0f1a97f8a4bfe8a108cb391f6bc000c078877452966fa4526856ed
Deleted: sha256:d6db9c79728a62609623bb99c504563a7c3be07cdf093b92be22f7e9ea14c4a6
Deleted: sha256:16d0c428bcf89b71525705219763462274d6f5267720c945b8396dbc9a396c55
Deleted: sha256:deb0652264aaa9727c30b62fcac1b88b9fdde5f1bd2e770e5531c180c2c42938
Deleted: sha256:2b90ad90efb33a55e0b5d3d8c49dcc1f40fda6f80ce1ba06c7146fcc3aba4a88
11-19-18_06:31:05 - Running <https://builds.apache.org/job/Mesos-Reviewbot/ws/support/verify-reviews.py>
Checking if review 69368 needs verification
Skipping blocking review 69368
Checking if review 69384 needs verification
Skipping blocking review 69384
Checking if review 69385 needs verification
Skipping blocking review 69385
Checking if review 69369 needs verification
Skipping blocking review 69369
Checking if review 69386 needs verification
Patch never verified, needs verification
Dependent review: https://reviews.apache.org/api/review-requests/69385/
Dependent review: https://reviews.apache.org/api/review-requests/69384/
Dependent review: https://reviews.apache.org/api/review-requests/69369/
Dependent review: https://reviews.apache.org/api/review-requests/69368/
Verifying review 69386
Dependent review: https://reviews.apache.org/api/review-requests/69385/
Dependent review: https://reviews.apache.org/api/review-requests/69384/
Dependent review: https://reviews.apache.org/api/review-requests/69369/
Dependent review: https://reviews.apache.org/api/review-requests/69368/
Applying review 69368
/usr/bin/python3 support/apply-reviews.py -n -r 69368
Applying review 69369
/usr/bin/python3 support/apply-reviews.py -n -r 69369
Applying review 69384
/usr/bin/python3 support/apply-reviews.py -n -r 69384
Applying review 69385
/usr/bin/python3 support/apply-reviews.py -n -r 69385
Applying review 69386
/usr/bin/python3 support/apply-reviews.py -n -r 69386
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_69386"]' 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 #23562
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Reviewbot/23562/display/redirect?page=changes>
Build failed in Jenkins: Mesos-Reviewbot #23561
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Reviewbot/23561/display/redirect?page=changes>
Changes:
[zhq527725] Added a test `ROOT_UNPRIVILEGED_USER_SandboxOwnership`.
------------------------------------------
[...truncated 43.75 MB...]
I1119 15:44:24.353307 19921 slave.cpp:1452] Successfully authenticated with master master@172.17.0.2:46554
I1119 15:44:24.353644 19921 slave.cpp:1883] Will retry registration in 8.939004ms if necessary
I1119 15:44:24.353790 19945 master.cpp:6650] Received register agent message from slave@172.17.0.2:46554 (e901eb9fb0ec)
I1119 15:44:24.354034 19945 master.cpp:3986] Authorizing agent providing resources 'cpus:2; mem:1024; disk:1024; ports:[31000-32000]' with principal 'test-principal'
I1119 15:44:24.354626 19927 master.cpp:6717] Authorized registration of agent at slave@172.17.0.2:46554 (e901eb9fb0ec)
I1119 15:44:24.354724 19927 master.cpp:6832] Registering agent at slave@172.17.0.2:46554 (e901eb9fb0ec) with id 95d3e960-4674-48d7-9030-654092a73707-S0
I1119 15:44:24.355286 19963 registrar.cpp:487] Applied 1 operations in 159249ns; attempting to update the registry
I1119 15:44:24.355912 19963 registrar.cpp:544] Successfully updated the registry in 571904ns
I1119 15:44:24.356061 19925 master.cpp:6880] Admitted agent 95d3e960-4674-48d7-9030-654092a73707-S0 at slave@172.17.0.2:46554 (e901eb9fb0ec)
I1119 15:44:24.356674 19925 master.cpp:6925] Registered agent 95d3e960-4674-48d7-9030-654092a73707-S0 at slave@172.17.0.2:46554 (e901eb9fb0ec) with cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I1119 15:44:24.356719 19939 slave.cpp:1485] Registered with master master@172.17.0.2:46554; given agent ID 95d3e960-4674-48d7-9030-654092a73707-S0
I1119 15:44:24.356820 19953 task_status_update_manager.cpp:188] Resuming sending task status updates
I1119 15:44:24.357000 19939 slave.cpp:1505] Checkpointing SlaveInfo to '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_qUGz4x/meta/slaves/95d3e960-4674-48d7-9030-654092a73707-S0/slave.info'
I1119 15:44:24.357012 19917 hierarchical.cpp:603] Added agent 95d3e960-4674-48d7-9030-654092a73707-S0 (e901eb9fb0ec) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] (allocated: {})
I1119 15:44:24.357875 19939 slave.cpp:1554] Forwarding agent update {"operations":{},"resource_version_uuid":{"value":"8eAB2zE+RYO35zHmSGZn0Q=="},"slave_id":{"value":"95d3e960-4674-48d7-9030-654092a73707-S0"},"update_oversubscribed_resources":false}
I1119 15:44:24.358319 19917 hierarchical.cpp:1566] Performed allocation for 1 agents in 1.158842ms
I1119 15:44:24.358371 19957 master.cpp:7984] Ignoring update on agent 95d3e960-4674-48d7-9030-654092a73707-S0 at slave@172.17.0.2:46554 (e901eb9fb0ec) as it reports no changes
I1119 15:44:24.358844 19957 master.cpp:9514] Sending offers [ 95d3e960-4674-48d7-9030-654092a73707-O0 ] to framework 95d3e960-4674-48d7-9030-654092a73707-0000 (default) at scheduler-55dc659b-d033-42a1-be7d-9a1342ce1a25@172.17.0.2:46554
I1119 15:44:24.359350 19943 sched.cpp:914] Scheduler::resourceOffers took 103198ns
I1119 15:44:24.361027 19919 master.cpp:11513] Removing offer 95d3e960-4674-48d7-9030-654092a73707-O0
I1119 15:44:24.361343 19919 master.cpp:4511] Processing ACCEPT call for offers: [ 95d3e960-4674-48d7-9030-654092a73707-O0 ] on agent 95d3e960-4674-48d7-9030-654092a73707-S0 at slave@172.17.0.2:46554 (e901eb9fb0ec) for framework 95d3e960-4674-48d7-9030-654092a73707-0000 (default) at scheduler-55dc659b-d033-42a1-be7d-9a1342ce1a25@172.17.0.2:46554
I1119 15:44:24.361434 19919 master.cpp:3563] Authorizing framework principal 'test-principal' to launch task f4f2dabf-558b-49a3-bc28-161b966f1d02
I1119 15:44:24.363277 19963 master.cpp:4088] Adding task f4f2dabf-558b-49a3-bc28-161b966f1d02 with resources cpus(allocated: *):2; mem(allocated: *):1024; disk(allocated: *):1024; ports(allocated: *):[31000-32000] of framework 95d3e960-4674-48d7-9030-654092a73707-0000 (default) at scheduler-55dc659b-d033-42a1-be7d-9a1342ce1a25@172.17.0.2:46554 on agent 95d3e960-4674-48d7-9030-654092a73707-S0 at slave@172.17.0.2:46554 (e901eb9fb0ec)
I1119 15:44:24.363823 19963 master.cpp:5483] Launching task f4f2dabf-558b-49a3-bc28-161b966f1d02 of framework 95d3e960-4674-48d7-9030-654092a73707-0000 (default) at scheduler-55dc659b-d033-42a1-be7d-9a1342ce1a25@172.17.0.2:46554 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 95d3e960-4674-48d7-9030-654092a73707-S0 at slave@172.17.0.2:46554 (e901eb9fb0ec) on new executor
I1119 15:44:24.364943 19933 slave.cpp:2020] Got assigned task 'f4f2dabf-558b-49a3-bc28-161b966f1d02' for framework 95d3e960-4674-48d7-9030-654092a73707-0000
I1119 15:44:24.365135 19933 slave.cpp:8914] Checkpointing FrameworkInfo to '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_qUGz4x/meta/slaves/95d3e960-4674-48d7-9030-654092a73707-S0/frameworks/95d3e960-4674-48d7-9030-654092a73707-0000/framework.info'
I1119 15:44:24.365509 19933 slave.cpp:8925] Checkpointing framework pid 'scheduler-55dc659b-d033-42a1-be7d-9a1342ce1a25@172.17.0.2:46554' to '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_qUGz4x/meta/slaves/95d3e960-4674-48d7-9030-654092a73707-S0/frameworks/95d3e960-4674-48d7-9030-654092a73707-0000/framework.pid'
I1119 15:44:24.366806 19933 slave.cpp:2394] Authorizing task 'f4f2dabf-558b-49a3-bc28-161b966f1d02' for framework 95d3e960-4674-48d7-9030-654092a73707-0000
I1119 15:44:24.366865 19933 slave.cpp:8472] Authorizing framework principal 'test-principal' to launch task f4f2dabf-558b-49a3-bc28-161b966f1d02
I1119 15:44:24.368482 19949 slave.cpp:2837] Launching task 'f4f2dabf-558b-49a3-bc28-161b966f1d02' for framework 95d3e960-4674-48d7-9030-654092a73707-0000
I1119 15:44:24.368559 19949 paths.cpp:752] Creating sandbox '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_qUGz4x/slaves/95d3e960-4674-48d7-9030-654092a73707-S0/frameworks/95d3e960-4674-48d7-9030-654092a73707-0000/executors/f4f2dabf-558b-49a3-bc28-161b966f1d02/runs/f7509f00-9d97-4ff5-80b8-88f048600fbe' for user 'mesos'
I1119 15:44:24.369153 19949 slave.cpp:9700] Checkpointing ExecutorInfo to '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_qUGz4x/meta/slaves/95d3e960-4674-48d7-9030-654092a73707-S0/frameworks/95d3e960-4674-48d7-9030-654092a73707-0000/executors/f4f2dabf-558b-49a3-bc28-161b966f1d02/executor.info'
I1119 15:44:24.369670 19949 paths.cpp:755] Creating sandbox '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_qUGz4x/meta/slaves/95d3e960-4674-48d7-9030-654092a73707-S0/frameworks/95d3e960-4674-48d7-9030-654092a73707-0000/executors/f4f2dabf-558b-49a3-bc28-161b966f1d02/runs/f7509f00-9d97-4ff5-80b8-88f048600fbe'
I1119 15:44:24.369936 19949 slave.cpp:9000] Launching executor 'f4f2dabf-558b-49a3-bc28-161b966f1d02' of framework 95d3e960-4674-48d7-9030-654092a73707-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_qUGz4x/slaves/95d3e960-4674-48d7-9030-654092a73707-S0/frameworks/95d3e960-4674-48d7-9030-654092a73707-0000/executors/f4f2dabf-558b-49a3-bc28-161b966f1d02/runs/f7509f00-9d97-4ff5-80b8-88f048600fbe'
I1119 15:44:24.370627 19949 slave.cpp:3515] Launching container f7509f00-9d97-4ff5-80b8-88f048600fbe for executor 'f4f2dabf-558b-49a3-bc28-161b966f1d02' of framework 95d3e960-4674-48d7-9030-654092a73707-0000
I1119 15:44:24.371124 19949 slave.cpp:9731] Checkpointing TaskInfo to '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_qUGz4x/meta/slaves/95d3e960-4674-48d7-9030-654092a73707-S0/frameworks/95d3e960-4674-48d7-9030-654092a73707-0000/executors/f4f2dabf-558b-49a3-bc28-161b966f1d02/runs/f7509f00-9d97-4ff5-80b8-88f048600fbe/tasks/f4f2dabf-558b-49a3-bc28-161b966f1d02/task.info'
I1119 15:44:24.371753 19949 slave.cpp:3034] Queued task 'f4f2dabf-558b-49a3-bc28-161b966f1d02' for executor 'f4f2dabf-558b-49a3-bc28-161b966f1d02' of framework 95d3e960-4674-48d7-9030-654092a73707-0000
I1119 15:44:24.371840 19949 slave.cpp:994] Successfully attached '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_qUGz4x/slaves/95d3e960-4674-48d7-9030-654092a73707-S0/frameworks/95d3e960-4674-48d7-9030-654092a73707-0000/executors/f4f2dabf-558b-49a3-bc28-161b966f1d02/runs/f7509f00-9d97-4ff5-80b8-88f048600fbe' to virtual path '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_qUGz4x/slaves/95d3e960-4674-48d7-9030-654092a73707-S0/frameworks/95d3e960-4674-48d7-9030-654092a73707-0000/executors/f4f2dabf-558b-49a3-bc28-161b966f1d02/runs/latest'
I1119 15:44:24.371881 19949 slave.cpp:994] Successfully attached '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_qUGz4x/slaves/95d3e960-4674-48d7-9030-654092a73707-S0/frameworks/95d3e960-4674-48d7-9030-654092a73707-0000/executors/f4f2dabf-558b-49a3-bc28-161b966f1d02/runs/f7509f00-9d97-4ff5-80b8-88f048600fbe' to virtual path '/frameworks/95d3e960-4674-48d7-9030-654092a73707-0000/executors/f4f2dabf-558b-49a3-bc28-161b966f1d02/runs/latest'
I1119 15:44:24.371917 19949 slave.cpp:994] Successfully attached '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_qUGz4x/slaves/95d3e960-4674-48d7-9030-654092a73707-S0/frameworks/95d3e960-4674-48d7-9030-654092a73707-0000/executors/f4f2dabf-558b-49a3-bc28-161b966f1d02/runs/f7509f00-9d97-4ff5-80b8-88f048600fbe' to virtual path '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_qUGz4x/slaves/95d3e960-4674-48d7-9030-654092a73707-S0/frameworks/95d3e960-4674-48d7-9030-654092a73707-0000/executors/f4f2dabf-558b-49a3-bc28-161b966f1d02/runs/f7509f00-9d97-4ff5-80b8-88f048600fbe'
I1119 15:44:24.372424 19943 containerizer.cpp:1288] Starting container f7509f00-9d97-4ff5-80b8-88f048600fbe
I1119 15:44:24.373358 19943 containerizer.cpp:1454] Checkpointed ContainerConfig at '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_lUx3HI/containers/f7509f00-9d97-4ff5-80b8-88f048600fbe/config'
I1119 15:44:24.373389 19943 containerizer.cpp:3130] Transitioning the state of container f7509f00-9d97-4ff5-80b8-88f048600fbe from PROVISIONING to PREPARING
I1119 15:44:24.377677 19919 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:46554"},{"name":"MESOS_CHECKPOINT","type":"VALUE","value":"1"},{"name":"MESOS_DIRECTORY","type":"VALUE","value":"/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_qUGz4x/slaves/95d3e960-4674-48d7-9030-654092a73707-S0/frameworks/95d3e960-4674-48d7-9030-654092a73707-0000/executors/f4f2dabf-558b-49a3-bc28-161b966f1d02/runs/f7509f00-9d97-4ff5-80b8-88f048600fbe"},{"name":"MESOS_EXECUTOR_ID","type":"VALUE","value":"f4f2dabf-558b-49a3-bc28-161b966f1d02"},{"name":"MESOS_EXECUTOR_SHUTDOWN_GRACE_PERIOD","type":"VALUE","value":"5secs"},{"name":"MESOS_FRAMEWORK_ID","type":"VALUE","value":"95d3e960-4674-48d7-9030-654092a73707-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":"95d3e960-4674-48d7-9030-654092a73707-S0"},{"name":"MESOS_SLAVE_PID","type":"VALUE","value":"slave@172.17.0.2:46554"},{"name":"MESOS_SUBSCRIPTION_BACKOFF_MAX","type":"VALUE","value":"15secs"},{"name":"MESOS_SANDBOX","type":"VALUE","value":"/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_qUGz4x/slaves/95d3e960-4674-48d7-9030-654092a73707-S0/frameworks/95d3e960-4674-48d7-9030-654092a73707-0000/executors/f4f2dabf-558b-49a3-bc28-161b966f1d02/runs/f7509f00-9d97-4ff5-80b8-88f048600fbe"}]},"task_environment":{},"user":"mesos","working_directory":"/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_qUGz4x/slaves/95d3e960-4674-48d7-9030-654092a73707-S0/frameworks/95d3e960-4674-48d7-9030-654092a73707-0000/executors/f4f2dabf-558b-49a3-bc28-161b966f1d02/runs/f7509f00-9d97-4ff5-80b8-88f048600fbe"}" --pipe_read="24" --pipe_write="26" --runtime_directory="/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_lUx3HI/containers/f7509f00-9d97-4ff5-80b8-88f048600fbe" --unshare_namespace_mnt="false"'
I1119 15:44:24.382684 19919 launcher.cpp:145] Forked child with pid '27720' for container 'f7509f00-9d97-4ff5-80b8-88f048600fbe'
I1119 15:44:24.382804 19919 containerizer.cpp:2052] Checkpointing container's forked pid 27720 to '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_qUGz4x/meta/slaves/95d3e960-4674-48d7-9030-654092a73707-S0/frameworks/95d3e960-4674-48d7-9030-654092a73707-0000/executors/f4f2dabf-558b-49a3-bc28-161b966f1d02/runs/f7509f00-9d97-4ff5-80b8-88f048600fbe/pids/forked.pid'
I1119 15:44:24.383575 19919 containerizer.cpp:3130] Transitioning the state of container f7509f00-9d97-4ff5-80b8-88f048600fbe from PREPARING to ISOLATING
I1119 15:44:24.385152 19921 containerizer.cpp:3130] Transitioning the state of container f7509f00-9d97-4ff5-80b8-88f048600fbe from ISOLATING to FETCHING
I1119 15:44:24.385391 19961 fetcher.cpp:369] Starting to fetch URIs for container: f7509f00-9d97-4ff5-80b8-88f048600fbe, directory: /tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_qUGz4x/slaves/95d3e960-4674-48d7-9030-654092a73707-S0/frameworks/95d3e960-4674-48d7-9030-654092a73707-0000/executors/f4f2dabf-558b-49a3-bc28-161b966f1d02/runs/f7509f00-9d97-4ff5-80b8-88f048600fbe
I1119 15:44:24.386524 19927 containerizer.cpp:3130] Transitioning the state of container f7509f00-9d97-4ff5-80b8-88f048600fbe from FETCHING to RUNNING
I1119 15:44:24.856842 27757 exec.cpp:162] Version: 1.8.0
I1119 15:44:24.867075 19947 slave.cpp:4809] Got registration for executor 'f4f2dabf-558b-49a3-bc28-161b966f1d02' of framework 95d3e960-4674-48d7-9030-654092a73707-0000 from executor(1)@172.17.0.2:38160
I1119 15:44:24.867661 19947 slave.cpp:4895] Checkpointing executor pid 'executor(1)@172.17.0.2:38160' to '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_qUGz4x/meta/slaves/95d3e960-4674-48d7-9030-654092a73707-S0/frameworks/95d3e960-4674-48d7-9030-654092a73707-0000/executors/f4f2dabf-558b-49a3-bc28-161b966f1d02/runs/f7509f00-9d97-4ff5-80b8-88f048600fbe/pids/libprocess.pid'
I1119 15:44:24.870671 19935 slave.cpp:3247] Sending queued task 'f4f2dabf-558b-49a3-bc28-161b966f1d02' to executor 'f4f2dabf-558b-49a3-bc28-161b966f1d02' of framework 95d3e960-4674-48d7-9030-654092a73707-0000 at executor(1)@172.17.0.2:38160
I1119 15:44:24.872539 27773 exec.cpp:236] Executor registered on agent 95d3e960-4674-48d7-9030-654092a73707-S0
I1119 15:44:24.876579 27734 executor.cpp:184] Received SUBSCRIBED event
I1119 15:44:24.878105 27734 executor.cpp:188] Subscribed executor on e901eb9fb0ec
I1119 15:44:24.878307 27734 executor.cpp:184] Received LAUNCH event
I1119 15:44:24.880702 27734 executor.cpp:687] Starting task f4f2dabf-558b-49a3-bc28-161b966f1d02
I1119 15:44:24.885262 19951 slave.cpp:5275] Handling status update TASK_STARTING (Status UUID: 2e0dbddd-9cd3-4614-9468-733b6261ca25) for task f4f2dabf-558b-49a3-bc28-161b966f1d02 of framework 95d3e960-4674-48d7-9030-654092a73707-0000 from executor(1)@172.17.0.2:38160
I1119 15:44:24.887243 19945 task_status_update_manager.cpp:328] Received task status update TASK_STARTING (Status UUID: 2e0dbddd-9cd3-4614-9468-733b6261ca25) for task f4f2dabf-558b-49a3-bc28-161b966f1d02 of framework 95d3e960-4674-48d7-9030-654092a73707-0000
I1119 15:44:24.887313 19945 task_status_update_manager.cpp:507] Creating StatusUpdate stream for task f4f2dabf-558b-49a3-bc28-161b966f1d02 of framework 95d3e960-4674-48d7-9030-654092a73707-0000
I1119 15:44:24.888178 19945 task_status_update_manager.cpp:842] Checkpointing UPDATE for task status update TASK_STARTING (Status UUID: 2e0dbddd-9cd3-4614-9468-733b6261ca25) for task f4f2dabf-558b-49a3-bc28-161b966f1d02 of framework 95d3e960-4674-48d7-9030-654092a73707-0000
I1119 15:44:24.888424 19945 task_status_update_manager.cpp:383] Forwarding task status update TASK_STARTING (Status UUID: 2e0dbddd-9cd3-4614-9468-733b6261ca25) for task f4f2dabf-558b-49a3-bc28-161b966f1d02 of framework 95d3e960-4674-48d7-9030-654092a73707-0000 to the agent
I1119 15:44:24.888640 19937 slave.cpp:5767] Forwarding the update TASK_STARTING (Status UUID: 2e0dbddd-9cd3-4614-9468-733b6261ca25) for task f4f2dabf-558b-49a3-bc28-161b966f1d02 of framework 95d3e960-4674-48d7-9030-654092a73707-0000 to master@172.17.0.2:46554
I1119 15:44:24.888921 19937 slave.cpp:5660] Task status update manager successfully handled status update TASK_STARTING (Status UUID: 2e0dbddd-9cd3-4614-9468-733b6261ca25) for task f4f2dabf-558b-49a3-bc28-161b966f1d02 of framework 95d3e960-4674-48d7-9030-654092a73707-0000
I1119 15:44:24.888989 19937 slave.cpp:5676] Sending acknowledgement for status update TASK_STARTING (Status UUID: 2e0dbddd-9cd3-4614-9468-733b6261ca25) for task f4f2dabf-558b-49a3-bc28-161b966f1d02 of framework 95d3e960-4674-48d7-9030-654092a73707-0000 to executor(1)@172.17.0.2:38160
I1119 15:44:24.889093 19957 master.cpp:8420] Status update TASK_STARTING (Status UUID: 2e0dbddd-9cd3-4614-9468-733b6261ca25) for task f4f2dabf-558b-49a3-bc28-161b966f1d02 of framework 95d3e960-4674-48d7-9030-654092a73707-0000 from agent 95d3e960-4674-48d7-9030-654092a73707-S0 at slave@172.17.0.2:46554 (e901eb9fb0ec)
I1119 15:44:24.889163 19957 master.cpp:8477] Forwarding status update TASK_STARTING (Status UUID: 2e0dbddd-9cd3-4614-9468-733b6261ca25) for task f4f2dabf-558b-49a3-bc28-161b966f1d02 of framework 95d3e960-4674-48d7-9030-654092a73707-0000
I1119 15:44:24.889443 19957 master.cpp:10978] Updating the state of task f4f2dabf-558b-49a3-bc28-161b966f1d02 of framework 95d3e960-4674-48d7-9030-654092a73707-0000 (latest state: TASK_STARTING, status update state: TASK_STARTING)
I1119 15:44:24.889897 19957 sched.cpp:1022] Scheduler::statusUpdate took 138181ns
I1119 15:44:24.890863 19935 master.cpp:6286] Processing ACKNOWLEDGE call for status 2e0dbddd-9cd3-4614-9468-733b6261ca25 for task f4f2dabf-558b-49a3-bc28-161b966f1d02 of framework 95d3e960-4674-48d7-9030-654092a73707-0000 (default) at scheduler-55dc659b-d033-42a1-be7d-9a1342ce1a25@172.17.0.2:46554 on agent 95d3e960-4674-48d7-9030-654092a73707-S0
I1119 15:44:24.891264 19953 task_status_update_manager.cpp:401] Received task status update acknowledgement (UUID: 2e0dbddd-9cd3-4614-9468-733b6261ca25) for task f4f2dabf-558b-49a3-bc28-161b966f1d02 of framework 95d3e960-4674-48d7-9030-654092a73707-0000
I1119 15:44:24.891384 19953 task_status_update_manager.cpp:842] Checkpointing ACK for task status update TASK_STARTING (Status UUID: 2e0dbddd-9cd3-4614-9468-733b6261ca25) for task f4f2dabf-558b-49a3-bc28-161b966f1d02 of framework 95d3e960-4674-48d7-9030-654092a73707-0000
I1119 15:44:24.891629 19925 slave.cpp:4511] Task status update manager successfully handled status update acknowledgement (UUID: 2e0dbddd-9cd3-4614-9468-733b6261ca25) for task f4f2dabf-558b-49a3-bc28-161b966f1d02 of framework 95d3e960-4674-48d7-9030-654092a73707-0000
I1119 15:44:24.902729 27734 executor.cpp:502] Running '/mesos/mesos-1.8.0/_build/src/mesos-containerizer launch <POSSIBLY-SENSITIVE-DATA>'
I1119 15:44:24.906530 27734 executor.cpp:702] Forked command at 27782
I1119 15:44:24.911370 19955 slave.cpp:5275] Handling status update TASK_RUNNING (Status UUID: df09cae8-be4c-497b-832b-aabe9b8a843d) for task f4f2dabf-558b-49a3-bc28-161b966f1d02 of framework 95d3e960-4674-48d7-9030-654092a73707-0000 from executor(1)@172.17.0.2:38160
I1119 15:44:24.913535 19923 task_status_update_manager.cpp:328] Received task status update TASK_RUNNING (Status UUID: df09cae8-be4c-497b-832b-aabe9b8a843d) for task f4f2dabf-558b-49a3-bc28-161b966f1d02 of framework 95d3e960-4674-48d7-9030-654092a73707-0000
I1119 15:44:24.913631 19923 task_status_update_manager.cpp:842] Checkpointing UPDATE for task status update TASK_RUNNING (Status UUID: df09cae8-be4c-497b-832b-aabe9b8a843d) for task f4f2dabf-558b-49a3-bc28-161b966f1d02 of framework 95d3e960-4674-48d7-9030-654092a73707-0000
I1119 15:44:24.913904 19923 task_status_update_manager.cpp:383] Forwarding task status update TASK_RUNNING (Status UUID: df09cae8-be4c-497b-832b-aabe9b8a843d) for task f4f2dabf-558b-49a3-bc28-161b966f1d02 of framework 95d3e960-4674-48d7-9030-654092a73707-0000 to the agent
I1119 15:44:24.914085 19951 slave.cpp:5767] Forwarding the update TASK_RUNNING (Status UUID: df09cae8-be4c-497b-832b-aabe9b8a843d) for task f4f2dabf-558b-49a3-bc28-161b966f1d02 of framework 95d3e960-4674-48d7-9030-654092a73707-0000 to master@172.17.0.2:46554
I1119 15:44:24.914273 19951 slave.cpp:5660] Task status update manager successfully handled status update TASK_RUNNING (Status UUID: df09cae8-be4c-497b-832b-aabe9b8a843d) for task f4f2dabf-558b-49a3-bc28-161b966f1d02 of framework 95d3e960-4674-48d7-9030-654092a73707-0000
I1119 15:44:24.914325 19951 slave.cpp:5676] Sending acknowledgement for status update TASK_RUNNING (Status UUID: df09cae8-be4c-497b-832b-aabe9b8a843d) for task f4f2dabf-558b-49a3-bc28-161b966f1d02 of framework 95d3e960-4674-48d7-9030-654092a73707-0000 to executor(1)@172.17.0.2:38160
I1119 15:44:24.914489 19929 master.cpp:8420] Status update TASK_RUNNING (Status UUID: df09cae8-be4c-497b-832b-aabe9b8a843d) for task f4f2dabf-558b-49a3-bc28-161b966f1d02 of framework 95d3e960-4674-48d7-9030-654092a73707-0000 from agent 95d3e960-4674-48d7-9030-654092a73707-S0 at slave@172.17.0.2:46554 (e901eb9fb0ec)
I1119 15:44:24.914569 19929 master.cpp:8477] Forwarding status update TASK_RUNNING (Status UUID: df09cae8-be4c-497b-832b-aabe9b8a843d) for task f4f2dabf-558b-49a3-bc28-161b966f1d02 of framework 95d3e960-4674-48d7-9030-654092a73707-0000
I1119 15:44:24.914875 19929 master.cpp:10978] Updating the state of task f4f2dabf-558b-49a3-bc28-161b966f1d02 of framework 95d3e960-4674-48d7-9030-654092a73707-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING)
I1119 15:44:24.915185 19951 sched.cpp:1022] Scheduler::statusUpdate took 128684ns
I1119 15:44:24.915984 19941 master.cpp:6286] Processing ACKNOWLEDGE call for status df09cae8-be4c-497b-832b-aabe9b8a843d for task f4f2dabf-558b-49a3-bc28-161b966f1d02 of framework 95d3e960-4674-48d7-9030-654092a73707-0000 (default) at scheduler-55dc659b-d033-42a1-be7d-9a1342ce1a25@172.17.0.2:46554 on agent 95d3e960-4674-48d7-9030-654092a73707-S0
I1119 15:44:24.916412 19917 task_status_update_manager.cpp:401] Received task status update acknowledgement (UUID: df09cae8-be4c-497b-832b-aabe9b8a843d) for task f4f2dabf-558b-49a3-bc28-161b966f1d02 of framework 95d3e960-4674-48d7-9030-654092a73707-0000
I1119 15:44:24.916538 19917 task_status_update_manager.cpp:842] Checkpointing ACK for task status update TASK_RUNNING (Status UUID: df09cae8-be4c-497b-832b-aabe9b8a843d) for task f4f2dabf-558b-49a3-bc28-161b966f1d02 of framework 95d3e960-4674-48d7-9030-654092a73707-0000
I1119 15:44:24.916826 19917 slave.cpp:4511] Task status update manager successfully handled status update acknowledgement (UUID: df09cae8-be4c-497b-832b-aabe9b8a843d) for task f4f2dabf-558b-49a3-bc28-161b966f1d02 of framework 95d3e960-4674-48d7-9030-654092a73707-0000
I1119 15:44:24.917057 19917 slave.cpp:915] Agent terminating
I1119 15:44:25.323453 19927 hierarchical.cpp:1566] Performed allocation for 1 agents in 389455ns
I1119 15:44:25.723352 19959 master.cpp:1273] Agent 95d3e960-4674-48d7-9030-654092a73707-S0 at slave@172.17.0.2:46554 (e901eb9fb0ec) disconnected
I1119 15:44:25.723394 19959 master.cpp:3289] Disconnecting agent 95d3e960-4674-48d7-9030-654092a73707-S0 at slave@172.17.0.2:46554 (e901eb9fb0ec)
I1119 15:44:25.723456 19959 master.cpp:3308] Deactivating agent 95d3e960-4674-48d7-9030-654092a73707-S0 at slave@172.17.0.2:46554 (e901eb9fb0ec)
I1119 15:44:25.723714 19531 containerizer.cpp:305] Using isolation { environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni }
I1119 15:44:25.723757 19959 hierarchical.cpp:801] Agent 95d3e960-4674-48d7-9030-654092a73707-S0 deactivated
W1119 15:44:25.724490 19531 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W1119 15:44:25.724689 19531 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I1119 15:44:25.724733 19531 provisioner.cpp:298] Using default backend 'copy'
W1119 15:44:25.734989 19531 process.cpp:2829] Attempted to spawn already running process files@172.17.0.2:46554
I1119 15:44:25.735674 19531 cluster.cpp:485] Creating default 'local' authorizer
I1119 15:44:25.737932 19959 slave.cpp:267] Mesos agent started on @172.17.0.2:46554
I1119 15:44:25.737960 19959 slave.cpp:268] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_lUx3HI/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_lUx3HI/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_lUx3HI/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_lUx3HI/fetch" --fetcher_cache_size="2GB" --fetcher_stall_timeout="1mins" --frameworks_home="/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_lUx3HI/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_lUx3HI/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_lUx3HI" --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_qUGz4x" --zk_session_timeout="10secs"
I1119 15:44:25.738644 19959 credentials.hpp:86] Loading credential for authentication from '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_lUx3HI/credential'
I1119 15:44:25.738863 19959 slave.cpp:300] Agent using credential for: test-principal
I1119 15:44:25.738898 19959 credentials.hpp:37] Loading credentials for authentication from '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_lUx3HI/http_credentials'
I1119 15:44:25.739208 19959 http.cpp:1042] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I1119 15:44:25.739637 19959 disk_profile_adaptor.cpp:80] Creating default disk profile adaptor module
I1119 15:44:25.741591 19959 slave.cpp:615] 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"}]
I1119 15:44:25.741927 19959 slave.cpp:623] Agent attributes: [ ]
I1119 15:44:25.741987 19959 slave.cpp:632] Agent hostname: e901eb9fb0ec
I1119 15:44:25.742403 19937 task_status_update_manager.cpp:181] Pausing sending task status updates
I1119 15:44:25.744289 19929 state.cpp:66] Recovering state from '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_qUGz4x/meta'
I1119 15:44:25.744385 19929 state.cpp:711] No committed checkpointed resources found at '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_qUGz4x/meta/resources/resources.info'
I1119 15:44:25.749754 19953 slave.cpp:6915] Finished recovering checkpointed state from '/tmp/SlaveRecoveryTest_0_PingTimeoutDuringRecovery_qUGz4x/meta', beginning agent recovery
I1119 15:44:25.750535 19953 slave.cpp:7394] Recovering framework 95d3e960-4674-48d7-9030-654092a73707-0000
I1119 15:44:25.750739 19953 slave.cpp:9118] Recovering executor 'f4f2dabf-558b-49a3-bc28-161b966f1d02' of framework 95d3e960-4674-48d7-9030-654092a73707-0000
I1119 15:44:25.751700 19963 task_status_update_manager.cpp:207] Recovering task status update manager
I1119 15:44:25.751780 19963 task_status_update_manager.cpp:215] Recovering executor 'f4f2dabf-558b-49a3-bc28-161b966f1d02' of framework 95d3e960-4674-48d7-9030-654092a73707-0000
I1119 15:44:25.751947 19963 task_status_update_manager.cpp:507] Creating StatusUpdate stream for task f4f2dabf-558b-49a3-bc28-161b966f1d02 of framework 95d3e960-4674-48d7-9030-654092a73707-0000
I1119 15:44:25.752720 19963 task_status_update_manager.cpp:818] Replaying task status update stream for task f4f2dabf-558b-49a3-bc28-161b966f1d02
I1119 15:44:25.753597 19931 containerizer.cpp:727] Recovering Mesos containers
I1119 15:44:25.753702 19931 containerizer.cpp:784] Recovering container f7509f00-9d97-4ff5-80b8-88f048600fbe for executor 'f4f2dabf-558b-49a3-bc28-161b966f1d02' of framework 95d3e960-4674-48d7-9030-654092a73707-0000
I1119 15:44:25.755798 19931 containerizer.cpp:1053] Recovering isolators
I1119 15:44:25.757019 19935 containerizer.cpp:1092] Recovering provisioner
I1119 15:44:25.757886 19947 provisioner.cpp:494] Provisioner recovery complete
I1119 15:44:25.759780 19957 composing.cpp:339] Finished recovering all containerizers
I1119 15:44:25.759922 19961 slave.cpp:7144] Recovering executors
I1119 15:44:25.760089 19961 slave.cpp:7168] Sending reconnect request to executor 'f4f2dabf-558b-49a3-bc28-161b966f1d02' of framework 95d3e960-4674-48d7-9030-654092a73707-0000 at executor(1)@172.17.0.2:38160
I1119 15:44:25.761785 27771 exec.cpp:282] Received reconnect request from agent 95d3e960-4674-48d7-9030-654092a73707-S0
I1119 15:44:25.764816 19935 slave.cpp:4972] Received re-registration message from executor 'f4f2dabf-558b-49a3-bc28-161b966f1d02' of framework 95d3e960-4674-48d7-9030-654092a73707-0000
I1119 15:44:25.766372 27777 exec.cpp:259] Executor reregistered on agent 95d3e960-4674-48d7-9030-654092a73707-S0
I1119 15:44:25.767066 19957 slave.cpp:5889] No pings from master received within 75secs
I1119 15:44:25.767388 19925 hierarchical.cpp:1566] Performed allocation for 1 agents in 297993ns
F1119 15:44:25.767621 19957 slave.cpp:1233] Check failed: state == DISCONNECTED || state == RUNNING || state == TERMINATING RECOVERING
*** Check failure stack trace: ***
I1119 15:44:25.769093 27743 executor.cpp:184] Received SUBSCRIBED event
I1119 15:44:25.769126 27743 executor.cpp:188] Subscribed executor on e901eb9fb0ec
@ 0x2b65681bf3c0 google::LogMessage::Fail()
@ 0x2b65681bf30c google::LogMessage::SendToLog()
@ 0x2b65681bed0e google::LogMessage::Flush()
@ 0x2b65681c1c22 google::LogMessageFatal::~LogMessageFatal()
@ 0x2b65668f8a0e mesos::internal::slave::Slave::detected()
@ 0x2b6566991cae _ZZN7process8dispatchIN5mesos8internal5slave5SlaveERKNS_6FutureI6OptionINS1_10MasterInfoEEEESB_EEvRKNS_3PIDIT_EEMSD_FvT0_EOT1_ENKUlOS9_PNS_11ProcessBaseEE_clESM_SO_
@ 0x2b6566a4bee9 _ZN5cpp176invokeIZN7process8dispatchIN5mesos8internal5slave5SlaveERKNS1_6FutureI6OptionINS3_10MasterInfoEEEESD_EEvRKNS1_3PIDIT_EEMSF_FvT0_EOT1_EUlOSB_PNS1_11ProcessBaseEE_ISB_SQ_EEEDTclcl7forwardISF_Efp_Espcl7forwardIT0_Efp0_EEEOSF_DpOSS_
@ 0x2b6566a4251d _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_
@ 0x2b6566a3bf19 _ZNO6lambda8internal7PartialIZN7process8dispatchIN5mesos8internal5slave5SlaveERKNS2_6FutureI6OptionINS4_10MasterInfoEEEESE_EEvRKNS2_3PIDIT_EEMSG_FvT0_EOT1_EUlOSC_PNS2_11ProcessBaseEE_JSC_St12_PlaceholderILi1EEEEclIJSR_EEEDTcl13invoke_expandcl4movedtdefpT1fEcl4movedtdefpT10bound_argsEcvN5cpp1416integer_sequenceImJLm0ELm1EEEE_Ecl16forward_as_tuplespcl7forwardIT_Efp_EEEEDpOS10_
@ 0x2b6566a388f5 _ZN5cpp176invokeIN6lambda8internal7PartialIZN7process8dispatchIN5mesos8internal5slave5SlaveERKNS4_6FutureI6OptionINS6_10MasterInfoEEEESG_EEvRKNS4_3PIDIT_EEMSI_FvT0_EOT1_EUlOSE_PNS4_11ProcessBaseEE_ISE_St12_PlaceholderILi1EEEEEIST_EEEDTclcl7forwardISI_Efp_Espcl7forwardIT0_Efp0_EEEOSI_DpOSY_
@ 0x2b6566a3698b _ZN6lambda8internal6InvokeIvEclINS0_7PartialIZN7process8dispatchIN5mesos8internal5slave5SlaveERKNS5_6FutureI6OptionINS7_10MasterInfoEEEESH_EEvRKNS5_3PIDIT_EEMSJ_FvT0_EOT1_EUlOSF_PNS5_11ProcessBaseEE_JSF_St12_PlaceholderILi1EEEEEJSU_EEEvOSJ_DpOT0_
@ 0x2b6566a34593 _ZNO6lambda12CallableOnceIFvPN7process11ProcessBaseEEE10CallableFnINS_8internal7PartialIZNS1_8dispatchIN5mesos8internal5slave5SlaveERKNS1_6FutureI6OptionINSA_10MasterInfoEEEESK_EEvRKNS1_3PIDIT_EEMSM_FvT0_EOT1_EUlOSI_S3_E_ISI_St12_PlaceholderILi1EEEEEEclEOS3_
@ 0x2b65680e0701 _ZNO6lambda12CallableOnceIFvPN7process11ProcessBaseEEEclES3_
@ 0x2b65680aa117 process::ProcessBase::consume()
@ 0x2b65680ce43a _ZNO7process13DispatchEvent7consumeEPNS_13EventConsumerE
@ 0x56022b798198 process::ProcessBase::serve()
@ 0x2b65680a73f4 process::ProcessManager::resume()
@ 0x2b65680a386e _ZZN7process14ProcessManager12init_threadsEvENKUlvE_clEv
@ 0x2b65680c9286 _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEE9_M_invokeIIEEEvSt12_Index_tupleIIXspT_EEE
@ 0x2b65680c85a7 _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEEclEv
@ 0x2b65680c7766 _ZNSt6thread5_ImplISt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEEE6_M_runEv
@ 0x2b656af81a60 (unknown)
@ 0x2b656b6f8184 start_thread
@ 0x2b656ba0c03d (unknown)
I1119 15:44:26.543121 27751 exec.cpp:518] Agent exited, but framework has checkpointing enabled. Waiting 15mins to reconnect with agent 95d3e960-4674-48d7-9030-654092a73707-S0
I1119 15:59:26.544574 27751 exec.cpp:499] Recovery timeout of 15mins exceeded; Shutting down
I1119 15:59:26.544698 27751 exec.cpp:445] Executor asked to shutdown
I1119 15:59:26.545298 27761 executor.cpp:184] Received SHUTDOWN event
I1119 15:59:26.545372 27761 executor.cpp:805] Shutting down
I1119 15:59:26.545437 27761 executor.cpp:918] Sending SIGTERM to process tree at pid 27782
I1119 15:59:26.558255 27761 executor.cpp:931] Sent SIGTERM to the following process trees:
[
-+- 27782 sh -c sleep 1000
\--- 27786 sleep 1000
]
I1119 15:59:26.558297 27761 executor.cpp:935] Scheduling escalation to SIGKILL in 3secs from now
I1119 15:59:26.618515 27765 executor.cpp:1003] Command terminated with signal Terminated (pid: 27782)
W1119 15:59:26.623934 27781 process.cpp:1890] Failed to send 'mesos.internal.StatusUpdateMessage' to '172.17.0.2:46554', connect: Failed to connect to 172.17.0.2:46554: Connection refused
I1119 15:59:27.622740 27781 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-1542638078-11556
Untagged: mesos-1542638078-11556:latest
Deleted: sha256:3e62a93d8bd8e0390d169ebe28664d16b2423ff8ef9a644678ebac132106ec05
Deleted: sha256:13aa77ffe3ea407fcff78c6206efd74411cd8131bed8869598edcd198d523c16
Deleted: sha256:14cd83ade1329135cacd0cc7542edfd0ddd29704d57b7ce0e97eaf94133460fa
Deleted: sha256:bba099ac80a87c0832597a220c252d6c7511dd42860263dab6d9092df97e0220
Deleted: sha256:04331f379017cf06a11a69330dd7b8e3a0a72324e924b2bb84055380289f6f8a
Deleted: sha256:2fc3b5dc6775de62222fd14213b379e671135bd0d68f40225d922c10da0b0050
Deleted: sha256:ae40064621830d00ee2530a084843cb927c6e74edba2492f2b96558dee72e4b1
Deleted: sha256:cf2e848c7e520dc04deecf877a75a287d1bdd6d3959b752014d9d3108548ca9e
Deleted: sha256:1aebf45ba0dff21d9d8593f64ea5deeca535c2c710905a75bc67dfe77dd75cbb
Deleted: sha256:0af182d114d5e182f1d19246e2de550ad2ecff7a986f4f0bfc61812fb3f5510d
Deleted: sha256:dd94390ef0a3942d90d022358055eccb775b7c98548ce2d02835d8128a8b44c9
Deleted: sha256:199a4167dd2a620f4aac65da2316c4c2483ed69aca18450f9ccc50fd3b4a8c94
Deleted: sha256:4a8c0a841f35f13b7dfc1ecfd9cfeff2ee1941a8757e35a547d39555fcf0ee49
Deleted: sha256:63666c57fa9baaf3afea4b41dc50fb2520c1144b4a651414125ff3f760fa6bef
Deleted: sha256:c4cbfe06049a1d4397579fafe05c46c2614907dd660ac6502e1c9abdde3ce5a6
Deleted: sha256:4a235cfb35cb467e6ccb63696919fd7610a65b38dbb5f0a75df3c9ba5d3bf72a
Deleted: sha256:2aac16bf047f0a652cebf4679e656204f7333a5f6a36719411184b00d179f444
Deleted: sha256:27582d491330e2da219385442fb8ed697a56de21fd197656d1cf20d1d4566c79
Deleted: sha256:093135a7e1725209853d1fae848b565fb0b7e99ff6aa0061a75f9892957fe930
Deleted: sha256:76271609a45252d9c1e1f2281d5292d937efdb47344267674552a47657ef828a
Deleted: sha256:a33bbd2f0dded1c20b08f34e6cd1845bf3f79077957d340b82f23b9f44cb62fe
Deleted: sha256:7d846223efc132a02eba8e1c29717f34c6bd86226f39aa9a4929625612223733
Deleted: sha256:dea7570ae71756f366f6e2d40cad5b818a8ceb3512ebe6f00c5a725934af6631
Deleted: sha256:e28cfa4c8f64266d8591a2dc5288a8e01f65397ee8bff675a7c7be65387f7ee6
Deleted: sha256:a8002d0149d8acae6c69ae4672e74132d1ed12ae6694a0dc0cf7daa8b52ff547
Deleted: sha256:c99be03213859c74636cce5171ff2c5833dafab2a616f628a93941018c9176a4
Deleted: sha256:582e678f8bd922b14afc6f9187098fa95aca0c028afa8c73711e333cb3cf70cc
Deleted: sha256:811ee0c2e74cca081be863b2f5c06c3d6767b3b36bd1d04e5b69a924456b61da
Deleted: sha256:ca372c199ec9f05d89cf79ef42b4a517180f4bd92709e73cf9491ba78addedb5
Deleted: sha256:943b4945e21e55c566609b9e62f32440fed886973728ee02ac429031e337135a
11-19-18_14:34:36 - Running <https://builds.apache.org/job/Mesos-Reviewbot/ws/support/verify-reviews.py>
Checking if review 69377 needs verification
Patch never verified, needs verification
Verifying review 69377
Applying review 69377
/usr/bin/python3 support/apply-reviews.py -n -r 69377
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_69377"]' 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