CRI-O tests failing on kata-runtime

[GabyCT]

There has been some failures while running the tests on CRI-O
http://jenkins.katacontainers.io/job/kata-containers-tests-ubuntu-18-04-PR/1230/console
http://jenkins.katacontainers.io/job/kata-containers-packaging-ubuntu-18-04-PR/930/console

Here are the logs

Meta details

Running kata-collect-data.sh version 1.10.0-rc0 (commit d10adfdc0314efb4530f604c3c6fffe76d9aac14) at 2019-12-11.22:23:30.085295868+0000.

---

Runtime is /usr/local/bin/kata-runtime.

kata-env

Output of "/usr/local/bin/kata-runtime kata-env":

[Meta]
  Version = "1.0.23"

[Runtime]
  Debug = true
  Trace = false
  DisableGuestSeccomp = true
  DisableNewNetNs = false
  SandboxCgroupOnly = false
  Path = "/usr/local/bin/kata-runtime"
  [Runtime.Version]
    Semver = "1.10.0-rc0"
    Commit = "d10adfdc0314efb4530f604c3c6fffe76d9aac14"
    OCI = "1.0.1-dev"
  [Runtime.Config]
    Path = "/usr/share/defaults/kata-containers/configuration.toml"

[Hypervisor]
  MachineType = "pc"
  Version = "QEMU emulator version 4.1.0 (kata-static)\nCopyright (c) 2003-2019 Fabrice Bellard and the QEMU Project developers"
  Path = "/usr/bin/qemu-system-x86_64"
  BlockDeviceDriver = "virtio-scsi"
  EntropySource = "/dev/urandom"
  Msize9p = 8192
  MemorySlots = 10
  Debug = true
  UseVSock = false
  SharedFS = "virtio-9p"

[Image]
  Path = "/usr/share/kata-containers/kata-containers-clearlinux-31880-osbuilder-dbbf160-agent-6182fa3.img"

[Kernel]
  Path = "/usr/share/kata-containers/vmlinuz-4.19.86-60"
  Parameters = "systemd.unit=kata-containers.target systemd.mask=systemd-networkd.service systemd.mask=systemd-networkd.socket agent.log=debug agent.log=debug"

[Initrd]
  Path = ""

[Proxy]
  Type = "kataProxy"
  Version = "kata-proxy version 1.10.0-rc0-3776c0987e8ee27d01a26bdc08036c9807608b42"
  Path = "/usr/libexec/kata-containers/kata-proxy"
  Debug = true

[Shim]
  Type = "kataShim"
  Version = "kata-shim version 1.10.0-rc0-b5067286baa85f8019dffef591ba2e92597c1ecf"
  Path = "/usr/libexec/kata-containers/kata-shim"
  Debug = true

[Agent]
  Type = "kata"
  Debug = true
  Trace = false
  TraceMode = ""
  TraceType = ""

[Host]
  Kernel = "5.0.0-1027-azure"
  Architecture = "amd64"
  VMContainerCapable = true
  SupportVSocks = true
  [Host.Distro]
    Name = "Ubuntu"
    Version = "18.04"
  [Host.CPU]
    Vendor = "GenuineIntel"
    Model = "Intel(R) Xeon(R) CPU E5-2673 v4 @ 2.30GHz"

[Netmon]
  Version = "kata-netmon version 1.10.0-rc0"
  Path = "/usr/libexec/kata-containers/kata-netmon"
  Debug = true
  Enable = false

---

Runtime config files

Runtime default config files

/etc/kata-containers/configuration.toml
/usr/share/defaults/kata-containers/configuration.toml

Runtime config file contents

Config file /etc/kata-containers/configuration.toml not found
Output of "cat "/usr/share/defaults/kata-containers/configuration.toml"":

# Copyright (c) 2017-2019 Intel Corporation
#
# SPDX-License-Identifier: Apache-2.0
#

# XXX: WARNING: this file is auto-generated.
# XXX:
# XXX: Source file: "cli/config/configuration-qemu.toml.in"
# XXX: Project:
# XXX:   Name: Kata Containers
# XXX:   Type: kata

[hypervisor.qemu]
path = "/usr/bin/qemu-system-x86_64"
kernel = "/usr/share/kata-containers/vmlinuz.container"
image = "/usr/share/kata-containers/kata-containers.img"
machine_type = "pc"

# Optional space-separated list of options to pass to the guest kernel.
# For example, use `kernel_params = "vsyscall=emulate"` if you are having
# trouble running pre-2.15 glibc.
#
# WARNING: - any parameter specified here will take priority over the default
# parameter value of the same name used to start the virtual machine.
# Do not set values here unless you understand the impact of doing so as you
# may stop the virtual machine from booting.
# To see the list of default parameters, enable hypervisor debug, create a
# container and look for 'default-kernel-parameters' log entries.
kernel_params = " agent.log=debug"

# Path to the firmware.
# If you want that qemu uses the default firmware leave this option empty
firmware = ""

# Machine accelerators
# comma-separated list of machine accelerators to pass to the hypervisor.
# For example, `machine_accelerators = "nosmm,nosmbus,nosata,nopit,static-prt,nofw"`
machine_accelerators=""

# Default number of vCPUs per SB/VM:
# unspecified or 0                --> will be set to 1
# < 0                             --> will be set to the actual number of physical cores
# > 0 <= number of physical cores --> will be set to the specified number
# > number of physical cores      --> will be set to the actual number of physical cores
default_vcpus = 1

# Default maximum number of vCPUs per SB/VM:
# unspecified or == 0             --> will be set to the actual number of physical cores or to the maximum number
#                                     of vCPUs supported by KVM if that number is exceeded
# > 0 <= number of physical cores --> will be set to the specified number
# > number of physical cores      --> will be set to the actual number of physical cores or to the maximum number
#                                     of vCPUs supported by KVM if that number is exceeded
# WARNING: Depending of the architecture, the maximum number of vCPUs supported by KVM is used when
# the actual number of physical cores is greater than it.
# WARNING: Be aware that this value impacts the virtual machine's memory footprint and CPU
# the hotplug functionality. For example, `default_maxvcpus = 240` specifies that until 240 vCPUs
# can be added to a SB/VM, but the memory footprint will be big. Another example, with
# `default_maxvcpus = 8` the memory footprint will be small, but 8 will be the maximum number of
# vCPUs supported by the SB/VM. In general, we recommend that you do not edit this variable,
# unless you know what are you doing.
default_maxvcpus = 0

# Bridges can be used to hot plug devices.
# Limitations:
# * Currently only pci bridges are supported
# * Until 30 devices per bridge can be hot plugged.
# * Until 5 PCI bridges can be cold plugged per VM.
#   This limitation could be a bug in qemu or in the kernel
# Default number of bridges per SB/VM:
# unspecified or 0   --> will be set to 1
# > 1 <= 5           --> will be set to the specified number
# > 5                --> will be set to 5
default_bridges = 1

# Default memory size in MiB for SB/VM.
# If unspecified then it will be set 2048 MiB.
default_memory = 2048
#
# Default memory slots per SB/VM.
# If unspecified then it will be set 10.
# This is will determine the times that memory will be hotadded to sandbox/VM.
#memory_slots = 10

# The size in MiB will be plused to max memory of hypervisor.
# It is the memory address space for the NVDIMM devie.
# If set block storage driver (block_device_driver) to "nvdimm",
# should set memory_offset to the size of block device.
# Default 0
#memory_offset = 0

# Disable block device from being used for a container's rootfs.
# In case of a storage driver like devicemapper where a container's 
# root file system is backed by a block device, the block device is passed
# directly to the hypervisor for performance reasons. 
# This flag prevents the block device from being passed to the hypervisor, 
# 9pfs is used instead to pass the rootfs.
disable_block_device_use = false

# Shared file system type:
#   - virtio-9p (default)
#   - virtio-fs
shared_fs = "virtio-9p"

# Path to vhost-user-fs daemon.
virtio_fs_daemon = "/usr/bin/virtiofsd"

# Default size of DAX cache in MiB
virtio_fs_cache_size = 1024

# Extra args for virtiofsd daemon
#
# Format example:
#   ["-o", "arg1=xxx,arg2", "-o", "hello world", "--arg3=yyy"]
#
# see `virtiofsd -h` for possible options.
virtio_fs_extra_args = []

# Cache mode:
#
#  - none
#    Metadata, data, and pathname lookup are not cached in guest. They are
#    always fetched from host and any changes are immediately pushed to host.
#
#  - auto
#    Metadata and pathname lookup cache expires after a configured amount of
#    time (default is 1 second). Data is cached while the file is open (close
#    to open consistency).
#
#  - always
#    Metadata, data, and pathname lookup are cached in guest and never expire.
virtio_fs_cache = "always"

# Block storage driver to be used for the hypervisor in case the container
# rootfs is backed by a block device. This is virtio-scsi, virtio-blk
# or nvdimm.
block_device_driver = "virtio-scsi"

# Specifies cache-related options will be set to block devices or not.
# Default false
#block_device_cache_set = true

# Specifies cache-related options for block devices.
# Denotes whether use of O_DIRECT (bypass the host page cache) is enabled.
# Default false
#block_device_cache_direct = true

# Specifies cache-related options for block devices.
# Denotes whether flush requests for the device are ignored.
# Default false
#block_device_cache_noflush = true

# Enable iothreads (data-plane) to be used. This causes IO to be
# handled in a separate IO thread. This is currently only implemented
# for SCSI.
#
enable_iothreads = false

# Enable pre allocation of VM RAM, default false
# Enabling this will result in lower container density
# as all of the memory will be allocated and locked
# This is useful when you want to reserve all the memory
# upfront or in the cases where you want memory latencies
# to be very predictable
# Default false
#enable_mem_prealloc = true

# Enable huge pages for VM RAM, default false
# Enabling this will result in the VM memory
# being allocated using huge pages.
# This is useful when you want to use vhost-user network
# stacks within the container. This will automatically 
# result in memory pre allocation
#enable_hugepages = true

# Enable file based guest memory support. The default is an empty string which
# will disable this feature. In the case of virtio-fs, this is enabled
# automatically and '/dev/shm' is used as the backing folder.
# This option will be ignored if VM templating is enabled.
#file_mem_backend = ""

# Enable swap of vm memory. Default false.
# The behaviour is undefined if mem_prealloc is also set to true
#enable_swap = true

# This option changes the default hypervisor and kernel parameters
# to enable debug output where available. This extra output is added
# to the proxy logs, but only when proxy debug is also enabled.
# 
# Default false
enable_debug = true

# Disable the customizations done in the runtime when it detects
# that it is running on top a VMM. This will result in the runtime
# behaving as it would when running on bare metal.
# 
#disable_nesting_checks = true

# This is the msize used for 9p shares. It is the number of bytes 
# used for 9p packet payload.
#msize_9p = 8192

# If true and vsocks are supported, use vsocks to communicate directly
# with the agent and no proxy is started, otherwise use unix
# sockets and start a proxy to communicate with the agent.
# Default false
#use_vsock = true

# VFIO devices are hotplugged on a bridge by default. 
# Enable hotplugging on root bus. This may be required for devices with
# a large PCI bar, as this is a current limitation with hotplugging on 
# a bridge. This value is valid for "pc" machine type.
# Default false
#hotplug_vfio_on_root_bus = true

# If vhost-net backend for virtio-net is not desired, set to true. Default is false, which trades off
# security (vhost-net runs ring0) for network I/O performance. 
#disable_vhost_net = true

#
# Default entropy source.
# The path to a host source of entropy (including a real hardware RNG)
# /dev/urandom and /dev/random are two main options.
# Be aware that /dev/random is a blocking source of entropy.  If the host
# runs out of entropy, the VMs boot time will increase leading to get startup
# timeouts.
# The source of entropy /dev/urandom is non-blocking and provides a
# generally acceptable source of entropy. It should work well for pretty much
# all practical purposes.
#entropy_source= "/dev/urandom"

# Path to OCI hook binaries in the *guest rootfs*.
# This does not affect host-side hooks which must instead be added to
# the OCI spec passed to the runtime.
#
# You can create a rootfs with hooks by customizing the osbuilder scripts:
# https://github.com/kata-containers/osbuilder
#
# Hooks must be stored in a subdirectory of guest_hook_path according to their
# hook type, i.e. "guest_hook_path/{prestart,postart,poststop}".
# The agent will scan these directories for executable files and add them, in
# lexicographical order, to the lifecycle of the guest container.
# Hooks are executed in the runtime namespace of the guest. See the official documentation:
# https://github.com/opencontainers/runtime-spec/blob/v1.0.1/config.md#posix-platform-hooks
# Warnings will be logged if any error is encountered will scanning for hooks,
# but it will not abort container execution.
#guest_hook_path = "/usr/share/oci/hooks"

[factory]
# VM templating support. Once enabled, new VMs are created from template
# using vm cloning. They will share the same initial kernel, initramfs and
# agent memory by mapping it readonly. It helps speeding up new container
# creation and saves a lot of memory if there are many kata containers running
# on the same host.
#
# When disabled, new VMs are created from scratch.
#
# Note: Requires "initrd=" to be set ("image=" is not supported).
#
# Default false
#enable_template = true

# Specifies the path of template.
#
# Default "/run/vc/vm/template"
#template_path = "/run/vc/vm/template"

# The number of caches of VMCache:
# unspecified or == 0   --> VMCache is disabled
# > 0                   --> will be set to the specified number
#
# VMCache is a function that creates VMs as caches before using it.
# It helps speed up new container creation.
# The function consists of a server and some clients communicating
# through Unix socket.  The protocol is gRPC in protocols/cache/cache.proto.
# The VMCache server will create some VMs and cache them by factory cache.
# It will convert the VM to gRPC format and transport it when gets
# requestion from clients.
# Factory grpccache is the VMCache client.  It will request gRPC format
# VM and convert it back to a VM.  If VMCache function is enabled,
# kata-runtime will request VM from factory grpccache when it creates
# a new sandbox.
#
# Default 0
#vm_cache_number = 0

# Specify the address of the Unix socket that is used by VMCache.
#
# Default /var/run/kata-containers/cache.sock
#vm_cache_endpoint = "/var/run/kata-containers/cache.sock"

[proxy.kata]
path = "/usr/libexec/kata-containers/kata-proxy"

# If enabled, proxy messages will be sent to the system log
# (default: disabled)
enable_debug = true

[shim.kata]
path = "/usr/libexec/kata-containers/kata-shim"

# If enabled, shim messages will be sent to the system log
# (default: disabled)
enable_debug = true

# If enabled, the shim will create opentracing.io traces and spans.
# (See https://www.jaegertracing.io/docs/getting-started).
#
# Note: By default, the shim runs in a separate network namespace. Therefore,
# to allow it to send trace details to the Jaeger agent running on the host,
# it is necessary to set 'disable_new_netns=true' so that it runs in the host
# network namespace.
#
# (default: disabled)
#enable_tracing = true

[agent.kata]
# If enabled, make the agent display debug-level messages.
# (default: disabled)
enable_debug = true

# Enable agent tracing.
#
# If enabled, the default trace mode is "dynamic" and the
# default trace type is "isolated". The trace mode and type are set
# explicity with the `trace_type=` and `trace_mode=` options.
#
# Notes:
#
# - Tracing is ONLY enabled when `enable_tracing` is set: explicitly
#   setting `trace_mode=` and/or `trace_type=` without setting `enable_tracing`
#   will NOT activate agent tracing.
#
# - See https://github.com/kata-containers/agent/blob/master/TRACING.md for
#   full details.
#
# (default: disabled)
#enable_tracing = true
#
#trace_mode = "dynamic"
#trace_type = "isolated"

# Comma separated list of kernel modules and their parameters.
# These modules will be loaded in the guest kernel using modprobe(8).
# The following example can be used to load two kernel modules with parameters
#  - kernel_modules=["e1000e InterruptThrottleRate=3000,3000,3000 EEE=1", "i915 enable_ppgtt=0"]
# The first word is considered as the module name and the rest as its parameters.
# Container will not be started when:
#  * A kernel module is specified and the modprobe command is not installed in the guest
#    or it fails loading the module.
#  * The module is not available in the guest or it doesn't met the guest kernel
#    requirements, like architecture and version.
#
kernel_modules=[]


[netmon]
# If enabled, the network monitoring process gets started when the
# sandbox is created. This allows for the detection of some additional
# network being added to the existing network namespace, after the
# sandbox has been created.
# (default: disabled)
#enable_netmon = true

# Specify the path to the netmon binary.
path = "/usr/libexec/kata-containers/kata-netmon"

# If enabled, netmon messages will be sent to the system log
# (default: disabled)
enable_debug = true

[runtime]
# If enabled, the runtime will log additional debug messages to the
# system log
# (default: disabled)
enable_debug = true
#
# Internetworking model
# Determines how the VM should be connected to the
# the container network interface
# Options:
#
#   - macvtap
#     Used when the Container network interface can be bridged using
#     macvtap.
#
#   - none
#     Used when customize network. Only creates a tap device. No veth pair.
#
#   - tcfilter
#     Uses tc filter rules to redirect traffic from the network interface
#     provided by plugin to a tap interface connected to the VM.
#
internetworking_model="tcfilter"

# disable guest seccomp
# Determines whether container seccomp profiles are passed to the virtual
# machine and applied by the kata agent. If set to true, seccomp is not applied
# within the guest
# (default: true)
disable_guest_seccomp=true

# If enabled, the runtime will create opentracing.io traces and spans.
# (See https://www.jaegertracing.io/docs/getting-started).
# (default: disabled)
#enable_tracing = true

# If enabled, the runtime will not create a network namespace for shim and hypervisor processes.
# This option may have some potential impacts to your host. It should only be used when you know what you're doing.
# `disable_new_netns` conflicts with `enable_netmon`
# `disable_new_netns` conflicts with `internetworking_model=tcfilter` and `internetworking_model=macvtap`. It works only
# with `internetworking_model=none`. The tap device will be in the host network namespace and can connect to a bridge
# (like OVS) directly.
# If you are using docker, `disable_new_netns` only works with `docker run --net=none`
# (default: false)
#disable_new_netns = true

# if enabled, the runtime will add all the kata processes inside one dedicated cgroup.
# The container cgroups in the host are not created, just one single cgroup per sandbox.
# The sandbox cgroup is not constrained by the runtime
# The runtime caller is free to restrict or collect cgroup stats of the overall Kata sandbox.
# The sandbox cgroup path is the parent cgroup of a container with the PodSandbox annotation.
# See: https://godoc.org/github.com/kata-containers/runtime/virtcontainers#ContainerType
sandbox_cgroup_only=false

# Enabled experimental feature list, format: ["a", "b"].
# Experimental features are features not stable enough for production,
# They may break compatibility, and are prepared for a big version bump.
# Supported experimental features:
# 1. "newstore": new persist storage driver which breaks backward compatibility,
#				expected to move out of experimental in 2.0.0.
# (default: [])
experimental=[]

---

KSM throttler

version

Output of " --version":

/usr/local/bin/kata-collect-data.sh: line 178: --version: command not found

systemd service

Image details

---
osbuilder:
  url: "https://github.com/kata-containers/osbuilder"
  version: "unknown"
rootfs-creation-time: "2019-12-11T21:56:23.204033938+0000Z"
description: "osbuilder rootfs"
file-format-version: "0.0.2"
architecture: "x86_64"
base-distro:
  name: "Clear"
  version: "31880"
  packages:
    default:
      - "chrony"
      - "iptables-bin"
      - "kmod-bin"
      - "libudev0-shim"
      - "systemd"
      - "util-linux-bin"
    extra:

agent:
  url: "https://github.com/kata-containers/agent"
  name: "kata-agent"
  version: "1.10.0-rc0-6182fa34c64ba6eb1b6028d8b6647a86844129c4"
  agent-is-init-daemon: "no"

---

Initrd details

No initrd

---

Logfiles

Runtime logs

Recent runtime problems found in system journal:

time="2019-12-11T22:22:18.947244072Z" level=warning msg="sandbox's cgroup won't be updated: cgroup path is empty" arch=amd64 command=create container=6f38e9f2e0fbbb04e8c76eed1378a63f30cceaeb788d7a4d3b3db886b2857941 name=kata-runtime pid=125127 sandbox=6f38e9f2e0fbbb04e8c76eed1378a63f30cceaeb788d7a4d3b3db886b2857941 source=virtcontainers subsystem=sandbox
time="2019-12-11T22:22:18.952986721Z" level=info msg="sanner return error: read unix @->/run/vc/vm/6f38e9f2e0fbbb04e8c76eed1378a63f30cceaeb788d7a4d3b3db886b2857941/qmp.sock: use of closed network connection" arch=amd64 command=create container=6f38e9f2e0fbbb04e8c76eed1378a63f30cceaeb788d7a4d3b3db886b2857941 name=kata-runtime pid=125127 source=virtcontainers subsystem=qmp
time="2019-12-11T22:22:22.837316722Z" level=info msg="{\"return\": [{\"props\": {\"core-id\": 0, \"thread-id\": 0, \"node-id\": 0, \"die-id\": 0, \"socket-id\": 3}, \"vcpus-count\": 1, \"type\": \"host-x86_64-cpu\"}, {\"props\": {\"core-id\": 0, \"thread-id\": 0, \"node-id\": 0, \"die-id\": 0, \"socket-id\": 2}, \"vcpus-count\": 1, \"type\": \"host-x86_64-cpu\"}, {\"props\": {\"core-id\": 0, \"thread-id\": 0, \"node-id\": 0, \"die-id\": 0, \"socket-id\": 1}, \"vcpus-count\": 1, \"type\": \"host-x86_64-cpu\"}, {\"props\": {\"core-id\": 0, \"thread-id\": 0, \"node-id\": 0, \"die-id\": 0, \"socket-id\": 0}, \"vcpus-count\": 1, \"qom-path\": \"/machine/unattached/device[0]\", \"type\": \"host-x86_64-cpu\"}]}" arch=amd64 command=create container=f1e5d493951c831c2696e1b7abe16994dc23847adb0267296b3c2f21c4747a5f name=kata-runtime pid=125313 source=virtcontainers subsystem=qmp
time="2019-12-11T22:22:22.839156838Z" level=info msg="{\"arguments\":{\"core-id\":\"0\",\"die-id\":\"0\",\"driver\":\"host-x86_64-cpu\",\"id\":\"cpu-0\",\"socket-id\":\"3\",\"thread-id\":\"0\"},\"execute\":\"device_add\"}" arch=amd64 command=create container=f1e5d493951c831c2696e1b7abe16994dc23847adb0267296b3c2f21c4747a5f name=kata-runtime pid=125313 source=virtcontainers subsystem=qmp
time="2019-12-11T22:22:22.843360673Z" level=info msg="{\"arguments\":{\"core-id\":\"0\",\"die-id\":\"0\",\"driver\":\"host-x86_64-cpu\",\"id\":\"cpu-1\",\"socket-id\":\"2\",\"thread-id\":\"0\"},\"execute\":\"device_add\"}" arch=amd64 command=create container=f1e5d493951c831c2696e1b7abe16994dc23847adb0267296b3c2f21c4747a5f name=kata-runtime pid=125313 source=virtcontainers subsystem=qmp
time="2019-12-11T22:22:22.871541306Z" level=warning msg="sandbox's cgroup won't be updated: cgroup path is empty" arch=amd64 command=create container=f1e5d493951c831c2696e1b7abe16994dc23847adb0267296b3c2f21c4747a5f name=kata-runtime pid=125313 sandbox=6f38e9f2e0fbbb04e8c76eed1378a63f30cceaeb788d7a4d3b3db886b2857941 source=virtcontainers subsystem=sandbox
time="2019-12-11T22:22:22.873087719Z" level=info msg="sanner return error: read unix @->/run/vc/vm/6f38e9f2e0fbbb04e8c76eed1378a63f30cceaeb788d7a4d3b3db886b2857941/qmp.sock: use of closed network connection" arch=amd64 command=create container=f1e5d493951c831c2696e1b7abe16994dc23847adb0267296b3c2f21c4747a5f name=kata-runtime pid=125313 source=virtcontainers subsystem=qmp
time="2019-12-11T22:22:23.001715784Z" level=warning msg="hot-remove VM memory not supported" arch=amd64 command=start container=f1e5d493951c831c2696e1b7abe16994dc23847adb0267296b3c2f21c4747a5f hotplug=memory name=kata-runtime pid=125391 sandbox=6f38e9f2e0fbbb04e8c76eed1378a63f30cceaeb788d7a4d3b3db886b2857941 source=virtcontainers subsystem=qemu
time="2019-12-11T22:22:23.006129821Z" level=info msg="sanner return error: read unix @->/run/vc/vm/6f38e9f2e0fbbb04e8c76eed1378a63f30cceaeb788d7a4d3b3db886b2857941/qmp.sock: use of closed network connection" arch=amd64 command=start container=f1e5d493951c831c2696e1b7abe16994dc23847adb0267296b3c2f21c4747a5f name=kata-runtime pid=125391 sandbox=6f38e9f2e0fbbb04e8c76eed1378a63f30cceaeb788d7a4d3b3db886b2857941 source=virtcontainers subsystem=qmp
time="2019-12-11T22:22:23.499056501Z" level=warning msg="no such file or directory: /run/kata-containers/shared/sandboxes/6f38e9f2e0fbbb04e8c76eed1378a63f30cceaeb788d7a4d3b3db886b2857941/f1e5d493951c831c2696e1b7abe16994dc23847adb0267296b3c2f21c4747a5f/rootfs"
time="2019-12-11T22:22:23.507861774Z" level=info msg="sanner return error: read unix @->/run/vc/vm/6f38e9f2e0fbbb04e8c76eed1378a63f30cceaeb788d7a4d3b3db886b2857941/qmp.sock: use of closed network connection" arch=amd64 command=kill container=f1e5d493951c831c2696e1b7abe16994dc23847adb0267296b3c2f21c4747a5f name=kata-runtime pid=125447 sandbox=6f38e9f2e0fbbb04e8c76eed1378a63f30cceaeb788d7a4d3b3db886b2857941 source=virtcontainers subsystem=qmp
time="2019-12-11T22:22:23.950352337Z" level=warning msg="no such file or directory: /run/kata-containers/shared/sandboxes/6f38e9f2e0fbbb04e8c76eed1378a63f30cceaeb788d7a4d3b3db886b2857941/6f38e9f2e0fbbb04e8c76eed1378a63f30cceaeb788d7a4d3b3db886b2857941/rootfs"
time="2019-12-11T22:22:23.980719188Z" level=info msg="sanner return error: read unix @->/run/vc/vm/6f38e9f2e0fbbb04e8c76eed1378a63f30cceaeb788d7a4d3b3db886b2857941/qmp.sock: use of closed network connection" arch=amd64 command=kill container=6f38e9f2e0fbbb04e8c76eed1378a63f30cceaeb788d7a4d3b3db886b2857941 name=kata-runtime pid=125511 sandbox=6f38e9f2e0fbbb04e8c76eed1378a63f30cceaeb788d7a4d3b3db886b2857941 source=virtcontainers subsystem=qmp
time="2019-12-11T22:22:26.318866513Z" level=warning msg="sandox cgroups path is empty" arch=amd64 command=delete container=6f38e9f2e0fbbb04e8c76eed1378a63f30cceaeb788d7a4d3b3db886b2857941 name=kata-runtime pid=125645 sandbox=6f38e9f2e0fbbb04e8c76eed1378a63f30cceaeb788d7a4d3b3db886b2857941 source=virtcontainers subsystem=sandbox
time="2019-12-11T22:22:45.671308347Z" level=warning msg="load sandbox devices failed" arch=amd64 command=create container=e361670cb6a33cfcb61e0f17a82118e44d04f674819832acd6d5ea127b88b9ba error="open /run/vc/sbs/e361670cb6a33cfcb61e0f17a82118e44d04f674819832acd6d5ea127b88b9ba/devices.json: no such file or directory" name=kata-runtime pid=126931 sandbox=e361670cb6a33cfcb61e0f17a82118e44d04f674819832acd6d5ea127b88b9ba sandboxid=e361670cb6a33cfcb61e0f17a82118e44d04f674819832acd6d5ea127b88b9ba source=virtcontainers subsystem=sandbox
time="2019-12-11T22:22:45.672107853Z" level=debug msg="Could not retrieve anything from storage" arch=amd64 command=create container=e361670cb6a33cfcb61e0f17a82118e44d04f674819832acd6d5ea127b88b9ba name=kata-runtime pid=126931 source=virtcontainers subsystem=kata_agent
time="2019-12-11T22:22:45.673028761Z" level=debug arch=amd64 command=create container=e361670cb6a33cfcb61e0f17a82118e44d04f674819832acd6d5ea127b88b9ba default-kernel-parameters="tsc=reliable no_timer_check rcupdate.rcu_expedited=1 i8042.direct=1 i8042.dumbkbd=1 i8042.nopnp=1 i8042.noaux=1 noreplace-smp reboot=k console=hvc0 console=hvc1 iommu=off cryptomgr.notests net.ifnames=0 pci=lastbus=0 root=/dev/pmem0p1 rootflags=dax,data=ordered,errors=remount-ro ro rootfstype=ext4 debug systemd.show_status=true systemd.log_level=debug" name=kata-runtime pid=126931 source=virtcontainers subsystem=qemu
time="2019-12-11T22:22:45.730909848Z" level=info msg="sanner return error: read unix @->/run/vc/vm/e361670cb6a33cfcb61e0f17a82118e44d04f674819832acd6d5ea127b88b9ba/qmp.sock: use of closed network connection" arch=amd64 command=create container=e361670cb6a33cfcb61e0f17a82118e44d04f674819832acd6d5ea127b88b9ba name=kata-runtime pid=126931 source=virtcontainers subsystem=qmp
time="2019-12-11T22:22:46.63228202Z" level=warning msg="sandbox's cgroup won't be updated: cgroup path is empty" arch=amd64 command=create container=e361670cb6a33cfcb61e0f17a82118e44d04f674819832acd6d5ea127b88b9ba name=kata-runtime pid=126931 sandbox=e361670cb6a33cfcb61e0f17a82118e44d04f674819832acd6d5ea127b88b9ba source=virtcontainers subsystem=sandbox
time="2019-12-11T22:22:46.635387047Z" level=info msg="sanner return error: read unix @->/run/vc/vm/e361670cb6a33cfcb61e0f17a82118e44d04f674819832acd6d5ea127b88b9ba/qmp.sock: use of closed network connection" arch=amd64 command=create container=e361670cb6a33cfcb61e0f17a82118e44d04f674819832acd6d5ea127b88b9ba name=kata-runtime pid=126931 source=virtcontainers subsystem=qmp
time="2019-12-11T22:22:50.140452854Z" level=info msg="{\"return\": [{\"props\": {\"core-id\": 0, \"thread-id\": 0, \"node-id\": 0, \"die-id\": 0, \"socket-id\": 3}, \"vcpus-count\": 1, \"type\": \"host-x86_64-cpu\"}, {\"props\": {\"core-id\": 0, \"thread-id\": 0, \"node-id\": 0, \"die-id\": 0, \"socket-id\": 2}, \"vcpus-count\": 1, \"type\": \"host-x86_64-cpu\"}, {\"props\": {\"core-id\": 0, \"thread-id\": 0, \"node-id\": 0, \"die-id\": 0, \"socket-id\": 1}, \"vcpus-count\": 1, \"type\": \"host-x86_64-cpu\"}, {\"props\": {\"core-id\": 0, \"thread-id\": 0, \"node-id\": 0, \"die-id\": 0, \"socket-id\": 0}, \"vcpus-count\": 1, \"qom-path\": \"/machine/unattached/device[0]\", \"type\": \"host-x86_64-cpu\"}]}" arch=amd64 command=create container=fb4f2a54fd06f2d0b5bf6fa0c69790276cd3ef06d4aef979778dd6762e6f7516 name=kata-runtime pid=127115 source=virtcontainers subsystem=qmp
time="2019-12-11T22:22:50.150843646Z" level=info msg="{\"arguments\":{\"core-id\":\"0\",\"die-id\":\"0\",\"driver\":\"host-x86_64-cpu\",\"id\":\"cpu-0\",\"socket-id\":\"3\",\"thread-id\":\"0\"},\"execute\":\"device_add\"}" arch=amd64 command=create container=fb4f2a54fd06f2d0b5bf6fa0c69790276cd3ef06d4aef979778dd6762e6f7516 name=kata-runtime pid=127115 source=virtcontainers subsystem=qmp
time="2019-12-11T22:22:50.155096384Z" level=info msg="{\"arguments\":{\"core-id\":\"0\",\"die-id\":\"0\",\"driver\":\"host-x86_64-cpu\",\"id\":\"cpu-1\",\"socket-id\":\"2\",\"thread-id\":\"0\"},\"execute\":\"device_add\"}" arch=amd64 command=create container=fb4f2a54fd06f2d0b5bf6fa0c69790276cd3ef06d4aef979778dd6762e6f7516 name=kata-runtime pid=127115 source=virtcontainers subsystem=qmp
time="2019-12-11T22:22:50.175030061Z" level=warning msg="sandbox's cgroup won't be updated: cgroup path is empty" arch=amd64 command=create container=fb4f2a54fd06f2d0b5bf6fa0c69790276cd3ef06d4aef979778dd6762e6f7516 name=kata-runtime pid=127115 sandbox=e361670cb6a33cfcb61e0f17a82118e44d04f674819832acd6d5ea127b88b9ba source=virtcontainers subsystem=sandbox
time="2019-12-11T22:22:50.177125079Z" level=info msg="sanner return error: read unix @->/run/vc/vm/e361670cb6a33cfcb61e0f17a82118e44d04f674819832acd6d5ea127b88b9ba/qmp.sock: use of closed network connection" arch=amd64 command=create container=fb4f2a54fd06f2d0b5bf6fa0c69790276cd3ef06d4aef979778dd6762e6f7516 name=kata-runtime pid=127115 source=virtcontainers subsystem=qmp
time="2019-12-11T22:22:50.322576968Z" level=warning msg="hot-remove VM memory not supported" arch=amd64 command=start container=fb4f2a54fd06f2d0b5bf6fa0c69790276cd3ef06d4aef979778dd6762e6f7516 hotplug=memory name=kata-runtime pid=127183 sandbox=e361670cb6a33cfcb61e0f17a82118e44d04f674819832acd6d5ea127b88b9ba source=virtcontainers subsystem=qemu
time="2019-12-11T22:22:50.331115844Z" level=info msg="sanner return error: read unix @->/run/vc/vm/e361670cb6a33cfcb61e0f17a82118e44d04f674819832acd6d5ea127b88b9ba/qmp.sock: use of closed network connection" arch=amd64 command=start container=fb4f2a54fd06f2d0b5bf6fa0c69790276cd3ef06d4aef979778dd6762e6f7516 name=kata-runtime pid=127183 sandbox=e361670cb6a33cfcb61e0f17a82118e44d04f674819832acd6d5ea127b88b9ba source=virtcontainers subsystem=qmp
time="2019-12-11T22:22:50.797982881Z" level=warning msg="no such file or directory: /run/kata-containers/shared/sandboxes/e361670cb6a33cfcb61e0f17a82118e44d04f674819832acd6d5ea127b88b9ba/fb4f2a54fd06f2d0b5bf6fa0c69790276cd3ef06d4aef979778dd6762e6f7516/rootfs"
time="2019-12-11T22:22:50.808747277Z" level=info msg="sanner return error: read unix @->/run/vc/vm/e361670cb6a33cfcb61e0f17a82118e44d04f674819832acd6d5ea127b88b9ba/qmp.sock: use of closed network connection" arch=amd64 command=kill container=fb4f2a54fd06f2d0b5bf6fa0c69790276cd3ef06d4aef979778dd6762e6f7516 name=kata-runtime pid=127238 sandbox=e361670cb6a33cfcb61e0f17a82118e44d04f674819832acd6d5ea127b88b9ba source=virtcontainers subsystem=qmp
time="2019-12-11T22:22:51.2483747Z" level=warning msg="no such file or directory: /run/kata-containers/shared/sandboxes/e361670cb6a33cfcb61e0f17a82118e44d04f674819832acd6d5ea127b88b9ba/e361670cb6a33cfcb61e0f17a82118e44d04f674819832acd6d5ea127b88b9ba/rootfs"
time="2019-12-11T22:22:51.280579975Z" level=info msg="sanner return error: read unix @->/run/vc/vm/e361670cb6a33cfcb61e0f17a82118e44d04f674819832acd6d5ea127b88b9ba/qmp.sock: use of closed network connection" arch=amd64 command=kill container=e361670cb6a33cfcb61e0f17a82118e44d04f674819832acd6d5ea127b88b9ba name=kata-runtime pid=127304 sandbox=e361670cb6a33cfcb61e0f17a82118e44d04f674819832acd6d5ea127b88b9ba source=virtcontainers subsystem=qmp
time="2019-12-11T22:22:53.726504802Z" level=warning msg="sandox cgroups path is empty" arch=amd64 command=delete container=e361670cb6a33cfcb61e0f17a82118e44d04f674819832acd6d5ea127b88b9ba name=kata-runtime pid=127409 sandbox=e361670cb6a33cfcb61e0f17a82118e44d04f674819832acd6d5ea127b88b9ba source=virtcontainers subsystem=sandbox
time="2019-12-11T22:23:13.576901322Z" level=warning msg="load sandbox devices failed" arch=amd64 command=create container=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 error="open /run/vc/sbs/6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545/devices.json: no such file or directory" name=kata-runtime pid=128689 sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 sandboxid=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 source=virtcontainers subsystem=sandbox
time="2019-12-11T22:23:13.577515528Z" level=debug msg="Could not retrieve anything from storage" arch=amd64 command=create container=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 name=kata-runtime pid=128689 source=virtcontainers subsystem=kata_agent
time="2019-12-11T22:23:13.578449236Z" level=debug arch=amd64 command=create container=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 default-kernel-parameters="tsc=reliable no_timer_check rcupdate.rcu_expedited=1 i8042.direct=1 i8042.dumbkbd=1 i8042.nopnp=1 i8042.noaux=1 noreplace-smp reboot=k console=hvc0 console=hvc1 iommu=off cryptomgr.notests net.ifnames=0 pci=lastbus=0 root=/dev/pmem0p1 rootflags=dax,data=ordered,errors=remount-ro ro rootfstype=ext4 debug systemd.show_status=true systemd.log_level=debug" name=kata-runtime pid=128689 source=virtcontainers subsystem=qemu
time="2019-12-11T22:23:13.633755102Z" level=info msg="sanner return error: read unix @->/run/vc/vm/6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545/qmp.sock: use of closed network connection" arch=amd64 command=create container=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 name=kata-runtime pid=128689 source=virtcontainers subsystem=qmp
time="2019-12-11T22:23:14.544277176Z" level=warning msg="sandbox's cgroup won't be updated: cgroup path is empty" arch=amd64 command=create container=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 name=kata-runtime pid=128689 sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 source=virtcontainers subsystem=sandbox
time="2019-12-11T22:23:14.547583904Z" level=info msg="sanner return error: read unix @->/run/vc/vm/6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545/qmp.sock: use of closed network connection" arch=amd64 command=create container=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 name=kata-runtime pid=128689 source=virtcontainers subsystem=qmp
time="2019-12-11T22:23:17.427834124Z" level=info msg="{\"return\": [{\"props\": {\"core-id\": 0, \"thread-id\": 0, \"node-id\": 0, \"die-id\": 0, \"socket-id\": 3}, \"vcpus-count\": 1, \"type\": \"host-x86_64-cpu\"}, {\"props\": {\"core-id\": 0, \"thread-id\": 0, \"node-id\": 0, \"die-id\": 0, \"socket-id\": 2}, \"vcpus-count\": 1, \"type\": \"host-x86_64-cpu\"}, {\"props\": {\"core-id\": 0, \"thread-id\": 0, \"node-id\": 0, \"die-id\": 0, \"socket-id\": 1}, \"vcpus-count\": 1, \"type\": \"host-x86_64-cpu\"}, {\"props\": {\"core-id\": 0, \"thread-id\": 0, \"node-id\": 0, \"die-id\": 0, \"socket-id\": 0}, \"vcpus-count\": 1, \"qom-path\": \"/machine/unattached/device[0]\", \"type\": \"host-x86_64-cpu\"}]}" arch=amd64 command=create container=beeaf53c923f259e321d76fc63ae9badd7967e6801a10caa1d49b76895145629 name=kata-runtime pid=128910 source=virtcontainers subsystem=qmp
time="2019-12-11T22:23:17.430619348Z" level=info msg="{\"arguments\":{\"core-id\":\"0\",\"die-id\":\"0\",\"driver\":\"host-x86_64-cpu\",\"id\":\"cpu-0\",\"socket-id\":\"3\",\"thread-id\":\"0\"},\"execute\":\"device_add\"}" arch=amd64 command=create container=beeaf53c923f259e321d76fc63ae9badd7967e6801a10caa1d49b76895145629 name=kata-runtime pid=128910 source=virtcontainers subsystem=qmp
time="2019-12-11T22:23:17.437736908Z" level=info msg="{\"arguments\":{\"core-id\":\"0\",\"die-id\":\"0\",\"driver\":\"host-x86_64-cpu\",\"id\":\"cpu-1\",\"socket-id\":\"2\",\"thread-id\":\"0\"},\"execute\":\"device_add\"}" arch=amd64 command=create container=beeaf53c923f259e321d76fc63ae9badd7967e6801a10caa1d49b76895145629 name=kata-runtime pid=128910 source=virtcontainers subsystem=qmp
time="2019-12-11T22:23:17.463293325Z" level=warning msg="sandbox's cgroup won't be updated: cgroup path is empty" arch=amd64 command=create container=beeaf53c923f259e321d76fc63ae9badd7967e6801a10caa1d49b76895145629 name=kata-runtime pid=128910 sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 source=virtcontainers subsystem=sandbox
time="2019-12-11T22:23:17.464901938Z" level=info msg="sanner return error: read unix @->/run/vc/vm/6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545/qmp.sock: use of closed network connection" arch=amd64 command=create container=beeaf53c923f259e321d76fc63ae9badd7967e6801a10caa1d49b76895145629 name=kata-runtime pid=128910 source=virtcontainers subsystem=qmp
time="2019-12-11T22:23:17.598865174Z" level=warning msg="hot-remove VM memory not supported" arch=amd64 command=start container=beeaf53c923f259e321d76fc63ae9badd7967e6801a10caa1d49b76895145629 hotplug=memory name=kata-runtime pid=128975 sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 source=virtcontainers subsystem=qemu
time="2019-12-11T22:23:17.602234002Z" level=info msg="sanner return error: read unix @->/run/vc/vm/6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545/qmp.sock: use of closed network connection" arch=amd64 command=start container=beeaf53c923f259e321d76fc63ae9badd7967e6801a10caa1d49b76895145629 name=kata-runtime pid=128975 sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 source=virtcontainers subsystem=qmp
time="2019-12-11T22:23:18.348744493Z" level=warning msg="no such file or directory: /run/kata-containers/shared/sandboxes/6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545/beeaf53c923f259e321d76fc63ae9badd7967e6801a10caa1d49b76895145629/rootfs"
time="2019-12-11T22:23:18.360966396Z" level=info msg="sanner return error: read unix @->/run/vc/vm/6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545/qmp.sock: use of closed network connection" arch=amd64 command=kill container=beeaf53c923f259e321d76fc63ae9badd7967e6801a10caa1d49b76895145629 name=kata-runtime pid=129068 sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 source=virtcontainers subsystem=qmp
time="2019-12-11T22:23:18.799600367Z" level=warning msg="no such file or directory: /run/kata-containers/shared/sandboxes/6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545/6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545/rootfs"
time="2019-12-11T22:23:18.835379867Z" level=info msg="sanner return error: read unix @->/run/vc/vm/6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545/qmp.sock: use of closed network connection" arch=amd64 command=kill container=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 name=kata-runtime pid=129129 sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 source=virtcontainers subsystem=qmp
time="2019-12-11T22:23:21.339521707Z" level=warning msg="sandox cgroups path is empty" arch=amd64 command=delete container=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 name=kata-runtime pid=129225 sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 source=virtcontainers subsystem=sandbox

Proxy logs

Recent proxy problems found in system journal:

time="2019-12-11T22:22:51.13395362Z" level=info msg="time=\"2019-12-11T22:22:51.123450591Z\" level=debug msg=\"request end\" debug_console=false duration=\"494.469µs\" name=kata-agent pid=41 request=/grpc.AgentService/SignalProcess resp=\"&Empty{XXX_unrecognized:[],}\" sandbox=e361670cb6a33cfcb61e0f17a82118e44d04f674819832acd6d5ea127b88b9ba source=agent\n" name=kata-proxy pid=126977 sandbox=e361670cb6a33cfcb61e0f17a82118e44d04f674819832acd6d5ea127b88b9ba source=agent
time="2019-12-11T22:22:51.24719389Z" level=info msg="time=\"2019-12-11T22:22:51.236949267Z\" level=debug msg=\"request end\" debug_console=false duration=105.724736ms name=kata-agent pid=41 request=/grpc.AgentService/RemoveContainer resp=\"&Empty{XXX_unrecognized:[],}\" sandbox=e361670cb6a33cfcb61e0f17a82118e44d04f674819832acd6d5ea127b88b9ba source=agent\n" name=kata-proxy pid=126977 sandbox=e361670cb6a33cfcb61e0f17a82118e44d04f674819832acd6d5ea127b88b9ba source=agent
time="2019-12-11T22:22:51.25769398Z" level=info msg="[    5.281368] sd 0:0:0:0: [sda] Synchronize Cache(10) failed: Result: hostbyte=0x04 driverbyte=0x00\n" name=kata-proxy pid=126977 sandbox=e361670cb6a33cfcb61e0f17a82118e44d04f674819832acd6d5ea127b88b9ba source=agent
time="2019-12-11T22:22:51.277125846Z" level=info msg="time=\"2019-12-11T22:22:51.266884341Z\" level=debug msg=\"request end\" debug_console=false duration=15.20303ms name=kata-agent pid=41 request=/grpc.AgentService/DestroySandbox resp=\"&Empty{XXX_unrecognized:[],}\" sandbox=e361670cb6a33cfcb61e0f17a82118e44d04f674819832acd6d5ea127b88b9ba source=agent\n" name=kata-proxy pid=126977 sandbox=e361670cb6a33cfcb61e0f17a82118e44d04f674819832acd6d5ea127b88b9ba source=agent
time="2019-12-11T22:22:51.27765445Z" level=fatal msg="failed to handle exit signal" error="close unix @->/run/vc/vm/e361670cb6a33cfcb61e0f17a82118e44d04f674819832acd6d5ea127b88b9ba/kata.sock: use of closed network connection" name=kata-proxy pid=126977 sandbox=e361670cb6a33cfcb61e0f17a82118e44d04f674819832acd6d5ea127b88b9ba source=proxy
time="2019-12-11T22:23:14.214039494Z" level=info msg="[    0.320655] EXT4-fs (pmem0p1): DAX enabled. Warning: EXPERIMENTAL, use at your own risk\n" name=kata-proxy pid=128736 sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 source=agent
time="2019-12-11T22:23:14.215205103Z" level=info msg="[    0.321727] EXT4-fs (pmem0p1): mounted filesystem with ordered data mode. Opts: dax,data=ordered,errors=remount-ro\n" name=kata-proxy pid=128736 sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 source=agent
time="2019-12-11T22:23:14.2979008Z" level=info msg="[    0.404550] systemd[38]: tmp.mount: Failed to connect stdout to the journal socket, ignoring: No such file or directory\n" name=kata-proxy pid=128736 sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 source=agent
time="2019-12-11T22:23:14.298650607Z" level=info msg="[    0.405115] systemd[39]: systemd-remount-fs.service: Failed to connect stdout to the journal socket, ignoring: No such file or directory\n" name=kata-proxy pid=128736 sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 source=agent
time="2019-12-11T22:23:14.29909161Z" level=info msg="[    0.405696] systemd[39]: systemd-remount-fs.service: Kernel keyring not supported, ignoring.\n" name=kata-proxy pid=128736 sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 source=agent
time="2019-12-11T22:23:14.300595423Z" level=info msg="[    0.407159] systemd[38]: tmp.mount: Kernel keyring not supported, ignoring.\n" name=kata-proxy pid=128736 sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 source=agent
time="2019-12-11T22:23:14.301828033Z" level=info msg="[    0.408298] systemd[40]: systemd-sysctl.service: Failed to connect stdout to the journal socket, ignoring: No such file or directory\n" name=kata-proxy pid=128736 sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 source=agent
time="2019-12-11T22:23:14.30380235Z" level=info msg="[    0.410342] systemd[40]: systemd-sysctl.service: Kernel keyring not supported, ignoring.\n" name=kata-proxy pid=128736 sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 source=agent
time="2019-12-11T22:23:14.316501657Z" level=info msg="[    0.423120] systemd-sysctl[40]: Couldn't write '16' to 'kernel/sysrq', ignoring: No such file or directory\n" name=kata-proxy pid=128736 sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 source=agent
time="2019-12-11T22:23:14.324903928Z" level=info msg="[    0.431504] systemd[42]: kata-agent.service: Kernel keyring not supported, ignoring.\n" name=kata-proxy pid=128736 sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 source=agent
time="2019-12-11T22:23:14.394898918Z" level=info msg="time=\"2019-12-11T22:23:14.380583049Z\" level=debug msg=\"request end\" debug_console=false duration=6.497387ms name=kata-agent pid=42 request=/grpc.AgentService/CreateSandbox resp=\"&Empty{XXX_unrecognized:[],}\" sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 source=agent\n" name=kata-proxy pid=128736 sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 source=agent
time="2019-12-11T22:23:14.533153483Z" level=info msg="time=\"2019-12-11T22:23:14.518807014Z\" level=debug msg=\"request end\" debug_console=false duration=106.189066ms name=kata-agent pid=42 request=/grpc.AgentService/CreateContainer resp=\"&Empty{XXX_unrecognized:[],}\" sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 source=agent\n" name=kata-proxy pid=128736 sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 source=agent
time="2019-12-11T22:23:14.533915089Z" level=info msg="time=\"2019-12-11T22:23:14.519077871Z\" level=info msg=\"ignoring unexpected signal\" debug_console=false name=kata-agent pid=42 sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 signal=\"child exited\" source=agent\n" name=kata-proxy pid=128736 sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 source=agent
time="2019-12-11T22:23:14.534308692Z" level=info msg="time=\"2019-12-11T22:23:14.51972645Z\" level=info msg=\"ignoring unexpected signal\" debug_console=false name=kata-agent pid=42 sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 signal=\"child exited\" source=agent\n" name=kata-proxy pid=128736 sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 source=agent
time="2019-12-11T22:23:14.542016157Z" level=info msg="time=\"2019-12-11T22:23:14.527510953Z\" level=debug msg=\"request end\" debug_console=false duration=\"4.006µs\" name=kata-agent pid=42 request=/grpc.AgentService/OnlineCPUMem resp=\"&Empty{XXX_unrecognized:[],}\" sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 source=agent\n" name=kata-proxy pid=128736 sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 source=agent
time="2019-12-11T22:23:14.567807575Z" level=info msg="time=\"2019-12-11T22:23:14.553327967Z\" level=debug msg=\"request end\" debug_console=false duration=\"209.839µs\" name=kata-agent pid=42 request=/grpc.AgentService/CloseStdin resp=\"&Empty{XXX_unrecognized:[],}\" sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 source=agent\n" name=kata-proxy pid=128736 sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 source=agent
time="2019-12-11T22:23:14.605790395Z" level=info msg="time=\"2019-12-11T22:23:14.591366606Z\" level=debug msg=\"request end\" debug_console=false duration=2.705457ms name=kata-agent pid=42 request=/grpc.AgentService/StartContainer resp=\"&Empty{XXX_unrecognized:[],}\" sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 source=agent\n" name=kata-proxy pid=128736 sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 source=agent
time="2019-12-11T22:23:17.419688155Z" level=info msg="time=\"2019-12-11T22:23:17.405262758Z\" level=debug msg=\"request end\" debug_console=false duration=114.191624ms name=kata-agent pid=42 request=/grpc.AgentService/CreateContainer resp=\"&Empty{XXX_unrecognized:[],}\" sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 source=agent\n" name=kata-proxy pid=128736 sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 source=agent
time="2019-12-11T22:23:17.420165159Z" level=info msg="time=\"2019-12-11T22:23:17.405661901Z\" level=info msg=\"ignoring unexpected signal\" debug_console=false name=kata-agent pid=42 sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 signal=\"child exited\" source=agent\n" name=kata-proxy pid=128736 sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 source=agent
time="2019-12-11T22:23:17.420605663Z" level=info msg="time=\"2019-12-11T22:23:17.405925739Z\" level=info msg=\"ignoring unexpected signal\" debug_console=false name=kata-agent pid=42 sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 signal=\"child exited\" source=agent\n" name=kata-proxy pid=128736 sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 source=agent
time="2019-12-11T22:23:17.448103396Z" level=info msg="time=\"2019-12-11T22:23:17.43369481Z\" level=debug msg=\"request end\" debug_console=false duration=\"3.068µs\" name=kata-agent pid=42 request=/grpc.AgentService/OnlineCPUMem resp=\"&Empty{XXX_unrecognized:[],}\" sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 source=agent\n" name=kata-proxy pid=128736 sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 source=agent
time="2019-12-11T22:23:17.462540018Z" level=info msg="time=\"2019-12-11T22:23:17.448150343Z\" level=debug msg=\"request end\" debug_console=false duration=\"25.336µs\" name=kata-agent pid=42 request=/grpc.AgentService/OnlineCPUMem resp=\"&Empty{XXX_unrecognized:[],}\" sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 source=agent\n" name=kata-proxy pid=128736 sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 source=agent
time="2019-12-11T22:23:17.500162337Z" level=info msg="time=\"2019-12-11T22:23:17.485755779Z\" level=debug msg=\"request end\" debug_console=false duration=48.909087ms name=kata-agent pid=42 request=/grpc.AgentService/CloseStdin resp=\"&Empty{XXX_unrecognized:[],}\" sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 source=agent\n" name=kata-proxy pid=128736 sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 source=agent
time="2019-12-11T22:23:17.594659438Z" level=info msg="time=\"2019-12-11T22:23:17.580321441Z\" level=debug msg=\"request end\" debug_console=false duration=5.274716ms name=kata-agent pid=42 request=/grpc.AgentService/StartContainer resp=\"&Empty{XXX_unrecognized:[],}\" sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 source=agent\n" name=kata-proxy pid=128736 sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 source=agent
time="2019-12-11T22:23:17.596698955Z" level=debug msg="Copy stream error" error="write unix /run/vc/sbs/6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545/proxy.sock->@: use of closed network connection" name=kata-proxy pid=128736 sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 source=proxy
time="2019-12-11T22:23:17.601260494Z" level=info msg="time=\"2019-12-11T22:23:17.586864673Z\" level=debug msg=\"request end\" debug_console=false duration=\"2.726µs\" name=kata-agent pid=42 request=/grpc.AgentService/OnlineCPUMem resp=\"&Empty{XXX_unrecognized:[],}\" sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 source=agent\n" name=kata-proxy pid=128736 sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 source=agent
time="2019-12-11T22:23:17.634574076Z" level=info msg="time=\"2019-12-11T22:23:17.620112909Z\" level=debug msg=\"request end\" debug_console=false duration=183.858341ms name=kata-agent pid=42 request=/grpc.AgentService/ReadStdout resp=\"data:\\\"1:C 11 Dec 22:23:17.619 # oO0OoO0OoO0Oo Redis is starting oO0OoO0OoO0Oo\\\\n1:C 11 Dec 22:23:17.619 # Redis version=4.0.9, bits=64, commit=00000000, modified=0, pid=1, just started\\\\n1:C 11 Dec 22:23:17.619 # Warning: no config file specified, using the default config. In order to specify a config file use redis-server /path/to/redis.conf\\\\n\\\" \" sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 source=agent\n" name=kata-proxy pid=128736 sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 source=agent
time="2019-12-11T22:23:17.645915872Z" level=info msg="time=\"2019-12-11T22:23:17.631237773Z\" level=debug msg=\"request end\" debug_console=false duration=\"38.342µs\" name=kata-agent pid=42 request=/grpc.AgentService/ReadStdout resp=\"data:\\\"1:M 11 Dec 22:23:17.628 # WARNING: The TCP backlog setting of 511 cannot be enforced because /proc/sys/net/core/somaxconn is set to the lower value of 128.\\\\n1:M 11 Dec 22:23:17.628 # Server initialized\\\\n1:M 11 Dec 22:23:17.628 # WARNING overcommit_memory is set to 0! Background save may fail under low memory condition. To fix this issue add 'vm.overcommit_memory = 1' to /etc/sysctl.conf and then reboot or run the command 'sysctl vm.overcommit_memory=1' for this to take effect.\\\\n1:M 11 Dec 22:23:17.628 * Ready to accept connections\\\\n\\\" \" sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 source=agent\n" name=kata-proxy pid=128736 sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 source=agent
time="2019-12-11T22:23:17.811600477Z" level=info msg="time=\"2019-12-11T22:23:17.797177418Z\" level=debug msg=\"request end\" debug_console=false duration=35.933439ms name=kata-agent pid=42 request=/grpc.AgentService/ExecProcess resp=\"&Empty{XXX_unrecognized:[],}\" sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 source=agent\n" name=kata-proxy pid=128736 sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 source=agent
time="2019-12-11T22:23:17.817845229Z" level=info msg="time=\"2019-12-11T22:23:17.797211097Z\" level=info msg=\"ignoring unexpected signal\" debug_console=false name=kata-agent pid=42 sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 signal=\"child exited\" source=agent\n" name=kata-proxy pid=128736 sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 source=agent
time="2019-12-11T22:23:17.81907814Z" level=info msg="time=\"2019-12-11T22:23:17.803821292Z\" level=info msg=\"ignoring unexpected signal\" debug_console=false name=kata-agent pid=42 sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 signal=\"child exited\" source=agent\n" name=kata-proxy pid=128736 sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 source=agent
time="2019-12-11T22:23:17.82028425Z" level=info msg="time=\"2019-12-11T22:23:17.805756782Z\" level=info msg=\"ignoring unexpected signal\" debug_console=false name=kata-agent pid=42 sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 signal=\"child exited\" source=agent\n" name=kata-proxy pid=128736 sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 source=agent
time="2019-12-11T22:23:17.829823231Z" level=info msg="time=\"2019-12-11T22:23:17.815148176Z\" level=debug msg=\"request end\" debug_console=false duration=\"15.853µs\" name=kata-agent pid=42 request=/grpc.AgentService/CloseStdin resp=\"&Empty{XXX_unrecognized:[],}\" sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 source=agent\n" name=kata-proxy pid=128736 sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 source=agent
time="2019-12-11T22:23:17.960405438Z" level=info msg="time=\"2019-12-11T22:23:17.946038334Z\" level=debug msg=\"request end\" debug_console=false duration=\"482.782µs\" name=kata-agent pid=42 request=/grpc.AgentService/SignalProcess resp=\"&Empty{XXX_unrecognized:[],}\" sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 source=agent\n" name=kata-proxy pid=128736 sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 source=agent
time="2019-12-11T22:23:18.059775474Z" level=info msg="time=\"2019-12-11T22:23:18.0452987Z\" level=info msg=\"ignoring unexpected signal\" debug_console=false name=kata-agent pid=42 sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 signal=\"child exited\" source=agent\n" name=kata-proxy pid=128736 sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 source=agent
time="2019-12-11T22:23:18.170297399Z" level=info msg="time=\"2019-12-11T22:23:18.155853459Z\" level=debug msg=\"request end\" debug_console=false duration=\"735.861µs\" name=kata-agent pid=42 request=/grpc.AgentService/SignalProcess resp=\"&Empty{XXX_unrecognized:[],}\" sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 source=agent\n" name=kata-proxy pid=128736 sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 source=agent
time="2019-12-11T22:23:18.347063179Z" level=info msg="time=\"2019-12-11T22:23:18.332654953Z\" level=debug msg=\"request end\" debug_console=false duration=169.637609ms name=kata-agent pid=42 request=/grpc.AgentService/RemoveContainer resp=\"&Empty{XXX_unrecognized:[],}\" sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 source=agent\n" name=kata-proxy pid=128736 sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 source=agent
time="2019-12-11T22:23:18.360686793Z" level=info msg="[    4.466305] sd 0:0:0:1: [sdb] Synchronize Cache(10) failed: Result: hostbyte=0x00 driverbyte=0x08\n" name=kata-proxy pid=128736 sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 source=agent
time="2019-12-11T22:23:18.595288657Z" level=info msg="time=\"2019-12-11T22:23:18.580895307Z\" level=debug msg=\"request end\" debug_console=false duration=\"881.929µs\" name=kata-agent pid=42 request=/grpc.AgentService/SignalProcess resp=\"&Empty{XXX_unrecognized:[],}\" sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 source=agent\n" name=kata-proxy pid=128736 sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 source=agent
time="2019-12-11T22:23:18.600845604Z" level=info msg="time=\"2019-12-11T22:23:18.586193115Z\" level=info msg=\"ignoring unexpected signal\" debug_console=false name=kata-agent pid=42 sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 signal=\"child exited\" source=agent\n" name=kata-proxy pid=128736 sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 source=agent
time="2019-12-11T22:23:18.71031482Z" level=info msg="time=\"2019-12-11T22:23:18.695636738Z\" level=debug msg=\"request end\" debug_console=false duration=3.731954ms name=kata-agent pid=42 request=/grpc.AgentService/SignalProcess resp=\"&Empty{XXX_unrecognized:[],}\" sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 source=agent\n" name=kata-proxy pid=128736 sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 source=agent
time="2019-12-11T22:23:18.796768244Z" level=info msg="time=\"2019-12-11T22:23:18.782414699Z\" level=debug msg=\"request end\" debug_console=false duration=80.198818ms name=kata-agent pid=42 request=/grpc.AgentService/RemoveContainer resp=\"&Empty{XXX_unrecognized:[],}\" sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 source=agent\n" name=kata-proxy pid=128736 sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 source=agent
time="2019-12-11T22:23:18.810426058Z" level=info msg="[    4.916994] sd 0:0:0:0: [sda] Synchronize Cache(10) failed: Result: hostbyte=0x04 driverbyte=0x00\n" name=kata-proxy pid=128736 sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 source=agent
time="2019-12-11T22:23:18.831821537Z" level=info msg="time=\"2019-12-11T22:23:18.817474812Z\" level=debug msg=\"request end\" debug_console=false duration=20.1379ms name=kata-agent pid=42 request=/grpc.AgentService/DestroySandbox resp=\"&Empty{XXX_unrecognized:[],}\" sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 source=agent\n" name=kata-proxy pid=128736 sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 source=agent
time="2019-12-11T22:23:18.832522043Z" level=fatal msg="failed to handle exit signal" error="close unix @->/run/vc/vm/6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545/kata.sock: use of closed network connection" name=kata-proxy pid=128736 sandbox=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 source=proxy

Shim logs

Recent shim problems found in system journal:

time="2019-12-11T22:19:37.408245129Z" level=info msg="copy stderr failed" container=2f2ce9616e72eea6179d766f410d7aa4043cbb1af1138659001f58f9de05e77c error="rpc error: code = Unknown desc = EOF" exec-id=9cbf9ac3-4ad1-4925-a8c0-7dea4cf3d549 name=kata-shim pid=73 source=shim
time="2019-12-11T22:19:37.409868743Z" level=info msg="copy stdout failed" container=2f2ce9616e72eea6179d766f410d7aa4043cbb1af1138659001f58f9de05e77c error="rpc error: code = Unknown desc = EOF" exec-id=9cbf9ac3-4ad1-4925-a8c0-7dea4cf3d549 name=kata-shim pid=73 source=shim
time="2019-12-11T22:19:37.566026164Z" level=info msg="copy stderr failed" container=2f2ce9616e72eea6179d766f410d7aa4043cbb1af1138659001f58f9de05e77c error="rpc error: code = Unknown desc = EOF" exec-id=07ed028e-366c-4903-9fb8-2984b600d52a name=kata-shim pid=85 source=shim
time="2019-12-11T22:19:37.567869079Z" level=info msg="copy stdout failed" container=2f2ce9616e72eea6179d766f410d7aa4043cbb1af1138659001f58f9de05e77c error="rpc error: code = Unknown desc = EOF" exec-id=07ed028e-366c-4903-9fb8-2984b600d52a name=kata-shim pid=85 source=shim
time="2019-12-11T22:19:37.717327643Z" level=info msg="copy stderr failed" container=2f2ce9616e72eea6179d766f410d7aa4043cbb1af1138659001f58f9de05e77c error="rpc error: code = Unknown desc = EOF" exec-id=2f2ce9616e72eea6179d766f410d7aa4043cbb1af1138659001f58f9de05e77c name=kata-shim pid=1 source=shim
time="2019-12-11T22:19:37.717684546Z" level=info msg="copy stdout failed" container=2f2ce9616e72eea6179d766f410d7aa4043cbb1af1138659001f58f9de05e77c error="rpc error: code = Unknown desc = EOF" exec-id=2f2ce9616e72eea6179d766f410d7aa4043cbb1af1138659001f58f9de05e77c name=kata-shim pid=1 source=shim
time="2019-12-11T22:19:40.609072971Z" level=info msg="copy stderr failed" container=782c2545d97898a5ae94ef5b81829824b9f6d58cc579e44bc61af5a5f4e9bc7f error="rpc error: code = Unknown desc = EOF" exec-id=782c2545d97898a5ae94ef5b81829824b9f6d58cc579e44bc61af5a5f4e9bc7f name=kata-shim pid=1 source=shim
time="2019-12-11T22:19:40.609072971Z" level=info msg="copy stdout failed" container=782c2545d97898a5ae94ef5b81829824b9f6d58cc579e44bc61af5a5f4e9bc7f error="rpc error: code = Unknown desc = EOF" exec-id=782c2545d97898a5ae94ef5b81829824b9f6d58cc579e44bc61af5a5f4e9bc7f name=kata-shim pid=1 source=shim
time="2019-12-11T22:20:05.563400832Z" level=info msg="copy stderr failed" container=0bb40b84adf2517ce7aceb6d925baa95b9adfd76f5af97e602b439a8955af98c error="rpc error: code = Unknown desc = EOF" exec-id=0bb40b84adf2517ce7aceb6d925baa95b9adfd76f5af97e602b439a8955af98c name=kata-shim pid=1 source=shim
time="2019-12-11T22:20:05.57035099Z" level=info msg="copy stdout failed" container=0bb40b84adf2517ce7aceb6d925baa95b9adfd76f5af97e602b439a8955af98c error="rpc error: code = Unknown desc = EOF" exec-id=0bb40b84adf2517ce7aceb6d925baa95b9adfd76f5af97e602b439a8955af98c name=kata-shim pid=1 source=shim
time="2019-12-11T22:20:07.255500433Z" level=info msg="copy stdout failed" container=21f37ba04687bcf6df3e2a2f08655556f05ff5b671f29bc06c5c663462e8d7d7 error="rpc error: code = Unknown desc = EOF" exec-id=21f37ba04687bcf6df3e2a2f08655556f05ff5b671f29bc06c5c663462e8d7d7 name=kata-shim pid=1 source=shim
time="2019-12-11T22:20:07.257054246Z" level=info msg="copy stderr failed" container=21f37ba04687bcf6df3e2a2f08655556f05ff5b671f29bc06c5c663462e8d7d7 error="rpc error: code = Unknown desc = EOF" exec-id=21f37ba04687bcf6df3e2a2f08655556f05ff5b671f29bc06c5c663462e8d7d7 name=kata-shim pid=1 source=shim
time="2019-12-11T22:20:34.201131223Z" level=info msg="copy stderr failed" container=5165815b26b2e6d4d06cb9330629bba926ebf8d5faf05beefad35f7a326b2abd error="rpc error: code = Unknown desc = EOF" exec-id=02e87ca4-82ef-43e4-85d1-f60e7e79d260 name=kata-shim pid=13 source=shim
time="2019-12-11T22:20:34.203017939Z" level=info msg="copy stdout failed" container=5165815b26b2e6d4d06cb9330629bba926ebf8d5faf05beefad35f7a326b2abd error="rpc error: code = Unknown desc = EOF" exec-id=02e87ca4-82ef-43e4-85d1-f60e7e79d260 name=kata-shim pid=13 source=shim
time="2019-12-11T22:20:34.356978532Z" level=info msg="copy stderr failed" container=5165815b26b2e6d4d06cb9330629bba926ebf8d5faf05beefad35f7a326b2abd error="rpc error: code = Unknown desc = EOF" exec-id=a1b255e4-7fcb-4dd9-9167-68044a422f4e name=kata-shim pid=24 source=shim
time="2019-12-11T22:20:34.359284252Z" level=info msg="copy stdout failed" container=5165815b26b2e6d4d06cb9330629bba926ebf8d5faf05beefad35f7a326b2abd error="rpc error: code = Unknown desc = EOF" exec-id=a1b255e4-7fcb-4dd9-9167-68044a422f4e name=kata-shim pid=24 source=shim
time="2019-12-11T22:20:34.529791684Z" level=info msg="copy stdout failed" container=5165815b26b2e6d4d06cb9330629bba926ebf8d5faf05beefad35f7a326b2abd error="rpc error: code = Unknown desc = EOF" exec-id=5165815b26b2e6d4d06cb9330629bba926ebf8d5faf05beefad35f7a326b2abd name=kata-shim pid=1 source=shim
time="2019-12-11T22:20:34.53052779Z" level=info msg="copy stderr failed" container=5165815b26b2e6d4d06cb9330629bba926ebf8d5faf05beefad35f7a326b2abd error="rpc error: code = Unknown desc = EOF" exec-id=5165815b26b2e6d4d06cb9330629bba926ebf8d5faf05beefad35f7a326b2abd name=kata-shim pid=1 source=shim
time="2019-12-11T22:20:37.281739578Z" level=info msg="copy stderr failed" container=4aaf8fb192a078797cd8d68683477c1e1678afb51e309a3e4c1191f14f71d1ff error="rpc error: code = Unknown desc = EOF" exec-id=4aaf8fb192a078797cd8d68683477c1e1678afb51e309a3e4c1191f14f71d1ff name=kata-shim pid=1 source=shim
time="2019-12-11T22:20:37.282541385Z" level=info msg="copy stdout failed" container=4aaf8fb192a078797cd8d68683477c1e1678afb51e309a3e4c1191f14f71d1ff error="rpc error: code = Unknown desc = EOF" exec-id=4aaf8fb192a078797cd8d68683477c1e1678afb51e309a3e4c1191f14f71d1ff name=kata-shim pid=1 source=shim
time="2019-12-11T22:21:01.848408179Z" level=info msg="copy stderr failed" container=97a8d5e241d98d253c62af4cb16c81a17fef3afb5567d704b3a5ccae98839a9b error="rpc error: code = Unknown desc = EOF" exec-id=669c773c-0618-4d74-a991-d5ba55522a86 name=kata-shim pid=11 source=shim
time="2019-12-11T22:21:01.84969929Z" level=info msg="copy stdout failed" container=97a8d5e241d98d253c62af4cb16c81a17fef3afb5567d704b3a5ccae98839a9b error="rpc error: code = Unknown desc = EOF" exec-id=669c773c-0618-4d74-a991-d5ba55522a86 name=kata-shim pid=11 source=shim
time="2019-12-11T22:21:02.016466517Z" level=info msg="copy stderr failed" container=97a8d5e241d98d253c62af4cb16c81a17fef3afb5567d704b3a5ccae98839a9b error="rpc error: code = Unknown desc = EOF" exec-id=4deeb454-438e-4a8c-9b26-a32cbe9af25a name=kata-shim pid=22 source=shim
time="2019-12-11T22:21:02.018788637Z" level=info msg="copy stdout failed" container=97a8d5e241d98d253c62af4cb16c81a17fef3afb5567d704b3a5ccae98839a9b error="rpc error: code = Unknown desc = EOF" exec-id=4deeb454-438e-4a8c-9b26-a32cbe9af25a name=kata-shim pid=22 source=shim
time="2019-12-11T22:21:02.205825437Z" level=info msg="copy stderr failed" container=97a8d5e241d98d253c62af4cb16c81a17fef3afb5567d704b3a5ccae98839a9b error="rpc error: code = Unknown desc = EOF" exec-id=97a8d5e241d98d253c62af4cb16c81a17fef3afb5567d704b3a5ccae98839a9b name=kata-shim pid=1 source=shim
time="2019-12-11T22:21:02.206560544Z" level=info msg="copy stdout failed" container=97a8d5e241d98d253c62af4cb16c81a17fef3afb5567d704b3a5ccae98839a9b error="rpc error: code = Unknown desc = EOF" exec-id=97a8d5e241d98d253c62af4cb16c81a17fef3afb5567d704b3a5ccae98839a9b name=kata-shim pid=1 source=shim
time="2019-12-11T22:21:04.931466836Z" level=info msg="copy stdout failed" container=95ca7946fd8337d339c604117965f42eb5c9b7eb731fac72b7659a3a191d443b error="rpc error: code = Unknown desc = EOF" exec-id=95ca7946fd8337d339c604117965f42eb5c9b7eb731fac72b7659a3a191d443b name=kata-shim pid=1 source=shim
time="2019-12-11T22:21:04.934715663Z" level=info msg="copy stderr failed" container=95ca7946fd8337d339c604117965f42eb5c9b7eb731fac72b7659a3a191d443b error="rpc error: code = Unknown desc = EOF" exec-id=95ca7946fd8337d339c604117965f42eb5c9b7eb731fac72b7659a3a191d443b name=kata-shim pid=1 source=shim
time="2019-12-11T22:21:29.930988661Z" level=info msg="copy stderr failed" container=615313b31087b237bb20ad7bd8b6b9045a3892de50551644c1b9ffcc06a00b36 error="rpc error: code = Unknown desc = EOF" exec-id=c3513ea2-2e71-40fc-b76e-716a2c156cb8 name=kata-shim pid=7 source=shim
time="2019-12-11T22:21:29.932641174Z" level=info msg="copy stdout failed" container=615313b31087b237bb20ad7bd8b6b9045a3892de50551644c1b9ffcc06a00b36 error="rpc error: code = Unknown desc = EOF" exec-id=c3513ea2-2e71-40fc-b76e-716a2c156cb8 name=kata-shim pid=7 source=shim
time="2019-12-11T22:21:30.163277897Z" level=info msg="copy stderr failed" container=615313b31087b237bb20ad7bd8b6b9045a3892de50551644c1b9ffcc06a00b36 error="rpc error: code = Unknown desc = EOF" exec-id=615313b31087b237bb20ad7bd8b6b9045a3892de50551644c1b9ffcc06a00b36 name=kata-shim pid=1 source=shim
time="2019-12-11T22:21:30.164627508Z" level=info msg="copy stdout failed" container=615313b31087b237bb20ad7bd8b6b9045a3892de50551644c1b9ffcc06a00b36 error="rpc error: code = Unknown desc = EOF" exec-id=615313b31087b237bb20ad7bd8b6b9045a3892de50551644c1b9ffcc06a00b36 name=kata-shim pid=1 source=shim
time="2019-12-11T22:21:30.611662133Z" level=info msg="copy stderr failed" container=4e84a1a7e7ceb45933e45aa63bf14292a8b548b299367f81eae6e3de0f2591e2 error="rpc error: code = Unknown desc = EOF" exec-id=4e84a1a7e7ceb45933e45aa63bf14292a8b548b299367f81eae6e3de0f2591e2 name=kata-shim pid=1 source=shim
time="2019-12-11T22:21:30.613509549Z" level=info msg="copy stdout failed" container=4e84a1a7e7ceb45933e45aa63bf14292a8b548b299367f81eae6e3de0f2591e2 error="rpc error: code = Unknown desc = EOF" exec-id=4e84a1a7e7ceb45933e45aa63bf14292a8b548b299367f81eae6e3de0f2591e2 name=kata-shim pid=1 source=shim
time="2019-12-11T22:21:56.328032687Z" level=info msg="copy stdout failed" container=b6880be31f48ec5fe8ac8d83822000b3c4a936a96b97853f249693889bb57dc1 error="rpc error: code = Unknown desc = EOF" exec-id=b6880be31f48ec5fe8ac8d83822000b3c4a936a96b97853f249693889bb57dc1 name=kata-shim pid=1 source=shim
time="2019-12-11T22:21:56.329110497Z" level=info msg="copy stderr failed" container=b6880be31f48ec5fe8ac8d83822000b3c4a936a96b97853f249693889bb57dc1 error="rpc error: code = Unknown desc = EOF" exec-id=b6880be31f48ec5fe8ac8d83822000b3c4a936a96b97853f249693889bb57dc1 name=kata-shim pid=1 source=shim
time="2019-12-11T22:22:23.261928238Z" level=info msg="copy stderr failed" container=f1e5d493951c831c2696e1b7abe16994dc23847adb0267296b3c2f21c4747a5f error="rpc error: code = Unknown desc = EOF" exec-id=f1e5d493951c831c2696e1b7abe16994dc23847adb0267296b3c2f21c4747a5f name=kata-shim pid=1 source=shim
time="2019-12-11T22:22:23.263148148Z" level=info msg="copy stdout failed" container=f1e5d493951c831c2696e1b7abe16994dc23847adb0267296b3c2f21c4747a5f error="rpc error: code = Unknown desc = EOF" exec-id=f1e5d493951c831c2696e1b7abe16994dc23847adb0267296b3c2f21c4747a5f name=kata-shim pid=1 source=shim
time="2019-12-11T22:22:23.764801101Z" level=info msg="copy stderr failed" container=6f38e9f2e0fbbb04e8c76eed1378a63f30cceaeb788d7a4d3b3db886b2857941 error="rpc error: code = Unknown desc = EOF" exec-id=6f38e9f2e0fbbb04e8c76eed1378a63f30cceaeb788d7a4d3b3db886b2857941 name=kata-shim pid=1 source=shim
time="2019-12-11T22:22:23.765409706Z" level=info msg="copy stdout failed" container=6f38e9f2e0fbbb04e8c76eed1378a63f30cceaeb788d7a4d3b3db886b2857941 error="rpc error: code = Unknown desc = EOF" exec-id=6f38e9f2e0fbbb04e8c76eed1378a63f30cceaeb788d7a4d3b3db886b2857941 name=kata-shim pid=1 source=shim
time="2019-12-11T22:22:50.583599581Z" level=info msg="copy stderr failed" container=fb4f2a54fd06f2d0b5bf6fa0c69790276cd3ef06d4aef979778dd6762e6f7516 error="rpc error: code = Unknown desc = EOF" exec-id=fb4f2a54fd06f2d0b5bf6fa0c69790276cd3ef06d4aef979778dd6762e6f7516 name=kata-shim pid=1 source=shim
time="2019-12-11T22:22:50.585843701Z" level=info msg="copy stdout failed" container=fb4f2a54fd06f2d0b5bf6fa0c69790276cd3ef06d4aef979778dd6762e6f7516 error="rpc error: code = Unknown desc = EOF" exec-id=fb4f2a54fd06f2d0b5bf6fa0c69790276cd3ef06d4aef979778dd6762e6f7516 name=kata-shim pid=1 source=shim
time="2019-12-11T22:22:51.030283533Z" level=info msg="copy stdout failed" container=e361670cb6a33cfcb61e0f17a82118e44d04f674819832acd6d5ea127b88b9ba error="rpc error: code = Unknown desc = EOF" exec-id=e361670cb6a33cfcb61e0f17a82118e44d04f674819832acd6d5ea127b88b9ba name=kata-shim pid=1 source=shim
time="2019-12-11T22:22:51.032603452Z" level=info msg="copy stderr failed" container=e361670cb6a33cfcb61e0f17a82118e44d04f674819832acd6d5ea127b88b9ba error="rpc error: code = Unknown desc = EOF" exec-id=e361670cb6a33cfcb61e0f17a82118e44d04f674819832acd6d5ea127b88b9ba name=kata-shim pid=1 source=shim
time="2019-12-11T22:23:17.829552229Z" level=info msg="copy stderr failed" container=beeaf53c923f259e321d76fc63ae9badd7967e6801a10caa1d49b76895145629 error="rpc error: code = Unknown desc = EOF" exec-id=85bbd55d-6b02-4687-ad94-70f6a2814967 name=kata-shim pid=10 source=shim
time="2019-12-11T22:23:17.831263943Z" level=info msg="copy stdout failed" container=beeaf53c923f259e321d76fc63ae9badd7967e6801a10caa1d49b76895145629 error="rpc error: code = Unknown desc = EOF" exec-id=85bbd55d-6b02-4687-ad94-70f6a2814967 name=kata-shim pid=10 source=shim
time="2019-12-11T22:23:18.061179986Z" level=info msg="copy stdout failed" container=beeaf53c923f259e321d76fc63ae9badd7967e6801a10caa1d49b76895145629 error="rpc error: code = Unknown desc = EOF" exec-id=beeaf53c923f259e321d76fc63ae9badd7967e6801a10caa1d49b76895145629 name=kata-shim pid=1 source=shim
time="2019-12-11T22:23:18.061285287Z" level=info msg="copy stderr failed" container=beeaf53c923f259e321d76fc63ae9badd7967e6801a10caa1d49b76895145629 error="rpc error: code = Unknown desc = EOF" exec-id=beeaf53c923f259e321d76fc63ae9badd7967e6801a10caa1d49b76895145629 name=kata-shim pid=1 source=shim
time="2019-12-11T22:23:18.600493401Z" level=info msg="copy stdout failed" container=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 error="rpc error: code = Unknown desc = EOF" exec-id=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 name=kata-shim pid=1 source=shim
time="2019-12-11T22:23:18.608108664Z" level=info msg="copy stderr failed" container=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 error="rpc error: code = Unknown desc = EOF" exec-id=6a190318674e5608357e236df5a1fc21f76b71ed7ad349576180f199c0935545 name=kata-shim pid=1 source=shim

Throttler logs

No recent throttler problems found in system journal.

---

Container manager details

Have docker

Docker

Output of "docker version":

Client:
 Version:           18.06.3-ce
 API version:       1.38
 Go version:        go1.10.3
 Git commit:        d7080c1
 Built:             Wed Feb 20 02:28:10 2019
 OS/Arch:           linux/amd64
 Experimental:      false
Cannot connect to the Docker daemon at unix:///var/run/docker.sock. Is the docker daemon running?

Output of "docker info":

Cannot connect to the Docker daemon at unix:///var/run/docker.sock. Is the docker daemon running?

Output of "systemctl show docker":

Type=notify
Restart=on-failure
NotifyAccess=main
RestartUSec=100ms
TimeoutStartUSec=infinity
TimeoutStopUSec=1min 30s
RuntimeMaxUSec=infinity
WatchdogUSec=0
WatchdogTimestampMonotonic=0
PermissionsStartOnly=no
RootDirectoryStartOnly=no
RemainAfterExit=no
GuessMainPID=yes
MainPID=0
ControlPID=0
FileDescriptorStoreMax=0
NFileDescriptorStore=0
StatusErrno=0
Result=success
UID=[not set]
GID=[not set]
NRestarts=0
ExecMainStartTimestamp=Wed 2019-12-11 21:59:08 UTC
ExecMainStartTimestampMonotonic=3850730012
ExecMainExitTimestamp=Wed 2019-12-11 22:02:39 UTC
ExecMainExitTimestampMonotonic=4061869309
ExecMainPID=34488
ExecMainCode=1
ExecMainStatus=0
ExecStart={ path=/usr/bin/dockerd ; argv[]=/usr/bin/dockerd -D --add-runtime kata-runtime=/usr/local/bin/kata-runtime --default-runtime=runc --storage-driver=overlay2 ; ignore_errors=no ; start_time=[Wed 2019-12-11 21:59:08 UTC] ; stop_time=[Wed 2019-12-11 22:02:39 UTC] ; pid=34488 ; code=exited ; status=0 }
ExecReload={ path=/bin/kill ; argv[]=/bin/kill -s HUP $MAINPID ; ignore_errors=no ; start_time=[n/a] ; stop_time=[n/a] ; pid=0 ; code=(null) ; status=0/0 }
Slice=system.slice
MemoryCurrent=[not set]
CPUUsageNSec=[not set]
TasksCurrent=[not set]
IPIngressBytes=18446744073709551615
IPIngressPackets=18446744073709551615
IPEgressBytes=18446744073709551615
IPEgressPackets=18446744073709551615
Delegate=yes
DelegateControllers=cpu cpuacct io blkio memory devices pids
CPUAccounting=no
CPUWeight=[not set]
StartupCPUWeight=[not set]
CPUShares=[not set]
StartupCPUShares=[not set]
CPUQuotaPerSecUSec=infinity
IOAccounting=no
IOWeight=[not set]
StartupIOWeight=[not set]
BlockIOAccounting=no
BlockIOWeight=[not set]
StartupBlockIOWeight=[not set]
MemoryAccounting=no
MemoryLow=0
MemoryHigh=infinity
MemoryMax=infinity
MemorySwapMax=infinity
MemoryLimit=infinity
DevicePolicy=auto
TasksAccounting=yes
TasksMax=infinity
IPAccounting=no
UMask=0022
LimitCPU=infinity
LimitCPUSoft=infinity
LimitFSIZE=infinity
LimitFSIZESoft=infinity
LimitDATA=infinity
LimitDATASoft=infinity
LimitSTACK=infinity
LimitSTACKSoft=8388608
LimitCORE=infinity
LimitCORESoft=infinity
LimitRSS=infinity
LimitRSSSoft=infinity
LimitNOFILE=1048576
LimitNOFILESoft=1048576
LimitAS=infinity
LimitASSoft=infinity
LimitNPROC=infinity
LimitNPROCSoft=infinity
LimitMEMLOCK=16777216
LimitMEMLOCKSoft=16777216
LimitLOCKS=infinity
LimitLOCKSSoft=infinity
LimitSIGPENDING=63967
LimitSIGPENDINGSoft=63967
LimitMSGQUEUE=819200
LimitMSGQUEUESoft=819200
LimitNICE=0
LimitNICESoft=0
LimitRTPRIO=0
LimitRTPRIOSoft=0
LimitRTTIME=infinity
LimitRTTIMESoft=infinity
OOMScoreAdjust=0
Nice=0
IOSchedulingClass=0
IOSchedulingPriority=0
CPUSchedulingPolicy=0
CPUSchedulingPriority=0
TimerSlackNSec=50000
CPUSchedulingResetOnFork=no
NonBlocking=no
StandardInput=null
StandardInputData=
StandardOutput=journal
StandardError=inherit
TTYReset=no
TTYVHangup=no
TTYVTDisallocate=no
SyslogPriority=30
SyslogLevelPrefix=yes
SyslogLevel=6
SyslogFacility=3
LogLevelMax=-1
SecureBits=0
CapabilityBoundingSet=cap_chown cap_dac_override cap_dac_read_search cap_fowner cap_fsetid cap_kill cap_setgid cap_setuid cap_setpcap cap_linux_immutable cap_net_bind_service cap_net_broadcast cap_net_admin cap_net_raw cap_ipc_lock cap_ipc_owner cap_sys_module cap_sys_rawio cap_sys_chroot cap_sys_ptrace cap_sys_pacct cap_sys_admin cap_sys_boot cap_sys_nice cap_sys_resource cap_sys_time cap_sys_tty_config cap_mknod cap_lease cap_audit_write cap_audit_control cap_setfcap cap_mac_override cap_mac_admin cap_syslog cap_wake_alarm cap_block_suspend
AmbientCapabilities=
DynamicUser=no
RemoveIPC=no
MountFlags=
PrivateTmp=no
PrivateDevices=no
ProtectKernelTunables=no
ProtectKernelModules=no
ProtectControlGroups=no
PrivateNetwork=no
PrivateUsers=no
ProtectHome=no
ProtectSystem=no
SameProcessGroup=no
UtmpMode=init
IgnoreSIGPIPE=yes
NoNewPrivileges=no
SystemCallErrorNumber=0
LockPersonality=no
RuntimeDirectoryPreserve=no
RuntimeDirectoryMode=0755
StateDirectoryMode=0755
CacheDirectoryMode=0755
LogsDirectoryMode=0755
ConfigurationDirectoryMode=0755
MemoryDenyWriteExecute=no
RestrictRealtime=no
RestrictNamespaces=no
MountAPIVFS=no
KeyringMode=private
KillMode=process
KillSignal=15
SendSIGKILL=yes
SendSIGHUP=no
Id=docker.service
Names=docker.service
Requires=system.slice docker.socket sysinit.target
Wants=network-online.target
WantedBy=multi-user.target
ConsistsOf=docker.socket
Conflicts=shutdown.target
Before=multi-user.target shutdown.target
After=sysinit.target network-online.target docker.socket systemd-journald.socket system.slice basic.target firewalld.service
TriggeredBy=docker.socket
Documentation=https://docs.docker.com
Description=Docker Application Container Engine
LoadState=loaded
ActiveState=inactive
SubState=dead
FragmentPath=/lib/systemd/system/docker.service
DropInPaths=/etc/systemd/system/docker.service.d/kata-containers.conf
UnitFileState=enabled
UnitFilePreset=enabled
StateChangeTimestamp=Wed 2019-12-11 22:02:39 UTC
StateChangeTimestampMonotonic=4061869318
InactiveExitTimestamp=Wed 2019-12-11 21:59:08 UTC
InactiveExitTimestampMonotonic=3850730064
ActiveEnterTimestamp=Wed 2019-12-11 21:59:16 UTC
ActiveEnterTimestampMonotonic=3858924103
ActiveExitTimestamp=Wed 2019-12-11 22:02:38 UTC
ActiveExitTimestampMonotonic=4060863056
InactiveEnterTimestamp=Wed 2019-12-11 22:02:39 UTC
InactiveEnterTimestampMonotonic=4061869318
CanStart=yes
CanStop=yes
CanReload=yes
CanIsolate=no
StopWhenUnneeded=no
RefuseManualStart=no
RefuseManualStop=no
AllowIsolate=no
DefaultDependencies=yes
OnFailureJobMode=replace
IgnoreOnIsolate=no
NeedDaemonReload=no
JobTimeoutUSec=infinity
JobRunningTimeoutUSec=infinity
JobTimeoutAction=none
ConditionResult=yes
AssertResult=yes
ConditionTimestamp=Wed 2019-12-11 21:59:08 UTC
ConditionTimestampMonotonic=3850728388
AssertTimestamp=Wed 2019-12-11 21:59:08 UTC
AssertTimestampMonotonic=3850728389
Transient=no
Perpetual=no
StartLimitIntervalUSec=1min
StartLimitBurst=3
StartLimitAction=none
FailureAction=none
SuccessAction=none
InvocationID=0662b5a5773a4bd7aff4a21c248a2f75
CollectMode=inactive

Have kubectl

Kubernetes

Output of "kubectl version":

Client Version: version.Info{Major:"1", Minor:"16", GitVersion:"v1.16.2", GitCommit:"c97fe5036ef3df2967d086711e6c0c405941e14b", GitTreeState:"clean", BuildDate:"2019-10-15T19:18:23Z", GoVersion:"go1.12.10", Compiler:"gc", Platform:"linux/amd64"}
The connection to the server localhost:8080 was refused - did you specify the right host or port?

Output of "kubectl config view":

apiVersion: v1
clusters: []
contexts: []
current-context: ""
kind: Config
preferences: {}
users: []

Output of "systemctl show kubelet":

Type=simple
Restart=always
NotifyAccess=none
RestartUSec=10s
TimeoutStartUSec=1min 30s
TimeoutStopUSec=1min 30s
RuntimeMaxUSec=infinity
WatchdogUSec=0
WatchdogTimestampMonotonic=0
PermissionsStartOnly=no
RootDirectoryStartOnly=no
RemainAfterExit=no
GuessMainPID=yes
MainPID=0
ControlPID=0
FileDescriptorStoreMax=0
NFileDescriptorStore=0
StatusErrno=0
Result=exit-code
UID=[not set]
GID=[not set]
NRestarts=126
ExecMainStartTimestamp=Wed 2019-12-11 22:23:24 UTC
ExecMainStartTimestampMonotonic=5307275992
ExecMainExitTimestamp=Wed 2019-12-11 22:23:24 UTC
ExecMainExitTimestampMonotonic=5307347076
ExecMainPID=129346
ExecMainCode=1
ExecMainStatus=255
ExecStart={ path=/usr/bin/kubelet ; argv[]=/usr/bin/kubelet $KUBELET_KUBECONFIG_ARGS $KUBELET_CONFIG_ARGS $KUBELET_KUBEADM_ARGS $KUBELET_EXTRA_ARGS ; ignore_errors=no ; start_time=[Wed 2019-12-11 22:23:24 UTC] ; stop_time=[Wed 2019-12-11 22:23:24 UTC] ; pid=129346 ; code=exited ; status=255 }
Slice=system.slice
MemoryCurrent=[not set]
CPUUsageNSec=[not set]
TasksCurrent=[not set]
IPIngressBytes=18446744073709551615
IPIngressPackets=18446744073709551615
IPEgressBytes=18446744073709551615
IPEgressPackets=18446744073709551615
Delegate=no
CPUAccounting=no
CPUWeight=[not set]
StartupCPUWeight=[not set]
CPUShares=[not set]
StartupCPUShares=[not set]
CPUQuotaPerSecUSec=infinity
IOAccounting=no
IOWeight=[not set]
StartupIOWeight=[not set]
BlockIOAccounting=no
BlockIOWeight=[not set]
StartupBlockIOWeight=[not set]
MemoryAccounting=no
MemoryLow=0
MemoryHigh=infinity
MemoryMax=infinity
MemorySwapMax=infinity
MemoryLimit=infinity
DevicePolicy=auto
TasksAccounting=yes
TasksMax=19190
IPAccounting=no
Environment=[unprintable] [unprintable] KUBELET_CONFIG_ARGS=--config=/var/lib/kubelet/config.yaml
EnvironmentFile=/var/lib/kubelet/kubeadm-flags.env (ignore_errors=yes)
EnvironmentFile=/etc/default/kubelet (ignore_errors=yes)
UMask=0022
LimitCPU=infinity
LimitCPUSoft=infinity
LimitFSIZE=infinity
LimitFSIZESoft=infinity
LimitDATA=infinity
LimitDATASoft=infinity
LimitSTACK=infinity
LimitSTACKSoft=8388608
LimitCORE=infinity
LimitCORESoft=0
LimitRSS=infinity
LimitRSSSoft=infinity
LimitNOFILE=4096
LimitNOFILESoft=1024
LimitAS=infinity
LimitASSoft=infinity
LimitNPROC=63967
LimitNPROCSoft=63967
LimitMEMLOCK=16777216
LimitMEMLOCKSoft=16777216
LimitLOCKS=infinity
LimitLOCKSSoft=infinity
LimitSIGPENDING=63967
LimitSIGPENDINGSoft=63967
LimitMSGQUEUE=819200
LimitMSGQUEUESoft=819200
LimitNICE=0
LimitNICESoft=0
LimitRTPRIO=0
LimitRTPRIOSoft=0
LimitRTTIME=infinity
LimitRTTIMESoft=infinity
OOMScoreAdjust=0
Nice=0
IOSchedulingClass=0
IOSchedulingPriority=0
CPUSchedulingPolicy=0
CPUSchedulingPriority=0
TimerSlackNSec=50000
CPUSchedulingResetOnFork=no
NonBlocking=no
StandardInput=null
StandardInputData=
StandardOutput=journal
StandardError=inherit
TTYReset=no
TTYVHangup=no
TTYVTDisallocate=no
SyslogPriority=30
SyslogLevelPrefix=yes
SyslogLevel=6
SyslogFacility=3
LogLevelMax=-1
SecureBits=0
CapabilityBoundingSet=cap_chown cap_dac_override cap_dac_read_search cap_fowner cap_fsetid cap_kill cap_setgid cap_setuid cap_setpcap cap_linux_immutable cap_net_bind_service cap_net_broadcast cap_net_admin cap_net_raw cap_ipc_lock cap_ipc_owner cap_sys_module cap_sys_rawio cap_sys_chroot cap_sys_ptrace cap_sys_pacct cap_sys_admin cap_sys_boot cap_sys_nice cap_sys_resource cap_sys_time cap_sys_tty_config cap_mknod cap_lease cap_audit_write cap_audit_control cap_setfcap cap_mac_override cap_mac_admin cap_syslog cap_wake_alarm cap_block_suspend
AmbientCapabilities=
DynamicUser=no
RemoveIPC=no
MountFlags=
PrivateTmp=no
PrivateDevices=no
ProtectKernelTunables=no
ProtectKernelModules=no
ProtectControlGroups=no
PrivateNetwork=no
PrivateUsers=no
ProtectHome=no
ProtectSystem=no
SameProcessGroup=no
UtmpMode=init
IgnoreSIGPIPE=yes
NoNewPrivileges=no
SystemCallErrorNumber=0
LockPersonality=no
RuntimeDirectoryPreserve=no
RuntimeDirectoryMode=0755
StateDirectoryMode=0755
CacheDirectoryMode=0755
LogsDirectoryMode=0755
ConfigurationDirectoryMode=0755
MemoryDenyWriteExecute=no
RestrictRealtime=no
RestrictNamespaces=no
MountAPIVFS=no
KeyringMode=private
KillMode=control-group
KillSignal=15
SendSIGKILL=yes
SendSIGHUP=no
Id=kubelet.service
Names=kubelet.service
Requires=system.slice sysinit.target
WantedBy=multi-user.target
Conflicts=shutdown.target
Before=multi-user.target shutdown.target
After=systemd-journald.socket sysinit.target basic.target system.slice
Documentation=https://kubernetes.io/docs/home/
Description=kubelet: The Kubernetes Node Agent
LoadState=loaded
ActiveState=activating
SubState=auto-restart
FragmentPath=/lib/systemd/system/kubelet.service
DropInPaths=/etc/systemd/system/kubelet.service.d/0-crio.conf /etc/systemd/system/kubelet.service.d/10-kubeadm.conf
UnitFileState=enabled
UnitFilePreset=enabled
StateChangeTimestamp=Wed 2019-12-11 22:23:24 UTC
StateChangeTimestampMonotonic=5307348135
InactiveExitTimestamp=Wed 2019-12-11 22:23:24 UTC
InactiveExitTimestampMonotonic=5307348135
ActiveEnterTimestamp=Wed 2019-12-11 22:23:24 UTC
ActiveEnterTimestampMonotonic=5307276060
ActiveExitTimestamp=Wed 2019-12-11 22:23:24 UTC
ActiveExitTimestampMonotonic=5307347266
InactiveEnterTimestamp=Wed 2019-12-11 22:23:24 UTC
InactiveEnterTimestampMonotonic=5307347266
CanStart=yes
CanStop=yes
CanReload=no
CanIsolate=no
StopWhenUnneeded=no
RefuseManualStart=no
RefuseManualStop=no
AllowIsolate=no
DefaultDependencies=yes
OnFailureJobMode=replace
IgnoreOnIsolate=no
NeedDaemonReload=no
JobTimeoutUSec=infinity
JobRunningTimeoutUSec=infinity
JobTimeoutAction=none
ConditionResult=yes
AssertResult=yes
ConditionTimestamp=Wed 2019-12-11 22:23:24 UTC
ConditionTimestampMonotonic=5307274104
AssertTimestamp=Wed 2019-12-11 22:23:24 UTC
AssertTimestampMonotonic=5307274104
Transient=no
Perpetual=no
StartLimitIntervalUSec=0
StartLimitBurst=5
StartLimitAction=none
FailureAction=none
SuccessAction=none
InvocationID=7df049adfed641a2ba3b75bddeff8893
CollectMode=inactive

Have crio

crio

Output of "crio --version":

crio version 1.16.0
commit: "fa99ff4ae2aa45115bf3c6bb33db07191db2518e-dirty"

Output of "systemctl show crio":

Type=simple
Restart=on-failure
NotifyAccess=none
RestartUSec=5s
TimeoutStartUSec=1min 30s
TimeoutStopUSec=1min 30s
RuntimeMaxUSec=infinity
WatchdogUSec=0
WatchdogTimestampMonotonic=0
PermissionsStartOnly=no
RootDirectoryStartOnly=no
RemainAfterExit=no
GuessMainPID=yes
MainPID=0
ControlPID=0
FileDescriptorStoreMax=0
NFileDescriptorStore=0
StatusErrno=0
Result=success
UID=[not set]
GID=[not set]
NRestarts=0
ExecMainStartTimestampMonotonic=0
ExecMainExitTimestampMonotonic=0
ExecMainPID=0
ExecMainCode=0
ExecMainStatus=0
ExecStart={ path=/usr/local/bin/crio ; argv[]=/usr/local/bin/crio --log-level debug ; ignore_errors=no ; start_time=[n/a] ; stop_time=[n/a] ; pid=0 ; code=(null) ; status=0/0 }
Slice=system.slice
MemoryCurrent=[not set]
CPUUsageNSec=[not set]
TasksCurrent=[not set]
IPIngressBytes=18446744073709551615
IPIngressPackets=18446744073709551615
IPEgressBytes=18446744073709551615
IPEgressPackets=18446744073709551615
Delegate=no
CPUAccounting=no
CPUWeight=[not set]
StartupCPUWeight=[not set]
CPUShares=[not set]
StartupCPUShares=[not set]
CPUQuotaPerSecUSec=infinity
IOAccounting=no
IOWeight=[not set]
StartupIOWeight=[not set]
BlockIOAccounting=no
BlockIOWeight=[not set]
StartupBlockIOWeight=[not set]
MemoryAccounting=no
MemoryLow=0
MemoryHigh=infinity
MemoryMax=infinity
MemorySwapMax=infinity
MemoryLimit=infinity
DevicePolicy=auto
TasksAccounting=yes
TasksMax=19190
IPAccounting=no
UMask=0022
LimitCPU=infinity
LimitCPUSoft=infinity
LimitFSIZE=infinity
LimitFSIZESoft=infinity
LimitDATA=infinity
LimitDATASoft=infinity
LimitSTACK=infinity
LimitSTACKSoft=8388608
LimitCORE=infinity
LimitCORESoft=0
LimitRSS=infinity
LimitRSSSoft=infinity
LimitNOFILE=4096
LimitNOFILESoft=1024
LimitAS=infinity
LimitASSoft=infinity
LimitNPROC=63967
LimitNPROCSoft=63967
LimitMEMLOCK=16777216
LimitMEMLOCKSoft=16777216
LimitLOCKS=infinity
LimitLOCKSSoft=infinity
LimitSIGPENDING=63967
LimitSIGPENDINGSoft=63967
LimitMSGQUEUE=819200
LimitMSGQUEUESoft=819200
LimitNICE=0
LimitNICESoft=0
LimitRTPRIO=0
LimitRTPRIOSoft=0
LimitRTTIME=infinity
LimitRTTIMESoft=infinity
OOMScoreAdjust=0
Nice=0
IOSchedulingClass=0
IOSchedulingPriority=0
CPUSchedulingPolicy=0
CPUSchedulingPriority=0
TimerSlackNSec=50000
CPUSchedulingResetOnFork=no
NonBlocking=no
StandardInput=null
StandardInputData=
StandardOutput=journal
StandardError=inherit
TTYReset=no
TTYVHangup=no
TTYVTDisallocate=no
SyslogPriority=30
SyslogLevelPrefix=yes
SyslogLevel=6
SyslogFacility=3
LogLevelMax=-1
SecureBits=0
CapabilityBoundingSet=cap_chown cap_dac_override cap_dac_read_search cap_fowner cap_fsetid cap_kill cap_setgid cap_setuid cap_setpcap cap_linux_immutable cap_net_bind_service cap_net_broadcast cap_net_admin cap_net_raw cap_ipc_lock cap_ipc_owner cap_sys_module cap_sys_rawio cap_sys_chroot cap_sys_ptrace cap_sys_pacct cap_sys_admin cap_sys_boot cap_sys_nice cap_sys_resource cap_sys_time cap_sys_tty_config cap_mknod cap_lease cap_audit_write cap_audit_control cap_setfcap cap_mac_override cap_mac_admin cap_syslog cap_wake_alarm cap_block_suspend
AmbientCapabilities=
DynamicUser=no
RemoveIPC=no
MountFlags=
PrivateTmp=no
PrivateDevices=no
ProtectKernelTunables=no
ProtectKernelModules=no
ProtectControlGroups=no
PrivateNetwork=no
PrivateUsers=no
ProtectHome=no
ProtectSystem=no
SameProcessGroup=no
UtmpMode=init
IgnoreSIGPIPE=yes
NoNewPrivileges=no
SystemCallErrorNumber=0
LockPersonality=no
RuntimeDirectoryPreserve=no
RuntimeDirectoryMode=0755
StateDirectoryMode=0755
CacheDirectoryMode=0755
LogsDirectoryMode=0755
ConfigurationDirectoryMode=0755
MemoryDenyWriteExecute=no
RestrictRealtime=no
RestrictNamespaces=no
MountAPIVFS=no
KeyringMode=private
KillMode=control-group
KillSignal=15
SendSIGKILL=yes
SendSIGHUP=no
Id=crio.service
Names=crio.service
Requires=system.slice sysinit.target
Conflicts=shutdown.target
Before=shutdown.target
After=systemd-journald.socket basic.target sysinit.target system.slice
Documentation=https://github.com/cri-o/cri-o
Description=CRI-O daemon
LoadState=loaded
ActiveState=inactive
SubState=dead
FragmentPath=/etc/systemd/system/crio.service
UnitFileState=disabled
UnitFilePreset=enabled
StateChangeTimestampMonotonic=0
InactiveExitTimestampMonotonic=0
ActiveEnterTimestampMonotonic=0
ActiveExitTimestampMonotonic=0
InactiveEnterTimestampMonotonic=0
CanStart=yes
CanStop=yes
CanReload=no
CanIsolate=no
StopWhenUnneeded=no
RefuseManualStart=no
RefuseManualStop=no
AllowIsolate=no
DefaultDependencies=yes
OnFailureJobMode=replace
IgnoreOnIsolate=no
NeedDaemonReload=no
JobTimeoutUSec=infinity
JobRunningTimeoutUSec=infinity
JobTimeoutAction=none
ConditionResult=no
AssertResult=no
ConditionTimestampMonotonic=0
AssertTimestampMonotonic=0
Transient=no
Perpetual=no
StartLimitIntervalUSec=10s
StartLimitBurst=5
StartLimitAction=none
FailureAction=none
SuccessAction=none
CollectMode=inactive

Output of "cat /etc/crio/crio.conf":

# The CRI-O configuration file specifies all of the available configuration
# options and command-line flags for the crio(8) OCI Kubernetes Container Runtime
# daemon, but in a TOML format that can be more easily modified and versioned.
#
# Please refer to crio.conf(5) for details of all configuration options.

# CRI-O supports partial configuration reload during runtime, which can be
# done by sending SIGHUP to the running process. Currently supported options
# are explicitly mentioned with: 'This option supports live configuration
# reload'.

# CRI-O reads its storage defaults from the containers-storage.conf(5) file
# located at /etc/containers/storage.conf. Modify this storage configuration if
# you want to change the system's defaults. If you want to modify storage just
# for CRI-O, you can change the storage configuration options here.
[crio]

# Path to the "root directory". CRI-O stores all of its data, including
# containers images, in this directory.
#root = "/var/lib/containers/storage"

# Path to the "run directory". CRI-O stores all of its state in this directory.
#runroot = "/var/run/containers/storage"

# Storage driver used to manage the storage of images and containers. Please
# refer to containers-storage.conf(5) to see all available storage drivers.
#storage_driver = ""

# List to pass options to the storage driver. Please refer to
# containers-storage.conf(5) to see all available storage options.
#storage_option = [
#]

# The default log directory where all logs will go unless directly specified by
# the kubelet. The log directory specified must be an absolute directory.
log_dir = "/var/log/crio/pods"

# Location for CRI-O to lay down the version file
version_file = "/var/lib/crio/version"

# The crio.api table contains settings for the kubelet/gRPC interface.
[crio.api]

# Path to AF_LOCAL socket on which CRI-O will listen.
listen = "/var/run/crio/crio.sock"

# Host IP considered as the primary IP to use by CRI-O for things such as host network IP.
host_ip = ""

# IP address on which the stream server will listen.
stream_address = "127.0.0.1"

# The port on which the stream server will listen.
stream_port = "0"

# Enable encrypted TLS transport of the stream server.
stream_enable_tls = false

# Path to the x509 certificate file used to serve the encrypted stream. This
# file can change, and CRI-O will automatically pick up the changes within 5
# minutes.
stream_tls_cert = ""

# Path to the key file used to serve the encrypted stream. This file can
# change and CRI-O will automatically pick up the changes within 5 minutes.
stream_tls_key = ""

# Path to the x509 CA(s) file used to verify and authenticate client
# communication with the encrypted stream. This file can change and CRI-O will
# automatically pick up the changes within 5 minutes.
stream_tls_ca = ""

# Maximum grpc send message size in bytes. If not set or <=0, then CRI-O will default to 16 * 1024 * 1024.
grpc_max_send_msg_size = 16777216

# Maximum grpc receive message size. If not set or <= 0, then CRI-O will default to 16 * 1024 * 1024.
grpc_max_recv_msg_size = 16777216

# The crio.runtime table contains settings pertaining to the OCI runtime used
# and options for how to set up and manage the OCI runtime.
[crio.runtime]
manage_network_ns_lifecycle = true

# A list of ulimits to be set in containers by default, specified as
# "<ulimit name>=<soft limit>:<hard limit>", for example:
# "nofile=1024:2048"
# If nothing is set here, settings will be inherited from the CRI-O daemon
#default_ulimits = [
#]

# default_runtime is the _name_ of the OCI runtime to be used as the default.
# The name is matched against the runtimes map below.
default_runtime = "runc"

# If true, the runtime will not use pivot_root, but instead use MS_MOVE.
no_pivot = false

# Path to the conmon binary, used for monitoring the OCI runtime.
# Will be searched for using $PATH if empty.
conmon = ""

# Cgroup setting for conmon
conmon_cgroup = "pod"

# Environment variable list for the conmon process, used for passing necessary
# environment variables to conmon or the runtime.
conmon_env = [
	"PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin",
]

# If true, SELinux will be used for pod separation on the host.
selinux = false

# Path to the seccomp.json profile which is used as the default seccomp profile
# for the runtime. If not specified, then the internal default seccomp profile
# will be used.
seccomp_profile = ""

# Used to change the name of the default AppArmor profile of CRI-O. The default
# profile name is "crio-default-" followed by the version string of CRI-O.
apparmor_profile = "crio-default-1.16.0"

# Cgroup management implementation used for the runtime.
cgroup_manager = "cgroupfs"

# List of default capabilities for containers. If it is empty or commented out,
# only the capabilities defined in the containers json file by the user/kube
# will be added.
default_capabilities = [
	"CHOWN", 
	"DAC_OVERRIDE", 
	"FSETID", 
	"FOWNER", 
	"NET_RAW", 
	"SETGID", 
	"SETUID", 
	"SETPCAP", 
	"NET_BIND_SERVICE", 
	"SYS_CHROOT", 
	"KILL", 
]

# List of default sysctls. If it is empty or commented out, only the sysctls
# defined in the container json file by the user/kube will be added.
default_sysctls = [
]

# List of additional devices. specified as
# "<device-on-host>:<device-on-container>:<permissions>", for example: "--device=/dev/sdc:/dev/xvdc:rwm".
#If it is empty or commented out, only the devices
# defined in the container json file by the user/kube will be added.
additional_devices = [
]

# Path to OCI hooks directories for automatically executed hooks.
hooks_dir = [
]

# List of default mounts for each container. **Deprecated:** this option will
# be removed in future versions in favor of default_mounts_file.
default_mounts = [
]

# Path to the file specifying the defaults mounts for each container. The
# format of the config is /SRC:/DST, one mount per line. Notice that CRI-O reads
# its default mounts from the following two files:
#
#   1) /etc/containers/mounts.conf (i.e., default_mounts_file): This is the
#      override file, where users can either add in their own default mounts, or
#      override the default mounts shipped with the package.
#
#   2) /usr/share/containers/mounts.conf: This is the default file read for
#      mounts. If you want CRI-O to read from a different, specific mounts file,
#      you can change the default_mounts_file. Note, if this is done, CRI-O will
#      only add mounts it finds in this file.
#
#default_mounts_file = ""

# Maximum number of processes allowed in a container.
pids_limit = 1024

# Maximum sized allowed for the container log file. Negative numbers indicate
# that no size limit is imposed. If it is positive, it must be >= 8192 to
# match/exceed conmon's read buffer. The file is truncated and re-opened so the
# limit is never exceeded.
log_size_max = -1

# Whether container output should be logged to journald in addition to the kuberentes log file
log_to_journald = false

# Path to directory in which container exit files are written to by conmon.
container_exits_dir = "/var/run/crio/exits"

# Path to directory for container attach sockets.
container_attach_socket_dir = "/var/run/crio"

# The prefix to use for the source of the bind mounts.
bind_mount_prefix = ""

# If set to true, all containers will run in read-only mode.
read_only = false

# Changes the verbosity of the logs based on the level it is set to. Options
# are fatal, panic, error, warn, info, and debug. This option supports live
# configuration reload.
log_level = "error"

# The UID mappings for the user namespace of each container. A range is
# specified in the form containerUID:HostUID:Size. Multiple ranges must be
# separated by comma.
uid_mappings = ""

# The GID mappings for the user namespace of each container. A range is
# specified in the form containerGID:HostGID:Size. Multiple ranges must be
# separated by comma.
gid_mappings = ""

# The minimal amount of time in seconds to wait before issuing a timeout
# regarding the proper termination of the container.
ctr_stop_timeout = 0

# ManageNetworkNSLifecycle determines whether we pin and remove network namespace
# and manage its lifecycle.
#manage_network_ns_lifecycle = false

# The "crio.runtime.runtimes" table defines a list of OCI compatible runtimes.
# The runtime to use is picked based on the runtime_handler provided by the CRI.
# If no runtime_handler is provided, the runtime will be picked based on the level
# of trust of the workload. Each entry in the table should follow the format:
#
#[crio.runtime.runtimes.runtime-handler]
#  runtime_path = "/usr/local/bin/crio-runc"
#  runtime_type = "oci"
#  runtime_root = "/path/to/the/root"
#
# Where:
# - runtime-handler: name used to identify the runtime
# - runtime_path (optional, string): absolute path to the runtime executable in
#   the host filesystem. If omitted, the runtime-handler identifier should match
#   the runtime executable name, and the runtime executable should be placed
#   in $PATH.
# - runtime_type (optional, string): type of runtime, one of: "oci", "vm". If
#   omitted, an "oci" runtime is assumed.
# - runtime_root (optional, string): root directory for storage of containers
#   state.


[crio.runtime.runtimes.runc]
runtime_path = "/usr/local/bin/crio-runc"
runtime_type = "oci"
runtime_root = "/run/runc"
[crio.runtime.runtimes.kata]
runtime_path = "/usr/local/bin/kata-runtime"
runtime_root = "/run/vc"
runtime_type = "oci"


# Kata Containers is an OCI runtime, where containers are run inside lightweight
# VMs. Kata provides additional isolation towards the host, minimizing the host attack
# surface and mitigating the consequences of containers breakout.

# Kata Containers with the default configured VMM
#[crio.runtime.runtimes.kata-runtime]

# Kata Containers with the QEMU VMM
#[crio.runtime.runtimes.kata-qemu]

# Kata Containers with the Firecracker VMM
#[crio.runtime.runtimes.kata-fc]

# The crio.image table contains settings pertaining to the management of OCI images.
#
# CRI-O reads its configured registries defaults from the system wide
# containers-registries.conf(5) located in /etc/containers/registries.conf. If
# you want to modify just CRI-O, you can change the registries configuration in
# this file. Otherwise, leave insecure_registries and registries commented out to
# use the system's defaults from /etc/containers/registries.conf.
[crio.image]

# Default transport for pulling images from a remote container storage.
default_transport = "docker://"

# The path to a file containing credentials necessary for pulling images from
# secure registries. The file is similar to that of /var/lib/kubelet/config.json
global_auth_file = ""

# The image used to instantiate infra containers.
# This option supports live configuration reload.
pause_image = "k8s.gcr.io/pause:3.1"

# The path to a file containing credentials specific for pulling the pause_image from
# above. The file is similar to that of /var/lib/kubelet/config.json
# This option supports live configuration reload.
pause_image_auth_file = ""

# The command to run to have a container stay in the paused state.
# When explicitly set to "", it will fallback to the entrypoint and command
# specified in the pause image. When commented out, it will fallback to the
# default: "/pause". This option supports live configuration reload.
pause_command = "/pause"

# Path to the file which decides what sort of policy we use when deciding
# whether or not to trust an image that we've pulled. It is not recommended that
# this option be used, as the default behavior of using the system-wide default
# policy (i.e., /etc/containers/policy.json) is most often preferred. Please
# refer to containers-policy.json(5) for more details.
signature_policy = ""

# List of registries to skip TLS verification for pulling images. Please
# consider configuring the registries via /etc/containers/registries.conf before
# changing them here.
#insecure_registries = "[]"

# Controls how image volumes are handled. The valid values are mkdir, bind and
# ignore; the latter will ignore volumes entirely.
image_volumes = "mkdir"

# List of registries to be used when pulling an unqualified image (e.g.,
# "alpine:latest"). By default, registries is set to "docker.io" for
# compatibility reasons. Depending on your workload and usecase you may add more
# registries (e.g., "quay.io", "registry.fedoraproject.org",
# "registry.opensuse.org", etc.).
registries = [ "docker.io" ] 
# ]


# The crio.network table containers settings pertaining to the management of
# CNI plugins.
[crio.network]

# Path to the directory where CNI configuration files are located.
network_dir = "/etc/cni/net.d/"

# Paths to directories where CNI plugin binaries are located.
plugin_dirs = [
	"/opt/cni/bin/",
]

# A necessary configuration for Prometheus based metrics retrieval
[crio.metrics]

# Globally enable or disable metrics support.
enable_metrics = false

# The port on which the metrics server will listen.
metrics_port = 9090

Have containerd

containerd

Output of "containerd --version":

containerd containerd.io 1.2.10 b34a5c8af56e510852c35414db4c1f4fa6172339

Output of "systemctl show containerd":

Type=simple
Restart=no
NotifyAccess=none
RestartUSec=100ms
TimeoutStartUSec=1min 30s
TimeoutStopUSec=1min 30s
RuntimeMaxUSec=infinity
WatchdogUSec=0
WatchdogTimestamp=Wed 2019-12-11 21:47:46 UTC
WatchdogTimestampMonotonic=3168553975
PermissionsStartOnly=no
RootDirectoryStartOnly=no
RemainAfterExit=no
GuessMainPID=yes
MainPID=7693
ControlPID=0
FileDescriptorStoreMax=0
NFileDescriptorStore=0
StatusErrno=0
Result=success
UID=[not set]
GID=[not set]
NRestarts=0
ExecMainStartTimestamp=Wed 2019-12-11 21:47:46 UTC
ExecMainStartTimestampMonotonic=3168553897
ExecMainExitTimestampMonotonic=0
ExecMainPID=7693
ExecMainCode=0
ExecMainStatus=0
ExecStartPre={ path=/sbin/modprobe ; argv[]=/sbin/modprobe overlay ; ignore_errors=yes ; start_time=[n/a] ; stop_time=[n/a] ; pid=0 ; code=(null) ; status=0/0 }
ExecStart={ path=/usr/bin/containerd ; argv[]=/usr/bin/containerd ; ignore_errors=no ; start_time=[n/a] ; stop_time=[n/a] ; pid=0 ; code=(null) ; status=0/0 }
Slice=system.slice
ControlGroup=/system.slice/containerd.service
MemoryCurrent=[not set]
CPUUsageNSec=[not set]
TasksCurrent=13
IPIngressBytes=18446744073709551615
IPIngressPackets=18446744073709551615
IPEgressBytes=18446744073709551615
IPEgressPackets=18446744073709551615
Delegate=yes
DelegateControllers=cpu cpuacct io blkio memory devices pids
CPUAccounting=no
CPUWeight=[not set]
StartupCPUWeight=[not set]
CPUShares=[not set]
StartupCPUShares=[not set]
CPUQuotaPerSecUSec=infinity
IOAccounting=no
IOWeight=[not set]
StartupIOWeight=[not set]
BlockIOAccounting=no
BlockIOWeight=[not set]
StartupBlockIOWeight=[not set]
MemoryAccounting=no
MemoryLow=0
MemoryHigh=infinity
MemoryMax=infinity
MemorySwapMax=infinity
MemoryLimit=infinity
DevicePolicy=auto
TasksAccounting=yes
TasksMax=infinity
IPAccounting=no
UMask=0022
LimitCPU=infinity
LimitCPUSoft=infinity
LimitFSIZE=infinity
LimitFSIZESoft=infinity
LimitDATA=infinity
LimitDATASoft=infinity
LimitSTACK=infinity
LimitSTACKSoft=8388608
LimitCORE=infinity
LimitCORESoft=infinity
LimitRSS=infinity
LimitRSSSoft=infinity
LimitNOFILE=1048576
LimitNOFILESoft=1048576
LimitAS=infinity
LimitASSoft=infinity
LimitNPROC=infinity
LimitNPROCSoft=infinity
LimitMEMLOCK=16777216
LimitMEMLOCKSoft=16777216
LimitLOCKS=infinity
LimitLOCKSSoft=infinity
LimitSIGPENDING=63967
LimitSIGPENDINGSoft=63967
LimitMSGQUEUE=819200
LimitMSGQUEUESoft=819200
LimitNICE=0
LimitNICESoft=0
LimitRTPRIO=0
LimitRTPRIOSoft=0
LimitRTTIME=infinity
LimitRTTIMESoft=infinity
OOMScoreAdjust=0
Nice=0
IOSchedulingClass=0
IOSchedulingPriority=0
CPUSchedulingPolicy=0
CPUSchedulingPriority=0
TimerSlackNSec=50000
CPUSchedulingResetOnFork=no
NonBlocking=no
StandardInput=null
StandardInputData=
StandardOutput=journal
StandardError=inherit
TTYReset=no
TTYVHangup=no
TTYVTDisallocate=no
SyslogPriority=30
SyslogLevelPrefix=yes
SyslogLevel=6
SyslogFacility=3
LogLevelMax=-1
SecureBits=0
CapabilityBoundingSet=cap_chown cap_dac_override cap_dac_read_search cap_fowner cap_fsetid cap_kill cap_setgid cap_setuid cap_setpcap cap_linux_immutable cap_net_bind_service cap_net_broadcast cap_net_admin cap_net_raw cap_ipc_lock cap_ipc_owner cap_sys_module cap_sys_rawio cap_sys_chroot cap_sys_ptrace cap_sys_pacct cap_sys_admin cap_sys_boot cap_sys_nice cap_sys_resource cap_sys_time cap_sys_tty_config cap_mknod cap_lease cap_audit_write cap_audit_control cap_setfcap cap_mac_override cap_mac_admin cap_syslog cap_wake_alarm cap_block_suspend
AmbientCapabilities=
DynamicUser=no
RemoveIPC=no
MountFlags=
PrivateTmp=no
PrivateDevices=no
ProtectKernelTunables=no
ProtectKernelModules=no
ProtectControlGroups=no
PrivateNetwork=no
PrivateUsers=no
ProtectHome=no
ProtectSystem=no
SameProcessGroup=no
UtmpMode=init
IgnoreSIGPIPE=yes
NoNewPrivileges=no
SystemCallErrorNumber=0
LockPersonality=no
RuntimeDirectoryPreserve=no
RuntimeDirectoryMode=0755
StateDirectoryMode=0755
CacheDirectoryMode=0755
LogsDirectoryMode=0755
ConfigurationDirectoryMode=0755
MemoryDenyWriteExecute=no
RestrictRealtime=no
RestrictNamespaces=no
MountAPIVFS=no
KeyringMode=private
KillMode=process
KillSignal=15
SendSIGKILL=yes
SendSIGHUP=no
Id=containerd.service
Names=containerd.service
Requires=system.slice sysinit.target
WantedBy=multi-user.target
Conflicts=shutdown.target
Before=multi-user.target shutdown.target
After=network.target system.slice systemd-journald.socket basic.target sysinit.target
Documentation=https://containerd.io
Description=containerd container runtime
LoadState=loaded
ActiveState=active
SubState=running
FragmentPath=/lib/systemd/system/containerd.service
UnitFileState=enabled
UnitFilePreset=enabled
StateChangeTimestamp=Wed 2019-12-11 21:47:46 UTC
StateChangeTimestampMonotonic=3168553977
InactiveExitTimestamp=Wed 2019-12-11 21:47:46 UTC
InactiveExitTimestampMonotonic=3168506007
ActiveEnterTimestamp=Wed 2019-12-11 21:47:46 UTC
ActiveEnterTimestampMonotonic=3168553977
ActiveExitTimestampMonotonic=0
InactiveEnterTimestampMonotonic=0
CanStart=yes
CanStop=yes
CanReload=no
CanIsolate=no
StopWhenUnneeded=no
RefuseManualStart=no
RefuseManualStop=no
AllowIsolate=no
DefaultDependencies=yes
OnFailureJobMode=replace
IgnoreOnIsolate=no
NeedDaemonReload=no
JobTimeoutUSec=infinity
JobRunningTimeoutUSec=infinity
JobTimeoutAction=none
ConditionResult=yes
AssertResult=yes
ConditionTimestamp=Wed 2019-12-11 21:47:46 UTC
ConditionTimestampMonotonic=3168504263
AssertTimestamp=Wed 2019-12-11 21:47:46 UTC
AssertTimestampMonotonic=3168504263
Transient=no
Perpetual=no
StartLimitIntervalUSec=10s
StartLimitBurst=5
StartLimitAction=none
FailureAction=none
SuccessAction=none
InvocationID=29f07f15c313438b815436b9fb625bf1
CollectMode=inactive

Output of "cat /etc/containerd/config.toml":

disabled_plugins = ["cri"]

#root = "/var/lib/containerd"
#state = "/run/containerd"
#subreaper = true
#oom_score = 0

#[grpc]
#  address = "/run/containerd/containerd.sock"
#  uid = 0
#  gid = 0

#[debug]
#  address = "/run/containerd/debug.sock"
#  uid = 0
#  gid = 0
#  level = "info"

---

Packages

Have dpkg
Output of "dpkg -l|egrep "(cc-oci-runtimecc-runtimerunv|kata-proxy|kata-runtime|kata-shim|kata-ksm-throttler|kata-containers-image|linux-container|qemu-)"":

Have rpm
Output of "rpm -qa|egrep "(cc-oci-runtimecc-runtimerunv|kata-proxy|kata-runtime|kata-shim|kata-ksm-throttler|kata-containers-image|linux-container|qemu-)"":

[chavafg]

This is what I see in the cri-o log:

16:05:10 # time="2019-12-11 22:05:04.450282403Z" level=debug msg="response: &ExecSyncResponse{Stdout:[FILTERED],Stderr:[],ExitCode:0,}" file="go-grpc-middleware/chain.go:25" id=651fbfb4-70d5-46aa-b6eb-f48c0e203830
16:05:10 # HELLO
16:05:10 # time="2019-12-11 22:05:04.459889045Z" level=debug msg="request: &ExecSyncRequest{ContainerId:e3e37b79b36aefd6f9c6dde1c88c6c5cdbe26ba6e66662d885f86e97d5c3e3b3,Cmd:[sleep 3],Timeout:1,}" file="go-grpc-middleware/chain.go:25" id=9be6cf9d-e084-4c59-a1b6-d3bb0a289f6e
16:05:10 # time="2019-12-11 22:05:05.446118415Z" level=debug msg="response error: command error: EOF, stdout: , stderr: , exit code -1" file="go-grpc-middleware/chain.go:25" id=9be6cf9d-e084-4c59-a1b6-d3bb0a289f6e
16:05:10 # time="2019-12-11T22:05:05Z" level=fatal msg="execing command in container synchronously failed: rpc error: code = Unknown desc = command error: EOF, stdout: , stderr: , exit code -1"
16:05:10 # time="2019-12-11 22:05:05.462400664Z" level=debug msg="request: &ListContainersRequest{Filter:&ContainerFilter{Id:,State:&ContainerStateValue{State:CONTAINER_RUNNING,},PodSandboxId:,LabelSelector:map[string]string{},},}" file="go-grpc-middleware/chain.go:25" id=1d74a695-c14f-4ddc-a9de-6fc4d82cd6cc
16:05:10 # time="2019-12-11 22:05:05.462489868Z" level=debug msg="no filters were applied, returning full container list" file="server/container_list.go:59" id=1d74a695-c14f-4ddc-a9de-6fc4d82cd6cc
16:05:10 # time="2019-12-11 22:05:05.462581372Z" level=debug msg="response: &ListContainersResponse{Containers:[]*Container{&Container{Id:e3e37b79b36aefd6f9c6dde1c88c6c5cdbe26ba6e66662d885f86e97d5c3e3b3,PodSandboxId:13add21b4126bb1758cf2675b436e1af0d2fe0baa2078055f26b7acb9c911c2f,Metadata:&ContainerMetadata{Name:podsandbox1-redis,Attempt:0,},Image:&ImageSpec{Image:quay.io/crio/redis:alpine,},ImageRef:quay.io/crio/redis@sha256:1780b5a5496189974b94eb2595d86731d7a0820e4beb8ea770974298a943ed55,State:CONTAINER_RUNNING,CreatedAt:1576101903355149822,Labels:map[string]string{tier: backend,},Annotations:map[string]string{pod: podsandbox1,},},},}" file="go-grpc-middleware/chain.go:25" id=1d74a695-c14f-4ddc-a9de-6fc4d82cd6cc

[amshinde]

I tried reproducing this with the said version of runtime, shim, proxy and agent, but a different version of kernel released in our latest release. I was not able to reproduce this.
@chavafg Did you have any luck reproducing this locally?

Looks like the error code returned in this test is different from what is expected.
As seen in the logs, this is what is sent to cri-o:

level=fatal msg="execing command in container synchronously failed: rpc error: code = Unknown desc = command error: EOF, stdout: , stderr: , exit code -1"

But what is expected is error "command error: command time out" instead of "command error: EOF" as seen here.
cc @jodh-intel Is he has any input here.

[chavafg]

hi @amshinde yeah, I could reproduce the issue locally on Wednesday. Will try to get some logs and put them here.

[chavafg]

I could reproduced using latest (master) commits, here is what I did, using the json files from the test directory in the cri-o repo:

fuentess@testcrio1:~/go/src/github.com/cri-o/cri-o/test$ sudo crictl runp "$TESTDATA"/sandbox_config.json
2c6765bd0368550d91939bc1a2dfd402b1f7bf4de7e85c24c458a3024ec53e5a
fuentess@testcrio1:~/go/src/github.com/cri-o/cri-o/test$ pod_id=2c6765bd0368550d91939bc1a2dfd402b1f7bf4de7e85c24c458a3024ec53e5a
fuentess@testcrio1:~/go/src/github.com/cri-o/cri-o/test$ sudo crictl create "$pod_id" "$TESTDATA"/container_redis.json "$TESTDATA"/sandbox_config.json
a3cbe65e6fcfabc5093de6a09098655db1ac256d2d4b108d08d6836e3b35bd09
fuentess@testcrio1:~/go/src/github.com/cri-o/cri-o/test$ ctr_id=a3cbe65e6fcfabc5093de6a09098655db1ac256d2d4b108d08d6836e3b35bd09
fuentess@testcrio1:~/go/src/github.com/cri-o/cri-o/test$ sudo crictl start "$ctr_id"
a3cbe65e6fcfabc5093de6a09098655db1ac256d2d4b108d08d6836e3b35bd09
fuentess@testcrio1:~/go/src/github.com/cri-o/cri-o/test$ sudo crictl exec --sync "$ctr_id" echo HELLO
HELLO


fuentess@testcrio1:~/go/src/github.com/cri-o/cri-o/test$ sudo crictl exec --sync --timeout 1 "$ctr_id" sleep 3
FATA[0001] execing command in container synchronously failed: rpc error: code = Unknown desc = command error: EOF, stdout: , stderr: , exit code -1 

Here are the logs from cri-o and kata when running the exec:

Dec 13 16:34:00 testcrio1 kata-runtime[51777]: time="2019-12-13T16:34:00.654040336Z" level=info msg="release sandbox" arch=amd64 command=exec container=a3cbe65e6fcfabc5093de6a09098655db1ac256d2d4b108d08d6836e3b35bd09 name=kata-runtime pid=51777 sandbox=2c6765bd0368550d91939bc1a2dfd402b1f7bf4de7e85c24c458a3024ec53e5a source=virtcontainers subsystem=sandbox
Dec 13 16:34:00 testcrio1 kata-proxy[51201]: time="2019-12-13T16:34:00.661001967Z" level=info msg="time=\"2019-12-13T16:34:00.641093462Z\" level=info msg=\"ignoring unexpected signal\" debug_console=false name=kata-agent pid=41 sandbox=2c6765bd0368550d91939bc1a2dfd402b1f7bf4de7e85c24c458a3024ec53e5a signal=\"child exited\" source=agent\n" name=kata-proxy pid=51201 sandbox=2c6765bd0368550d91939bc1a2dfd402b1f7bf4de7e85c24c458a3024ec53e5a source=agent
Dec 13 16:34:00 testcrio1 kata-proxy[51201]: time="2019-12-13T16:34:00.661595769Z" level=info msg="time=\"2019-12-13T16:34:00.650891413Z\" level=info msg=\"ignoring unexpected signal\" debug_console=false name=kata-agent pid=41 sandbox=2c6765bd0368550d91939bc1a2dfd402b1f7bf4de7e85c24c458a3024ec53e5a signal=\"child exited\" source=agent\n" name=kata-proxy pid=51201 sandbox=2c6765bd0368550d91939bc1a2dfd402b1f7bf4de7e85c24c458a3024ec53e5a source=agent
Dec 13 16:34:00 testcrio1 kata-shim[51787]: time="2019-12-13T16:34:00.668347299Z" level=info msg=announce agent-socket="unix:///run/vc/sbs/2c6765bd0368550d91939bc1a2dfd402b1f7bf4de7e85c24c458a3024ec53e5a/proxy.sock" container=a3cbe65e6fcfabc5093de6a09098655db1ac256d2d4b108d08d6836e3b35bd09 debug=true exec-id=002c6a7a-b265-4747-8c92-162982af516d log-level=debug name=kata-shim pid=38 proxy-exit-code=true source=shim terminal=false tracing=false version=1.10.0-rc0-b5067286baa85f8019dffef591ba2e92597c1ecf
Dec 13 16:34:00 testcrio1 kata-proxy[51201]: time="2019-12-13T16:34:00.669287003Z" level=info msg="time=\"2019-12-13T16:34:00.641107963Z\" level=debug msg=\"log pipe has been closed: EOF\"\n" name=kata-proxy pid=51201 sandbox=2c6765bd0368550d91939bc1a2dfd402b1f7bf4de7e85c24c458a3024ec53e5a source=agent
Dec 13 16:34:00 testcrio1 kata-proxy[51201]: time="2019-12-13T16:34:00.675578731Z" level=info msg="time=\"2019-12-13T16:34:00.665039842Z\" level=debug msg=\"new request\" debug_console=false name=kata-agent pid=41 req=\"container_id:\\\"a3cbe65e6fcfabc5093de6a09098655db1ac256d2d4b108d08d6836e3b35bd09\\\" exec_id:\\\"002c6a7a-b265-4747-8c92-162982af516d\\\" \" request=/grpc.AgentService/CloseStdin sandbox=2c6765bd0368550d91939bc1a2dfd402b1f7bf4de7e85c24c458a3024ec53e5a source=agent\n" name=kata-proxy pid=51201 sandbox=2c6765bd0368550d91939bc1a2dfd402b1f7bf4de7e85c24c458a3024ec53e5a source=agent
Dec 13 16:34:00 testcrio1 kata-proxy[51201]: time="2019-12-13T16:34:00.675906432Z" level=info msg="time=\"2019-12-13T16:34:00.665463179Z\" level=debug msg=\"request end\" debug_console=false duration=\"16.748µs\" name=kata-agent pid=41 request=/grpc.AgentService/CloseStdin resp=\"&Empty{XXX_unrecognized:[],}\" sandbox=2c6765bd0368550d91939bc1a2dfd402b1f7bf4de7e85c24c458a3024ec53e5a source=agent\n" name=kata-proxy pid=51201 sandbox=2c6765bd0368550d91939bc1a2dfd402b1f7bf4de7e85c24c458a3024ec53e5a source=agent
Dec 13 16:34:00 testcrio1 kata-proxy[51201]: time="2019-12-13T16:34:00.679177247Z" level=info msg="time=\"2019-12-13T16:34:00.665768467Z\" level=debug msg=\"new request\" debug_console=false name=kata-agent pid=41 req=\"container_id:\\\"a3cbe65e6fcfabc5093de6a09098655db1ac256d2d4b108d08d6836e3b35bd09\\\" exec_id:\\\"002c6a7a-b265-4747-8c92-162982af516d\\\" len:32768 \" request=/grpc.AgentService/ReadStderr sandbox=2c6765bd0368550d91939bc1a2dfd402b1f7bf4de7e85c24c458a3024ec53e5a source=agent\n" name=kata-proxy pid=51201 sandbox=2c6765bd0368550d91939bc1a2dfd402b1f7bf4de7e85c24c458a3024ec53e5a source=agent
Dec 13 16:34:00 testcrio1 kata-proxy[51201]: time="2019-12-13T16:34:00.682971963Z" level=info msg="time=\"2019-12-13T16:34:00.672235844Z\" level=debug msg=\"new request\" debug_console=false name=kata-agent pid=41 req=\"container_id:\\\"a3cbe65e6fcfabc5093de6a09098655db1ac256d2d4b108d08d6836e3b35bd09\\\" exec_id:\\\"002c6a7a-b265-4747-8c92-162982af516d\\\" len:32768 \" request=/grpc.AgentService/ReadStdout sandbox=2c6765bd0368550d91939bc1a2dfd402b1f7bf4de7e85c24c458a3024ec53e5a source=agent\n" name=kata-proxy pid=51201 sandbox=2c6765bd0368550d91939bc1a2dfd402b1f7bf4de7e85c24c458a3024ec53e5a source=agent
Dec 13 16:34:01 testcrio1 crio[50903]: time="2019-12-13 16:34:01.851381714Z" level=debug msg="response error: command error: EOF, stdout: , stderr: , exit code -1" file="v1alpha2/api.pb.go:7852" id=2d2db18d-9d43-4d08-82ed-e7658361cee9
Dec 13 16:34:03 testcrio1 kata-proxy[51201]: time="2019-12-13T16:34:03.68550729Z" level=info msg="time=\"2019-12-13T16:34:03.674951624Z\" level=debug msg=\"request end\" debug_console=false duration=3.002190721s name=kata-agent pid=41 request=/grpc.AgentService/ReadStdout resp= sandbox=2c6765bd0368550d91939bc1a2dfd402b1f7bf4de7e85c24c458a3024ec53e5a source=agent\n" name=kata-proxy pid=51201 sandbox=2c6765bd0368550d91939bc1a2dfd402b1f7bf4de7e85c24c458a3024ec53e5a source=agent
Dec 13 16:34:03 testcrio1 kata-proxy[51201]: time="2019-12-13T16:34:03.685919891Z" level=info msg="time=\"2019-12-13T16:34:03.675490272Z\" level=debug msg=\"request end\" debug_console=false duration=3.009284635s name=kata-agent pid=41 request=/grpc.AgentService/ReadStderr resp= sandbox=2c6765bd0368550d91939bc1a2dfd402b1f7bf4de7e85c24c458a3024ec53e5a source=agent\n" name=kata-proxy pid=51201 sandbox=2c6765bd0368550d91939bc1a2dfd402b1f7bf4de7e85c24c458a3024ec53e5a source=agent
Dec 13 16:34:03 testcrio1 kata-proxy[51201]: time="2019-12-13T16:34:03.687326498Z" level=info msg="time=\"2019-12-13T16:34:03.676833783Z\" level=debug msg=\"process exited\" debug_console=false name=kata-agent pid=100 sandbox=2c6765bd0368550d91939bc1a2dfd402b1f7bf4de7e85c24c458a3024ec53e5a source=agent status=0\n" name=kata-proxy pid=51201 sandbox=2c6765bd0368550d91939bc1a2dfd402b1f7bf4de7e85c24c458a3024ec53e5a source=agent
Dec 13 16:34:03 testcrio1 kata-proxy[51201]: time="2019-12-13T16:34:03.687759299Z" level=info msg="time=\"2019-12-13T16:34:03.677013665Z\" level=info msg=\"ignoring unexpected signal\" debug_console=false name=kata-agent pid=41 sandbox=2c6765bd0368550d91939bc1a2dfd402b1f7bf4de7e85c24c458a3024ec53e5a signal=\"child exited\" source=agent\n" name=kata-proxy pid=51201 sandbox=2c6765bd0368550d91939bc1a2dfd402b1f7bf4de7e85c24c458a3024ec53e5a source=agent

this is the kernel version I am using: vmlinuz-4.19.86-60"

[amshinde]

The reason for the failure turned out due to the version of conmon we are using in the CI.
We build conmon from master today, and the latest version seems to have introduced a change in the exit code returned when the exec timed out.
Expected error code:
command error: command time out
With the latest conmon, we instead receive this:
command error: EOF

I have fixed this by checking out a version of conmon specified in our versions.yaml file.