All CRI-O tests are not working properly

[GabyCT]

Currently all the tests from github.com/kubernetes-incubator/cri-o are not working properly with cc-runtime. We are running the ctr.bats and only the first 16 tests are passing while the rest are failing.

1..30
ok 1 ctr not found correct error message
ok 2 ctr termination reason Completed
ok 3 ctr termination reason Error
ok 4 ctr remove
ok 5 ctr lifecycle
ok 6 ctr logging
ok 7 ctr logging [tty=true]
ok 8 ctr log max
ok 9 ctr partial line logging
ok 10 ctrs status for a pod
ok 11 ctr list filtering
ok 12 ctr list label filtering
ok 13 ctr metadata in list & status
ok 14 ctr execsync conflicting with conmon flags parsing
ok 15 ctr execsync
ok 16 ctr device add
not ok 17 ctr hostname env
# (in test file ctr.bats, line 669)
#   `[ "$status" -eq 0 ]' failed
#   time="2018-03-06T16:13:14Z" level=fatal msg="execing command in container synchronously failed: rpc error: code = Unknown desc = command error: Container b69e11c24dafa5622f1d0f00ad64a1646875dea63dba43c55ae215759f64641b is not running\n, stdout: , stderr: , exit code -1"
# 0
# time="2018-03-06 16:13:10.718455872Z" level=debug msg="backingFs=extfs,  projectQuotaSupported=false"
# time="2018-03-06 16:13:10.718524173Z" level=info msg="[graphdriver] using prior storage driver: overlay"
# time="2018-03-06 16:13:10.720093883Z" level=info msg="CNI network crionet (type=bridge) is used from /tmp/tmp.21rMywHwBF/cni/net.d/10-crio.conf"
# time="2018-03-06 16:13:10.720339284Z" level=info msg="CNI network crionet (type=bridge) is used from /tmp/tmp.21rMywHwBF/cni/net.d/10-crio.conf"
# time="2018-03-06 16:13:10.734034273Z" level=debug msg="seccomp status: true"
# time="2018-03-06 16:13:10.734785278Z" level=debug msg="Golang's threads limit set to 115110"
# time="2018-03-06 16:13:10.738602003Z" level=debug msg="sandboxes: []"
# time="2018-03-06 16:13:10.818259519Z" level=debug msg="ImageStatusRequest: &ImageStatusRequest{Image:&ImageSpec{Image:redis:alpine,},Verbose:false,}"
# time="2018-03-06 16:13:10.818396020Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.21rMywHwBF/crio+/tmp/tmp.21rMywHwBF/crio-run]docker.io/library/redis:alpine""
# time="2018-03-06 16:13:10.821002337Z" level=debug msg="ImageStatusResponse: &ImageStatusResponse{Image:&Image{Id:a25414f1264bd9462b8063b94e4eff25a95792358da98bf3cfc8c9f2ff8c9da7,RepoTags:[docker.io/library/redis:alpine redis:alpine],RepoDigests:[],Size_:27082854,Uid:nil,Username:,},Info:map[string]string{},}"
# time="2018-03-06 16:13:10.902920768Z" level=debug msg="ImageStatusRequest: &ImageStatusRequest{Image:&ImageSpec{Image:redis:alpine,},Verbose:false,}"
# time="2018-03-06 16:13:10.903000168Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.21rMywHwBF/crio+/tmp/tmp.21rMywHwBF/crio-run]docker.io/library/redis:alpine""
# time="2018-03-06 16:13:10.913237134Z" level=debug msg="ImageStatusResponse: &ImageStatusResponse{Image:&Image{Id:a25414f1264bd9462b8063b94e4eff25a95792358da98bf3cfc8c9f2ff8c9da7,RepoTags:[docker.io/library/redis:alpine redis:alpine],RepoDigests:[],Size_:27082854,Uid:nil,Username:,},Info:map[string]string{},}"
# time="2018-03-06 16:13:10.975825840Z" level=debug msg="ImageStatusRequest: &ImageStatusRequest{Image:&ImageSpec{Image:mrunalp/oom,},Verbose:false,}"
# time="2018-03-06 16:13:10.975918041Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.21rMywHwBF/crio+/tmp/tmp.21rMywHwBF/crio-run]docker.io/mrunalp/oom:latest""
# time="2018-03-06 16:13:10.976284743Z" level=debug msg="ImageStatusResponse: &ImageStatusResponse{Image:&Image{Id:15b6e73982b8ef46af00713e94408ae3ab5b6f1c6852a67376076440820a2226,RepoTags:[docker.io/mrunalp/oom:latest mrunalp/oom docker.io/library/mrunalp/oom],RepoDigests:[],Size_:5719481,Uid:nil,Username:,},Info:map[string]string{},}"
# time="2018-03-06 16:13:11.041192264Z" level=debug msg="ImageStatusRequest: &ImageStatusRequest{Image:&ImageSpec{Image:redis@sha256:03789f402b2ecfb98184bf128d180f398f81c63364948ff1454583b02442f73b,},Verbose:false,}"
# time="2018-03-06 16:13:11.041327565Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.21rMywHwBF/crio+/tmp/tmp.21rMywHwBF/crio-run]docker.io/library/redis:latest@03789f402b2ecfb98184bf128d180f398f81c63364948ff1454583b02442f73b""
# time="2018-03-06 16:13:11.041853168Z" level=debug msg="ImageStatusResponse: &ImageStatusResponse{Image:&Image{Id:03789f402b2ecfb98184bf128d180f398f81c63364948ff1454583b02442f73b,RepoTags:[docker.io/library/redis:latest redis@sha256:03789f402b2ecfb98184bf128d180f398f81c63364948ff1454583b02442f73b docker.io/library/redis@sha256:03789f402b2ecfb98184bf128d180f398f81c63364948ff1454583b02442f73b],RepoDigests:[],Size_:19826844,Uid:nil,Username:,},Info:map[string]string{},}"
# time="2018-03-06 16:13:11.104741575Z" level=debug msg="ImageStatusRequest: &ImageStatusRequest{Image:&ImageSpec{Image:runcom/stderr-test,},Verbose:false,}"
# time="2018-03-06 16:13:11.104809476Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.21rMywHwBF/crio+/tmp/tmp.21rMywHwBF/crio-run]docker.io/runcom/stderr-test:latest""
# time="2018-03-06 16:13:11.105197078Z" level=debug msg="ImageStatusResponse: &ImageStatusResponse{Image:&Image{Id:c79d3c3c6eb7cca3b65c40dca388e4d554e11d820840ba02d4b69f3e303cc11c,RepoTags:[docker.io/runcom/stderr-test:latest runcom/stderr-test:latest],RepoDigests:[],Size_:4906035,Uid:nil,Username:,},Info:map[string]string{},}"
# time="2018-03-06 16:13:11.170067999Z" level=debug msg="ImageStatusRequest: &ImageStatusRequest{Image:&ImageSpec{Image:runcom/stderr-test,},Verbose:false,}"
# time="2018-03-06 16:13:11.170124299Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.21rMywHwBF/crio+/tmp/tmp.21rMywHwBF/crio-run]docker.io/runcom/stderr-test:latest""
# time="2018-03-06 16:13:11.170434701Z" level=debug msg="ImageStatusResponse: &ImageStatusResponse{Image:&Image{Id:c79d3c3c6eb7cca3b65c40dca388e4d554e11d820840ba02d4b69f3e303cc11c,RepoTags:[docker.io/runcom/stderr-test:latest runcom/stderr-test:latest],RepoDigests:[],Size_:4906035,Uid:nil,Username:,},Info:map[string]string{},}"
# time="2018-03-06 16:13:11.223024442Z" level=debug msg="ImageStatusRequest: &ImageStatusRequest{Image:&ImageSpec{Image:busybox,},Verbose:false,}"
# time="2018-03-06 16:13:11.223113543Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.21rMywHwBF/crio+/tmp/tmp.21rMywHwBF/crio-run]docker.io/library/busybox:latest""
# time="2018-03-06 16:13:11.223449045Z" level=debug msg="ImageStatusResponse: &ImageStatusResponse{Image:&Image{Id:c4dbdfa078254897e57f5fb21ea5ecef03d668f2fb178e8b1f5d32b0681aa1d5,RepoTags:[docker.io/library/busybox:latest busybox:latest],RepoDigests:[],Size_:1148393,Uid:nil,Username:,},Info:map[string]string{},}"
# time="2018-03-06 16:13:11.289044770Z" level=debug msg="ImageStatusRequest: &ImageStatusRequest{Image:&ImageSpec{Image:busybox,},Verbose:false,}"
# time="2018-03-06 16:13:11.289091170Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.21rMywHwBF/crio+/tmp/tmp.21rMywHwBF/crio-run]docker.io/library/busybox:latest""
# time="2018-03-06 16:13:11.289462272Z" level=debug msg="ImageStatusResponse: &ImageStatusResponse{Image:&Image{Id:c4dbdfa078254897e57f5fb21ea5ecef03d668f2fb178e8b1f5d32b0681aa1d5,RepoTags:[docker.io/library/busybox:latest busybox:latest],RepoDigests:[],Size_:1148393,Uid:nil,Username:,},Info:map[string]string{},}"
# time="2018-03-06 16:13:11.343972926Z" level=debug msg="ImageStatusRequest: &ImageStatusRequest{Image:&ImageSpec{Image:mrunalp/image-volume-test,},Verbose:false,}"
# time="2018-03-06 16:13:11.344068526Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.21rMywHwBF/crio+/tmp/tmp.21rMywHwBF/crio-run]docker.io/mrunalp/image-volume-test:latest""
# time="2018-03-06 16:13:11.344443929Z" level=debug msg="ImageStatusResponse: &ImageStatusResponse{Image:&Image{Id:c4768efeaa2e2025caad70ff18088a596ff84b6b156f12e68a1ac463209367c8,RepoTags:[docker.io/mrunalp/image-volume-test:latest mrunalp/image-volume-test docker.io/library/mrunalp/image-volume-test],RepoDigests:[],Size_:1095628,Uid:nil,Username:,},Info:map[string]string{},}"
# time="2018-03-06 16:13:11.407018134Z" level=debug msg="ImageStatusRequest: &ImageStatusRequest{Image:&ImageSpec{Image:mrunalp/image-volume-test,},Verbose:false,}"
# time="2018-03-06 16:13:11.407090635Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.21rMywHwBF/crio+/tmp/tmp.21rMywHwBF/crio-run]docker.io/mrunalp/image-volume-test:latest""
# time="2018-03-06 16:13:11.407403937Z" level=debug msg="ImageStatusResponse: &ImageStatusResponse{Image:&Image{Id:c4768efeaa2e2025caad70ff18088a596ff84b6b156f12e68a1ac463209367c8,RepoTags:[docker.io/mrunalp/image-volume-test:latest mrunalp/image-volume-test docker.io/library/mrunalp/image-volume-test],RepoDigests:[],Size_:1095628,Uid:nil,Username:,},Info:map[string]string{},}"
# time="2018-03-06 16:13:11.467630927Z" level=debug msg="RunPodSandboxRequest &RunPodSandboxRequest{Config:&PodSandboxConfig{Metadata:&PodSandboxMetadata{Name:podsandbox1,Uid:redhat-test-crio,Namespace:redhat.test.crio,Attempt:1,},Hostname:crictl_host,LogDirectory:,DnsConfig:&DNSConfig{Servers:[],Searches:[8.8.8.8],Options:[],},PortMappings:[],Labels:map[string]string{group: test,},Annotations:map[string]string{owner: hmeng,security.alpha.kubernetes.io/seccomp/pod: unconfined,},Linux:&LinuxPodSandboxConfig{CgroupParent:/Burstable/pod_123-456,SecurityContext:&LinuxSandboxSecurityContext{NamespaceOptions:&NamespaceOption{HostNetwork:false,HostPid:false,HostIpc:false,},SelinuxOptions:&SELinuxOption{User:system_u,Role:system_r,Type:svirt_lxc_net_t,Level:s0:c4,c5,},RunAsUser:nil,ReadonlyRootfs:false,SupplementalGroups:[],Privileged:false,SeccompProfilePath:,},Sysctls:map[string]string{},},},}"
# time="2018-03-06 16:13:11.467685327Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.21rMywHwBF/crio+/tmp/tmp.21rMywHwBF/crio-run]docker.io/kubernetes/pause:latest""
# time="2018-03-06 16:13:11.467974629Z" level=debug msg="exporting opaque data as blob "sha256:c713bfa1b66381820184edb919bad71ac5aa56a93a1f2fc2c4c7f4d69effe351""
# time="2018-03-06 16:13:11.817523994Z" level=debug msg="created pod sandbox "3ad2d009ae41116a9b33bc06e19628bb841186fd9caf449dfe4149eb98de2971""
# time="2018-03-06 16:13:11.911359502Z" level=debug msg="pod sandbox "3ad2d009ae41116a9b33bc06e19628bb841186fd9caf449dfe4149eb98de2971" has work directory "/tmp/tmp.21rMywHwBF/crio/overlay-containers/3ad2d009ae41116a9b33bc06e19628bb841186fd9caf449dfe4149eb98de2971/userdata""
# time="2018-03-06 16:13:11.911450803Z" level=debug msg="pod sandbox "3ad2d009ae41116a9b33bc06e19628bb841186fd9caf449dfe4149eb98de2971" has run directory "/tmp/tmp.21rMywHwBF/crio-run/overlay-containers/3ad2d009ae41116a9b33bc06e19628bb841186fd9caf449dfe4149eb98de2971/userdata""
# time="2018-03-06 16:13:12.017289589Z" level=debug msg="mounted container "3ad2d009ae41116a9b33bc06e19628bb841186fd9caf449dfe4149eb98de2971" at "/tmp/tmp.21rMywHwBF/crio/overlay/625d62ff4f3dd264300250e5839e5af101e55a971c86fdad1f6f70a7ecf51855/merged""
# time="2018-03-06 16:13:12.017426990Z" level=info msg="Got pod network {Name:podsandbox1 Namespace:redhat.test.crio ID:3ad2d009ae41116a9b33bc06e19628bb841186fd9caf449dfe4149eb98de2971 NetNS:/var/run/netns/k8s_podsandbox1_redhat.test.crio_redhat-test-crio_1-61719882 PortMappings:[]}"
# time="2018-03-06 16:13:12.017450690Z" level=info msg="About to add CNI network cni-loopback (type=loopback)"
# time="2018-03-06 16:13:12.021668417Z" level=info msg="Got pod network {Name:podsandbox1 Namespace:redhat.test.crio ID:3ad2d009ae41116a9b33bc06e19628bb841186fd9caf449dfe4149eb98de2971 NetNS:/var/run/netns/k8s_podsandbox1_redhat.test.crio_redhat-test-crio_1-61719882 PortMappings:[]}"
# time="2018-03-06 16:13:12.021725418Z" level=info msg="About to add CNI network crionet (type=bridge)"
# time="2018-03-06 16:13:12.106450967Z" level=debug msg="running conmon: /root/go/src/github.com/kubernetes-incubator/cri-o/bin/conmon" args=[-c 3ad2d009ae41116a9b33bc06e19628bb841186fd9caf449dfe4149eb98de2971 -u 3ad2d009ae41116a9b33bc06e19628bb841186fd9caf449dfe4149eb98de2971 -r /usr/local/bin/cc-runtime -b /tmp/tmp.21rMywHwBF/crio-run/overlay-containers/3ad2d009ae41116a9b33bc06e19628bb841186fd9caf449dfe4149eb98de2971/userdata -p /tmp/tmp.21rMywHwBF/crio-run/overlay-containers/3ad2d009ae41116a9b33bc06e19628bb841186fd9caf449dfe4149eb98de2971/userdata/pidfile -l /var/log/crio/pods/3ad2d009ae41116a9b33bc06e19628bb841186fd9caf449dfe4149eb98de2971/3ad2d009ae41116a9b33bc06e19628bb841186fd9caf449dfe4149eb98de2971.log --exit-dir /var/run/crio/exits --socket-dir-path /var/run/crio]
# time="2018-03-06 16:13:13.535041423Z" level=debug msg="Received container pid: 58727"
# time="2018-03-06 16:13:13.866032968Z" level=debug msg="RunPodSandboxResponse: &RunPodSandboxResponse{PodSandboxId:3ad2d009ae41116a9b33bc06e19628bb841186fd9caf449dfe4149eb98de2971,}"
# 3ad2d009ae41116a9b33bc06e19628bb841186fd9caf449dfe4149eb98de2971
# time="2018-03-06 16:13:13.928260371Z" level=debug msg="CreateContainerRequest &CreateContainerRequest{PodSandboxId:3ad2d009ae41116a9b33bc06e19628bb841186fd9caf449dfe4149eb98de2971,Config:&ContainerConfig{Metadata:&ContainerMetadata{Name:container1,Attempt:1,},Image:&ImageSpec{Image:redis:alpine,},Command:[/bin/ls],Args:[],WorkingDir:/,Envs:[&KeyValue{Key:PATH,Value:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin,} &KeyValue{Key:TERM,Value:xterm,} &KeyValue{Key:TESTDIR,Value:test/dir1,} &KeyValue{Key:TESTFILE,Value:test/file1,}],Mounts:[],Devices:[],Labels:map[string]string{batch: no,type: small,},Annotations:map[string]string{daemon: crio,owner: dragon,},LogPath:,Stdin:false,StdinOnce:false,Tty:false,Linux:&LinuxContainerConfig{Resources:&LinuxContainerResources{CpuPeriod:10000,CpuQuota:20000,CpuShares:512,MemoryLimitInBytes:0,OomScoreAdj:30,CpusetCpus:,CpusetMems:,},SecurityContext:&LinuxContainerSecurityContext{Capabilities:&Capability{AddCapabilities:[setuid setgid],DropCapabilities:[],},Privileged:false,NamespaceOptions:nil,SelinuxOptions:&SELinuxOption{User:system_u,Role:system_r,Type:svirt_lxc_net_t,Level:s0:c4,c5,},RunAsUser:nil,RunAsUsername:,ReadonlyRootfs:false,SupplementalGroups:[],ApparmorProfile:,SeccompProfilePath:,NoNewPrivs:false,},},},SandboxConfig:&PodSandboxConfig{Metadata:&PodSandboxMetadata{Name:podsandbox1,Uid:redhat-test-crio,Namespace:redhat.test.crio,Attempt:1,},Hostname:crictl_host,LogDirectory:,DnsConfig:&DNSConfig{Servers:[],Searches:[8.8.8.8],Options:[],},PortMappings:[],Labels:map[string]string{group: test,},Annotations:map[string]string{owner: hmeng,security.alpha.kubernetes.io/seccomp/pod: unconfined,},Linux:&LinuxPodSandboxConfig{CgroupParent:/Burstable/pod_123-456,SecurityContext:&LinuxSandboxSecurityContext{NamespaceOptions:&NamespaceOption{HostNetwork:false,HostPid:false,HostIpc:false,},SelinuxOptions:&SELinuxOption{User:system_u,Role:system_r,Type:svirt_lxc_net_t,Level:s0:c4,c5,},RunAsUser:nil,ReadonlyRootfs:false,SupplementalGroups:[],Privileged:false,SeccompProfilePath:,},Sysctls:map[string]string{},},},}"
# time="2018-03-06 16:13:13.928407072Z" level=debug msg="setting container's log_path" ctr.logfile= log_path="/var/log/crio/pods/3ad2d009ae41116a9b33bc06e19628bb841186fd9caf449dfe4149eb98de2971/b69e11c24dafa5622f1d0f00ad64a1646875dea63dba43c55ae215759f64641b.log" sbox.logdir="/var/log/crio/pods/3ad2d009ae41116a9b33bc06e19628bb841186fd9caf449dfe4149eb98de2971"
# time="2018-03-06 16:13:13.928481173Z" level=debug msg="pod container state &{State:{Version:1.0.1 ID:3ad2d009ae41116a9b33bc06e19628bb841186fd9caf449dfe4149eb98de2971 Status:running Pid:58727 Bundle:/tmp/tmp.21rMywHwBF/crio-run/overlay-containers/3ad2d009ae41116a9b33bc06e19628bb841186fd9caf449dfe4149eb98de2971/userdata Annotations:map[com.github.containers.virtcontainers.pkg.oci.container_type:pod_sandbox com.github.containers.virtcontainers.pkg.oci.bundle_path:/tmp/tmp.21rMywHwBF/crio-run/overlay-containers/3ad2d009ae41116a9b33bc06e19628bb841186fd9caf449dfe4149eb98de2971/userdata com.github.containers.virtcontainers.pkg.oci.config:{"ociVersion":"1.0.0","root":{"path":"/tmp/tmp.21rMywHwBF/crio/overlay/625d62ff4f3dd264300250e5839e5af101e55a971c86fdad1f6f70a7ecf51855/merged","readonly":true},"hostname":"crictl_host","mounts":[{"destination":"/proc","type":"proc","source":"proc"},{"destination":"/dev","type":"tmpfs","source":"tmpfs","options":["nosuid","strictatime","mode=755","size=65536k"]},{"destination":"/dev/pts","type":"devpts","source":"devpts","options":["nosuid","noexec","newinstance","ptmxmode=0666","mode=0620","gid=5"]},{"destination":"/dev/shm","type":"tmpfs","source":"shm","options":["nosuid","noexec","nodev","mode=1777","size=65536k"]},{"destination":"/dev/mqueue","type":"mqueue","source":"mqueue","options":["nosuid","noexec","nodev"]},{"destination":"/sys","type":"sysfs","source":"sysfs","options":["nosuid","noexec","nodev","ro"]},{"destination":"/etc/resolv.conf","type":"bind","source":"/tmp/tmp.21rMywHwBF/crio-run/overlay-containers/3ad2d009ae41116a9b33bc06e19628bb841186fd9caf449dfe4149eb98de2971/userdata/resolv.conf","options":["ro","bind"]},{"destination":"/etc/hostname","type":"bind","source":"/tmp/tmp.21rMywHwBF/crio-run/overlay-containers/3ad2d009ae41116a9b33bc06e19628bb841186fd9caf449dfe4149eb98de2971/userdata/hostname","options":["ro","bind"]}],"annotations":{"group":"test","io.kubernetes.container.name":"POD","io.kubernetes.cri-o.Annotations":"{\"owner\":\"hmeng\",\"security.alpha.kubernetes.io/seccomp/pod\":\"unconfined\"}","io.kubernetes.cri-o.ContainerID":"3ad2d009ae41116a9b33bc06e19628bb841186fd9caf449dfe4149eb98de2971","io.kubernetes.cri-o.ContainerName":"k8s_POD_podsandbox1_redhat.test.crio_redhat-test-crio_1","io.kubernetes.cri-o.ContainerType":"sandbox","io.kubernetes.cri-o.Created":"2018-03-06T16:13:11.960470821Z","io.kubernetes.cri-o.HostName":"crictl_host","io.kubernetes.cri-o.HostnamePath":"/tmp/tmp.21rMywHwBF/crio-run/overlay-containers/3ad2d009ae41116a9b33bc06e19628bb841186fd9caf449dfe4149eb98de2971/userdata/hostname","io.kubernetes.cri-o.IP":"10.88.0.219","io.kubernetes.cri-o.KubeName":"podsandbox1","io.kubernetes.cri-o.Labels":"{\"group\":\"test\",\"io.kubernetes.container.name\":\"POD\"}","io.kubernetes.cri-o.LogPath":"/var/log/crio/pods/3ad2d009ae41116a9b33bc06e19628bb841186fd9caf449dfe4149eb98de2971/3ad2d009ae41116a9b33bc06e19628bb841186fd9caf449dfe4149eb98de2971.log","io.kubernetes.cri-o.Metadata":"{\"name\":\"podsandbox1\",\"uid\":\"redhat-test-crio\",\"namespace\":\"redhat.test.crio\",\"attempt\":1}","io.kubernetes.cri-o.MountPoint":"/tmp/tmp.21rMywHwBF/crio/overlay/625d62ff4f3dd264300250e5839e5af101e55a971c86fdad1f6f70a7ecf51855/merged","io.kubernetes.cri-o.Name":"k8s_podsandbox1_redhat.test.crio_redhat-test-crio_1","io.kubernetes.cri-o.PrivilegedRuntime":"false","io.kubernetes.cri-o.ResolvPath":"/tmp/tmp.21rMywHwBF/crio-run/overlay-containers/3ad2d009ae41116a9b33bc06e19628bb841186fd9caf449dfe4149eb98de2971/userdata/resolv.conf","io.kubernetes.cri-o.SandboxID":"3ad2d009ae41116a9b33bc06e19628bb841186fd9caf449dfe4149eb98de2971","io.kubernetes.cri-o.SeccompProfilePath":"","io.kubernetes.cri-o.ShmPath":"/tmp/tmp.21rMywHwBF/crio-run/overlay-containers/3ad2d009ae41116a9b33bc06e19628bb841186fd9caf449dfe4149eb98de2971/userdata/shm","io.kubernetes.cri-o.TrustedSandbox":"true","owner":"hmeng","security.alpha.kubernetes.io/seccomp/pod":"unconfined"},"linux":{"resources":{"devices":[{"allow":false,"access":"rwm"}],"cpu":{"shares":2}},"cgroupsPath":"/Burstable/pod_123-456/crio-3ad2d009ae41116a9b33bc06e19628bb841186fd9caf449dfe4149eb98de2971","namespaces":[{"type":"pid"},{"type":"network","path":"/var/run/netns/k8s_podsandbox1_redhat.test.crio_redhat-test-crio_1-61719882"},{"type":"ipc"},{"type":"uts"},{"type":"mount"}]},"process":{"user":{"uid":0,"gid":0},"args":["/pause"],"env":["PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin","TERM=xterm"],"cwd":"/","rlimits":[{"type":"RLIMIT_NOFILE","hard":1024,"soft":1024}],"oomScoreAdj":-998,"capabilities":{"ambient":["CAP_CHOWN","CAP_DAC_OVERRIDE","CAP_FSETID","CAP_FOWNER","CAP_MKNOD","CAP_NET_RAW","CAP_SETGID","CAP_SETUID","CAP_SETFCAP","CAP_SETPCAP","CAP_NET_BIND_SERVICE","CAP_SYS_CHROOT","CAP_KILL","CAP_AUDIT_WRITE"],"bounding":["CAP_CHOWN","CAP_DAC_OVERRIDE","CAP_FSETID","CAP_FOWNER","CAP_MKNOD","CAP_NET_RAW","CAP_SETGID","CAP_SETUID","CAP_SETFCAP","CAP_SETPCAP","CAP_NET_BIND_SERVICE","CAP_SYS_CHROOT","CAP_KILL","CAP_AUDIT_WRITE"],"effective":["CAP_CHOWN","CAP_DAC_OVERRIDE","CAP_FSETID","CAP_FOWNER","CAP_MKNOD","CAP_NET_RAW","CAP_SETGID","CAP_SETUID","CAP_SETFCAP","CAP_SETPCAP","CAP_NET_BIND_SERVICE","CAP_SYS_CHROOT","CAP_KILL","CAP_AUDIT_WRITE"],"inheritable":["CAP_CHOWN","CAP_DAC_OVERRIDE","CAP_FSETID","CAP_FOWNER","CAP_MKNOD","CAP_NET_RAW","CAP_SETGID","CAP_SETUID","CAP_SETFCAP","CAP_SETPCAP","CAP_NET_BIND_SERVICE","CAP_SYS_CHROOT","CAP_KILL","CAP_AUDIT_WRITE"],"permitted":["CAP_CHOWN","CAP_DAC_OVERRIDE","CAP_FSETID","CAP_FOWNER","CAP_MKNOD","CAP_NET_RAW","CAP_SETGID","CAP_SETUID","CAP_SETFCAP","CAP_SETPCAP","CAP_NET_BIND_SERVICE","CAP_SYS_CHROOT","CAP_KILL","CAP_AUDIT_WRITE"]}}}]} Created:2018-03-06 16:13:11.960470821 +0000 UTC m=+1.345538821 Started:2018-03-06 16:13:13.770772051 +0000 UTC m=+3.155840151 Finished:0001-01-01 00:00:00 +0000 UTC ExitCode:0 OOMKilled:false Error:}"
# time="2018-03-06 16:13:13.928554673Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.21rMywHwBF/crio+/tmp/tmp.21rMywHwBF/crio-run]docker.io/library/redis:alpine""
# time="2018-03-06 16:13:13.929593080Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.21rMywHwBF/crio+/tmp/tmp.21rMywHwBF/crio-run]docker.io/library/redis:alpine""
# time="2018-03-06 16:13:13.930170484Z" level=debug msg="exporting opaque data as blob "sha256:d3117424aaee14ab2b0edb68d3e3dcc1785b2e243b06bd6322f299284c640465""
# time="2018-03-06 16:13:14.012591718Z" level=debug msg="created container "b69e11c24dafa5622f1d0f00ad64a1646875dea63dba43c55ae215759f64641b""
# time="2018-03-06 16:13:14.070810795Z" level=debug msg="container "b69e11c24dafa5622f1d0f00ad64a1646875dea63dba43c55ae215759f64641b" has work directory "/tmp/tmp.21rMywHwBF/crio/overlay-containers/b69e11c24dafa5622f1d0f00ad64a1646875dea63dba43c55ae215759f64641b/userdata""
# time="2018-03-06 16:13:14.070865795Z" level=debug msg="container "b69e11c24dafa5622f1d0f00ad64a1646875dea63dba43c55ae215759f64641b" has run directory "/tmp/tmp.21rMywHwBF/crio-run/overlay-containers/b69e11c24dafa5622f1d0f00ad64a1646875dea63dba43c55ae215759f64641b/userdata""
# time="2018-03-06 16:13:14.163507396Z" level=debug msg="mounted container "b69e11c24dafa5622f1d0f00ad64a1646875dea63dba43c55ae215759f64641b" at "/tmp/tmp.21rMywHwBF/crio/overlay/b8512b552814814e33dd3564aaee845629baf5c1eb7459ae45c6d982009d6970/merged""
# time="2018-03-06 16:13:14.163570496Z" level=debug msg="OCI process args [/bin/ls]"
# time="2018-03-06 16:13:14.163729697Z" level=debug msg="CONTAINER USER: "
# time="2018-03-06 16:13:14.163857298Z" level=debug msg="UID: 0, GID: 0, Groups: [0 1 2 3 4 6 10 11 20 26 27]"
# time="2018-03-06 16:13:14.164465702Z" level=debug msg="running conmon: /root/go/src/github.com/kubernetes-incubator/cri-o/bin/conmon" args=[-c b69e11c24dafa5622f1d0f00ad64a1646875dea63dba43c55ae215759f64641b -u b69e11c24dafa5622f1d0f00ad64a1646875dea63dba43c55ae215759f64641b -r /usr/local/bin/cc-runtime -b /tmp/tmp.21rMywHwBF/crio-run/overlay-containers/b69e11c24dafa5622f1d0f00ad64a1646875dea63dba43c55ae215759f64641b/userdata -p /tmp/tmp.21rMywHwBF/crio-run/overlay-containers/b69e11c24dafa5622f1d0f00ad64a1646875dea63dba43c55ae215759f64641b/userdata/pidfile -l /var/log/crio/pods/3ad2d009ae41116a9b33bc06e19628bb841186fd9caf449dfe4149eb98de2971/b69e11c24dafa5622f1d0f00ad64a1646875dea63dba43c55ae215759f64641b.log --exit-dir /var/run/crio/exits --socket-dir-path /var/run/crio]
# time="2018-03-06 16:13:14.269318581Z" level=debug msg="Received container pid: 58795"
# time="2018-03-06 16:13:14.362532885Z" level=debug msg="CreateContainerResponse: &CreateContainerResponse{ContainerId:b69e11c24dafa5622f1d0f00ad64a1646875dea63dba43c55ae215759f64641b,}"
# b69e11c24dafa5622f1d0f00ad64a1646875dea63dba43c55ae215759f64641b
# time="2018-03-06 16:13:14.424351286Z" level=debug msg="ExecSyncRequest &ExecSyncRequest{ContainerId:b69e11c24dafa5622f1d0f00ad64a1646875dea63dba43c55ae215759f64641b,Cmd:[env],Timeout:0,}"
# time="2018-03-06 16:13:14.595494195Z" level=info msg="Received container exit code: -1, message: Container b69e11c24dafa5622f1d0f00ad64a1646875dea63dba43c55ae215759f64641b is not running
# "
# time="2018-03-06T16:13:14Z" level=fatal msg="execing command in container synchronously failed: rpc error: code = Unknown desc = command error: Container b69e11c24dafa5622f1d0f00ad64a1646875dea63dba43c55ae215759f64641b is not running\n, stdout: , stderr: , exit code -1"
# rm: cannot remove '/tmp/tmp.21rMywHwBF/crio/overlay/625d62ff4f3dd264300250e5839e5af101e55a971c86fdad1f6f70a7ecf51855/merged': Device or resource busy
# rm: cannot remove '/tmp/tmp.21rMywHwBF/crio/overlay/b8512b552814814e33dd3564aaee845629baf5c1eb7459ae45c6d982009d6970/merged': Device or resource busy
# rm: cannot remove '/tmp/tmp.21rMywHwBF/crio-run/overlay-containers/3ad2d009ae41116a9b33bc06e19628bb841186fd9caf449dfe4149eb98de2971/userdata/shm': Device or resource busy
not ok 18 ctr execsync failure
# (from function `cleanup_test' in file helpers.bash, line 357,
#  from function `teardown' in test file ctr.bats, line 6)
#   `cleanup_test' failed
#   5afe9ca5ed775913b0c167cecf03a1a6f5975e379df11cdb660e4e43fbc455db
# 0
# time="2018-03-06 16:13:29.321739990Z" level=debug msg="backingFs=extfs,  projectQuotaSupported=false"
# time="2018-03-06 16:13:29.321807791Z" level=info msg="[graphdriver] using prior storage driver: overlay"
# time="2018-03-06 16:13:29.323312600Z" level=info msg="CNI network crionet (type=bridge) is used from /tmp/tmp.fZVnuAtovV/cni/net.d/10-crio.conf"
# time="2018-03-06 16:13:29.323590102Z" level=info msg="CNI network crionet (type=bridge) is used from /tmp/tmp.fZVnuAtovV/cni/net.d/10-crio.conf"
# time="2018-03-06 16:13:29.333952769Z" level=debug msg="seccomp status: true"
# time="2018-03-06 16:13:29.334714574Z" level=debug msg="Golang's threads limit set to 115110"
# time="2018-03-06 16:13:29.338582999Z" level=debug msg="sandboxes: []"
# time="2018-03-06 16:13:29.338719600Z" level=error msg="Failed to start streaming server: listen tcp 10.1.10.4:10010: bind: address already in use"
# time="2018-03-06 16:13:29.378925961Z" level=debug msg="closed stream server"
# time="2018-03-06 16:13:29.432490708Z" level=debug msg="ImageStatusRequest: &ImageStatusRequest{Image:&ImageSpec{Image:redis:alpine,},Verbose:false,}"
# time="2018-03-06 16:13:29.432613908Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.fZVnuAtovV/crio+/tmp/tmp.fZVnuAtovV/crio-run]docker.io/library/redis:alpine""
# time="2018-03-06 16:13:29.433313313Z" level=debug msg="[graphdriver] trying provided driver "overlay""
# time="2018-03-06 16:13:29.456116861Z" level=debug msg="backingFs=extfs,  projectQuotaSupported=false"
# time="2018-03-06 16:13:29.457066867Z" level=debug msg="ImageStatusResponse: &ImageStatusResponse{Image:&Image{Id:bced999bf5c3aa11a1eab5a98e4d5d66f4054d467445158f5802eda503bc44e3,RepoTags:[docker.io/library/redis:alpine redis:alpine],RepoDigests:[],Size_:27082854,Uid:nil,Username:,},Info:map[string]string{},}"
# time="2018-03-06 16:13:29.534407768Z" level=debug msg="ImageStatusRequest: &ImageStatusRequest{Image:&ImageSpec{Image:redis:alpine,},Verbose:false,}"
# time="2018-03-06 16:13:29.534493768Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.fZVnuAtovV/crio+/tmp/tmp.fZVnuAtovV/crio-run]docker.io/library/redis:alpine""
# time="2018-03-06 16:13:29.534978971Z" level=debug msg="ImageStatusResponse: &ImageStatusResponse{Image:&Image{Id:bced999bf5c3aa11a1eab5a98e4d5d66f4054d467445158f5802eda503bc44e3,RepoTags:[docker.io/library/redis:alpine redis:alpine],RepoDigests:[],Size_:27082854,Uid:nil,Username:,},Info:map[string]string{},}"
# time="2018-03-06 16:13:29.590154729Z" level=debug msg="ImageStatusRequest: &ImageStatusRequest{Image:&ImageSpec{Image:mrunalp/oom,},Verbose:false,}"
# time="2018-03-06 16:13:29.590220229Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.fZVnuAtovV/crio+/tmp/tmp.fZVnuAtovV/crio-run]docker.io/mrunalp/oom:latest""
# time="2018-03-06 16:13:29.590568531Z" level=debug msg="ImageStatusResponse: &ImageStatusResponse{Image:&Image{Id:5450ef175d1a2bb66cfd7c343d7fee7269d60c463a5679869a37c669f8a9f85e,RepoTags:[docker.io/mrunalp/oom:latest mrunalp/oom docker.io/library/mrunalp/oom],RepoDigests:[],Size_:5719481,Uid:nil,Username:,},Info:map[string]string{},}"
# time="2018-03-06 16:13:29.646302892Z" level=debug msg="ImageStatusRequest: &ImageStatusRequest{Image:&ImageSpec{Image:redis@sha256:03789f402b2ecfb98184bf128d180f398f81c63364948ff1454583b02442f73b,},Verbose:false,}"
# time="2018-03-06 16:13:29.646434793Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.fZVnuAtovV/crio+/tmp/tmp.fZVnuAtovV/crio-run]docker.io/library/redis:latest@03789f402b2ecfb98184bf128d180f398f81c63364948ff1454583b02442f73b""
# time="2018-03-06 16:13:29.646975197Z" level=debug msg="ImageStatusResponse: &ImageStatusResponse{Image:&Image{Id:03789f402b2ecfb98184bf128d180f398f81c63364948ff1454583b02442f73b,RepoTags:[docker.io/library/redis:latest redis@sha256:03789f402b2ecfb98184bf128d180f398f81c63364948ff1454583b02442f73b docker.io/library/redis@sha256:03789f402b2ecfb98184bf128d180f398f81c63364948ff1454583b02442f73b],RepoDigests:[],Size_:19826844,Uid:nil,Username:,},Info:map[string]string{},}"
# time="2018-03-06 16:13:29.709099799Z" level=debug msg="ImageStatusRequest: &ImageStatusRequest{Image:&ImageSpec{Image:runcom/stderr-test,},Verbose:false,}"
# time="2018-03-06 16:13:29.709162099Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.fZVnuAtovV/crio+/tmp/tmp.fZVnuAtovV/crio-run]docker.io/runcom/stderr-test:latest""
# time="2018-03-06 16:13:29.709491802Z" level=debug msg="ImageStatusResponse: &ImageStatusResponse{Image:&Image{Id:d099ce4b13d611b29c56777345ea1569dbe45e11d5f9bd7fe2a3a94b89377272,RepoTags:[docker.io/runcom/stderr-test:latest runcom/stderr-test:latest],RepoDigests:[],Size_:4906035,Uid:nil,Username:,},Info:map[string]string{},}"
# time="2018-03-06 16:13:29.773763718Z" level=debug msg="ImageStatusRequest: &ImageStatusRequest{Image:&ImageSpec{Image:runcom/stderr-test,},Verbose:false,}"
# time="2018-03-06 16:13:29.773832818Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.fZVnuAtovV/crio+/tmp/tmp.fZVnuAtovV/crio-run]docker.io/runcom/stderr-test:latest""
# time="2018-03-06 16:13:29.774147120Z" level=debug msg="ImageStatusResponse: &ImageStatusResponse{Image:&Image{Id:d099ce4b13d611b29c56777345ea1569dbe45e11d5f9bd7fe2a3a94b89377272,RepoTags:[docker.io/runcom/stderr-test:latest runcom/stderr-test:latest],RepoDigests:[],Size_:4906035,Uid:nil,Username:,},Info:map[string]string{},}"
# time="2018-03-06 16:13:29.825382152Z" level=debug msg="ImageStatusRequest: &ImageStatusRequest{Image:&ImageSpec{Image:busybox,},Verbose:false,}"
# time="2018-03-06 16:13:29.825475353Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.fZVnuAtovV/crio+/tmp/tmp.fZVnuAtovV/crio-run]docker.io/library/busybox:latest""
# time="2018-03-06 16:13:29.825771455Z" level=debug msg="ImageStatusResponse: &ImageStatusResponse{Image:&Image{Id:c3a0b314b847e3c996e90d6173cb1b9349134076927a9720f8b8ff713b54c8e8,RepoTags:[docker.io/library/busybox:latest busybox:latest],RepoDigests:[],Size_:1148393,Uid:nil,Username:,},Info:map[string]string{},}"
# time="2018-03-06 16:13:29.891527280Z" level=debug msg="ImageStatusRequest: &ImageStatusRequest{Image:&ImageSpec{Image:busybox,},Verbose:false,}"
# time="2018-03-06 16:13:29.891610981Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.fZVnuAtovV/crio+/tmp/tmp.fZVnuAtovV/crio-run]docker.io/library/busybox:latest""
# time="2018-03-06 16:13:29.891964283Z" level=debug msg="ImageStatusResponse: &ImageStatusResponse{Image:&Image{Id:c3a0b314b847e3c996e90d6173cb1b9349134076927a9720f8b8ff713b54c8e8,RepoTags:[docker.io/library/busybox:latest busybox:latest],RepoDigests:[],Size_:1148393,Uid:nil,Username:,},Info:map[string]string{},}"
# time="2018-03-06 16:13:29.943775919Z" level=debug msg="ImageStatusRequest: &ImageStatusRequest{Image:&ImageSpec{Image:mrunalp/image-volume-test,},Verbose:false,}"
# time="2018-03-06 16:13:29.943869319Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.fZVnuAtovV/crio+/tmp/tmp.fZVnuAtovV/crio-run]docker.io/mrunalp/image-volume-test:latest""
# time="2018-03-06 16:13:29.944215022Z" level=debug msg="ImageStatusResponse: &ImageStatusResponse{Image:&Image{Id:f207321697619a8e0a808a3681b812ff122bed7508ad05042d0f0f097a7f82d2,RepoTags:[docker.io/mrunalp/image-volume-test:latest mrunalp/image-volume-test docker.io/library/mrunalp/image-volume-test],RepoDigests:[],Size_:1095628,Uid:nil,Username:,},Info:map[string]string{},}"
# time="2018-03-06 16:13:30.010418650Z" level=debug msg="ImageStatusRequest: &ImageStatusRequest{Image:&ImageSpec{Image:mrunalp/image-volume-test,},Verbose:false,}"
# time="2018-03-06 16:13:30.010498251Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.fZVnuAtovV/crio+/tmp/tmp.fZVnuAtovV/crio-run]docker.io/mrunalp/image-volume-test:latest""
# time="2018-03-06 16:13:30.010804253Z" level=debug msg="ImageStatusResponse: &ImageStatusResponse{Image:&Image{Id:f207321697619a8e0a808a3681b812ff122bed7508ad05042d0f0f097a7f82d2,RepoTags:[docker.io/mrunalp/image-volume-test:latest mrunalp/image-volume-test docker.io/library/mrunalp/image-volume-test],RepoDigests:[],Size_:1095628,Uid:nil,Username:,},Info:map[string]string{},}"
# time="2018-03-06 16:13:30.061631882Z" level=debug msg="RunPodSandboxRequest &RunPodSandboxRequest{Config:&PodSandboxConfig{Metadata:&PodSandboxMetadata{Name:podsandbox1,Uid:redhat-test-crio,Namespace:redhat.test.crio,Attempt:1,},Hostname:crictl_host,LogDirectory:,DnsConfig:&DNSConfig{Servers:[],Searches:[8.8.8.8],Options:[],},PortMappings:[],Labels:map[string]string{group: test,},Annotations:map[string]string{owner: hmeng,security.alpha.kubernetes.io/seccomp/pod: unconfined,},Linux:&LinuxPodSandboxConfig{CgroupParent:/Burstable/pod_123-456,SecurityContext:&LinuxSandboxSecurityContext{NamespaceOptions:&NamespaceOption{HostNetwork:false,HostPid:false,HostIpc:false,},SelinuxOptions:&SELinuxOption{User:system_u,Role:system_r,Type:svirt_lxc_net_t,Level:s0:c4,c5,},RunAsUser:nil,ReadonlyRootfs:false,SupplementalGroups:[],Privileged:false,SeccompProfilePath:,},Sysctls:map[string]string{},},},}"
# time="2018-03-06 16:13:30.061733983Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.fZVnuAtovV/crio+/tmp/tmp.fZVnuAtovV/crio-run]docker.io/kubernetes/pause:latest""
# time="2018-03-06 16:13:30.062079185Z" level=debug msg="exporting opaque data as blob "sha256:c713bfa1b66381820184edb919bad71ac5aa56a93a1f2fc2c4c7f4d69effe351""
# time="2018-03-06 16:13:30.158728111Z" level=debug msg="created pod sandbox "5afe9ca5ed775913b0c167cecf03a1a6f5975e379df11cdb660e4e43fbc455db""
# time="2018-03-06 16:13:30.220680312Z" level=debug msg="pod sandbox "5afe9ca5ed775913b0c167cecf03a1a6f5975e379df11cdb660e4e43fbc455db" has work directory "/tmp/tmp.fZVnuAtovV/crio/overlay-containers/5afe9ca5ed775913b0c167cecf03a1a6f5975e379df11cdb660e4e43fbc455db/userdata""
# time="2018-03-06 16:13:30.220789713Z" level=debug msg="pod sandbox "5afe9ca5ed775913b0c167cecf03a1a6f5975e379df11cdb660e4e43fbc455db" has run directory "/tmp/tmp.fZVnuAtovV/crio-run/overlay-containers/5afe9ca5ed775913b0c167cecf03a1a6f5975e379df11cdb660e4e43fbc455db/userdata""
# time="2018-03-06 16:13:30.361079521Z" level=debug msg="mounted container "5afe9ca5ed775913b0c167cecf03a1a6f5975e379df11cdb660e4e43fbc455db" at "/tmp/tmp.fZVnuAtovV/crio/overlay/b422929575996a2a121a2d93c136597d6848eca8f266dd8a44a0ad0a1529fb85/merged""
# time="2018-03-06 16:13:30.361195222Z" level=info msg="Got pod network {Name:podsandbox1 Namespace:redhat.test.crio ID:5afe9ca5ed775913b0c167cecf03a1a6f5975e379df11cdb660e4e43fbc455db NetNS:/var/run/netns/k8s_podsandbox1_redhat.test.crio_redhat-test-crio_1-56a5a4ab PortMappings:[]}"
# time="2018-03-06 16:13:30.361216222Z" level=info msg="About to add CNI network cni-loopback (type=loopback)"
# time="2018-03-06 16:13:30.364729445Z" level=info msg="Got pod network {Name:podsandbox1 Namespace:redhat.test.crio ID:5afe9ca5ed775913b0c167cecf03a1a6f5975e379df11cdb660e4e43fbc455db NetNS:/var/run/netns/k8s_podsandbox1_redhat.test.crio_redhat-test-crio_1-56a5a4ab PortMappings:[]}"
# time="2018-03-06 16:13:30.364746945Z" level=info msg="About to add CNI network crionet (type=bridge)"
# time="2018-03-06 16:13:30.437831419Z" level=debug msg="running conmon: /root/go/src/github.com/kubernetes-incubator/cri-o/bin/conmon" args=[-c 5afe9ca5ed775913b0c167cecf03a1a6f5975e379df11cdb660e4e43fbc455db -u 5afe9ca5ed775913b0c167cecf03a1a6f5975e379df11cdb660e4e43fbc455db -r /usr/local/bin/cc-runtime -b /tmp/tmp.fZVnuAtovV/crio-run/overlay-containers/5afe9ca5ed775913b0c167cecf03a1a6f5975e379df11cdb660e4e43fbc455db/userdata -p /tmp/tmp.fZVnuAtovV/crio-run/overlay-containers/5afe9ca5ed775913b0c167cecf03a1a6f5975e379df11cdb660e4e43fbc455db/userdata/pidfile -l /var/log/crio/pods/5afe9ca5ed775913b0c167cecf03a1a6f5975e379df11cdb660e4e43fbc455db/5afe9ca5ed775913b0c167cecf03a1a6f5975e379df11cdb660e4e43fbc455db.log --exit-dir /var/run/crio/exits --socket-dir-path /var/run/crio]
# time="2018-03-06 16:13:31.877696243Z" level=debug msg="Received container pid: 59758"
# time="2018-03-06 16:13:32.217115041Z" level=debug msg="RunPodSandboxResponse: &RunPodSandboxResponse{PodSandboxId:5afe9ca5ed775913b0c167cecf03a1a6f5975e379df11cdb660e4e43fbc455db,}"
# 5afe9ca5ed775913b0c167cecf03a1a6f5975e379df11cdb660e4e43fbc455db
# time="2018-03-06 16:13:32.278301838Z" level=debug msg="CreateContainerRequest &CreateContainerRequest{PodSandboxId:5afe9ca5ed775913b0c167cecf03a1a6f5975e379df11cdb660e4e43fbc455db,Config:&ContainerConfig{Metadata:&ContainerMetadata{Name:podsandbox1-redis,Attempt:0,},Image:&ImageSpec{Image:redis:alpine,},Command:[],Args:[docker-entrypoint.sh redis-server],WorkingDir:/data,Envs:[&KeyValue{Key:PATH,Value:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin,} &KeyValue{Key:TERM,Value:xterm,} &KeyValue{Key:REDIS_VERSION,Value:3.2.3,} &KeyValue{Key:REDIS_DOWNLOAD_URL,Value:http://download.redis.io/releases/redis-3.2.3.tar.gz,} &KeyValue{Key:REDIS_DOWNLOAD_SHA1,Value:92d6d93ef2efc91e595c8bf578bf72baff397507,}],Mounts:[],Devices:[],Labels:map[string]string{tier: backend,},Annotations:map[string]string{pod: podsandbox1,},LogPath:,Stdin:false,StdinOnce:false,Tty:false,Linux:&LinuxContainerConfig{Resources:&LinuxContainerResources{CpuPeriod:10000,CpuQuota:20000,CpuShares:512,MemoryLimitInBytes:209715200,OomScoreAdj:30,CpusetCpus:,CpusetMems:,},SecurityContext:&LinuxContainerSecurityContext{Capabilities:&Capability{AddCapabilities:[sys_admin],DropCapabilities:[],},Privileged:false,NamespaceOptions:nil,SelinuxOptions:nil,RunAsUser:nil,RunAsUsername:,ReadonlyRootfs:false,SupplementalGroups:[],ApparmorProfile:,SeccompProfilePath:,NoNewPrivs:false,},},},SandboxConfig:&PodSandboxConfig{Metadata:&PodSandboxMetadata{Name:podsandbox1,Uid:redhat-test-crio,Namespace:redhat.test.crio,Attempt:1,},Hostname:crictl_host,LogDirectory:,DnsConfig:&DNSConfig{Servers:[],Searches:[8.8.8.8],Options:[],},PortMappings:[],Labels:map[string]string{group: test,},Annotations:map[string]string{owner: hmeng,security.alpha.kubernetes.io/seccomp/pod: unconfined,},Linux:&LinuxPodSandboxConfig{CgroupParent:/Burstable/pod_123-456,SecurityContext:&LinuxSandboxSecurityContext{NamespaceOptions:&NamespaceOption{HostNetwork:false,HostPid:false,HostIpc:false,},SelinuxOptions:&SELinuxOption{User:system_u,Role:system_r,Type:svirt_lxc_net_t,Level:s0:c4,c5,},RunAsUser:nil,ReadonlyRootfs:false,SupplementalGroups:[],Privileged:false,SeccompProfilePath:,},Sysctls:map[string]string{},},},}"
# time="2018-03-06 16:13:32.278486939Z" level=debug msg="setting container's log_path" ctr.logfile= log_path="/var/log/crio/pods/5afe9ca5ed775913b0c167cecf03a1a6f5975e379df11cdb660e4e43fbc455db/ff20607e2eeac223ab9e3a54014610107d9b02c5c456f5fc8f05eafec3bf2c3e.log" sbox.logdir="/var/log/crio/pods/5afe9ca5ed775913b0c167cecf03a1a6f5975e379df11cdb660e4e43fbc455db"
# time="2018-03-06 16:13:32.278571939Z" level=debug msg="pod container state &{State:{Version:1.0.1 ID:5afe9ca5ed775913b0c167cecf03a1a6f5975e379df11cdb660e4e43fbc455db Status:running Pid:59758 Bundle:/tmp/tmp.fZVnuAtovV/crio-run/overlay-containers/5afe9ca5ed775913b0c167cecf03a1a6f5975e379df11cdb660e4e43fbc455db/userdata Annotations:map[com.github.containers.virtcontainers.pkg.oci.bundle_path:/tmp/tmp.fZVnuAtovV/crio-run/overlay-containers/5afe9ca5ed775913b0c167cecf03a1a6f5975e379df11cdb660e4e43fbc455db/userdata com.github.containers.virtcontainers.pkg.oci.config:{"ociVersion":"1.0.0","root":{"path":"/tmp/tmp.fZVnuAtovV/crio/overlay/b422929575996a2a121a2d93c136597d6848eca8f266dd8a44a0ad0a1529fb85/merged","readonly":true},"hostname":"crictl_host","mounts":[{"destination":"/proc","type":"proc","source":"proc"},{"destination":"/dev","type":"tmpfs","source":"tmpfs","options":["nosuid","strictatime","mode=755","size=65536k"]},{"destination":"/dev/pts","type":"devpts","source":"devpts","options":["nosuid","noexec","newinstance","ptmxmode=0666","mode=0620","gid=5"]},{"destination":"/dev/shm","type":"tmpfs","source":"shm","options":["nosuid","noexec","nodev","mode=1777","size=65536k"]},{"destination":"/dev/mqueue","type":"mqueue","source":"mqueue","options":["nosuid","noexec","nodev"]},{"destination":"/sys","type":"sysfs","source":"sysfs","options":["nosuid","noexec","nodev","ro"]},{"destination":"/etc/resolv.conf","type":"bind","source":"/tmp/tmp.fZVnuAtovV/crio-run/overlay-containers/5afe9ca5ed775913b0c167cecf03a1a6f5975e379df11cdb660e4e43fbc455db/userdata/resolv.conf","options":["ro","bind"]},{"destination":"/etc/hostname","type":"bind","source":"/tmp/tmp.fZVnuAtovV/crio-run/overlay-containers/5afe9ca5ed775913b0c167cecf03a1a6f5975e379df11cdb660e4e43fbc455db/userdata/hostname","options":["ro","bind"]}],"annotations":{"group":"test","io.kubernetes.container.name":"POD","io.kubernetes.cri-o.Annotations":"{\"owner\":\"hmeng\",\"security.alpha.kubernetes.io/seccomp/pod\":\"unconfined\"}","io.kubernetes.cri-o.ContainerID":"5afe9ca5ed775913b0c167cecf03a1a6f5975e379df11cdb660e4e43fbc455db","io.kubernetes.cri-o.ContainerName":"k8s_POD_podsandbox1_redhat.test.crio_redhat-test-crio_1","io.kubernetes.cri-o.ContainerType":"sandbox","io.kubernetes.cri-o.Created":"2018-03-06T16:13:30.267761017Z","io.kubernetes.cri-o.HostName":"crictl_host","io.kubernetes.cri-o.HostnamePath":"/tmp/tmp.fZVnuAtovV/crio-run/overlay-containers/5afe9ca5ed775913b0c167cecf03a1a6f5975e379df11cdb660e4e43fbc455db/userdata/hostname","io.kubernetes.cri-o.IP":"10.88.0.220","io.kubernetes.cri-o.KubeName":"podsandbox1","io.kubernetes.cri-o.Labels":"{\"group\":\"test\",\"io.kubernetes.container.name\":\"POD\"}","io.kubernetes.cri-o.LogPath":"/var/log/crio/pods/5afe9ca5ed775913b0c167cecf03a1a6f5975e379df11cdb660e4e43fbc455db/5afe9ca5ed775913b0c167cecf03a1a6f5975e379df11cdb660e4e43fbc455db.log","io.kubernetes.cri-o.Metadata":"{\"name\":\"podsandbox1\",\"uid\":\"redhat-test-crio\",\"namespace\":\"redhat.test.crio\",\"attempt\":1}","io.kubernetes.cri-o.MountPoint":"/tmp/tmp.fZVnuAtovV/crio/overlay/b422929575996a2a121a2d93c136597d6848eca8f266dd8a44a0ad0a1529fb85/merged","io.kubernetes.cri-o.Name":"k8s_podsandbox1_redhat.test.crio_redhat-test-crio_1","io.kubernetes.cri-o.PrivilegedRuntime":"false","io.kubernetes.cri-o.ResolvPath":"/tmp/tmp.fZVnuAtovV/crio-run/overlay-containers/5afe9ca5ed775913b0c167cecf03a1a6f5975e379df11cdb660e4e43fbc455db/userdata/resolv.conf","io.kubernetes.cri-o.SandboxID":"5afe9ca5ed775913b0c167cecf03a1a6f5975e379df11cdb660e4e43fbc455db","io.kubernetes.cri-o.SeccompProfilePath":"","io.kubernetes.cri-o.ShmPath":"/tmp/tmp.fZVnuAtovV/crio-run/overlay-containers/5afe9ca5ed775913b0c167cecf03a1a6f5975e379df11cdb660e4e43fbc455db/userdata/shm","io.kubernetes.cri-o.TrustedSandbox":"true","owner":"hmeng","security.alpha.kubernetes.io/seccomp/pod":"unconfined"},"linux":{"resources":{"devices":[{"allow":false,"access":"rwm"}],"cpu":{"shares":2}},"cgroupsPath":"/Burstable/pod_123-456/crio-5afe9ca5ed775913b0c167cecf03a1a6f5975e379df11cdb660e4e43fbc455db","namespaces":[{"type":"pid"},{"type":"network","path":"/var/run/netns/k8s_podsandbox1_redhat.test.crio_redhat-test-crio_1-56a5a4ab"},{"type":"ipc"},{"type":"uts"},{"type":"mount"}]},"process":{"user":{"uid":0,"gid":0},"args":["/pause"],"env":["PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin","TERM=xterm"],"cwd":"/","rlimits":[{"type":"RLIMIT_NOFILE","hard":1024,"soft":1024}],"oomScoreAdj":-998,"capabilities":{"ambient":["CAP_CHOWN","CAP_DAC_OVERRIDE","CAP_FSETID","CAP_FOWNER","CAP_MKNOD","CAP_NET_RAW","CAP_SETGID","CAP_SETUID","CAP_SETFCAP","CAP_SETPCAP","CAP_NET_BIND_SERVICE","CAP_SYS_CHROOT","CAP_KILL","CAP_AUDIT_WRITE"],"bounding":["CAP_CHOWN","CAP_DAC_OVERRIDE","CAP_FSETID","CAP_FOWNER","CAP_MKNOD","CAP_NET_RAW","CAP_SETGID","CAP_SETUID","CAP_SETFCAP","CAP_SETPCAP","CAP_NET_BIND_SERVICE","CAP_SYS_CHROOT","CAP_KILL","CAP_AUDIT_WRITE"],"effective":["CAP_CHOWN","CAP_DAC_OVERRIDE","CAP_FSETID","CAP_FOWNER","CAP_MKNOD","CAP_NET_RAW","CAP_SETGID","CAP_SETUID","CAP_SETFCAP","CAP_SETPCAP","CAP_NET_BIND_SERVICE","CAP_SYS_CHROOT","CAP_KILL","CAP_AUDIT_WRITE"],"inheritable":["CAP_CHOWN","CAP_DAC_OVERRIDE","CAP_FSETID","CAP_FOWNER","CAP_MKNOD","CAP_NET_RAW","CAP_SETGID","CAP_SETUID","CAP_SETFCAP","CAP_SETPCAP","CAP_NET_BIND_SERVICE","CAP_SYS_CHROOT","CAP_KILL","CAP_AUDIT_WRITE"],"permitted":["CAP_CHOWN","CAP_DAC_OVERRIDE","CAP_FSETID","CAP_FOWNER","CAP_MKNOD","CAP_NET_RAW","CAP_SETGID","CAP_SETUID","CAP_SETFCAP","CAP_SETPCAP","CAP_NET_BIND_SERVICE","CAP_SYS_CHROOT","CAP_KILL","CAP_AUDIT_WRITE"]}}} com.github.containers.virtcontainers.pkg.oci.container_type:pod_sandbox]} Created:2018-03-06 16:13:30.267761017 +0000 UTC m=+1.057935053 Started:2018-03-06 16:13:32.112591365 +0000 UTC m=+2.902765401 Finished:0001-01-01 00:00:00 +0000 UTC ExitCode:0 OOMKilled:false Error:}"
# time="2018-03-06 16:13:32.278683240Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.fZVnuAtovV/crio+/tmp/tmp.fZVnuAtovV/crio-run]docker.io/library/redis:alpine""
# time="2018-03-06 16:13:32.279261444Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.fZVnuAtovV/crio+/tmp/tmp.fZVnuAtovV/crio-run]docker.io/library/redis:alpine""
# time="2018-03-06 16:13:32.279836948Z" level=debug msg="exporting opaque data as blob "sha256:d3117424aaee14ab2b0edb68d3e3dcc1785b2e243b06bd6322f299284c640465""
# time="2018-03-06 16:13:32.361524877Z" level=debug msg="created container "ff20607e2eeac223ab9e3a54014610107d9b02c5c456f5fc8f05eafec3bf2c3e""
# time="2018-03-06 16:13:32.419662953Z" level=debug msg="container "ff20607e2eeac223ab9e3a54014610107d9b02c5c456f5fc8f05eafec3bf2c3e" has work directory "/tmp/tmp.fZVnuAtovV/crio/overlay-containers/ff20607e2eeac223ab9e3a54014610107d9b02c5c456f5fc8f05eafec3bf2c3e/userdata""
# time="2018-03-06 16:13:32.419763054Z" level=debug msg="container "ff20607e2eeac223ab9e3a54014610107d9b02c5c456f5fc8f05eafec3bf2c3e" has run directory "/tmp/tmp.fZVnuAtovV/crio-run/overlay-containers/ff20607e2eeac223ab9e3a54014610107d9b02c5c456f5fc8f05eafec3bf2c3e/userdata""
# time="2018-03-06 16:13:32.511533748Z" level=debug msg="mounted container "ff20607e2eeac223ab9e3a54014610107d9b02c5c456f5fc8f05eafec3bf2c3e" at "/tmp/tmp.fZVnuAtovV/crio/overlay/bc4f34a498f30c8b63dc252188efb14a788c1f5d101adc3b761fd91747fae5d4/merged""
# time="2018-03-06 16:13:32.511615149Z" level=debug msg="OCI process args [docker-entrypoint.sh docker-entrypoint.sh redis-server]"
# time="2018-03-06 16:13:32.511903250Z" level=debug msg="CONTAINER USER: "
# time="2018-03-06 16:13:32.512051451Z" level=debug msg="UID: 0, GID: 0, Groups: [0 1 2 3 4 6 10 11 20 26 27]"
# time="2018-03-06 16:13:32.512849057Z" level=debug msg="running conmon: /root/go/src/github.com/kubernetes-incubator/cri-o/bin/conmon" args=[-c ff20607e2eeac223ab9e3a54014610107d9b02c5c456f5fc8f05eafec3bf2c3e -u ff20607e2eeac223ab9e3a54014610107d9b02c5c456f5fc8f05eafec3bf2c3e -r /usr/local/bin/cc-runtime -b /tmp/tmp.fZVnuAtovV/crio-run/overlay-containers/ff20607e2eeac223ab9e3a54014610107d9b02c5c456f5fc8f05eafec3bf2c3e/userdata -p /tmp/tmp.fZVnuAtovV/crio-run/overlay-containers/ff20607e2eeac223ab9e3a54014610107d9b02c5c456f5fc8f05eafec3bf2c3e/userdata/pidfile -l /var/log/crio/pods/5afe9ca5ed775913b0c167cecf03a1a6f5975e379df11cdb660e4e43fbc455db/ff20607e2eeac223ab9e3a54014610107d9b02c5c456f5fc8f05eafec3bf2c3e.log --exit-dir /var/run/crio/exits --socket-dir-path /var/run/crio]
# time="2018-03-06 16:13:32.638436670Z" level=debug msg="Received container pid: 59831"
# time="2018-03-06 16:13:32.743712852Z" level=debug msg="CreateContainerResponse: &CreateContainerResponse{ContainerId:ff20607e2eeac223ab9e3a54014610107d9b02c5c456f5fc8f05eafec3bf2c3e,}"
# ff20607e2eeac223ab9e3a54014610107d9b02c5c456f5fc8f05eafec3bf2c3e
# time="2018-03-06 16:13:32.802055429Z" level=debug msg="StartContainerRequest &StartContainerRequest{ContainerId:ff20607e2eeac223ab9e3a54014610107d9b02c5c456f5fc8f05eafec3bf2c3e,}"
# time="2018-03-06 16:13:33.019289436Z" level=debug msg="StartContainerResponse &StartContainerResponse{}"
# ff20607e2eeac223ab9e3a54014610107d9b02c5c456f5fc8f05eafec3bf2c3e
# time="2018-03-06 16:13:33.226204476Z" level=debug msg="ExecSyncRequest &ExecSyncRequest{ContainerId:ff20607e2eeac223ab9e3a54014610107d9b02c5c456f5fc8f05eafec3bf2c3e,Cmd:[doesnotexist],Timeout:0,}"
# time="2018-03-06 16:13:33.436631339Z" level=info msg="Received container exit code: -1, message: ERROR received from VM agent, control msg received : Process could not be started: container_linux.go:296: starting container process caused "exec: \"doesnotexist\": executable file not found in $PATH"
# "
# time="2018-03-06T16:13:33Z" level=fatal msg="execing command in container synchronously failed: rpc error: code = Unknown desc = command error: ERROR received from VM agent, control msg received : Process could not be started: container_linux.go:296: starting container process caused \"exec: \\\"doesnotexist\\\": executable file not found in $PATH\"\n, stdout: , stderr: , exit code -1"
# time="2018-03-06 16:13:33.508210302Z" level=debug msg="ListContainersRequest &ListContainersRequest{Filter:&ContainerFilter{Id:,State:nil,PodSandboxId:,LabelSelector:map[string]string{},},}"
# time="2018-03-06 16:13:33.508265703Z" level=debug msg="ListContainersResponse: &ListContainersResponse{Containers:[&Container{Id:ff20607e2eeac223ab9e3a54014610107d9b02c5c456f5fc8f05eafec3bf2c3e,PodSandboxId:5afe9ca5ed775913b0c167cecf03a1a6f5975e379df11cdb660e4e43fbc455db,Metadata:&ContainerMetadata{Name:podsandbox1-redis,Attempt:0,},Image:&ImageSpec{Image:docker.io/library/redis:alpine,},ImageRef:,State:CONTAINER_RUNNING,CreatedAt:1520352812279218844,Labels:map[string]string{tier: backend,},Annotations:map[string]string{pod: podsandbox1,},}],}"
# time="2018-03-06 16:13:33.578377457Z" level=debug msg="StopContainerRequest &StopContainerRequest{ContainerId:ff20607e2eeac223ab9e3a54014610107d9b02c5c456f5fc8f05eafec3bf2c3e,Timeout:10,}"
# time="2018-03-06 16:13:33.800007692Z" level=debug msg="event: "/var/run/crio/exits/ff20607e2eeac223ab9e3a54014610107d9b02c5c456f5fc8f05eafec3bf2c3e.2YKZEZ": CREATE"
# time="2018-03-06 16:13:33.800057092Z" level=debug msg="container or sandbox exited: ff20607e2eeac223ab9e3a54014610107d9b02c5c456f5fc8f05eafec3bf2c3e.2YKZEZ"
# time="2018-03-06 16:13:33.800212093Z" level=debug msg="event: "/var/run/crio/exits/ff20607e2eeac223ab9e3a54014610107d9b02c5c456f5fc8f05eafec3bf2c3e.2YKZEZ": WRITE"
# time="2018-03-06 16:13:33.800321794Z" level=debug msg="event: "/var/run/crio/exits/ff20607e2eeac223ab9e3a54014610107d9b02c5c456f5fc8f05eafec3bf2c3e.2YKZEZ": WRITE"
# time="2018-03-06 16:13:33.800584996Z" level=debug msg="event: "/var/run/crio/exits/ff20607e2eeac223ab9e3a54014610107d9b02c5c456f5fc8f05eafec3bf2c3e.2YKZEZ": RENAME"
# time="2018-03-06 16:13:33.800606696Z" level=debug msg="event: "/var/run/crio/exits/ff20607e2eeac223ab9e3a54014610107d9b02c5c456f5fc8f05eafec3bf2c3e": CREATE"
# time="2018-03-06 16:13:33.800618896Z" level=debug msg="container or sandbox exited: ff20607e2eeac223ab9e3a54014610107d9b02c5c456f5fc8f05eafec3bf2c3e"
# time="2018-03-06 16:13:33.800639396Z" level=debug msg="container exited and found: ff20607e2eeac223ab9e3a54014610107d9b02c5c456f5fc8f05eafec3bf2c3e"
# time="2018-03-06 16:13:34.116980345Z" level=debug msg="unmounted container "ff20607e2eeac223ab9e3a54014610107d9b02c5c456f5fc8f05eafec3bf2c3e""
# time="2018-03-06 16:13:34.327672309Z" level=debug msg="StopContainerResponse ff20607e2eeac223ab9e3a54014610107d9b02c5c456f5fc8f05eafec3bf2c3e: &StopContainerResponse{}"
# ff20607e2eeac223ab9e3a54014610107d9b02c5c456f5fc8f05eafec3bf2c3e
# time="2018-03-06 16:13:34.390848618Z" level=debug msg="RemoveContainerRequest: &RemoveContainerRequest{ContainerId:ff20607e2eeac223ab9e3a54014610107d9b02c5c456f5fc8f05eafec3bf2c3e,}"
# time="2018-03-06 16:13:34.472220145Z" level=debug msg="event: "/var/run/crio/exits/ff20607e2eeac223ab9e3a54014610107d9b02c5c456f5fc8f05eafec3bf2c3e": REMOVE"
# time="2018-03-06 16:13:34.561587024Z" level=debug msg="RemoveContainerResponse: &RemoveContainerResponse{}"
# ff20607e2eeac223ab9e3a54014610107d9b02c5c456f5fc8f05eafec3bf2c3e
# time="2018-03-06 16:13:34.632337982Z" level=debug msg="ListPodSandboxRequest &ListPodSandboxRequest{Filter:&PodSandboxFilter{Id:,State:nil,LabelSelector:map[string]string{},},}"
# time="2018-03-06 16:13:34.632401782Z" level=debug msg="ListPodSandboxResponse &ListPodSandboxResponse{Items:[&PodSandbox{Id:5afe9ca5ed775913b0c167cecf03a1a6f5975e379df11cdb660e4e43fbc455db,Metadata:&PodSandboxMetadata{Name:podsandbox1,Uid:redhat-test-crio,Namespace:redhat.test.crio,Attempt:1,},State:SANDBOX_READY,CreatedAt:1520352810267761017,Labels:map[string]string{group: test,io.kubernetes.container.name: POD,},Annotations:map[string]string{owner: hmeng,security.alpha.kubernetes.io/seccomp/pod: unconfined,},}],}"
# time="2018-03-06 16:13:34.698154508Z" level=debug msg="StopPodSandboxRequest &StopPodSandboxRequest{PodSandboxId:5afe9ca5ed775913b0c167cecf03a1a6f5975e379df11cdb660e4e43fbc455db,}"
# time="2018-03-06 16:13:34.698205208Z" level=info msg="Got pod network {Name:podsandbox1 Namespace:redhat.test.crio ID:5afe9ca5ed775913b0c167cecf03a1a6f5975e379df11cdb660e4e43fbc455db NetNS:/var/run/netns/k8s_podsandbox1_redhat.test.crio_redhat-test-crio_1-56a5a4ab PortMappings:[]}"
# time="2018-03-06 16:13:34.698214808Z" level=info msg="About to del CNI network crionet (type=bridge)"
# time="2018-03-06 16:13:34.885960724Z" level=debug msg="event: "/var/run/crio/exits/5afe9ca5ed775913b0c167cecf03a1a6f5975e379df11cdb660e4e43fbc455db.BGK0EZ": CREATE"
# time="2018-03-06 16:13:34.885984624Z" level=debug msg="container or sandbox exited: 5afe9ca5ed775913b0c167cecf03a1a6f5975e379df11cdb660e4e43fbc455db.BGK0EZ"
# time="2018-03-06 16:13:34.886174825Z" level=debug msg="event: "/var/run/crio/exits/5afe9ca5ed775913b0c167cecf03a1a6f5975e379df11cdb660e4e43fbc455db.BGK0EZ": WRITE"
# time="2018-03-06 16:13:34.886477327Z" level=debug msg="event: "/var/run/crio/exits/5afe9ca5ed775913b0c167cecf03a1a6f5975e379df11cdb660e4e43fbc455db.BGK0EZ": WRITE"
# time="2018-03-06 16:13:34.886701229Z" level=debug msg="event: "/var/run/crio/exits/5afe9ca5ed775913b0c167cecf03a1a6f5975e379df11cdb660e4e43fbc455db.BGK0EZ": RENAME"
# time="2018-03-06 16:13:34.886870830Z" level=debug msg="event: "/var/run/crio/exits/5afe9ca5ed775913b0c167cecf03a1a6f5975e379df11cdb660e4e43fbc455db": CREATE"
# time="2018-03-06 16:13:34.886883030Z" level=debug msg="container or sandbox exited: 5afe9ca5ed775913b0c167cecf03a1a6f5975e379df11cdb660e4e43fbc455db"
# time="2018-03-06 16:13:34.886903230Z" level=debug msg="sandbox exited and found: 5afe9ca5ed775913b0c167cecf03a1a6f5975e379df11cdb660e4e43fbc455db"
# time="2018-03-06 16:13:35.218498477Z" level=debug msg="unmounted container "5afe9ca5ed775913b0c167cecf03a1a6f5975e379df11cdb660e4e43fbc455db""
# time="2018-03-06 16:13:35.218527178Z" level=debug msg="StopPodSandboxResponse 5afe9ca5ed775913b0c167cecf03a1a6f5975e379df11cdb660e4e43fbc455db: &StopPodSandboxResponse{}"
# Stopped sandbox 5afe9ca5ed775913b0c167cecf03a1a6f5975e379df11cdb660e4e43fbc455db
# time="2018-03-06 16:13:35.298902498Z" level=debug msg="RemovePodSandboxRequest &RemovePodSandboxRequest{PodSandboxId:5afe9ca5ed775913b0c167cecf03a1a6f5975e379df11cdb660e4e43fbc455db,}"
# time="2018-03-06 16:13:35.449067070Z" level=debug msg="Failed to unmount b422929575996a2a121a2d93c136597d6848eca8f266dd8a44a0ad0a1529fb85 overlay: /tmp/tmp.fZVnuAtovV/crio/overlay/b422929575996a2a121a2d93c136597d6848eca8f266dd8a44a0ad0a1529fb85/merged - invalid argument"
# time="2018-03-06 16:13:35.484690001Z" level=debug msg="unmounted container "5afe9ca5ed775913b0c167cecf03a1a6f5975e379df11cdb660e4e43fbc455db""
# time="2018-03-06 16:13:35.612051026Z" level=debug msg="RemovePodSandboxResponse &RemovePodSandboxResponse{}"
# Removed sandbox 5afe9ca5ed775913b0c167cecf03a1a6f5975e379df11cdb660e4e43fbc455db
# time="2018-03-06 16:13:35.627074123Z" level=debug msg="Caught SIGTERM"
# time="2018-03-06 16:13:35.627279424Z" level=debug msg="closing exit monitor..."
# time="2018-03-06 16:13:35.627295424Z" level=debug msg="closed exit monitor"
# time="2018-03-06 16:13:35.627313025Z" level=debug msg="closed main server"
# 0
# rm: cannot remove '/tmp/tmp.fZVnuAtovV/crio/overlay': Device or resource busy
not ok 19 ctr execsync exit code
# (from function `cleanup_test' in file helpers.bash, line 357,
#  from function `teardown' in test file ctr.bats, line 6)
#   `cleanup_test' failed
#   98c67b943e259da6b0362017d5b51d9415d20f2cab78bb9d9b50d96389f1dec6
# 0
# time="2018-03-06 16:13:47.814973729Z" level=debug msg="backingFs=extfs,  projectQuotaSupported=false"
# time="2018-03-06 16:13:47.815029930Z" level=info msg="[graphdriver] using prior storage driver: overlay"
# time="2018-03-06 16:13:47.816650840Z" level=info msg="CNI network crionet (type=bridge) is used from /tmp/tmp.yXXWla3vNF/cni/net.d/10-crio.conf"
# time="2018-03-06 16:13:47.816904742Z" level=info msg="CNI network crionet (type=bridge) is used from /tmp/tmp.yXXWla3vNF/cni/net.d/10-crio.conf"
# time="2018-03-06 16:13:47.833710251Z" level=debug msg="seccomp status: true"
# time="2018-03-06 16:13:47.834398855Z" level=debug msg="Golang's threads limit set to 115110"
# time="2018-03-06 16:13:47.838120079Z" level=debug msg="sandboxes: []"
# time="2018-03-06 16:13:47.838330881Z" level=error msg="Failed to start streaming server: listen tcp 10.1.10.4:10010: bind: address already in use"
# time="2018-03-06 16:13:47.874680716Z" level=debug msg="closed stream server"
# time="2018-03-06 16:13:47.931085781Z" level=debug msg="ImageStatusRequest: &ImageStatusRequest{Image:&ImageSpec{Image:redis:alpine,},Verbose:false,}"
# time="2018-03-06 16:13:47.931172482Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.yXXWla3vNF/crio+/tmp/tmp.yXXWla3vNF/crio-run]docker.io/library/redis:alpine""
# time="2018-03-06 16:13:47.931880586Z" level=debug msg="[graphdriver] trying provided driver "overlay""
# time="2018-03-06 16:13:47.953626427Z" level=debug msg="backingFs=extfs,  projectQuotaSupported=false"
# time="2018-03-06 16:13:47.954288431Z" level=debug msg="ImageStatusResponse: &ImageStatusResponse{Image:&Image{Id:5420714c71abdeafb797c8cc409ae313afcb8530f66d75503b6cf102230aaa71,RepoTags:[docker.io/library/redis:alpine redis:alpine],RepoDigests:[],Size_:27082854,Uid:nil,Username:,},Info:map[string]string{},}"
# time="2018-03-06 16:13:48.023043376Z" level=debug msg="ImageStatusRequest: &ImageStatusRequest{Image:&ImageSpec{Image:redis:alpine,},Verbose:false,}"
# time="2018-03-06 16:13:48.023091577Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.yXXWla3vNF/crio+/tmp/tmp.yXXWla3vNF/crio-run]docker.io/library/redis:alpine""
# time="2018-03-06 16:13:48.023470979Z" level=debug msg="ImageStatusResponse: &ImageStatusResponse{Image:&Image{Id:5420714c71abdeafb797c8cc409ae313afcb8530f66d75503b6cf102230aaa71,RepoTags:[docker.io/library/redis:alpine redis:alpine],RepoDigests:[],Size_:27082854,Uid:nil,Username:,},Info:map[string]string{},}"
# time="2018-03-06 16:13:48.072871699Z" level=debug msg="ImageStatusRequest: &ImageStatusRequest{Image:&ImageSpec{Image:mrunalp/oom,},Verbose:false,}"
# time="2018-03-06 16:13:48.072930799Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.yXXWla3vNF/crio+/tmp/tmp.yXXWla3vNF/crio-run]docker.io/mrunalp/oom:latest""
# time="2018-03-06 16:13:48.073207901Z" level=debug msg="ImageStatusResponse: &ImageStatusResponse{Image:&Image{Id:4215b4515947e49edfd77f26a2f0aeb6ba5eb189bfd4708282ecba11039e179d,RepoTags:[docker.io/mrunalp/oom:latest mrunalp/oom docker.io/library/mrunalp/oom],RepoDigests:[],Size_:5719481,Uid:nil,Username:,},Info:map[string]string{},}"
# time="2018-03-06 16:13:48.128638260Z" level=debug msg="ImageStatusRequest: &ImageStatusRequest{Image:&ImageSpec{Image:redis@sha256:03789f402b2ecfb98184bf128d180f398f81c63364948ff1454583b02442f73b,},Verbose:false,}"
# time="2018-03-06 16:13:48.128729560Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.yXXWla3vNF/crio+/tmp/tmp.yXXWla3vNF/crio-run]docker.io/library/redis:latest@03789f402b2ecfb98184bf128d180f398f81c63364948ff1454583b02442f73b""
# time="2018-03-06 16:13:48.129140363Z" level=debug msg="ImageStatusResponse: &ImageStatusResponse{Image:&Image{Id:03789f402b2ecfb98184bf128d180f398f81c63364948ff1454583b02442f73b,RepoTags:[docker.io/library/redis:latest redis@sha256:03789f402b2ecfb98184bf128d180f398f81c63364948ff1454583b02442f73b docker.io/library/redis@sha256:03789f402b2ecfb98184bf128d180f398f81c63364948ff1454583b02442f73b],RepoDigests:[],Size_:19826844,Uid:nil,Username:,},Info:map[string]string{},}"
# time="2018-03-06 16:13:48.183416214Z" level=debug msg="ImageStatusRequest: &ImageStatusRequest{Image:&ImageSpec{Image:runcom/stderr-test,},Verbose:false,}"
# time="2018-03-06 16:13:48.183515115Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.yXXWla3vNF/crio+/tmp/tmp.yXXWla3vNF/crio-run]docker.io/runcom/stderr-test:latest""
# time="2018-03-06 16:13:48.183844417Z" level=debug msg="ImageStatusResponse: &ImageStatusResponse{Image:&Image{Id:9174fc253f6f2613bac2b0a553316cc5603195672f6aae496ede838811817e69,RepoTags:[docker.io/runcom/stderr-test:latest runcom/stderr-test:latest],RepoDigests:[],Size_:4906035,Uid:nil,Username:,},Info:map[string]string{},}"
# time="2018-03-06 16:13:48.244856912Z" level=debug msg="ImageStatusRequest: &ImageStatusRequest{Image:&ImageSpec{Image:runcom/stderr-test,},Verbose:false,}"
# time="2018-03-06 16:13:48.244904612Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.yXXWla3vNF/crio+/tmp/tmp.yXXWla3vNF/crio-run]docker.io/runcom/stderr-test:latest""
# time="2018-03-06 16:13:48.245102414Z" level=debug msg="ImageStatusResponse: &ImageStatusResponse{Image:&Image{Id:9174fc253f6f2613bac2b0a553316cc5603195672f6aae496ede838811817e69,RepoTags:[docker.io/runcom/stderr-test:latest runcom/stderr-test:latest],RepoDigests:[],Size_:4906035,Uid:nil,Username:,},Info:map[string]string{},}"
# time="2018-03-06 16:13:48.294896936Z" level=debug msg="ImageStatusRequest: &ImageStatusRequest{Image:&ImageSpec{Image:busybox,},Verbose:false,}"
# time="2018-03-06 16:13:48.294944836Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.yXXWla3vNF/crio+/tmp/tmp.yXXWla3vNF/crio-run]docker.io/library/busybox:latest""
# time="2018-03-06 16:13:48.295196738Z" level=debug msg="ImageStatusResponse: &ImageStatusResponse{Image:&Image{Id:320489d3c9d1b977e34c9e6d0e2d0e2ff4c9ee9b5aafeb52d163e0a9064e758d,RepoTags:[docker.io/library/busybox:latest busybox:latest],RepoDigests:[],Size_:1148393,Uid:nil,Username:,},Info:map[string]string{},}"
# time="2018-03-06 16:13:48.357048538Z" level=debug msg="ImageStatusRequest: &ImageStatusRequest{Image:&ImageSpec{Image:busybox,},Verbose:false,}"
# time="2018-03-06 16:13:48.357092339Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.yXXWla3vNF/crio+/tmp/tmp.yXXWla3vNF/crio-run]docker.io/library/busybox:latest""
# time="2018-03-06 16:13:48.357303140Z" level=debug msg="ImageStatusResponse: &ImageStatusResponse{Image:&Image{Id:320489d3c9d1b977e34c9e6d0e2d0e2ff4c9ee9b5aafeb52d163e0a9064e758d,RepoTags:[docker.io/library/busybox:latest busybox:latest],RepoDigests:[],Size_:1148393,Uid:nil,Username:,},Info:map[string]string{},}"
# time="2018-03-06 16:13:48.411397190Z" level=debug msg="ImageStatusRequest: &ImageStatusRequest{Image:&ImageSpec{Image:mrunalp/image-volume-test,},Verbose:false,}"
# time="2018-03-06 16:13:48.411453490Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.yXXWla3vNF/crio+/tmp/tmp.yXXWla3vNF/crio-run]docker.io/mrunalp/image-volume-test:latest""
# time="2018-03-06 16:13:48.411714692Z" level=debug msg="ImageStatusResponse: &ImageStatusResponse{Image:&Image{Id:386c91ea6d8bfd4adc9500d6050efc2409e6d6480d18ef61d3cb14b285704140,RepoTags:[docker.io/mrunalp/image-volume-test:latest mrunalp/image-volume-test docker.io/library/mrunalp/image-volume-test],RepoDigests:[],Size_:1095628,Uid:nil,Username:,},Info:map[string]string{},}"
# time="2018-03-06 16:13:48.474541099Z" level=debug msg="ImageStatusRequest: &ImageStatusRequest{Image:&ImageSpec{Image:mrunalp/image-volume-test,},Verbose:false,}"
# time="2018-03-06 16:13:48.474608599Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.yXXWla3vNF/crio+/tmp/tmp.yXXWla3vNF/crio-run]docker.io/mrunalp/image-volume-test:latest""
# time="2018-03-06 16:13:48.474886401Z" level=debug msg="ImageStatusResponse: &ImageStatusResponse{Image:&Image{Id:386c91ea6d8bfd4adc9500d6050efc2409e6d6480d18ef61d3cb14b285704140,RepoTags:[docker.io/mrunalp/image-volume-test:latest mrunalp/image-volume-test docker.io/library/mrunalp/image-volume-test],RepoDigests:[],Size_:1095628,Uid:nil,Username:,},Info:map[string]string{},}"
# time="2018-03-06 16:13:48.525022526Z" level=debug msg="RunPodSandboxRequest &RunPodSandboxRequest{Config:&PodSandboxConfig{Metadata:&PodSandboxMetadata{Name:podsandbox1,Uid:redhat-test-crio,Namespace:redhat.test.crio,Attempt:1,},Hostname:crictl_host,LogDirectory:,DnsConfig:&DNSConfig{Servers:[],Searches:[8.8.8.8],Options:[],},PortMappings:[],Labels:map[string]string{group: test,},Annotations:map[string]string{owner: hmeng,security.alpha.kubernetes.io/seccomp/pod: unconfined,},Linux:&LinuxPodSandboxConfig{CgroupParent:/Burstable/pod_123-456,SecurityContext:&LinuxSandboxSecurityContext{NamespaceOptions:&NamespaceOption{HostNetwork:false,HostPid:false,HostIpc:false,},SelinuxOptions:&SELinuxOption{User:system_u,Role:system_r,Type:svirt_lxc_net_t,Level:s0:c4,c5,},RunAsUser:nil,ReadonlyRootfs:false,SupplementalGroups:[],Privileged:false,SeccompProfilePath:,},Sysctls:map[string]string{},},},}"
# time="2018-03-06 16:13:48.525068826Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.yXXWla3vNF/crio+/tmp/tmp.yXXWla3vNF/crio-run]docker.io/kubernetes/pause:latest""
# time="2018-03-06 16:13:48.525381028Z" level=debug msg="exporting opaque data as blob "sha256:c713bfa1b66381820184edb919bad71ac5aa56a93a1f2fc2c4c7f4d69effe351""
# time="2018-03-06 16:13:48.609125570Z" level=debug msg="created pod sandbox "98c67b943e259da6b0362017d5b51d9415d20f2cab78bb9d9b50d96389f1dec6""
# time="2018-03-06 16:13:48.671708175Z" level=debug msg="pod sandbox "98c67b943e259da6b0362017d5b51d9415d20f2cab78bb9d9b50d96389f1dec6" has work directory "/tmp/tmp.yXXWla3vNF/crio/overlay-containers/98c67b943e259da6b0362017d5b51d9415d20f2cab78bb9d9b50d96389f1dec6/userdata""
# time="2018-03-06 16:13:48.671775176Z" level=debug msg="pod sandbox "98c67b943e259da6b0362017d5b51d9415d20f2cab78bb9d9b50d96389f1dec6" has run directory "/tmp/tmp.yXXWla3vNF/crio-run/overlay-containers/98c67b943e259da6b0362017d5b51d9415d20f2cab78bb9d9b50d96389f1dec6/userdata""
# time="2018-03-06 16:13:48.811310779Z" level=debug msg="mounted container "98c67b943e259da6b0362017d5b51d9415d20f2cab78bb9d9b50d96389f1dec6" at "/tmp/tmp.yXXWla3vNF/crio/overlay/9cb33f2c25792900b1c9401c58b778b815ef140e36763bc832032662c018e26b/merged""
# time="2018-03-06 16:13:48.811388279Z" level=info msg="Got pod network {Name:podsandbox1 Namespace:redhat.test.crio ID:98c67b943e259da6b0362017d5b51d9415d20f2cab78bb9d9b50d96389f1dec6 NetNS:/var/run/netns/k8s_podsandbox1_redhat.test.crio_redhat-test-crio_1-af8f9e13 PortMappings:[]}"
# time="2018-03-06 16:13:48.811402079Z" level=info msg="About to add CNI network cni-loopback (type=loopback)"
# time="2018-03-06 16:13:48.815208804Z" level=info msg="Got pod network {Name:podsandbox1 Namespace:redhat.test.crio ID:98c67b943e259da6b0362017d5b51d9415d20f2cab78bb9d9b50d96389f1dec6 NetNS:/var/run/netns/k8s_podsandbox1_redhat.test.crio_redhat-test-crio_1-af8f9e13 PortMappings:[]}"
# time="2018-03-06 16:13:48.815226204Z" level=info msg="About to add CNI network crionet (type=bridge)"
# time="2018-03-06 16:13:48.904658683Z" level=debug msg="running conmon: /root/go/src/github.com/kubernetes-incubator/cri-o/bin/conmon" args=[-c 98c67b943e259da6b0362017d5b51d9415d20f2cab78bb9d9b50d96389f1dec6 -u 98c67b943e259da6b0362017d5b51d9415d20f2cab78bb9d9b50d96389f1dec6 -r /usr/local/bin/cc-runtime -b /tmp/tmp.yXXWla3vNF/crio-run/overlay-containers/98c67b943e259da6b0362017d5b51d9415d20f2cab78bb9d9b50d96389f1dec6/userdata -p /tmp/tmp.yXXWla3vNF/crio-run/overlay-containers/98c67b943e259da6b0362017d5b51d9415d20f2cab78bb9d9b50d96389f1dec6/userdata/pidfile -l /var/log/crio/pods/98c67b943e259da6b0362017d5b51d9415d20f2cab78bb9d9b50d96389f1dec6/98c67b943e259da6b0362017d5b51d9415d20f2cab78bb9d9b50d96389f1dec6.log --exit-dir /var/run/crio/exits --socket-dir-path /var/run/crio]
# time="2018-03-06 16:13:50.207798518Z" level=debug msg="Received container pid: 61247"
# time="2018-03-06 16:13:50.562196912Z" level=debug msg="RunPodSandboxResponse: &RunPodSandboxResponse{PodSandboxId:98c67b943e259da6b0362017d5b51d9415d20f2cab78bb9d9b50d96389f1dec6,}"
# 98c67b943e259da6b0362017d5b51d9415d20f2cab78bb9d9b50d96389f1dec6
# time="2018-03-06 16:13:50.620982792Z" level=debug msg="CreateContainerRequest &CreateContainerRequest{PodSandboxId:98c67b943e259da6b0362017d5b51d9415d20f2cab78bb9d9b50d96389f1dec6,Config:&ContainerConfig{Metadata:&ContainerMetadata{Name:podsandbox1-redis,Attempt:0,},Image:&ImageSpec{Image:redis:alpine,},Command:[],Args:[docker-entrypoint.sh redis-server],WorkingDir:/data,Envs:[&KeyValue{Key:PATH,Value:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin,} &KeyValue{Key:TERM,Value:xterm,} &KeyValue{Key:REDIS_VERSION,Value:3.2.3,} &KeyValue{Key:REDIS_DOWNLOAD_URL,Value:http://download.redis.io/releases/redis-3.2.3.tar.gz,} &KeyValue{Key:REDIS_DOWNLOAD_SHA1,Value:92d6d93ef2efc91e595c8bf578bf72baff397507,}],Mounts:[],Devices:[],Labels:map[string]string{tier: backend,},Annotations:map[string]string{pod: podsandbox1,},LogPath:,Stdin:false,StdinOnce:false,Tty:false,Linux:&LinuxContainerConfig{Resources:&LinuxContainerResources{CpuPeriod:10000,CpuQuota:20000,CpuShares:512,MemoryLimitInBytes:209715200,OomScoreAdj:30,CpusetCpus:,CpusetMems:,},SecurityContext:&LinuxContainerSecurityContext{Capabilities:&Capability{AddCapabilities:[sys_admin],DropCapabilities:[],},Privileged:false,NamespaceOptions:nil,SelinuxOptions:nil,RunAsUser:nil,RunAsUsername:,ReadonlyRootfs:false,SupplementalGroups:[],ApparmorProfile:,SeccompProfilePath:,NoNewPrivs:false,},},},SandboxConfig:&PodSandboxConfig{Metadata:&PodSandboxMetadata{Name:podsandbox1,Uid:redhat-test-crio,Namespace:redhat.test.crio,Attempt:1,},Hostname:crictl_host,LogDirectory:,DnsConfig:&DNSConfig{Servers:[],Searches:[8.8.8.8],Options:[],},PortMappings:[],Labels:map[string]string{group: test,},Annotations:map[string]string{owner: hmeng,security.alpha.kubernetes.io/seccomp/pod: unconfined,},Linux:&LinuxPodSandboxConfig{CgroupParent:/Burstable/pod_123-456,SecurityContext:&LinuxSandboxSecurityContext{NamespaceOptions:&NamespaceOption{HostNetwork:false,HostPid:false,HostIpc:false,},SelinuxOptions:&SELinuxOption{User:system_u,Role:system_r,Type:svirt_lxc_net_t,Level:s0:c4,c5,},RunAsUser:nil,ReadonlyRootfs:false,SupplementalGroups:[],Privileged:false,SeccompProfilePath:,},Sysctls:map[string]string{},},},}"
# time="2018-03-06 16:13:50.621088193Z" level=debug msg="setting container's log_path" ctr.logfile= log_path="/var/log/crio/pods/98c67b943e259da6b0362017d5b51d9415d20f2cab78bb9d9b50d96389f1dec6/edf925dba8d5ea65027af26d846db1acd2433e7a20741a25e177239d20246f77.log" sbox.logdir="/var/log/crio/pods/98c67b943e259da6b0362017d5b51d9415d20f2cab78bb9d9b50d96389f1dec6"
# time="2018-03-06 16:13:50.621144693Z" level=debug msg="pod container state &{State:{Version:1.0.1 ID:98c67b943e259da6b0362017d5b51d9415d20f2cab78bb9d9b50d96389f1dec6 Status:running Pid:61247 Bundle:/tmp/tmp.yXXWla3vNF/crio-run/overlay-containers/98c67b943e259da6b0362017d5b51d9415d20f2cab78bb9d9b50d96389f1dec6/userdata Annotations:map[com.github.containers.virtcontainers.pkg.oci.bundle_path:/tmp/tmp.yXXWla3vNF/crio-run/overlay-containers/98c67b943e259da6b0362017d5b51d9415d20f2cab78bb9d9b50d96389f1dec6/userdata com.github.containers.virtcontainers.pkg.oci.config:{"ociVersion":"1.0.0","root":{"path":"/tmp/tmp.yXXWla3vNF/crio/overlay/9cb33f2c25792900b1c9401c58b778b815ef140e36763bc832032662c018e26b/merged","readonly":true},"hostname":"crictl_host","mounts":[{"destination":"/proc","type":"proc","source":"proc"},{"destination":"/dev","type":"tmpfs","source":"tmpfs","options":["nosuid","strictatime","mode=755","size=65536k"]},{"destination":"/dev/pts","type":"devpts","source":"devpts","options":["nosuid","noexec","newinstance","ptmxmode=0666","mode=0620","gid=5"]},{"destination":"/dev/shm","type":"tmpfs","source":"shm","options":["nosuid","noexec","nodev","mode=1777","size=65536k"]},{"destination":"/dev/mqueue","type":"mqueue","source":"mqueue","options":["nosuid","noexec","nodev"]},{"destination":"/sys","type":"sysfs","source":"sysfs","options":["nosuid","noexec","nodev","ro"]},{"destination":"/etc/resolv.conf","type":"bind","source":"/tmp/tmp.yXXWla3vNF/crio-run/overlay-containers/98c67b943e259da6b0362017d5b51d9415d20f2cab78bb9d9b50d96389f1dec6/userdata/resolv.conf","options":["ro","bind"]},{"destination":"/etc/hostname","type":"bind","source":"/tmp/tmp.yXXWla3vNF/crio-run/overlay-containers/98c67b943e259da6b0362017d5b51d9415d20f2cab78bb9d9b50d96389f1dec6/userdata/hostname","options":["ro","bind"]}],"annotations":{"group":"test","io.kubernetes.container.name":"POD","io.kubernetes.cri-o.Annotations":"{\"owner\":\"hmeng\",\"security.alpha.kubernetes.io/seccomp/pod\":\"unconfined\"}","io.kubernetes.cri-o.ContainerID":"98c67b943e259da6b0362017d5b51d9415d20f2cab78bb9d9b50d96389f1dec6","io.kubernetes.cri-o.ContainerName":"k8s_POD_podsandbox1_redhat.test.crio_redhat-test-crio_1","io.kubernetes.cri-o.ContainerType":"sandbox","io.kubernetes.cri-o.Created":"2018-03-06T16:13:48.718450678Z","io.kubernetes.cri-o.HostName":"crictl_host","io.kubernetes.cri-o.HostnamePath":"/tmp/tmp.yXXWla3vNF/crio-run/overlay-containers/98c67b943e259da6b0362017d5b51d9415d20f2cab78bb9d9b50d96389f1dec6/userdata/hostname","io.kubernetes.cri-o.IP":"10.88.0.221","io.kubernetes.cri-o.KubeName":"podsandbox1","io.kubernetes.cri-o.Labels":"{\"group\":\"test\",\"io.kubernetes.container.name\":\"POD\"}","io.kubernetes.cri-o.LogPath":"/var/log/crio/pods/98c67b943e259da6b0362017d5b51d9415d20f2cab78bb9d9b50d96389f1dec6/98c67b943e259da6b0362017d5b51d9415d20f2cab78bb9d9b50d96389f1dec6.log","io.kubernetes.cri-o.Metadata":"{\"name\":\"podsandbox1\",\"uid\":\"redhat-test-crio\",\"namespace\":\"redhat.test.crio\",\"attempt\":1}","io.kubernetes.cri-o.MountPoint":"/tmp/tmp.yXXWla3vNF/crio/overlay/9cb33f2c25792900b1c9401c58b778b815ef140e36763bc832032662c018e26b/merged","io.kubernetes.cri-o.Name":"k8s_podsandbox1_redhat.test.crio_redhat-test-crio_1","io.kubernetes.cri-o.PrivilegedRuntime":"false","io.kubernetes.cri-o.ResolvPath":"/tmp/tmp.yXXWla3vNF/crio-run/overlay-containers/98c67b943e259da6b0362017d5b51d9415d20f2cab78bb9d9b50d96389f1dec6/userdata/resolv.conf","io.kubernetes.cri-o.SandboxID":"98c67b943e259da6b0362017d5b51d9415d20f2cab78bb9d9b50d96389f1dec6","io.kubernetes.cri-o.SeccompProfilePath":"","io.kubernetes.cri-o.ShmPath":"/tmp/tmp.yXXWla3vNF/crio-run/overlay-containers/98c67b943e259da6b0362017d5b51d9415d20f2cab78bb9d9b50d96389f1dec6/userdata/shm","io.kubernetes.cri-o.TrustedSandbox":"true","owner":"hmeng","security.alpha.kubernetes.io/seccomp/pod":"unconfined"},"linux":{"resources":{"devices":[{"allow":false,"access":"rwm"}],"cpu":{"shares":2}},"cgroupsPath":"/Burstable/pod_123-456/crio-98c67b943e259da6b0362017d5b51d9415d20f2cab78bb9d9b50d96389f1dec6","namespaces":[{"type":"pid"},{"type":"network","path":"/var/run/netns/k8s_podsandbox1_redhat.test.crio_redhat-test-crio_1-af8f9e13"},{"type":"ipc"},{"type":"uts"},{"type":"mount"}]},"process":{"user":{"uid":0,"gid":0},"args":["/pause"],"env":["PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin","TERM=xterm"],"cwd":"/","rlimits":[{"type":"RLIMIT_NOFILE","hard":1024,"soft":1024}],"oomScoreAdj":-998,"capabilities":{"ambient":["CAP_CHOWN","CAP_DAC_OVERRIDE","CAP_FSETID","CAP_FOWNER","CAP_MKNOD","CAP_NET_RAW","CAP_SETGID","CAP_SETUID","CAP_SETFCAP","CAP_SETPCAP","CAP_NET_BIND_SERVICE","CAP_SYS_CHROOT","CAP_KILL","CAP_AUDIT_WRITE"],"bounding":["CAP_CHOWN","CAP_DAC_OVERRIDE","CAP_FSETID","CAP_FOWNER","CAP_MKNOD","CAP_NET_RAW","CAP_SETGID","CAP_SETUID","CAP_SETFCAP","CAP_SETPCAP","CAP_NET_BIND_SERVICE","CAP_SYS_CHROOT","CAP_KILL","CAP_AUDIT_WRITE"],"effective":["CAP_CHOWN","CAP_DAC_OVERRIDE","CAP_FSETID","CAP_FOWNER","CAP_MKNOD","CAP_NET_RAW","CAP_SETGID","CAP_SETUID","CAP_SETFCAP","CAP_SETPCAP","CAP_NET_BIND_SERVICE","CAP_SYS_CHROOT","CAP_KILL","CAP_AUDIT_WRITE"],"inheritable":["CAP_CHOWN","CAP_DAC_OVERRIDE","CAP_FSETID","CAP_FOWNER","CAP_MKNOD","CAP_NET_RAW","CAP_SETGID","CAP_SETUID","CAP_SETFCAP","CAP_SETPCAP","CAP_NET_BIND_SERVICE","CAP_SYS_CHROOT","CAP_KILL","CAP_AUDIT_WRITE"],"permitted":["CAP_CHOWN","CAP_DAC_OVERRIDE","CAP_FSETID","CAP_FOWNER","CAP_MKNOD","CAP_NET_RAW","CAP_SETGID","CAP_SETUID","CAP_SETFCAP","CAP_SETPCAP","CAP_NET_BIND_SERVICE","CAP_SYS_CHROOT","CAP_KILL","CAP_AUDIT_WRITE"]}}} com.github.containers.virtcontainers.pkg.oci.container_type:pod_sandbox]} Created:2018-03-06 16:13:48.718450678 +0000 UTC m=+0.995655446 Started:2018-03-06 16:13:50.469109509 +0000 UTC m=+2.746314277 Finished:0001-01-01 00:00:00 +0000 UTC ExitCode:0 OOMKilled:false Error:}"
# time="2018-03-06 16:13:50.621213894Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.yXXWla3vNF/crio+/tmp/tmp.yXXWla3vNF/crio-run]docker.io/library/redis:alpine""
# time="2018-03-06 16:13:50.621854398Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.yXXWla3vNF/crio+/tmp/tmp.yXXWla3vNF/crio-run]docker.io/library/redis:alpine""
# time="2018-03-06 16:13:50.622213600Z" level=debug msg="exporting opaque data as blob "sha256:d3117424aaee14ab2b0edb68d3e3dcc1785b2e243b06bd6322f299284c640465""
# time="2018-03-06 16:13:50.711239776Z" level=debug msg="created container "edf925dba8d5ea65027af26d846db1acd2433e7a20741a25e177239d20246f77""
# time="2018-03-06 16:13:50.769354452Z" level=debug msg="container "edf925dba8d5ea65027af26d846db1acd2433e7a20741a25e177239d20246f77" has work directory "/tmp/tmp.yXXWla3vNF/crio/overlay-containers/edf925dba8d5ea65027af26d846db1acd2433e7a20741a25e177239d20246f77/userdata""
# time="2018-03-06 16:13:50.769474153Z" level=debug msg="container "edf925dba8d5ea65027af26d846db1acd2433e7a20741a25e177239d20246f77" has run directory "/tmp/tmp.yXXWla3vNF/crio-run/overlay-containers/edf925dba8d5ea65027af26d846db1acd2433e7a20741a25e177239d20246f77/userdata""
# time="2018-03-06 16:13:50.861175847Z" level=debug msg="mounted container "edf925dba8d5ea65027af26d846db1acd2433e7a20741a25e177239d20246f77" at "/tmp/tmp.yXXWla3vNF/crio/overlay/b7d09ca98ed8c5577cefba5b25a7392886a100674f3eeb7ba2e104a0cc040bf8/merged""
# time="2018-03-06 16:13:50.861234747Z" level=debug msg="OCI process args [docker-entrypoint.sh docker-entrypoint.sh redis-server]"
# time="2018-03-06 16:13:50.861481949Z" level=debug msg="CONTAINER USER: "
# time="2018-03-06 16:13:50.861701750Z" level=debug msg="UID: 0, GID: 0, Groups: [0 1 2 3 4 6 10 11 20 26 27]"
# time="2018-03-06 16:13:50.862331654Z" level=debug msg="running conmon: /root/go/src/github.com/kubernetes-incubator/cri-o/bin/conmon" args=[-c edf925dba8d5ea65027af26d846db1acd2433e7a20741a25e177239d20246f77 -u edf925dba8d5ea65027af26d846db1acd2433e7a20741a25e177239d20246f77 -r /usr/local/bin/cc-runtime -b /tmp/tmp.yXXWla3vNF/crio-run/overlay-containers/edf925dba8d5ea65027af26d846db1acd2433e7a20741a25e177239d20246f77/userdata -p /tmp/tmp.yXXWla3vNF/crio-run/overlay-containers/edf925dba8d5ea65027af26d846db1acd2433e7a20741a25e177239d20246f77/userdata/pidfile -l /var/log/crio/pods/98c67b943e259da6b0362017d5b51d9415d20f2cab78bb9d9b50d96389f1dec6/edf925dba8d5ea65027af26d846db1acd2433e7a20741a25e177239d20246f77.log --exit-dir /var/run/crio/exits --socket-dir-path /var/run/crio]
# time="2018-03-06 16:13:50.966073626Z" level=debug msg="Received container pid: 61323"
# time="2018-03-06 16:13:51.054828800Z" level=debug msg="CreateContainerResponse: &CreateContainerResponse{ContainerId:edf925dba8d5ea65027af26d846db1acd2433e7a20741a25e177239d20246f77,}"
# edf925dba8d5ea65027af26d846db1acd2433e7a20741a25e177239d20246f77
# time="2018-03-06 16:13:51.114357885Z" level=debug msg="StartContainerRequest &StartContainerRequest{ContainerId:edf925dba8d5ea65027af26d846db1acd2433e7a20741a25e177239d20246f77,}"
# time="2018-03-06 16:13:51.356659154Z" level=debug msg="StartContainerResponse &StartContainerResponse{}"
# edf925dba8d5ea65027af26d846db1acd2433e7a20741a25e177239d20246f77
# time="2018-03-06 16:13:51.526612954Z" level=debug msg="ExecSyncRequest &ExecSyncRequest{ContainerId:edf925dba8d5ea65027af26d846db1acd2433e7a20741a25e177239d20246f77,Cmd:[false],Timeout:0,}"
# time="2018-03-06 16:13:51.755617336Z" level=info msg="Received container exit code: 1, message: "
# time="2018-03-06 16:13:51.755779637Z" level=debug msg="ExecSyncResponse: &ExecSyncResponse{Stdout:[],Stderr:[],ExitCode:1,}"
#
#
# Exit code: 1
# time="2018-03-06 16:13:51.829212512Z" level=debug msg="ListContainersRequest &ListContainersRequest{Filter:&ContainerFilter{Id:,State:nil,PodSandboxId:,LabelSelector:map[string]string{},},}"
# time="2018-03-06 16:13:51.829272513Z" level=debug msg="ListContainersResponse: &ListContainersResponse{Containers:[&Container{Id:edf925dba8d5ea65027af26d846db1acd2433e7a20741a25e177239d20246f77,PodSandboxId:98c67b943e259da6b0362017d5b51d9415d20f2cab78bb9d9b50d96389f1dec6,Metadata:&ContainerMetadata{Name:podsandbox1-redis,Attempt:0,},Image:&ImageSpec{Image:docker.io/library/redis:alpine,},ImageRef:,State:CONTAINER_RUNNING,CreatedAt:1520352830621787997,Labels:map[string]string{tier: backend,},Annotations:map[string]string{pod: podsandbox1,},}],}"
# time="2018-03-06 16:13:51.900269872Z" level=debug msg="StopContainerRequest &StopContainerRequest{ContainerId:edf925dba8d5ea65027af26d846db1acd2433e7a20741a25e177239d20246f77,Timeout:10,}"
# time="2018-03-06 16:13:52.155256123Z" level=debug msg="event: "/var/run/crio/exits/edf925dba8d5ea65027af26d846db1acd2433e7a20741a25e177239d20246f77.RB4DFZ": RENAME"
# time="2018-03-06 16:13:52.155285723Z" level=debug msg="event: "/var/run/crio/exits/edf925dba8d5ea65027af26d846db1acd2433e7a20741a25e177239d20246f77": CREATE"
# time="2018-03-06 16:13:52.155314523Z" level=debug msg="container or sandbox exited: edf925dba8d5ea65027af26d846db1acd2433e7a20741a25e177239d20246f77"
# time="2018-03-06 16:13:52.155332623Z" level=debug msg="container exited and found: edf925dba8d5ea65027af26d846db1acd2433e7a20741a25e177239d20246f77"
# time="2018-03-06 16:13:52.417210218Z" level=debug msg="unmounted container "edf925dba8d5ea65027af26d846db1acd2433e7a20741a25e177239d20246f77""
# time="2018-03-06 16:13:52.620590934Z" level=debug msg="StopContainerResponse edf925dba8d5ea65027af26d846db1acd2433e7a20741a25e177239d20246f77: &StopContainerResponse{}"
# edf925dba8d5ea65027af26d846db1acd2433e7a20741a25e177239d20246f77
# time="2018-03-06 16:13:52.676656597Z" level=debug msg="RemoveContainerRequest: &RemoveContainerRequest{ContainerId:edf925dba8d5ea65027af26d846db1acd2433e7a20741a25e177239d20246f77,}"
# time="2018-03-06 16:13:52.771342210Z" level=debug msg="event: "/var/run/crio/exits/edf925dba8d5ea65027af26d846db1acd2433e7a20741a25e177239d20246f77": REMOVE"
# time="2018-03-06 16:13:52.861766295Z" level=debug msg="RemoveContainerResponse: &RemoveContainerResponse{}"
# edf925dba8d5ea65027af26d846db1acd2433e7a20741a25e177239d20246f77
# time="2018-03-06 16:13:52.945280836Z" level=debug msg="ListPodSandboxRequest &ListPodSandboxRequest{Filter:&PodSandboxFilter{Id:,State:nil,LabelSelector:map[string]string{},},}"
# time="2018-03-06 16:13:52.945338636Z" level=debug msg="ListPodSandboxResponse &ListPodSandboxResponse{Items:[&PodSandbox{Id:98c67b943e259da6b0362017d5b51d9415d20f2cab78bb9d9b50d96389f1dec6,Metadata:&PodSandboxMetadata{Name:podsandbox1,Uid:redhat-test-crio,Namespace:redhat.test.crio,Attempt:1,},State:SANDBOX_READY,CreatedAt:1520352828718450678,Labels:map[string]string{group: test,io.kubernetes.container.name: POD,},Annotations:map[string]string{owner: hmeng,security.alpha.kubernetes.io/seccomp/pod: unconfined,},}],}"
# time="2018-03-06 16:13:53.026260260Z" level=debug msg="StopPodSandboxRequest &StopPodSandboxRequest{PodSandboxId:98c67b943e259da6b0362017d5b51d9415d20f2cab78bb9d9b50d96389f1dec6,}"
# time="2018-03-06 16:13:53.026312160Z" level=info msg="Got pod network {Name:podsandbox1 Namespace:redhat.test.crio ID:98c67b943e259da6b0362017d5b51d9415d20f2cab78bb9d9b50d96389f1dec6 NetNS:/var/run/netns/k8s_podsandbox1_redhat.test.crio_redhat-test-crio_1-af8f9e13 PortMappings:[]}"
# time="2018-03-06 16:13:53.026326460Z" level=info msg="About to del CNI network crionet (type=bridge)"
# time="2018-03-06 16:13:53.231967091Z" level=debug msg="event: "/var/run/crio/exits/98c67b943e259da6b0362017d5b51d9415d20f2cab78bb9d9b50d96389f1dec6.DPKEFZ": RENAME"
# time="2018-03-06 16:13:53.232009791Z" level=debug msg="event: "/var/run/crio/exits/98c67b943e259da6b0362017d5b51d9415d20f2cab78bb9d9b50d96389f1dec6": CREATE"
# time="2018-03-06 16:13:53.232022091Z" level=debug msg="container or sandbox exited: 98c67b943e259da6b0362017d5b51d9415d20f2cab78bb9d9b50d96389f1dec6"
# time="2018-03-06 16:13:53.232044592Z" level=debug msg="sandbox exited and found: 98c67b943e259da6b0362017d5b51d9415d20f2cab78bb9d9b50d96389f1dec6"
# time="2018-03-06 16:13:53.519855954Z" level=debug msg="unmounted container "98c67b943e259da6b0362017d5b51d9415d20f2cab78bb9d9b50d96389f1dec6""
# time="2018-03-06 16:13:53.519931355Z" level=debug msg="StopPodSandboxResponse 98c67b943e259da6b0362017d5b51d9415d20f2cab78bb9d9b50d96389f1dec6: &StopPodSandboxResponse{}"
# Stopped sandbox 98c67b943e259da6b0362017d5b51d9415d20f2cab78bb9d9b50d96389f1dec6
# time="2018-03-06 16:13:53.600254975Z" level=debug msg="RemovePodSandboxRequest &RemovePodSandboxRequest{PodSandboxId:98c67b943e259da6b0362017d5b51d9415d20f2cab78bb9d9b50d96389f1dec6,}"
# time="2018-03-06 16:13:53.752280659Z" level=debug msg="Failed to unmount 9cb33f2c25792900b1c9401c58b778b815ef140e36763bc832032662c018e26b overlay: /tmp/tmp.yXXWla3vNF/crio/overlay/9cb33f2c25792900b1c9401c58b778b815ef140e36763bc832032662c018e26b/merged - invalid argument"
# time="2018-03-06 16:13:53.811617343Z" level=debug msg="unmounted container "98c67b943e259da6b0362017d5b51d9415d20f2cab78bb9d9b50d96389f1dec6""
# time="2018-03-06 16:13:53.917139426Z" level=debug msg="RemovePodSandboxResponse &RemovePodSandboxResponse{}"
# Removed sandbox 98c67b943e259da6b0362017d5b51d9415d20f2cab78bb9d9b50d96389f1dec6
# time="2018-03-06 16:13:53.932980528Z" level=debug msg="Caught SIGTERM"
# time="2018-03-06 16:13:53.933198529Z" level=debug msg="closing exit monitor..."
# time="2018-03-06 16:13:53.933234130Z" level=debug msg="closed exit monitor"
# time="2018-03-06 16:13:53.933248830Z" level=debug msg="closed main server"
# 0
# rm: cannot remove '/tmp/tmp.yXXWla3vNF/crio/overlay': Device or resource busy
not ok 20 ctr execsync std{out,err}
# (from function `cleanup_test' in file helpers.bash, line 357,
#  from function `teardown' in test file ctr.bats, line 6)
#   `cleanup_test' failed
# 0
# time="2018-03-06 16:14:05.880025735Z" level=debug msg="backingFs=extfs,  projectQuotaSupported=false"
# time="2018-03-06 16:14:05.880088936Z" level=info msg="[graphdriver] using prior storage driver: overlay"
# time="2018-03-06 16:14:05.882313450Z" level=info msg="CNI network crionet (type=bridge) is used from /tmp/tmp.vbNtQbA1gH/cni/net.d/10-crio.conf"
# time="2018-03-06 16:14:05.882582352Z" level=info msg="CNI network crionet (type=bridge) is used from /tmp/tmp.vbNtQbA1gH/cni/net.d/10-crio.conf"
# time="2018-03-06 16:14:05.907873815Z" level=debug msg="seccomp status: true"
# time="2018-03-06 16:14:05.908513820Z" level=debug msg="Golang's threads limit set to 115110"
# time="2018-03-06 16:14:05.912094743Z" level=debug msg="sandboxes: []"
# time="2018-03-06 16:14:05.912257644Z" level=error msg="Failed to start streaming server: listen tcp 10.1.10.4:10010: bind: address already in use"
# time="2018-03-06 16:14:05.946688067Z" level=debug msg="closed stream server"
# time="2018-03-06 16:14:05.990816452Z" level=debug msg="ImageStatusRequest: &ImageStatusRequest{Image:&ImageSpec{Image:redis:alpine,},Verbose:false,}"
# time="2018-03-06 16:14:05.990933553Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.vbNtQbA1gH/crio+/tmp/tmp.vbNtQbA1gH/crio-run]docker.io/library/redis:alpine""
# time="2018-03-06 16:14:05.991719158Z" level=debug msg="[graphdriver] trying provided driver "overlay""
# time="2018-03-06 16:14:06.014061702Z" level=debug msg="backingFs=extfs,  projectQuotaSupported=false"
# time="2018-03-06 16:14:06.014809507Z" level=debug msg="ImageStatusResponse: &ImageStatusResponse{Image:&Image{Id:736ae8e9196867b8db0035a747c70fd684aefddabac02b54ea2be88d3d04fc46,RepoTags:[docker.io/library/redis:alpine redis:alpine],RepoDigests:[],Size_:27082854,Uid:nil,Username:,},Info:map[string]string{},}"
# time="2018-03-06 16:14:06.090601498Z" level=debug msg="ImageStatusRequest: &ImageStatusRequest{Image:&ImageSpec{Image:redis:alpine,},Verbose:false,}"
# time="2018-03-06 16:14:06.090699398Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.vbNtQbA1gH/crio+/tmp/tmp.vbNtQbA1gH/crio-run]docker.io/library/redis:alpine""
# time="2018-03-06 16:14:06.091135801Z" level=debug msg="ImageStatusResponse: &ImageStatusResponse{Image:&Image{Id:736ae8e9196867b8db0035a747c70fd684aefddabac02b54ea2be88d3d04fc46,RepoTags:[docker.io/library/redis:alpine redis:alpine],RepoDigests:[],Size_:27082854,Uid:nil,Username:,},Info:map[string]string{},}"
# time="2018-03-06 16:14:06.148218070Z" level=debug msg="ImageStatusRequest: &ImageStatusRequest{Image:&ImageSpec{Image:mrunalp/oom,},Verbose:false,}"
# time="2018-03-06 16:14:06.148286671Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.vbNtQbA1gH/crio+/tmp/tmp.vbNtQbA1gH/crio-run]docker.io/mrunalp/oom:latest""
# time="2018-03-06 16:14:06.148564973Z" level=debug msg="ImageStatusResponse: &ImageStatusResponse{Image:&Image{Id:2ee60236bc3da9a5090fa282138fc2666a3dc8939f7d6cd4277462fcc7921b40,RepoTags:[docker.io/mrunalp/oom:latest mrunalp/oom docker.io/library/mrunalp/oom],RepoDigests:[],Size_:5719481,Uid:nil,Username:,},Info:map[string]string{},}"
# time="2018-03-06 16:14:06.208633061Z" level=debug msg="ImageStatusRequest: &ImageStatusRequest{Image:&ImageSpec{Image:redis@sha256:03789f402b2ecfb98184bf128d180f398f81c63364948ff1454583b02442f73b,},Verbose:false,}"
# time="2018-03-06 16:14:06.208741862Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.vbNtQbA1gH/crio+/tmp/tmp.vbNtQbA1gH/crio-run]docker.io/library/redis:latest@03789f402b2ecfb98184bf128d180f398f81c63364948ff1454583b02442f73b""
# time="2018-03-06 16:14:06.209279765Z" level=debug msg="ImageStatusResponse: &ImageStatusResponse{Image:&Image{Id:03789f402b2ecfb98184bf128d180f398f81c63364948ff1454583b02442f73b,RepoTags:[docker.io/library/redis:latest redis@sha256:03789f402b2ecfb98184bf128d180f398f81c63364948ff1454583b02442f73b docker.io/library/redis@sha256:03789f402b2ecfb98184bf128d180f398f81c63364948ff1454583b02442f73b],RepoDigests:[],Size_:19826844,Uid:nil,Username:,},Info:map[string]string{},}"
# time="2018-03-06 16:14:06.263877319Z" level=debug msg="ImageStatusRequest: &ImageStatusRequest{Image:&ImageSpec{Image:runcom/stderr-test,},Verbose:false,}"
# time="2018-03-06 16:14:06.263987419Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.vbNtQbA1gH/crio+/tmp/tmp.vbNtQbA1gH/crio-run]docker.io/runcom/stderr-test:latest""
# time="2018-03-06 16:14:06.264261121Z" level=debug msg="ImageStatusResponse: &ImageStatusResponse{Image:&Image{Id:e0691003e5af3453313b4df7b97a0ffd9664fc354e7c08dd71b6b3f27e5279af,RepoTags:[docker.io/runcom/stderr-test:latest runcom/stderr-test:latest],RepoDigests:[],Size_:4906035,Uid:nil,Username:,},Info:map[string]string{},}"
# time="2018-03-06 16:14:06.325605318Z" level=debug msg="ImageStatusRequest: &ImageStatusRequest{Image:&ImageSpec{Image:runcom/stderr-test,},Verbose:false,}"
# time="2018-03-06 16:14:06.325674418Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.vbNtQbA1gH/crio+/tmp/tmp.vbNtQbA1gH/crio-run]docker.io/runcom/stderr-test:latest""
# time="2018-03-06 16:14:06.326015121Z" level=debug msg="ImageStatusResponse: &ImageStatusResponse{Image:&Image{Id:e0691003e5af3453313b4df7b97a0ffd9664fc354e7c08dd71b6b3f27e5279af,RepoTags:[docker.io/runcom/stderr-test:latest runcom/stderr-test:latest],RepoDigests:[],Size_:4906035,Uid:nil,Username:,},Info:map[string]string{},}"
# time="2018-03-06 16:14:06.374890137Z" level=debug msg="ImageStatusRequest: &ImageStatusRequest{Image:&ImageSpec{Image:busybox,},Verbose:false,}"
# time="2018-03-06 16:14:06.374966037Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.vbNtQbA1gH/crio+/tmp/tmp.vbNtQbA1gH/crio-run]docker.io/library/busybox:latest""
# time="2018-03-06 16:14:06.375185939Z" level=debug msg="ImageStatusResponse: &ImageStatusResponse{Image:&Image{Id:30f2ad263ae7414755eb491e03dddb4bc6dcb589001d07114c284d5ffcb45925,RepoTags:[docker.io/library/busybox:latest busybox:latest],RepoDigests:[],Size_:1148393,Uid:nil,Username:,},Info:map[string]string{},}"
# time="2018-03-06 16:14:06.437111139Z" level=debug msg="ImageStatusRequest: &ImageStatusRequest{Image:&ImageSpec{Image:busybox,},Verbose:false,}"
# time="2018-03-06 16:14:06.437151240Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.vbNtQbA1gH/crio+/tmp/tmp.vbNtQbA1gH/crio-run]docker.io/library/busybox:latest""
# time="2018-03-06 16:14:06.437323541Z" level=debug msg="ImageStatusResponse: &ImageStatusResponse{Image:&Image{Id:30f2ad263ae7414755eb491e03dddb4bc6dcb589001d07114c284d5ffcb45925,RepoTags:[docker.io/library/busybox:latest busybox:latest],RepoDigests:[],Size_:1148393,Uid:nil,Username:,},Info:map[string]string{},}"
# time="2018-03-06 16:14:06.490715686Z" level=debug msg="ImageStatusRequest: &ImageStatusRequest{Image:&ImageSpec{Image:mrunalp/image-volume-test,},Verbose:false,}"
# time="2018-03-06 16:14:06.490774787Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.vbNtQbA1gH/crio+/tmp/tmp.vbNtQbA1gH/crio-run]docker.io/mrunalp/image-volume-test:latest""
# time="2018-03-06 16:14:06.491053688Z" level=debug msg="ImageStatusResponse: &ImageStatusResponse{Image:&Image{Id:70c41864bedaa27a9f4de437e773caf46378338c2c0e15ea1872b71d35314d12,RepoTags:[docker.io/mrunalp/image-volume-test:latest mrunalp/image-volume-test docker.io/library/mrunalp/image-volume-test],RepoDigests:[],Size_:1095628,Uid:nil,Username:,},Info:map[string]string{},}"
# time="2018-03-06 16:14:06.553950495Z" level=debug msg="ImageStatusRequest: &ImageStatusRequest{Image:&ImageSpec{Image:mrunalp/image-volume-test,},Verbose:false,}"
# time="2018-03-06 16:14:06.553999096Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.vbNtQbA1gH/crio+/tmp/tmp.vbNtQbA1gH/crio-run]docker.io/mrunalp/image-volume-test:latest""
# time="2018-03-06 16:14:06.554214997Z" level=debug msg="ImageStatusResponse: &ImageStatusResponse{Image:&Image{Id:70c41864bedaa27a9f4de437e773caf46378338c2c0e15ea1872b71d35314d12,RepoTags:[docker.io/mrunalp/image-volume-test:latest mrunalp/image-volume-test docker.io/library/mrunalp/image-volume-test],RepoDigests:[],Size_:1095628,Uid:nil,Username:,},Info:map[string]string{},}"
# time="2018-03-06 16:14:06.609127952Z" level=debug msg="RunPodSandboxRequest &RunPodSandboxRequest{Config:&PodSandboxConfig{Metadata:&PodSandboxMetadata{Name:podsandbox1,Uid:redhat-test-crio,Namespace:redhat.test.crio,Attempt:1,},Hostname:crictl_host,LogDirectory:,DnsConfig:&DNSConfig{Servers:[],Searches:[8.8.8.8],Options:[],},PortMappings:[],Labels:map[string]string{group: test,},Annotations:map[string]string{owner: hmeng,security.alpha.kubernetes.io/seccomp/pod: unconfined,},Linux:&LinuxPodSandboxConfig{CgroupParent:/Burstable/pod_123-456,SecurityContext:&LinuxSandboxSecurityContext{NamespaceOptions:&NamespaceOption{HostNetwork:false,HostPid:false,HostIpc:false,},SelinuxOptions:&SELinuxOption{User:system_u,Role:system_r,Type:svirt_lxc_net_t,Level:s0:c4,c5,},RunAsUser:nil,ReadonlyRootfs:false,SupplementalGroups:[],Privileged:false,SeccompProfilePath:,},Sysctls:map[string]string{},},},}"
# time="2018-03-06 16:14:06.609193753Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.vbNtQbA1gH/crio+/tmp/tmp.vbNtQbA1gH/crio-run]docker.io/kubernetes/pause:latest""
# time="2018-03-06 16:14:06.609643556Z" level=debug msg="exporting opaque data as blob "sha256:c713bfa1b66381820184edb919bad71ac5aa56a93a1f2fc2c4c7f4d69effe351""
# time="2018-03-06 16:14:06.709770403Z" level=debug msg="created pod sandbox "be31d1264b6607fb13c2cff4609707618a735318f7a8f0c010459c13c06129c0""
# time="2018-03-06 16:14:06.771706404Z" level=debug msg="pod sandbox "be31d1264b6607fb13c2cff4609707618a735318f7a8f0c010459c13c06129c0" has work directory "/tmp/tmp.vbNtQbA1gH/crio/overlay-containers/be31d1264b6607fb13c2cff4609707618a735318f7a8f0c010459c13c06129c0/userdata""
# time="2018-03-06 16:14:06.771796705Z" level=debug msg="pod sandbox "be31d1264b6607fb13c2cff4609707618a735318f7a8f0c010459c13c06129c0" has run directory "/tmp/tmp.vbNtQbA1gH/crio-run/overlay-containers/be31d1264b6607fb13c2cff4609707618a735318f7a8f0c010459c13c06129c0/userdata""
# time="2018-03-06 16:14:06.911222607Z" level=debug msg="mounted container "be31d1264b6607fb13c2cff4609707618a735318f7a8f0c010459c13c06129c0" at "/tmp/tmp.vbNtQbA1gH/crio/overlay/4aa4dac98f46a00acf0fce5e086be178f56d046635e5c3c870bdbf26adc9a2bf/merged""
# time="2018-03-06 16:14:06.911298507Z" level=info msg="Got pod network {Name:podsandbox1 Namespace:redhat.test.crio ID:be31d1264b6607fb13c2cff4609707618a735318f7a8f0c010459c13c06129c0 NetNS:/var/run/netns/k8s_podsandbox1_redhat.test.crio_redhat-test-crio_1-19d48373 PortMappings:[]}"
# time="2018-03-06 16:14:06.911314507Z" level=info msg="About to add CNI network cni-loopback (type=loopback)"
# time="2018-03-06 16:14:06.915075532Z" level=info msg="Got pod network {Name:podsandbox1 Namespace:redhat.test.crio ID:be31d1264b6607fb13c2cff4609707618a735318f7a8f0c010459c13c06129c0 NetNS:/var/run/netns/k8s_podsandbox1_redhat.test.crio_redhat-test-crio_1-19d48373 PortMappings:[]}"
# time="2018-03-06 16:14:06.915092732Z" level=info msg="About to add CNI network crionet (type=bridge)"
# time="2018-03-06 16:14:06.995966455Z" level=debug msg="running conmon: /root/go/src/github.com/kubernetes-incubator/cri-o/bin/conmon" args=[-c be31d1264b6607fb13c2cff4609707618a735318f7a8f0c010459c13c06129c0 -u be31d1264b6607fb13c2cff4609707618a735318f7a8f0c010459c13c06129c0 -r /usr/local/bin/cc-runtime -b /tmp/tmp.vbNtQbA1gH/crio-run/overlay-containers/be31d1264b6607fb13c2cff4609707618a735318f7a8f0c010459c13c06129c0/userdata -p /tmp/tmp.vbNtQbA1gH/crio-run/overlay-containers/be31d1264b6607fb13c2cff4609707618a735318f7a8f0c010459c13c06129c0/userdata/pidfile -l /var/log/crio/pods/be31d1264b6607fb13c2cff4609707618a735318f7a8f0c010459c13c06129c0/be31d1264b6607fb13c2cff4609707618a735318f7a8f0c010459c13c06129c0.log --exit-dir /var/run/crio/exits --socket-dir-path /var/run/crio]
# time="2018-03-06 16:14:08.574208465Z" level=debug msg="Received container pid: 62721"
# time="2018-03-06 16:14:08.896006147Z" level=debug msg="RunPodSandboxResponse: &RunPodSandboxResponse{PodSandboxId:be31d1264b6607fb13c2cff4609707618a735318f7a8f0c010459c13c06129c0,}"
# be31d1264b6607fb13c2cff4609707618a735318f7a8f0c010459c13c06129c0
# time="2018-03-06 16:14:08.960080762Z" level=debug msg="CreateContainerRequest &CreateContainerRequest{PodSandboxId:be31d1264b6607fb13c2cff4609707618a735318f7a8f0c010459c13c06129c0,Config:&ContainerConfig{Metadata:&ContainerMetadata{Name:podsandbox1-redis,Attempt:0,},Image:&ImageSpec{Image:redis:alpine,},Command:[],Args:[docker-entrypoint.sh redis-server],WorkingDir:/data,Envs:[&KeyValue{Key:PATH,Value:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin,} &KeyValue{Key:TERM,Value:xterm,} &KeyValue{Key:REDIS_VERSION,Value:3.2.3,} &KeyValue{Key:REDIS_DOWNLOAD_URL,Value:http://download.redis.io/releases/redis-3.2.3.tar.gz,} &KeyValue{Key:REDIS_DOWNLOAD_SHA1,Value:92d6d93ef2efc91e595c8bf578bf72baff397507,}],Mounts:[],Devices:[],Labels:map[string]string{tier: backend,},Annotations:map[string]string{pod: podsandbox1,},LogPath:,Stdin:false,StdinOnce:false,Tty:false,Linux:&LinuxContainerConfig{Resources:&LinuxContainerResources{CpuPeriod:10000,CpuQuota:20000,CpuShares:512,MemoryLimitInBytes:209715200,OomScoreAdj:30,CpusetCpus:,CpusetMems:,},SecurityContext:&LinuxContainerSecurityContext{Capabilities:&Capability{AddCapabilities:[sys_admin],DropCapabilities:[],},Privileged:false,NamespaceOptions:nil,SelinuxOptions:nil,RunAsUser:nil,RunAsUsername:,ReadonlyRootfs:false,SupplementalGroups:[],ApparmorProfile:,SeccompProfilePath:,NoNewPrivs:false,},},},SandboxConfig:&PodSandboxConfig{Metadata:&PodSandboxMetadata{Name:podsandbox1,Uid:redhat-test-crio,Namespace:redhat.test.crio,Attempt:1,},Hostname:crictl_host,LogDirectory:,DnsConfig:&DNSConfig{Servers:[],Searches:[8.8.8.8],Options:[],},PortMappings:[],Labels:map[string]string{group: test,},Annotations:map[string]string{owner: hmeng,security.alpha.kubernetes.io/seccomp/pod: unconfined,},Linux:&LinuxPodSandboxConfig{CgroupParent:/Burstable/pod_123-456,SecurityContext:&LinuxSandboxSecurityContext{NamespaceOptions:&NamespaceOption{HostNetwork:false,HostPid:false,HostIpc:false,},SelinuxOptions:&SELinuxOption{User:system_u,Role:system_r,Type:svirt_lxc_net_t,Level:s0:c4,c5,},RunAsUser:nil,ReadonlyRootfs:false,SupplementalGroups:[],Privileged:false,SeccompProfilePath:,},Sysctls:map[string]string{},},},}"
# time="2018-03-06 16:14:08.960196962Z" level=debug msg="setting container's log_path" ctr.logfile= log_path="/var/log/crio/pods/be31d1264b6607fb13c2cff4609707618a735318f7a8f0c010459c13c06129c0/3c941c142f95cfbf5dc6c1a87de5f91837d1f450ee2ad775501a738394b8568e.log" sbox.logdir="/var/log/crio/pods/be31d1264b6607fb13c2cff4609707618a735318f7a8f0c010459c13c06129c0"
# time="2018-03-06 16:14:08.960307963Z" level=debug msg="pod container state &{State:{Version:1.0.1 ID:be31d1264b6607fb13c2cff4609707618a735318f7a8f0c010459c13c06129c0 Status:running Pid:62721 Bundle:/tmp/tmp.vbNtQbA1gH/crio-run/overlay-containers/be31d1264b6607fb13c2cff4609707618a735318f7a8f0c010459c13c06129c0/userdata Annotations:map[com.github.containers.virtcontainers.pkg.oci.config:{"ociVersion":"1.0.0","root":{"path":"/tmp/tmp.vbNtQbA1gH/crio/overlay/4aa4dac98f46a00acf0fce5e086be178f56d046635e5c3c870bdbf26adc9a2bf/merged","readonly":true},"hostname":"crictl_host","mounts":[{"destination":"/proc","type":"proc","source":"proc"},{"destination":"/dev","type":"tmpfs","source":"tmpfs","options":["nosuid","strictatime","mode=755","size=65536k"]},{"destination":"/dev/pts","type":"devpts","source":"devpts","options":["nosuid","noexec","newinstance","ptmxmode=0666","mode=0620","gid=5"]},{"destination":"/dev/shm","type":"tmpfs","source":"shm","options":["nosuid","noexec","nodev","mode=1777","size=65536k"]},{"destination":"/dev/mqueue","type":"mqueue","source":"mqueue","options":["nosuid","noexec","nodev"]},{"destination":"/sys","type":"sysfs","source":"sysfs","options":["nosuid","noexec","nodev","ro"]},{"destination":"/etc/resolv.conf","type":"bind","source":"/tmp/tmp.vbNtQbA1gH/crio-run/overlay-containers/be31d1264b6607fb13c2cff4609707618a735318f7a8f0c010459c13c06129c0/userdata/resolv.conf","options":["ro","bind"]},{"destination":"/etc/hostname","type":"bind","source":"/tmp/tmp.vbNtQbA1gH/crio-run/overlay-containers/be31d1264b6607fb13c2cff4609707618a735318f7a8f0c010459c13c06129c0/userdata/hostname","options":["ro","bind"]}],"annotations":{"group":"test","io.kubernetes.container.name":"POD","io.kubernetes.cri-o.Annotations":"{\"owner\":\"hmeng\",\"security.alpha.kubernetes.io/seccomp/pod\":\"unconfined\"}","io.kubernetes.cri-o.ContainerID":"be31d1264b6607fb13c2cff4609707618a735318f7a8f0c010459c13c06129c0","io.kubernetes.cri-o.ContainerName":"k8s_POD_podsandbox1_redhat.test.crio_redhat-test-crio_1","io.kubernetes.cri-o.ContainerType":"sandbox","io.kubernetes.cri-o.Created":"2018-03-06T16:14:06.818814609Z","io.kubernetes.cri-o.HostName":"crictl_host","io.kubernetes.cri-o.HostnamePath":"/tmp/tmp.vbNtQbA1gH/crio-run/overlay-containers/be31d1264b6607fb13c2cff4609707618a735318f7a8f0c010459c13c06129c0/userdata/hostname","io.kubernetes.cri-o.IP":"10.88.0.222","io.kubernetes.cri-o.KubeName":"podsandbox1","io.kubernetes.cri-o.Labels":"{\"group\":\"test\",\"io.kubernetes.container.name\":\"POD\"}","io.kubernetes.cri-o.LogPath":"/var/log/crio/pods/be31d1264b6607fb13c2cff4609707618a735318f7a8f0c010459c13c06129c0/be31d1264b6607fb13c2cff4609707618a735318f7a8f0c010459c13c06129c0.log","io.kubernetes.cri-o.Metadata":"{\"name\":\"podsandbox1\",\"uid\":\"redhat-test-crio\",\"namespace\":\"redhat.test.crio\",\"attempt\":1}","io.kubernetes.cri-o.MountPoint":"/tmp/tmp.vbNtQbA1gH/crio/overlay/4aa4dac98f46a00acf0fce5e086be178f56d046635e5c3c870bdbf26adc9a2bf/merged","io.kubernetes.cri-o.Name":"k8s_podsandbox1_redhat.test.crio_redhat-test-crio_1","io.kubernetes.cri-o.PrivilegedRuntime":"false","io.kubernetes.cri-o.ResolvPath":"/tmp/tmp.vbNtQbA1gH/crio-run/overlay-containers/be31d1264b6607fb13c2cff4609707618a735318f7a8f0c010459c13c06129c0/userdata/resolv.conf","io.kubernetes.cri-o.SandboxID":"be31d1264b6607fb13c2cff4609707618a735318f7a8f0c010459c13c06129c0","io.kubernetes.cri-o.SeccompProfilePath":"","io.kubernetes.cri-o.ShmPath":"/tmp/tmp.vbNtQbA1gH/crio-run/overlay-containers/be31d1264b6607fb13c2cff4609707618a735318f7a8f0c010459c13c06129c0/userdata/shm","io.kubernetes.cri-o.TrustedSandbox":"true","owner":"hmeng","security.alpha.kubernetes.io/seccomp/pod":"unconfined"},"linux":{"resources":{"devices":[{"allow":false,"access":"rwm"}],"cpu":{"shares":2}},"cgroupsPath":"/Burstable/pod_123-456/crio-be31d1264b6607fb13c2cff4609707618a735318f7a8f0c010459c13c06129c0","namespaces":[{"type":"pid"},{"type":"network","path":"/var/run/netns/k8s_podsandbox1_redhat.test.crio_redhat-test-crio_1-19d48373"},{"type":"ipc"},{"type":"uts"},{"type":"mount"}]},"process":{"user":{"uid":0,"gid":0},"args":["/pause"],"env":["PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin","TERM=xterm"],"cwd":"/","rlimits":[{"type":"RLIMIT_NOFILE","hard":1024,"soft":1024}],"oomScoreAdj":-998,"capabilities":{"ambient":["CAP_CHOWN","CAP_DAC_OVERRIDE","CAP_FSETID","CAP_FOWNER","CAP_MKNOD","CAP_NET_RAW","CAP_SETGID","CAP_SETUID","CAP_SETFCAP","CAP_SETPCAP","CAP_NET_BIND_SERVICE","CAP_SYS_CHROOT","CAP_KILL","CAP_AUDIT_WRITE"],"bounding":["CAP_CHOWN","CAP_DAC_OVERRIDE","CAP_FSETID","CAP_FOWNER","CAP_MKNOD","CAP_NET_RAW","CAP_SETGID","CAP_SETUID","CAP_SETFCAP","CAP_SETPCAP","CAP_NET_BIND_SERVICE","CAP_SYS_CHROOT","CAP_KILL","CAP_AUDIT_WRITE"],"effective":["CAP_CHOWN","CAP_DAC_OVERRIDE","CAP_FSETID","CAP_FOWNER","CAP_MKNOD","CAP_NET_RAW","CAP_SETGID","CAP_SETUID","CAP_SETFCAP","CAP_SETPCAP","CAP_NET_BIND_SERVICE","CAP_SYS_CHROOT","CAP_KILL","CAP_AUDIT_WRITE"],"inheritable":["CAP_CHOWN","CAP_DAC_OVERRIDE","CAP_FSETID","CAP_FOWNER","CAP_MKNOD","CAP_NET_RAW","CAP_SETGID","CAP_SETUID","CAP_SETFCAP","CAP_SETPCAP","CAP_NET_BIND_SERVICE","CAP_SYS_CHROOT","CAP_KILL","CAP_AUDIT_WRITE"],"permitted":["CAP_CHOWN","CAP_DAC_OVERRIDE","CAP_FSETID","CAP_FOWNER","CAP_MKNOD","CAP_NET_RAW","CAP_SETGID","CAP_SETUID","CAP_SETFCAP","CAP_SETPCAP","CAP_NET_BIND_SERVICE","CAP_SYS_CHROOT","CAP_KILL","CAP_AUDIT_WRITE"]}}} com.github.containers.virtcontainers.pkg.oci.container_type:pod_sandbox com.github.containers.virtcontainers.pkg.oci.bundle_path:/tmp/tmp.vbNtQbA1gH/crio-run/overlay-containers/be31d1264b6607fb13c2cff4609707618a735318f7a8f0c010459c13c06129c0/userdata]} Created:2018-03-06 16:14:06.818814609 +0000 UTC m=+1.049326690 Started:2018-03-06 16:14:08.785416532 +0000 UTC m=+3.015928613 Finished:0001-01-01 00:00:00 +0000 UTC ExitCode:0 OOMKilled:false Error:}"
# time="2018-03-06 16:14:08.960377563Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.vbNtQbA1gH/crio+/tmp/tmp.vbNtQbA1gH/crio-run]docker.io/library/redis:alpine""
# time="2018-03-06 16:14:08.960979567Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.vbNtQbA1gH/crio+/tmp/tmp.vbNtQbA1gH/crio-run]docker.io/library/redis:alpine""
# time="2018-03-06 16:14:08.961310870Z" level=debug msg="exporting opaque data as blob "sha256:d3117424aaee14ab2b0edb68d3e3dcc1785b2e243b06bd6322f299284c640465""
# time="2018-03-06 16:14:09.060526211Z" level=debug msg="created container "3c941c142f95cfbf5dc6c1a87de5f91837d1f450ee2ad775501a738394b8568e""
# time="2018-03-06 16:14:09.122858915Z" level=debug msg="container "3c941c142f95cfbf5dc6c1a87de5f91837d1f450ee2ad775501a738394b8568e" has work directory "/tmp/tmp.vbNtQbA1gH/crio/overlay-containers/3c941c142f95cfbf5dc6c1a87de5f91837d1f450ee2ad775501a738394b8568e/userdata""
# time="2018-03-06 16:14:09.122933715Z" level=debug msg="container "3c941c142f95cfbf5dc6c1a87de5f91837d1f450ee2ad775501a738394b8568e" has run directory "/tmp/tmp.vbNtQbA1gH/crio-run/overlay-containers/3c941c142f95cfbf5dc6c1a87de5f91837d1f450ee2ad775501a738394b8568e/userdata""
# time="2018-03-06 16:14:09.211733290Z" level=debug msg="mounted container "3c941c142f95cfbf5dc6c1a87de5f91837d1f450ee2ad775501a738394b8568e" at "/tmp/tmp.vbNtQbA1gH/crio/overlay/231458a82390625ce9c1a5469fdb391d9cbc4a533e112daa993779363403c640/merged""
# time="2018-03-06 16:14:09.211815490Z" level=debug msg="OCI process args [docker-entrypoint.sh docker-entrypoint.sh redis-server]"
# time="2018-03-06 16:14:09.212048092Z" level=debug msg="CONTAINER USER: "
# time="2018-03-06 16:14:09.212202193Z" level=debug msg="UID: 0, GID: 0, Groups: [0 1 2 3 4 6 10 11 20 26 27]"
# time="2018-03-06 16:14:09.212893797Z" level=debug msg="running conmon: /root/go/src/github.com/kubernetes-incubator/cri-o/bin/conmon" args=[-c 3c941c142f95cfbf5dc6c1a87de5f91837d1f450ee2ad775501a738394b8568e -u 3c941c142f95cfbf5dc6c1a87de5f91837d1f450ee2ad775501a738394b8568e -r /usr/local/bin/cc-runtime -b /tmp/tmp.vbNtQbA1gH/crio-run/overlay-containers/3c941c142f95cfbf5dc6c1a87de5f91837d1f450ee2ad775501a738394b8568e/userdata -p /tmp/tmp.vbNtQbA1gH/crio-run/overlay-containers/3c941c142f95cfbf5dc6c1a87de5f91837d1f450ee2ad775501a738394b8568e/userdata/pidfile -l /var/log/crio/pods/be31d1264b6607fb13c2cff4609707618a735318f7a8f0c010459c13c06129c0/3c941c142f95cfbf5dc6c1a87de5f91837d1f450ee2ad775501a738394b8568e.log --exit-dir /var/run/crio/exits --socket-dir-path /var/run/crio]
# time="2018-03-06 16:14:09.332584871Z" level=debug msg="Received container pid: 62790"
# time="2018-03-06 16:14:09.440919472Z" level=debug msg="CreateContainerResponse: &CreateContainerResponse{ContainerId:3c941c142f95cfbf5dc6c1a87de5f91837d1f450ee2ad775501a738394b8568e,}"
# 3c941c142f95cfbf5dc6c1a87de5f91837d1f450ee2ad775501a738394b8568e
# time="2018-03-06 16:14:09.501136062Z" level=debug msg="StartContainerRequest &StartContainerRequest{ContainerId:3c941c142f95cfbf5dc6c1a87de5f91837d1f450ee2ad775501a738394b8568e,}"
# time="2018-03-06 16:14:09.743481329Z" level=debug msg="StartContainerResponse &StartContainerResponse{}"
# 3c941c142f95cfbf5dc6c1a87de5f91837d1f450ee2ad775501a738394b8568e
# time="2018-03-06 16:14:09.943134521Z" level=debug msg="ExecSyncRequest &ExecSyncRequest{ContainerId:3c941c142f95cfbf5dc6c1a87de5f91837d1f450ee2ad775501a738394b8568e,Cmd:[echo hello0 stdout],Timeout:0,}"
# time="2018-03-06 16:14:10.156226600Z" level=info msg="Received container exit code: 0, message: "
# time="2018-03-06 16:14:10.156354900Z" level=debug msg="ExecSyncResponse: &ExecSyncResponse{Stdout:[104 101 108 108 111 48 32 115 116 100 111 117 116 10],Stderr:[],ExitCode:0,}"
# hello0 stdout
# time="2018-03-06 16:14:10.237480325Z" level=debug msg="CreateContainerRequest &CreateContainerRequest{PodSandboxId:be31d1264b6607fb13c2cff4609707618a735318f7a8f0c010459c13c06129c0,Config:&ContainerConfig{Metadata:&ContainerMetadata{Name:container1,Attempt:1,},Image:&ImageSpec{Image:runcom/stderr-test,},Command:[/bin/sleep 600],Args:[],WorkingDir:/,Envs:[&KeyValue{Key:PATH,Value:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin,} &KeyValue{Key:TERM,Value:xterm,} &KeyValue{Key:TESTDIR,Value:test/dir1,} &KeyValue{Key:TESTFILE,Value:test/file1,}],Mounts:[],Devices:[],Labels:map[string]string{batch: no,type: small,},Annotations:map[string]string{daemon: crio,owner: dragon,},LogPath:,Stdin:false,StdinOnce:false,Tty:false,Linux:&LinuxContainerConfig{Resources:&LinuxContainerResources{CpuPeriod:10000,CpuQuota:20000,CpuShares:512,MemoryLimitInBytes:0,OomScoreAdj:30,CpusetCpus:,CpusetMems:,},SecurityContext:&LinuxContainerSecurityContext{Capabilities:&Capability{AddCapabilities:[setuid setgid],DropCapabilities:[],},Privileged:false,NamespaceOptions:nil,SelinuxOptions:&SELinuxOption{User:system_u,Role:system_r,Type:svirt_lxc_net_t,Level:s0:c4,c5,},RunAsUser:nil,RunAsUsername:,ReadonlyRootfs:false,SupplementalGroups:[],ApparmorProfile:,SeccompProfilePath:,NoNewPrivs:false,},},},SandboxConfig:&PodSandboxConfig{Metadata:&PodSandboxMetadata{Name:podsandbox1,Uid:redhat-test-crio,Namespace:redhat.test.crio,Attempt:1,},Hostname:crictl_host,LogDirectory:,DnsConfig:&DNSConfig{Servers:[],Searches:[8.8.8.8],Options:[],},PortMappings:[],Labels:map[string]string{group: test,},Annotations:map[string]string{owner: hmeng,security.alpha.kubernetes.io/seccomp/pod: unconfined,},Linux:&LinuxPodSandboxConfig{CgroupParent:/Burstable/pod_123-456,SecurityContext:&LinuxSandboxSecurityContext{NamespaceOptions:&NamespaceOption{HostNetwork:false,HostPid:false,HostIpc:false,},SelinuxOptions:&SELinuxOption{User:system_u,Role:system_r,Type:svirt_lxc_net_t,Level:s0:c4,c5,},RunAsUser:nil,ReadonlyRootfs:false,SupplementalGroups:[],Privileged:false,SeccompProfilePath:,},Sysctls:map[string]string{},},},}"
# time="2018-03-06 16:14:10.237691527Z" level=debug msg="setting container's log_path" ctr.logfile= log_path="/var/log/crio/pods/be31d1264b6607fb13c2cff4609707618a735318f7a8f0c010459c13c06129c0/5ef552eba875831dc2c4932bf7da624248b4c36b04d71ddfa31ed44eb6a7b4d4.log" sbox.logdir="/var/log/crio/pods/be31d1264b6607fb13c2cff4609707618a735318f7a8f0c010459c13c06129c0"
# time="2018-03-06 16:14:10.237818127Z" level=debug msg="pod container state &{State:{Version:1.0.1 ID:be31d1264b6607fb13c2cff4609707618a735318f7a8f0c010459c13c06129c0 Status:running Pid:62721 Bundle:/tmp/tmp.vbNtQbA1gH/crio-run/overlay-containers/be31d1264b6607fb13c2cff4609707618a735318f7a8f0c010459c13c06129c0/userdata Annotations:map[com.github.containers.virtcontainers.pkg.oci.bundle_path:/tmp/tmp.vbNtQbA1gH/crio-run/overlay-containers/be31d1264b6607fb13c2cff4609707618a735318f7a8f0c010459c13c06129c0/userdata com.github.containers.virtcontainers.pkg.oci.config:{"ociVersion":"1.0.0","root":{"path":"/tmp/tmp.vbNtQbA1gH/crio/overlay/4aa4dac98f46a00acf0fce5e086be178f56d046635e5c3c870bdbf26adc9a2bf/merged","readonly":true},"hostname":"crictl_host","mounts":[{"destination":"/proc","type":"proc","source":"proc"},{"destination":"/dev","type":"tmpfs","source":"tmpfs","options":["nosuid","strictatime","mode=755","size=65536k"]},{"destination":"/dev/pts","type":"devpts","source":"devpts","options":["nosuid","noexec","newinstance","ptmxmode=0666","mode=0620","gid=5"]},{"destination":"/dev/shm","type":"tmpfs","source":"shm","options":["nosuid","noexec","nodev","mode=1777","size=65536k"]},{"destination":"/dev/mqueue","type":"mqueue","source":"mqueue","options":["nosuid","noexec","nodev"]},{"destination":"/sys","type":"sysfs","source":"sysfs","options":["nosuid","noexec","nodev","ro"]},{"destination":"/etc/resolv.conf","type":"bind","source":"/tmp/tmp.vbNtQbA1gH/crio-run/overlay-containers/be31d1264b6607fb13c2cff4609707618a735318f7a8f0c010459c13c06129c0/userdata/resolv.conf","options":["ro","bind"]},{"destination":"/etc/hostname","type":"bind","source":"/tmp/tmp.vbNtQbA1gH/crio-run/overlay-containers/be31d1264b6607fb13c2cff4609707618a735318f7a8f0c010459c13c06129c0/userdata/hostname","options":["ro","bind"]}],"annotations":{"group":"test","io.kubernetes.container.name":"POD","io.kubernetes.cri-o.Annotations":"{\"owner\":\"hmeng\",\"security.alpha.kubernetes.io/seccomp/pod\":\"unconfined\"}","io.kubernetes.cri-o.ContainerID":"be31d1264b6607fb13c2cff4609707618a735318f7a8f0c010459c13c06129c0","io.kubernetes.cri-o.ContainerName":"k8s_POD_podsandbox1_redhat.test.crio_redhat-test-crio_1","io.kubernetes.cri-o.ContainerType":"sandbox","io.kubernetes.cri-o.Created":"2018-03-06T16:14:06.818814609Z","io.kubernetes.cri-o.HostName":"crictl_host","io.kubernetes.cri-o.HostnamePath":"/tmp/tmp.vbNtQbA1gH/crio-run/overlay-containers/be31d1264b6607fb13c2cff4609707618a735318f7a8f0c010459c13c06129c0/userdata/hostname","io.kubernetes.cri-o.IP":"10.88.0.222","io.kubernetes.cri-o.KubeName":"podsandbox1","io.kubernetes.cri-o.Labels":"{\"group\":\"test\",\"io.kubernetes.container.name\":\"POD\"}","io.kubernetes.cri-o.LogPath":"/var/log/crio/pods/be31d1264b6607fb13c2cff4609707618a735318f7a8f0c010459c13c06129c0/be31d1264b6607fb13c2cff4609707618a735318f7a8f0c010459c13c06129c0.log","io.kubernetes.cri-o.Metadata":"{\"name\":\"podsandbox1\",\"uid\":\"redhat-test-crio\",\"namespace\":\"redhat.test.crio\",\"attempt\":1}","io.kubernetes.cri-o.MountPoint":"/tmp/tmp.vbNtQbA1gH/crio/overlay/4aa4dac98f46a00acf0fce5e086be178f56d046635e5c3c870bdbf26adc9a2bf/merged","io.kubernetes.cri-o.Name":"k8s_podsandbox1_redhat.test.crio_redhat-test-crio_1","io.kubernetes.cri-o.PrivilegedRuntime":"false","io.kubernetes.cri-o.ResolvPath":"/tmp/tmp.vbNtQbA1gH/crio-run/overlay-containers/be31d1264b6607fb13c2cff4609707618a735318f7a8f0c010459c13c06129c0/userdata/resolv.conf","io.kubernetes.cri-o.SandboxID":"be31d1264b6607fb13c2cff4609707618a735318f7a8f0c010459c13c06129c0","io.kubernetes.cri-o.SeccompProfilePath":"","io.kubernetes.cri-o.ShmPath":"/tmp/tmp.vbNtQbA1gH/crio-run/overlay-containers/be31d1264b6607fb13c2cff4609707618a735318f7a8f0c010459c13c06129c0/userdata/shm","io.kubernetes.cri-o.TrustedSandbox":"true","owner":"hmeng","security.alpha.kubernetes.io/seccomp/pod":"unconfined"},"linux":{"resources":{"devices":[{"allow":false,"access":"rwm"}],"cpu":{"shares":2}},"cgroupsPath":"/Burstable/pod_123-456/crio-be31d1264b6607fb13c2cff4609707618a735318f7a8f0c010459c13c06129c0","namespaces":[{"type":"pid"},{"type":"network","path":"/var/run/netns/k8s_podsandbox1_redhat.test.crio_redhat-test-crio_1-19d48373"},{"type":"ipc"},{"type":"uts"},{"type":"mount"}]},"process":{"user":{"uid":0,"gid":0},"args":["/pause"],"env":["PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin","TERM=xterm"],"cwd":"/","rlimits":[{"type":"RLIMIT_NOFILE","hard":1024,"soft":1024}],"oomScoreAdj":-998,"capabilities":{"ambient":["CAP_CHOWN","CAP_DAC_OVERRIDE","CAP_FSETID","CAP_FOWNER","CAP_MKNOD","CAP_NET_RAW","CAP_SETGID","CAP_SETUID","CAP_SETFCAP","CAP_SETPCAP","CAP_NET_BIND_SERVICE","CAP_SYS_CHROOT","CAP_KILL","CAP_AUDIT_WRITE"],"bounding":["CAP_CHOWN","CAP_DAC_OVERRIDE","CAP_FSETID","CAP_FOWNER","CAP_MKNOD","CAP_NET_RAW","CAP_SETGID","CAP_SETUID","CAP_SETFCAP","CAP_SETPCAP","CAP_NET_BIND_SERVICE","CAP_SYS_CHROOT","CAP_KILL","CAP_AUDIT_WRITE"],"effective":["CAP_CHOWN","CAP_DAC_OVERRIDE","CAP_FSETID","CAP_FOWNER","CAP_MKNOD","CAP_NET_RAW","CAP_SETGID","CAP_SETUID","CAP_SETFCAP","CAP_SETPCAP","CAP_NET_BIND_SERVICE","CAP_SYS_CHROOT","CAP_KILL","CAP_AUDIT_WRITE"],"inheritable":["CAP_CHOWN","CAP_DAC_OVERRIDE","CAP_FSETID","CAP_FOWNER","CAP_MKNOD","CAP_NET_RAW","CAP_SETGID","CAP_SETUID","CAP_SETFCAP","CAP_SETPCAP","CAP_NET_BIND_SERVICE","CAP_SYS_CHROOT","CAP_KILL","CAP_AUDIT_WRITE"],"permitted":["CAP_CHOWN","CAP_DAC_OVERRIDE","CAP_FSETID","CAP_FOWNER","CAP_MKNOD","CAP_NET_RAW","CAP_SETGID","CAP_SETUID","CAP_SETFCAP","CAP_SETPCAP","CAP_NET_BIND_SERVICE","CAP_SYS_CHROOT","CAP_KILL","CAP_AUDIT_WRITE"]}}} com.github.containers.virtcontainers.pkg.oci.container_type:pod_sandbox]} Created:2018-03-06 16:14:06.818814609 +0000 UTC m=+1.049326690 Started:2018-03-06 16:14:08.785416532 +0000 UTC m=+3.015928613 Finished:0001-01-01 00:00:00 +0000 UTC ExitCode:0 OOMKilled:false Error:}"
# time="2018-03-06 16:14:10.237937528Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.vbNtQbA1gH/crio+/tmp/tmp.vbNtQbA1gH/crio-run]docker.io/runcom/stderr-test:latest""
# time="2018-03-06 16:14:10.238395331Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.vbNtQbA1gH/crio+/tmp/tmp.vbNtQbA1gH/crio-run]docker.io/runcom/stderr-test:latest""
# time="2018-03-06 16:14:10.238913534Z" level=debug msg="exporting opaque data as blob "sha256:5501612c200f99317c33b7a02dfbe6e30a76deea821e0f115eb4a6ab7f2ef689""
# time="2018-03-06 16:14:10.317180641Z" level=debug msg="created container "5ef552eba875831dc2c4932bf7da624248b4c36b04d71ddfa31ed44eb6a7b4d4""
# time="2018-03-06 16:14:10.415868479Z" level=debug msg="container "5ef552eba875831dc2c4932bf7da624248b4c36b04d71ddfa31ed44eb6a7b4d4" has work directory "/tmp/tmp.vbNtQbA1gH/crio/overlay-containers/5ef552eba875831dc2c4932bf7da624248b4c36b04d71ddfa31ed44eb6a7b4d4/userdata""
# time="2018-03-06 16:14:10.415948780Z" level=debug msg="container "5ef552eba875831dc2c4932bf7da624248b4c36b04d71ddfa31ed44eb6a7b4d4" has run directory "/tmp/tmp.vbNtQbA1gH/crio-run/overlay-containers/5ef552eba875831dc2c4932bf7da624248b4c36b04d71ddfa31ed44eb6a7b4d4/userdata""
# time="2018-03-06 16:14:10.469488826Z" level=debug msg="mounted container "5ef552eba875831dc2c4932bf7da624248b4c36b04d71ddfa31ed44eb6a7b4d4" at "/tmp/tmp.vbNtQbA1gH/crio/overlay/304ac85209866a1a59f6d3fe83144492cd902b57218a34c11d69e96ba98212db/merged""
# time="2018-03-06 16:14:10.469508726Z" level=debug msg="OCI process args [/bin/sleep 600]"
# time="2018-03-06 16:14:10.469720127Z" level=debug msg="CONTAINER USER: "
# time="2018-03-06 16:14:10.469863728Z" level=debug msg="UID: 0, GID: 0, Groups: [0 1 2 3 4 6 10 11 20 26 27]"
# time="2018-03-06 16:14:10.470430332Z" level=debug msg="running conmon: /root/go/src/github.com/kubernetes-incubator/cri-o/bin/conmon" args=[-c 5ef552eba875831dc2c4932bf7da624248b4c36b04d71ddfa31ed44eb6a7b4d4 -u 5ef552eba875831dc2c4932bf7da624248b4c36b04d71ddfa31ed44eb6a7b4d4 -r /usr/local/bin/cc-runtime -b /tmp/tmp.vbNtQbA1gH/crio-run/overlay-containers/5ef552eba875831dc2c4932bf7da624248b4c36b04d71ddfa31ed44eb6a7b4d4/userdata -p /tmp/tmp.vbNtQbA1gH/crio-run/overlay-containers/5ef552eba875831dc2c4932bf7da624248b4c36b04d71ddfa31ed44eb6a7b4d4/userdata/pidfile -l /var/log/crio/pods/be31d1264b6607fb13c2cff4609707618a735318f7a8f0c010459c13c06129c0/5ef552eba875831dc2c4932bf7da624248b4c36b04d71ddfa31ed44eb6a7b4d4.log --exit-dir /var/run/crio/exits --socket-dir-path /var/run/crio]
# time="2018-03-06 16:14:10.569142171Z" level=debug msg="Received container pid: 62964"
# time="2018-03-06 16:14:10.669910923Z" level=debug msg="CreateContainerResponse: &CreateContainerResponse{ContainerId:5ef552eba875831dc2c4932bf7da624248b4c36b04d71ddfa31ed44eb6a7b4d4,}"
# 5ef552eba875831dc2c4932bf7da624248b4c36b04d71ddfa31ed44eb6a7b4d4
# time="2018-03-06 16:14:10.735025544Z" level=debug msg="StartContainerRequest &StartContainerRequest{ContainerId:5ef552eba875831dc2c4932bf7da624248b4c36b04d71ddfa31ed44eb6a7b4d4,}"
# time="2018-03-06 16:14:11.001966071Z" level=debug msg="StartContainerResponse &StartContainerResponse{}"
# 5ef552eba875831dc2c4932bf7da624248b4c36b04d71ddfa31ed44eb6a7b4d4
# time="2018-03-06 16:14:11.160412595Z" level=debug msg="ExecSyncRequest &ExecSyncRequest{ContainerId:5ef552eba875831dc2c4932bf7da624248b4c36b04d71ddfa31ed44eb6a7b4d4,Cmd:[stderr],Timeout:0,}"
# time="2018-03-06 16:14:11.379062510Z" level=info msg="Received container exit code: 0, message: "
# time="2018-03-06 16:14:11.379279011Z" level=debug msg="ExecSyncResponse: &ExecSyncResponse{Stdout:[],Stderr:[116 104 105 115 32 103 111 101 115 32 116 111 32 115 116 100 101 114 114 10],ExitCode:0,}"
#
# this goes to stderr
# time="2018-03-06 16:14:11.442009817Z" level=debug msg="StopPodSandboxRequest &StopPodSandboxRequest{PodSandboxId:be31d1264b6607fb13c2cff4609707618a735318f7a8f0c010459c13c06129c0,}"
# time="2018-03-06 16:14:11.442051217Z" level=info msg="Got pod network {Name:podsandbox1 Namespace:redhat.test.crio ID:be31d1264b6607fb13c2cff4609707618a735318f7a8f0c010459c13c06129c0 NetNS:/var/run/netns/k8s_podsandbox1_redhat.test.crio_redhat-test-crio_1-19d48373 PortMappings:[]}"
# time="2018-03-06 16:14:11.442066317Z" level=info msg="About to del CNI network crionet (type=bridge)"
# time="2018-03-06 16:14:21.720100994Z" level=debug msg="event: "/var/run/crio/exits/5ef552eba875831dc2c4932bf7da624248b4c36b04d71ddfa31ed44eb6a7b4d4.7I01EZ": CREATE"
# time="2018-03-06 16:14:21.720152095Z" level=debug msg="container or sandbox exited: 5ef552eba875831dc2c4932bf7da624248b4c36b04d71ddfa31ed44eb6a7b4d4.7I01EZ"
# time="2018-03-06 16:14:21.720190295Z" level=debug msg="event: "/var/run/crio/exits/5ef552eba875831dc2c4932bf7da624248b4c36b04d71ddfa31ed44eb6a7b4d4.7I01EZ": WRITE"
# time="2018-03-06 16:14:21.720239095Z" level=debug msg="event: "/var/run/crio/exits/5ef552eba875831dc2c4932bf7da624248b4c36b04d71ddfa31ed44eb6a7b4d4.7I01EZ": RENAME"
# time="2018-03-06 16:14:21.720255695Z" level=debug msg="event: "/var/run/crio/exits/5ef552eba875831dc2c4932bf7da624248b4c36b04d71ddfa31ed44eb6a7b4d4": CREATE"
# time="2018-03-06 16:14:21.720265695Z" level=debug msg="container or sandbox exited: 5ef552eba875831dc2c4932bf7da624248b4c36b04d71ddfa31ed44eb6a7b4d4"
# time="2018-03-06 16:14:21.720281895Z" level=debug msg="container exited and found: 5ef552eba875831dc2c4932bf7da624248b4c36b04d71ddfa31ed44eb6a7b4d4"
# time="2018-03-06 16:14:24.661129713Z" level=debug msg="unmounted container "5ef552eba875831dc2c4932bf7da624248b4c36b04d71ddfa31ed44eb6a7b4d4""
# time="2018-03-06 16:14:24.920443090Z" level=debug msg="event: "/var/run/crio/exits/3c941c142f95cfbf5dc6c1a87de5f91837d1f450ee2ad775501a738394b8568e.DC0WEZ": CREATE"
# time="2018-03-06 16:14:24.920467490Z" level=debug msg="container or sandbox exited: 3c941c142f95cfbf5dc6c1a87de5f91837d1f450ee2ad775501a738394b8568e.DC0WEZ"
# time="2018-03-06 16:14:24.920561291Z" level=debug msg="event: "/var/run/crio/exits/3c941c142f95cfbf5dc6c1a87de5f91837d1f450ee2ad775501a738394b8568e.DC0WEZ": WRITE"
# time="2018-03-06 16:14:24.920651491Z" level=debug msg="event: "/var/run/crio/exits/3c941c142f95cfbf5dc6c1a87de5f91837d1f450ee2ad775501a738394b8568e.DC0WEZ": WRITE"
# time="2018-03-06 16:14:24.920829692Z" level=debug msg="event: "/var/run/crio/exits/3c941c142f95cfbf5dc6c1a87de5f91837d1f450ee2ad775501a738394b8568e.DC0WEZ": RENAME"
# time="2018-03-06 16:14:24.920856793Z" level=debug msg="event: "/var/run/crio/exits/3c941c142f95cfbf5dc6c1a87de5f91837d1f450ee2ad775501a738394b8568e": CREATE"
# time="2018-03-06 16:14:24.920869893Z" level=debug msg="container or sandbox exited: 3c941c142f95cfbf5dc6c1a87de5f91837d1f450ee2ad775501a738394b8568e"
# time="2018-03-06 16:14:24.920890893Z" level=debug msg="container exited and found: 3c941c142f95cfbf5dc6c1a87de5f91837d1f450ee2ad775501a738394b8568e"
# time="2018-03-06 16:14:25.167346286Z" level=debug msg="unmounted container "3c941c142f95cfbf5dc6c1a87de5f91837d1f450ee2ad775501a738394b8568e""
# time="2018-03-06 16:14:25.439449646Z" level=debug msg="event: "/var/run/crio/exits/be31d1264b6607fb13c2cff4609707618a735318f7a8f0c010459c13c06129c0.MLC7EZ": RENAME"
# time="2018-03-06 16:14:25.439504846Z" level=debug msg="event: "/var/run/crio/exits/be31d1264b6607fb13c2cff4609707618a735318f7a8f0c010459c13c06129c0": CREATE"
# time="2018-03-06 16:14:25.439520046Z" level=debug msg="container or sandbox exited: be31d1264b6607fb13c2cff4609707618a735318f7a8f0c010459c13c06129c0"
# time="2018-03-06 16:14:25.439570747Z" level=debug msg="sandbox exited and found: be31d1264b6607fb13c2cff4609707618a735318f7a8f0c010459c13c06129c0"
# time="2018-03-06 16:14:25.773453906Z" level=debug msg="unmounted container "be31d1264b6607fb13c2cff4609707618a735318f7a8f0c010459c13c06129c0""
# time="2018-03-06 16:14:25.773519406Z" level=debug msg="StopPodSandboxResponse be31d1264b6607fb13c2cff4609707618a735318f7a8f0c010459c13c06129c0: &StopPodSandboxResponse{}"
# Stopped sandbox be31d1264b6607fb13c2cff4609707618a735318f7a8f0c010459c13c06129c0
# time="2018-03-06 16:14:25.864317193Z" level=debug msg="RemovePodSandboxRequest &RemovePodSandboxRequest{PodSandboxId:be31d1264b6607fb13c2cff4609707618a735318f7a8f0c010459c13c06129c0,}"
# time="2018-03-06 16:14:25.964121939Z" level=debug msg="Failed to unmount 304ac85209866a1a59f6d3fe83144492cd902b57218a34c11d69e96ba98212db overlay: /tmp/tmp.vbNtQbA1gH/crio/overlay/304ac85209866a1a59f6d3fe83144492cd902b57218a34c11d69e96ba98212db/merged - invalid argument"
# time="2018-03-06 16:14:26.010985342Z" level=debug msg="unmounted container "5ef552eba875831dc2c4932bf7da624248b4c36b04d71ddfa31ed44eb6a7b4d4""
# time="2018-03-06 16:14:26.210547932Z" level=debug msg="Failed to unmount 231458a82390625ce9c1a5469fdb391d9cbc4a533e112daa993779363403c640 overlay: /tmp/tmp.vbNtQbA1gH/crio/overlay/231458a82390625ce9c1a5469fdb391d9cbc4a533e112daa993779363403c640/merged - invalid argument"
# time="2018-03-06 16:14:26.260985258Z" level=debug msg="unmounted container "3c941c142f95cfbf5dc6c1a87de5f91837d1f450ee2ad775501a738394b8568e""
# time="2018-03-06 16:14:26.498114391Z" level=debug msg="Failed to unmount 4aa4dac98f46a00acf0fce5e086be178f56d046635e5c3c870bdbf26adc9a2bf overlay: /tmp/tmp.vbNtQbA1gH/crio/overlay/4aa4dac98f46a00acf0fce5e086be178f56d046635e5c3c870bdbf26adc9a2bf/merged - invalid argument"
# time="2018-03-06 16:14:26.542861581Z" level=debug msg="unmounted container "be31d1264b6607fb13c2cff4609707618a735318f7a8f0c010459c13c06129c0""
# time="2018-03-06 16:14:26.661213646Z" level=debug msg="RemovePodSandboxResponse &RemovePodSandboxResponse{}"
# Removed sandbox be31d1264b6607fb13c2cff4609707618a735318f7a8f0c010459c13c06129c0
# time="2018-03-06 16:14:26.731089798Z" level=debug msg="ListContainersRequest &ListContainersRequest{Filter:&ContainerFilter{Id:,State:nil,PodSandboxId:,LabelSelector:map[string]string{},},}"
# time="2018-03-06 16:14:26.731115798Z" level=debug msg="ListContainersResponse: &ListContainersResponse{Containers:[],}"
# time="2018-03-06 16:14:26.804632273Z" level=debug msg="ListPodSandboxRequest &ListPodSandboxRequest{Filter:&PodSandboxFilter{Id:,State:nil,LabelSelector:map[string]string{},},}"
# time="2018-03-06 16:14:26.804664674Z" level=debug msg="ListPodSandboxResponse &ListPodSandboxResponse{Items:[],}"
# time="2018-03-06 16:14:26.822150887Z" level=debug msg="Caught SIGTERM"
# time="2018-03-06 16:14:26.822383288Z" level=debug msg="closing exit monitor..."
# time="2018-03-06 16:14:26.822419788Z" level=debug msg="closed exit monitor"
# time="2018-03-06 16:14:26.822430589Z" level=debug msg="closed main server"
# 0
# rm: cannot remove '/tmp/tmp.vbNtQbA1gH/crio/overlay': Device or resource busy
not ok 21 ctr stop idempotent
# (from function `cleanup_test' in file helpers.bash, line 357,
#  from function `teardown' in test file ctr.bats, line 6)
#   `cleanup_test' failed
#   b717351723b30fd969cb1e8176ceb3afbfdbc8e8cc97cdeb7bba18159871b00b
# 0
# time="2018-03-06 16:14:38.569747336Z" level=debug msg="backingFs=extfs,  projectQuotaSupported=false"
# time="2018-03-06 16:14:38.569841336Z" level=info msg="[graphdriver] using prior storage driver: overlay"
# time="2018-03-06 16:14:38.571370746Z" level=info msg="CNI network crionet (type=bridge) is used from /tmp/tmp.945S2mjriV/cni/net.d/10-crio.conf"
# time="2018-03-06 16:14:38.571556148Z" level=info msg="CNI network crionet (type=bridge) is used from /tmp/tmp.945S2mjriV/cni/net.d/10-crio.conf"
# time="2018-03-06 16:14:38.588234355Z" level=debug msg="seccomp status: true"
# time="2018-03-06 16:14:38.589042861Z" level=debug msg="Golang's threads limit set to 115110"
# time="2018-03-06 16:14:38.592748585Z" level=debug msg="sandboxes: []"
# time="2018-03-06 16:14:38.592893786Z" level=error msg="Failed to start streaming server: listen tcp 10.1.10.4:10010: bind: address already in use"
# time="2018-03-06 16:14:38.631392234Z" level=debug msg="closed stream server"
# time="2018-03-06 16:14:38.682368664Z" level=debug msg="ImageStatusRequest: &ImageStatusRequest{Image:&ImageSpec{Image:redis:alpine,},Verbose:false,}"
# time="2018-03-06 16:14:38.682487565Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.945S2mjriV/crio+/tmp/tmp.945S2mjriV/crio-run]docker.io/library/redis:alpine""
# time="2018-03-06 16:14:38.683213269Z" level=debug msg="[graphdriver] trying provided driver "overlay""
# time="2018-03-06 16:14:38.704594008Z" level=debug msg="backingFs=extfs,  projectQuotaSupported=false"
# time="2018-03-06 16:14:38.705486713Z" level=debug msg="ImageStatusResponse: &ImageStatusResponse{Image:&Image{Id:d3288b98c844ca70967bbdd73248f71cf4e03847576382f29b3e72180d45be08,RepoTags:[docker.io/library/redis:alpine redis:alpine],RepoDigests:[],Size_:27082854,Uid:nil,Username:,},Info:map[string]string{},}"
# time="2018-03-06 16:14:38.784158722Z" level=debug msg="ImageStatusRequest: &ImageStatusRequest{Image:&ImageSpec{Image:redis:alpine,},Verbose:false,}"
# time="2018-03-06 16:14:38.784218622Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.945S2mjriV/crio+/tmp/tmp.945S2mjriV/crio-run]docker.io/library/redis:alpine""
# time="2018-03-06 16:14:38.784674825Z" level=debug msg="ImageStatusResponse: &ImageStatusResponse{Image:&Image{Id:d3288b98c844ca70967bbdd73248f71cf4e03847576382f29b3e72180d45be08,RepoTags:[docker.io/library/redis:alpine redis:alpine],RepoDigests:[],Size_:27082854,Uid:nil,Username:,},Info:map[string]string{},}"
# time="2018-03-06 16:14:38.844367711Z" level=debug msg="ImageStatusRequest: &ImageStatusRequest{Image:&ImageSpec{Image:mrunalp/oom,},Verbose:false,}"
# time="2018-03-06 16:14:38.844448112Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.945S2mjriV/crio+/tmp/tmp.945S2mjriV/crio-run]docker.io/mrunalp/oom:latest""
# time="2018-03-06 16:14:38.844706713Z" level=debug msg="ImageStatusResponse: &ImageStatusResponse{Image:&Image{Id:e441583a29ab77264a21e8f821fb932c7696e77f0c6d1b6ff3090e2d01144313,RepoTags:[docker.io/mrunalp/oom:latest mrunalp/oom docker.io/library/mrunalp/oom],RepoDigests:[],Size_:5719481,Uid:nil,Username:,},Info:map[string]string{},}"
# time="2018-03-06 16:14:38.903753495Z" level=debug msg="ImageStatusRequest: &ImageStatusRequest{Image:&ImageSpec{Image:redis@sha256:03789f402b2ecfb98184bf128d180f398f81c63364948ff1454583b02442f73b,},Verbose:false,}"
# time="2018-03-06 16:14:38.903888796Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.945S2mjriV/crio+/tmp/tmp.945S2mjriV/crio-run]docker.io/library/redis:latest@03789f402b2ecfb98184bf128d180f398f81c63364948ff1454583b02442f73b""
# time="2018-03-06 16:14:38.904323099Z" level=debug msg="ImageStatusResponse: &ImageStatusResponse{Image:&Image{Id:03789f402b2ecfb98184bf128d180f398f81c63364948ff1454583b02442f73b,RepoTags:[docker.io/library/redis:latest redis@sha256:03789f402b2ecfb98184bf128d180f398f81c63364948ff1454583b02442f73b docker.io/library/redis@sha256:03789f402b2ecfb98184bf128d180f398f81c63364948ff1454583b02442f73b],RepoDigests:[],Size_:19826844,Uid:nil,Username:,},Info:map[string]string{},}"
# time="2018-03-06 16:14:38.962594975Z" level=debug msg="ImageStatusRequest: &ImageStatusRequest{Image:&ImageSpec{Image:runcom/stderr-test,},Verbose:false,}"
# time="2018-03-06 16:14:38.962674476Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.945S2mjriV/crio+/tmp/tmp.945S2mjriV/crio-run]docker.io/runcom/stderr-test:latest""
# time="2018-03-06 16:14:38.963004078Z" level=debug msg="ImageStatusResponse: &ImageStatusResponse{Image:&Image{Id:fbf623534d1f962280b5960fd8c17eb5b58c299af8ab24509633ca89fb65e7e7,RepoTags:[docker.io/runcom/stderr-test:latest runcom/stderr-test:latest],RepoDigests:[],Size_:4906035,Uid:nil,Username:,},Info:map[string]string{},}"
# time="2018-03-06 16:14:39.029346307Z" level=debug msg="ImageStatusRequest: &ImageStatusRequest{Image:&ImageSpec{Image:runcom/stderr-test,},Verbose:false,}"
# time="2018-03-06 16:14:39.029416307Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.945S2mjriV/crio+/tmp/tmp.945S2mjriV/crio-run]docker.io/runcom/stderr-test:latest""
# time="2018-03-06 16:14:39.029712209Z" level=debug msg="ImageStatusResponse: &ImageStatusResponse{Image:&Image{Id:fbf623534d1f962280b5960fd8c17eb5b58c299af8ab24509633ca89fb65e7e7,RepoTags:[docker.io/runcom/stderr-test:latest runcom/stderr-test:latest],RepoDigests:[],Size_:4906035,Uid:nil,Username:,},Info:map[string]string{},}"
# time="2018-03-06 16:14:39.082477750Z" level=debug msg="ImageStatusRequest: &ImageStatusRequest{Image:&ImageSpec{Image:busybox,},Verbose:false,}"
# time="2018-03-06 16:14:39.082532951Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.945S2mjriV/crio+/tmp/tmp.945S2mjriV/crio-run]docker.io/library/busybox:latest""
# time="2018-03-06 16:14:39.082770952Z" level=debug msg="ImageStatusResponse: &ImageStatusResponse{Image:&Image{Id:642a5c32f80ff444baba0257ddb129488c736947239d13bcd39bd754b785b0a2,RepoTags:[docker.io/library/busybox:latest busybox:latest],RepoDigests:[],Size_:1148393,Uid:nil,Username:,},Info:map[string]string{},}"
# time="2018-03-06 16:14:39.149242682Z" level=debug msg="ImageStatusRequest: &ImageStatusRequest{Image:&ImageSpec{Image:busybox,},Verbose:false,}"
# time="2018-03-06 16:14:39.149345082Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.945S2mjriV/crio+/tmp/tmp.945S2mjriV/crio-run]docker.io/library/busybox:latest""
# time="2018-03-06 16:14:39.149656884Z" level=debug msg="ImageStatusResponse: &ImageStatusResponse{Image:&Image{Id:642a5c32f80ff444baba0257ddb129488c736947239d13bcd39bd754b785b0a2,RepoTags:[docker.io/library/busybox:latest busybox:latest],RepoDigests:[],Size_:1148393,Uid:nil,Username:,},Info:map[string]string{},}"
# time="2018-03-06 16:14:39.206657653Z" level=debug msg="ImageStatusRequest: &ImageStatusRequest{Image:&ImageSpec{Image:mrunalp/image-volume-test,},Verbose:false,}"
# time="2018-03-06 16:14:39.206717253Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.945S2mjriV/crio+/tmp/tmp.945S2mjriV/crio-run]docker.io/mrunalp/image-volume-test:latest""
# time="2018-03-06 16:14:39.207067756Z" level=debug msg="ImageStatusResponse: &ImageStatusResponse{Image:&Image{Id:1edf0106cede533c22eea527e360ca78ea20fd5fa105f57d75301390c0461c59,RepoTags:[docker.io/mrunalp/image-volume-test:latest mrunalp/image-volume-test docker.io/library/mrunalp/image-volume-test],RepoDigests:[],Size_:1095628,Uid:nil,Username:,},Info:map[string]string{},}"
# time="2018-03-06 16:14:39.272378878Z" level=debug msg="ImageStatusRequest: &ImageStatusRequest{Image:&ImageSpec{Image:mrunalp/image-volume-test,},Verbose:false,}"
# time="2018-03-06 16:14:39.272438678Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.945S2mjriV/crio+/tmp/tmp.945S2mjriV/crio-run]docker.io/mrunalp/image-volume-test:latest""
# time="2018-03-06 16:14:39.272731180Z" level=debug msg="ImageStatusResponse: &ImageStatusResponse{Image:&Image{Id:1edf0106cede533c22eea527e360ca78ea20fd5fa105f57d75301390c0461c59,RepoTags:[docker.io/mrunalp/image-volume-test:latest mrunalp/image-volume-test docker.io/library/mrunalp/image-volume-test],RepoDigests:[],Size_:1095628,Uid:nil,Username:,},Info:map[string]string{},}"
# time="2018-03-06 16:14:39.332413966Z" level=debug msg="RunPodSandboxRequest &RunPodSandboxRequest{Config:&PodSandboxConfig{Metadata:&PodSandboxMetadata{Name:podsandbox1,Uid:redhat-test-crio,Namespace:redhat.test.crio,Attempt:1,},Hostname:crictl_host,LogDirectory:,DnsConfig:&DNSConfig{Servers:[],Searches:[8.8.8.8],Options:[],},PortMappings:[],Labels:map[string]string{group: test,},Annotations:map[string]string{owner: hmeng,security.alpha.kubernetes.io/seccomp/pod: unconfined,},Linux:&LinuxPodSandboxConfig{CgroupParent:/Burstable/pod_123-456,SecurityContext:&LinuxSandboxSecurityContext{NamespaceOptions:&NamespaceOption{HostNetwork:false,HostPid:false,HostIpc:false,},SelinuxOptions:&SELinuxOption{User:system_u,Role:system_r,Type:svirt_lxc_net_t,Level:s0:c4,c5,},RunAsUser:nil,ReadonlyRootfs:false,SupplementalGroups:[],Privileged:false,SeccompProfilePath:,},Sysctls:map[string]string{},},},}"
# time="2018-03-06 16:14:39.332538967Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.945S2mjriV/crio+/tmp/tmp.945S2mjriV/crio-run]docker.io/kubernetes/pause:latest""
# time="2018-03-06 16:14:39.333162871Z" level=debug msg="exporting opaque data as blob "sha256:c713bfa1b66381820184edb919bad71ac5aa56a93a1f2fc2c4c7f4d69effe351""
# time="2018-03-06 16:14:39.408635258Z" level=debug msg="created pod sandbox "b717351723b30fd969cb1e8176ceb3afbfdbc8e8cc97cdeb7bba18159871b00b""
# time="2018-03-06 16:14:39.469997755Z" level=debug msg="pod sandbox "b717351723b30fd969cb1e8176ceb3afbfdbc8e8cc97cdeb7bba18159871b00b" has work directory "/tmp/tmp.945S2mjriV/crio/overlay-containers/b717351723b30fd969cb1e8176ceb3afbfdbc8e8cc97cdeb7bba18159871b00b/userdata""
# time="2018-03-06 16:14:39.470078556Z" level=debug msg="pod sandbox "b717351723b30fd969cb1e8176ceb3afbfdbc8e8cc97cdeb7bba18159871b00b" has run directory "/tmp/tmp.945S2mjriV/crio-run/overlay-containers/b717351723b30fd969cb1e8176ceb3afbfdbc8e8cc97cdeb7bba18159871b00b/userdata""
# time="2018-03-06 16:14:39.611142867Z" level=debug msg="mounted container "b717351723b30fd969cb1e8176ceb3afbfdbc8e8cc97cdeb7bba18159871b00b" at "/tmp/tmp.945S2mjriV/crio/overlay/de6d3e250c2f8d12eafc7d48b843a70aa674decb58b370228f2ac42c7f8539ff/merged""
# time="2018-03-06 16:14:39.611244668Z" level=info msg="Got pod network {Name:podsandbox1 Namespace:redhat.test.crio ID:b717351723b30fd969cb1e8176ceb3afbfdbc8e8cc97cdeb7bba18159871b00b NetNS:/var/run/netns/k8s_podsandbox1_redhat.test.crio_redhat-test-crio_1-e51ace73 PortMappings:[]}"
# time="2018-03-06 16:14:39.611256668Z" level=info msg="About to add CNI network cni-loopback (type=loopback)"
# time="2018-03-06 16:14:39.614672790Z" level=info msg="Got pod network {Name:podsandbox1 Namespace:redhat.test.crio ID:b717351723b30fd969cb1e8176ceb3afbfdbc8e8cc97cdeb7bba18159871b00b NetNS:/var/run/netns/k8s_podsandbox1_redhat.test.crio_redhat-test-crio_1-e51ace73 PortMappings:[]}"
# time="2018-03-06 16:14:39.614690790Z" level=info msg="About to add CNI network crionet (type=bridge)"
# time="2018-03-06 16:14:39.692667294Z" level=debug msg="running conmon: /root/go/src/github.com/kubernetes-incubator/cri-o/bin/conmon" args=[-c b717351723b30fd969cb1e8176ceb3afbfdbc8e8cc97cdeb7bba18159871b00b -u b717351723b30fd969cb1e8176ceb3afbfdbc8e8cc97cdeb7bba18159871b00b -r /usr/local/bin/cc-runtime -b /tmp/tmp.945S2mjriV/crio-run/overlay-containers/b717351723b30fd969cb1e8176ceb3afbfdbc8e8cc97cdeb7bba18159871b00b/userdata -p /tmp/tmp.945S2mjriV/crio-run/overlay-containers/b717351723b30fd969cb1e8176ceb3afbfdbc8e8cc97cdeb7bba18159871b00b/userdata/pidfile -l /var/log/crio/pods/b717351723b30fd969cb1e8176ceb3afbfdbc8e8cc97cdeb7bba18159871b00b/b717351723b30fd969cb1e8176ceb3afbfdbc8e8cc97cdeb7bba18159871b00b.log --exit-dir /var/run/crio/exits --socket-dir-path /var/run/crio]
# time="2018-03-06 16:14:41.224474096Z" level=debug msg="Received container pid: 64336"
# time="2018-03-06 16:14:41.564020390Z" level=debug msg="RunPodSandboxResponse: &RunPodSandboxResponse{PodSandboxId:b717351723b30fd969cb1e8176ceb3afbfdbc8e8cc97cdeb7bba18159871b00b,}"
# b717351723b30fd969cb1e8176ceb3afbfdbc8e8cc97cdeb7bba18159871b00b
# time="2018-03-06 16:14:41.625493588Z" level=debug msg="CreateContainerRequest &CreateContainerRequest{PodSandboxId:b717351723b30fd969cb1e8176ceb3afbfdbc8e8cc97cdeb7bba18159871b00b,Config:&ContainerConfig{Metadata:&ContainerMetadata{Name:podsandbox1-redis,Attempt:0,},Image:&ImageSpec{Image:redis:alpine,},Command:[],Args:[docker-entrypoint.sh redis-server],WorkingDir:/data,Envs:[&KeyValue{Key:PATH,Value:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin,} &KeyValue{Key:TERM,Value:xterm,} &KeyValue{Key:REDIS_VERSION,Value:3.2.3,} &KeyValue{Key:REDIS_DOWNLOAD_URL,Value:http://download.redis.io/releases/redis-3.2.3.tar.gz,} &KeyValue{Key:REDIS_DOWNLOAD_SHA1,Value:92d6d93ef2efc91e595c8bf578bf72baff397507,}],Mounts:[],Devices:[],Labels:map[string]string{tier: backend,},Annotations:map[string]string{pod: podsandbox1,},LogPath:,Stdin:false,StdinOnce:false,Tty:false,Linux:&LinuxContainerConfig{Resources:&LinuxContainerResources{CpuPeriod:10000,CpuQuota:20000,CpuShares:512,MemoryLimitInBytes:209715200,OomScoreAdj:30,CpusetCpus:,CpusetMems:,},SecurityContext:&LinuxContainerSecurityContext{Capabilities:&Capability{AddCapabilities:[sys_admin],DropCapabilities:[],},Privileged:false,NamespaceOptions:nil,SelinuxOptions:nil,RunAsUser:nil,RunAsUsername:,ReadonlyRootfs:false,SupplementalGroups:[],ApparmorProfile:,SeccompProfilePath:,NoNewPrivs:false,},},},SandboxConfig:&PodSandboxConfig{Metadata:&PodSandboxMetadata{Name:podsandbox1,Uid:redhat-test-crio,Namespace:redhat.test.crio,Attempt:1,},Hostname:crictl_host,LogDirectory:,DnsConfig:&DNSConfig{Servers:[],Searches:[8.8.8.8],Options:[],},PortMappings:[],Labels:map[string]string{group: test,},Annotations:map[string]string{owner: hmeng,security.alpha.kubernetes.io/seccomp/pod: unconfined,},Linux:&LinuxPodSandboxConfig{CgroupParent:/Burstable/pod_123-456,SecurityContext:&LinuxSandboxSecurityContext{NamespaceOptions:&NamespaceOption{HostNetwork:false,HostPid:false,HostIpc:false,},SelinuxOptions:&SELinuxOption{User:system_u,Role:system_r,Type:svirt_lxc_net_t,Level:s0:c4,c5,},RunAsUser:nil,ReadonlyRootfs:false,SupplementalGroups:[],Privileged:false,SeccompProfilePath:,},Sysctls:map[string]string{},},},}"
# time="2018-03-06 16:14:41.625631089Z" level=debug msg="setting container's log_path" ctr.logfile= log_path="/var/log/crio/pods/b717351723b30fd969cb1e8176ceb3afbfdbc8e8cc97cdeb7bba18159871b00b/da09679b72424fbbf6948c8662a6b9dd41457a915577e482578763334135a0f0.log" sbox.logdir="/var/log/crio/pods/b717351723b30fd969cb1e8176ceb3afbfdbc8e8cc97cdeb7bba18159871b00b"
# time="2018-03-06 16:14:41.625708989Z" level=debug msg="pod container state &{State:{Version:1.0.1 ID:b717351723b30fd969cb1e8176ceb3afbfdbc8e8cc97cdeb7bba18159871b00b Status:running Pid:64336 Bundle:/tmp/tmp.945S2mjriV/crio-run/overlay-containers/b717351723b30fd969cb1e8176ceb3afbfdbc8e8cc97cdeb7bba18159871b00b/userdata Annotations:map[com.github.containers.virtcontainers.pkg.oci.config:{"ociVersion":"1.0.0","root":{"path":"/tmp/tmp.945S2mjriV/crio/overlay/de6d3e250c2f8d12eafc7d48b843a70aa674decb58b370228f2ac42c7f8539ff/merged","readonly":true},"hostname":"crictl_host","mounts":[{"destination":"/proc","type":"proc","source":"proc"},{"destination":"/dev","type":"tmpfs","source":"tmpfs","options":["nosuid","strictatime","mode=755","size=65536k"]},{"destination":"/dev/pts","type":"devpts","source":"devpts","options":["nosuid","noexec","newinstance","ptmxmode=0666","mode=0620","gid=5"]},{"destination":"/dev/shm","type":"tmpfs","source":"shm","options":["nosuid","noexec","nodev","mode=1777","size=65536k"]},{"destination":"/dev/mqueue","type":"mqueue","source":"mqueue","options":["nosuid","noexec","nodev"]},{"destination":"/sys","type":"sysfs","source":"sysfs","options":["nosuid","noexec","nodev","ro"]},{"destination":"/etc/resolv.conf","type":"bind","source":"/tmp/tmp.945S2mjriV/crio-run/overlay-containers/b717351723b30fd969cb1e8176ceb3afbfdbc8e8cc97cdeb7bba18159871b00b/userdata/resolv.conf","options":["ro","bind"]},{"destination":"/etc/hostname","type":"bind","source":"/tmp/tmp.945S2mjriV/crio-run/overlay-containers/b717351723b30fd969cb1e8176ceb3afbfdbc8e8cc97cdeb7bba18159871b00b/userdata/hostname","options":["ro","bind"]}],"annotations":{"group":"test","io.kubernetes.container.name":"POD","io.kubernetes.cri-o.Annotations":"{\"owner\":\"hmeng\",\"security.alpha.kubernetes.io/seccomp/pod\":\"unconfined\"}","io.kubernetes.cri-o.ContainerID":"b717351723b30fd969cb1e8176ceb3afbfdbc8e8cc97cdeb7bba18159871b00b","io.kubernetes.cri-o.ContainerName":"k8s_POD_podsandbox1_redhat.test.crio_redhat-test-crio_1","io.kubernetes.cri-o.ContainerType":"sandbox","io.kubernetes.cri-o.Created":"2018-03-06T16:14:39.521291887Z","io.kubernetes.cri-o.HostName":"crictl_host","io.kubernetes.cri-o.HostnamePath":"/tmp/tmp.945S2mjriV/crio-run/overlay-containers/b717351723b30fd969cb1e8176ceb3afbfdbc8e8cc97cdeb7bba18159871b00b/userdata/hostname","io.kubernetes.cri-o.IP":"10.88.0.223","io.kubernetes.cri-o.KubeName":"podsandbox1","io.kubernetes.cri-o.Labels":"{\"group\":\"test\",\"io.kubernetes.container.name\":\"POD\"}","io.kubernetes.cri-o.LogPath":"/var/log/crio/pods/b717351723b30fd969cb1e8176ceb3afbfdbc8e8cc97cdeb7bba18159871b00b/b717351723b30fd969cb1e8176ceb3afbfdbc8e8cc97cdeb7bba18159871b00b.log","io.kubernetes.cri-o.Metadata":"{\"name\":\"podsandbox1\",\"uid\":\"redhat-test-crio\",\"namespace\":\"redhat.test.crio\",\"attempt\":1}","io.kubernetes.cri-o.MountPoint":"/tmp/tmp.945S2mjriV/crio/overlay/de6d3e250c2f8d12eafc7d48b843a70aa674decb58b370228f2ac42c7f8539ff/merged","io.kubernetes.cri-o.Name":"k8s_podsandbox1_redhat.test.crio_redhat-test-crio_1","io.kubernetes.cri-o.PrivilegedRuntime":"false","io.kubernetes.cri-o.ResolvPath":"/tmp/tmp.945S2mjriV/crio-run/overlay-containers/b717351723b30fd969cb1e8176ceb3afbfdbc8e8cc97cdeb7bba18159871b00b/userdata/resolv.conf","io.kubernetes.cri-o.SandboxID":"b717351723b30fd969cb1e8176ceb3afbfdbc8e8cc97cdeb7bba18159871b00b","io.kubernetes.cri-o.SeccompProfilePath":"","io.kubernetes.cri-o.ShmPath":"/tmp/tmp.945S2mjriV/crio-run/overlay-containers/b717351723b30fd969cb1e8176ceb3afbfdbc8e8cc97cdeb7bba18159871b00b/userdata/shm","io.kubernetes.cri-o.TrustedSandbox":"true","owner":"hmeng","security.alpha.kubernetes.io/seccomp/pod":"unconfined"},"linux":{"resources":{"devices":[{"allow":false,"access":"rwm"}],"cpu":{"shares":2}},"cgroupsPath":"/Burstable/pod_123-456/crio-b717351723b30fd969cb1e8176ceb3afbfdbc8e8cc97cdeb7bba18159871b00b","namespaces":[{"type":"pid"},{"type":"network","path":"/var/run/netns/k8s_podsandbox1_redhat.test.crio_redhat-test-crio_1-e51ace73"},{"type":"ipc"},{"type":"uts"},{"type":"mount"}]},"process":{"user":{"uid":0,"gid":0},"args":["/pause"],"env":["PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin","TERM=xterm"],"cwd":"/","rlimits":[{"type":"RLIMIT_NOFILE","hard":1024,"soft":1024}],"oomScoreAdj":-998,"capabilities":{"ambient":["CAP_CHOWN","CAP_DAC_OVERRIDE","CAP_FSETID","CAP_FOWNER","CAP_MKNOD","CAP_NET_RAW","CAP_SETGID","CAP_SETUID","CAP_SETFCAP","CAP_SETPCAP","CAP_NET_BIND_SERVICE","CAP_SYS_CHROOT","CAP_KILL","CAP_AUDIT_WRITE"],"bounding":["CAP_CHOWN","CAP_DAC_OVERRIDE","CAP_FSETID","CAP_FOWNER","CAP_MKNOD","CAP_NET_RAW","CAP_SETGID","CAP_SETUID","CAP_SETFCAP","CAP_SETPCAP","CAP_NET_BIND_SERVICE","CAP_SYS_CHROOT","CAP_KILL","CAP_AUDIT_WRITE"],"effective":["CAP_CHOWN","CAP_DAC_OVERRIDE","CAP_FSETID","CAP_FOWNER","CAP_MKNOD","CAP_NET_RAW","CAP_SETGID","CAP_SETUID","CAP_SETFCAP","CAP_SETPCAP","CAP_NET_BIND_SERVICE","CAP_SYS_CHROOT","CAP_KILL","CAP_AUDIT_WRITE"],"inheritable":["CAP_CHOWN","CAP_DAC_OVERRIDE","CAP_FSETID","CAP_FOWNER","CAP_MKNOD","CAP_NET_RAW","CAP_SETGID","CAP_SETUID","CAP_SETFCAP","CAP_SETPCAP","CAP_NET_BIND_SERVICE","CAP_SYS_CHROOT","CAP_KILL","CAP_AUDIT_WRITE"],"permitted":["CAP_CHOWN","CAP_DAC_OVERRIDE","CAP_FSETID","CAP_FOWNER","CAP_MKNOD","CAP_NET_RAW","CAP_SETGID","CAP_SETUID","CAP_SETFCAP","CAP_SETPCAP","CAP_NET_BIND_SERVICE","CAP_SYS_CHROOT","CAP_KILL","CAP_AUDIT_WRITE"]}}} com.github.containers.virtcontainers.pkg.oci.container_type:pod_sandbox com.github.containers.virtcontainers.pkg.oci.bundle_path:/tmp/tmp.945S2mjriV/crio-run/overlay-containers/b717351723b30fd969cb1e8176ceb3afbfdbc8e8cc97cdeb7bba18159871b00b/userdata]} Created:2018-03-06 16:14:39.521291887 +0000 UTC m=+1.053975514 Started:2018-03-06 16:14:41.457981905 +0000 UTC m=+2.990665432 Finished:0001-01-01 00:00:00 +0000 UTC ExitCode:0 OOMKilled:false Error:}"
# time="2018-03-06 16:14:41.625788190Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.945S2mjriV/crio+/tmp/tmp.945S2mjriV/crio-run]docker.io/library/redis:alpine""
# time="2018-03-06 16:14:41.626493594Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.945S2mjriV/crio+/tmp/tmp.945S2mjriV/crio-run]docker.io/library/redis:alpine""
# time="2018-03-06 16:14:41.627036698Z" level=debug msg="exporting opaque data as blob "sha256:d3117424aaee14ab2b0edb68d3e3dcc1785b2e243b06bd6322f299284c640465""
# time="2018-03-06 16:14:41.711373843Z" level=debug msg="created container "da09679b72424fbbf6948c8662a6b9dd41457a915577e482578763334135a0f0""
# time="2018-03-06 16:14:41.771020828Z" level=debug msg="container "da09679b72424fbbf6948c8662a6b9dd41457a915577e482578763334135a0f0" has work directory "/tmp/tmp.945S2mjriV/crio/overlay-containers/da09679b72424fbbf6948c8662a6b9dd41457a915577e482578763334135a0f0/userdata""
# time="2018-03-06 16:14:41.771080929Z" level=debug msg="container "da09679b72424fbbf6948c8662a6b9dd41457a915577e482578763334135a0f0" has run directory "/tmp/tmp.945S2mjriV/crio-run/overlay-containers/da09679b72424fbbf6948c8662a6b9dd41457a915577e482578763334135a0f0/userdata""
# time="2018-03-06 16:14:41.866654846Z" level=debug msg="mounted container "da09679b72424fbbf6948c8662a6b9dd41457a915577e482578763334135a0f0" at "/tmp/tmp.945S2mjriV/crio/overlay/2ed53ffa1914ca6c493194bebb7dab67264754002172b730b7ae2b2541943a77/merged""
# time="2018-03-06 16:14:41.866713647Z" level=debug msg="OCI process args [docker-entrypoint.sh docker-entrypoint.sh redis-server]"
# time="2018-03-06 16:14:41.867052849Z" level=debug msg="CONTAINER USER: "
# time="2018-03-06 16:14:41.867262250Z" level=debug msg="UID: 0, GID: 0, Groups: [0 1 2 3 4 6 10 11 20 26 27]"
# time="2018-03-06 16:14:41.868000055Z" level=debug msg="running conmon: /root/go/src/github.com/kubernetes-incubator/cri-o/bin/conmon" args=[-c da09679b72424fbbf6948c8662a6b9dd41457a915577e482578763334135a0f0 -u da09679b72424fbbf6948c8662a6b9dd41457a915577e482578763334135a0f0 -r /usr/local/bin/cc-runtime -b /tmp/tmp.945S2mjriV/crio-run/overlay-containers/da09679b72424fbbf6948c8662a6b9dd41457a915577e482578763334135a0f0/userdata -p /tmp/tmp.945S2mjriV/crio-run/overlay-containers/da09679b72424fbbf6948c8662a6b9dd41457a915577e482578763334135a0f0/userdata/pidfile -l /var/log/crio/pods/b717351723b30fd969cb1e8176ceb3afbfdbc8e8cc97cdeb7bba18159871b00b/da09679b72424fbbf6948c8662a6b9dd41457a915577e482578763334135a0f0.log --exit-dir /var/run/crio/exits --socket-dir-path /var/run/crio]
# time="2018-03-06 16:14:41.976276055Z" level=debug msg="Received container pid: 64406"
# time="2018-03-06 16:14:42.068184649Z" level=debug msg="CreateContainerResponse: &CreateContainerResponse{ContainerId:da09679b72424fbbf6948c8662a6b9dd41457a915577e482578763334135a0f0,}"
# da09679b72424fbbf6948c8662a6b9dd41457a915577e482578763334135a0f0
# time="2018-03-06 16:14:42.126655627Z" level=debug msg="StartContainerRequest &StartContainerRequest{ContainerId:da09679b72424fbbf6948c8662a6b9dd41457a915577e482578763334135a0f0,}"
# time="2018-03-06 16:14:42.365098268Z" level=debug msg="StartContainerResponse &StartContainerResponse{}"
# da09679b72424fbbf6948c8662a6b9dd41457a915577e482578763334135a0f0
# time="2018-03-06 16:14:42.579011551Z" level=debug msg="StopContainerRequest &StopContainerRequest{ContainerId:da09679b72424fbbf6948c8662a6b9dd41457a915577e482578763334135a0f0,Timeout:10,}"
# time="2018-03-06 16:14:42.809640441Z" level=debug msg="event: "/var/run/crio/exits/da09679b72424fbbf6948c8662a6b9dd41457a915577e482578763334135a0f0.RR2ZEZ": CREATE"
# time="2018-03-06 16:14:42.809675342Z" level=debug msg="container or sandbox exited: da09679b72424fbbf6948c8662a6b9dd41457a915577e482578763334135a0f0.RR2ZEZ"
# time="2018-03-06 16:14:42.809710242Z" level=debug msg="event: "/var/run/crio/exits/da09679b72424fbbf6948c8662a6b9dd41457a915577e482578763334135a0f0.RR2ZEZ": WRITE"
# time="2018-03-06 16:14:42.809761442Z" level=debug msg="event: "/var/run/crio/exits/da09679b72424fbbf6948c8662a6b9dd41457a915577e482578763334135a0f0.RR2ZEZ": RENAME"
# time="2018-03-06 16:14:42.809785542Z" level=debug msg="event: "/var/run/crio/exits/da09679b72424fbbf6948c8662a6b9dd41457a915577e482578763334135a0f0": CREATE"
# time="2018-03-06 16:14:42.809827243Z" level=debug msg="container or sandbox exited: da09679b72424fbbf6948c8662a6b9dd41457a915577e482578763334135a0f0"
# time="2018-03-06 16:14:42.809849843Z" level=debug msg="container exited and found: da09679b72424fbbf6948c8662a6b9dd41457a915577e482578763334135a0f0"
# time="2018-03-06 16:14:43.016398478Z" level=debug msg="unmounted container "da09679b72424fbbf6948c8662a6b9dd41457a915577e482578763334135a0f0""
# time="2018-03-06 16:14:43.200080965Z" level=debug msg="StopContainerResponse da09679b72424fbbf6948c8662a6b9dd41457a915577e482578763334135a0f0: &StopContainerResponse{}"
# da09679b72424fbbf6948c8662a6b9dd41457a915577e482578763334135a0f0
# time="2018-03-06 16:14:43.265170086Z" level=debug msg="StopContainerRequest &StopContainerRequest{ContainerId:da09679b72424fbbf6948c8662a6b9dd41457a915577e482578763334135a0f0,Timeout:10,}"
# time="2018-03-06 16:14:43.354483263Z" level=debug msg="StopContainerResponse da09679b72424fbbf6948c8662a6b9dd41457a915577e482578763334135a0f0: &StopContainerResponse{}"
# da09679b72424fbbf6948c8662a6b9dd41457a915577e482578763334135a0f0
# time="2018-03-06 16:14:43.424075413Z" level=debug msg="ListContainersRequest &ListContainersRequest{Filter:&ContainerFilter{Id:,State:nil,PodSandboxId:,LabelSelector:map[string]string{},},}"
# time="2018-03-06 16:14:43.424135413Z" level=debug msg="ListContainersResponse: &ListContainersResponse{Containers:[&Container{Id:da09679b72424fbbf6948c8662a6b9dd41457a915577e482578763334135a0f0,PodSandboxId:b717351723b30fd969cb1e8176ceb3afbfdbc8e8cc97cdeb7bba18159871b00b,Metadata:&ContainerMetadata{Name:podsandbox1-redis,Attempt:0,},Image:&ImageSpec{Image:docker.io/library/redis:alpine,},ImageRef:,State:CONTAINER_EXITED,CreatedAt:1520352881626441994,Labels:map[string]string{tier: backend,},Annotations:map[string]string{pod: podsandbox1,},}],}"
# time="2018-03-06 16:14:43.492688656Z" level=debug msg="StopContainerRequest &StopContainerRequest{ContainerId:da09679b72424fbbf6948c8662a6b9dd41457a915577e482578763334135a0f0,Timeout:10,}"
# time="2018-03-06 16:14:43.599481646Z" level=debug msg="StopContainerResponse da09679b72424fbbf6948c8662a6b9dd41457a915577e482578763334135a0f0: &StopContainerResponse{}"
# da09679b72424fbbf6948c8662a6b9dd41457a915577e482578763334135a0f0
# time="2018-03-06 16:14:43.663762762Z" level=debug msg="RemoveContainerRequest: &RemoveContainerRequest{ContainerId:da09679b72424fbbf6948c8662a6b9dd41457a915577e482578763334135a0f0,}"
# time="2018-03-06 16:14:43.761292992Z" level=debug msg="event: "/var/run/crio/exits/da09679b72424fbbf6948c8662a6b9dd41457a915577e482578763334135a0f0": REMOVE"
# time="2018-03-06 16:14:43.863177251Z" level=debug msg="RemoveContainerResponse: &RemoveContainerResponse{}"
# da09679b72424fbbf6948c8662a6b9dd41457a915577e482578763334135a0f0
# time="2018-03-06 16:14:43.938261836Z" level=debug msg="ListPodSandboxRequest &ListPodSandboxRequest{Filter:&PodSandboxFilter{Id:,State:nil,LabelSelector:map[string]string{},},}"
# time="2018-03-06 16:14:43.938342137Z" level=debug msg="ListPodSandboxResponse &ListPodSandboxResponse{Items:[&PodSandbox{Id:b717351723b30fd969cb1e8176ceb3afbfdbc8e8cc97cdeb7bba18159871b00b,Metadata:&PodSandboxMetadata{Name:podsandbox1,Uid:redhat-test-crio,Namespace:redhat.test.crio,Attempt:1,},State:SANDBOX_READY,CreatedAt:1520352879521291887,Labels:map[string]string{group: test,io.kubernetes.container.name: POD,},Annotations:map[string]string{owner: hmeng,security.alpha.kubernetes.io/seccomp/pod: unconfined,},}],}"
# time="2018-03-06 16:14:44.009529297Z" level=debug msg="StopPodSandboxRequest &StopPodSandboxRequest{PodSandboxId:b717351723b30fd969cb1e8176ceb3afbfdbc8e8cc97cdeb7bba18159871b00b,}"
# time="2018-03-06 16:14:44.009576897Z" level=info msg="Got pod network {Name:podsandbox1 Namespace:redhat.test.crio ID:b717351723b30fd969cb1e8176ceb3afbfdbc8e8cc97cdeb7bba18159871b00b NetNS:/var/run/netns/k8s_podsandbox1_redhat.test.crio_redhat-test-crio_1-e51ace73 PortMappings:[]}"
# time="2018-03-06 16:14:44.009609797Z" level=info msg="About to del CNI network crionet (type=bridge)"
# time="2018-03-06 16:14:44.211899005Z" level=debug msg="event: "/var/run/crio/exits/b717351723b30fd969cb1e8176ceb3afbfdbc8e8cc97cdeb7bba18159871b00b.J2ZEFZ": CREATE"
# time="2018-03-06 16:14:44.211931305Z" level=debug msg="container or sandbox exited: b717351723b30fd969cb1e8176ceb3afbfdbc8e8cc97cdeb7bba18159871b00b.J2ZEFZ"
# time="2018-03-06 16:14:44.212057706Z" level=debug msg="event: "/var/run/crio/exits/b717351723b30fd969cb1e8176ceb3afbfdbc8e8cc97cdeb7bba18159871b00b.J2ZEFZ": WRITE"
# time="2018-03-06 16:14:44.212134206Z" level=debug msg="event: "/var/run/crio/exits/b717351723b30fd969cb1e8176ceb3afbfdbc8e8cc97cdeb7bba18159871b00b.J2ZEFZ": WRITE"
# time="2018-03-06 16:14:44.212246007Z" level=debug msg="event: "/var/run/crio/exits/b717351723b30fd969cb1e8176ceb3afbfdbc8e8cc97cdeb7bba18159871b00b.J2ZEFZ": RENAME"
# time="2018-03-06 16:14:44.212320307Z" level=debug msg="event: "/var/run/crio/exits/b717351723b30fd969cb1e8176ceb3afbfdbc8e8cc97cdeb7bba18159871b00b": CREATE"
# time="2018-03-06 16:14:44.212333107Z" level=debug msg="container or sandbox exited: b717351723b30fd969cb1e8176ceb3afbfdbc8e8cc97cdeb7bba18159871b00b"
# time="2018-03-06 16:14:44.212354008Z" level=debug msg="sandbox exited and found: b717351723b30fd969cb1e8176ceb3afbfdbc8e8cc97cdeb7bba18159871b00b"
# time="2018-03-06 16:14:44.517021877Z" level=debug msg="unmounted container "b717351723b30fd969cb1e8176ceb3afbfdbc8e8cc97cdeb7bba18159871b00b""
# time="2018-03-06 16:14:44.517103577Z" level=debug msg="StopPodSandboxResponse b717351723b30fd969cb1e8176ceb3afbfdbc8e8cc97cdeb7bba18159871b00b: &StopPodSandboxResponse{}"
# Stopped sandbox b717351723b30fd969cb1e8176ceb3afbfdbc8e8cc97cdeb7bba18159871b00b
# time="2018-03-06 16:14:44.603724137Z" level=debug msg="RemovePodSandboxRequest &RemovePodSandboxRequest{PodSandboxId:b717351723b30fd969cb1e8176ceb3afbfdbc8e8cc97cdeb7bba18159871b00b,}"
# time="2018-03-06 16:14:44.760045847Z" level=debug msg="Failed to unmount de6d3e250c2f8d12eafc7d48b843a70aa674decb58b370228f2ac42c7f8539ff overlay: /tmp/tmp.945S2mjriV/crio/overlay/de6d3e250c2f8d12eafc7d48b843a70aa674decb58b370228f2ac42c7f8539ff/merged - invalid argument"
# time="2018-03-06 16:14:44.811284578Z" level=debug msg="unmounted container "b717351723b30fd969cb1e8176ceb3afbfdbc8e8cc97cdeb7bba18159871b00b""
# time="2018-03-06 16:14:44.918329870Z" level=debug msg="RemovePodSandboxResponse &RemovePodSandboxResponse{}"
# Removed sandbox b717351723b30fd969cb1e8176ceb3afbfdbc8e8cc97cdeb7bba18159871b00b
# time="2018-03-06 16:14:44.933969671Z" level=debug msg="Caught SIGTERM"
# time="2018-03-06 16:14:44.934968178Z" level=debug msg="closing exit monitor..."
# time="2018-03-06 16:14:44.935006278Z" level=debug msg="closed exit monitor"
# time="2018-03-06 16:14:44.935018678Z" level=debug msg="closed main server"
# 0
# rm: cannot remove '/tmp/tmp.945S2mjriV/crio/overlay': Device or resource busy
not ok 22 ctr caps drop
# (from function `cleanup_test' in file helpers.bash, line 357,
#  from function `teardown' in test file ctr.bats, line 6)
#   `cleanup_test' failed
#   4bb936f5c6c600e7cd97a69ef7c5dcf795a124f5166fbd6770e5e49264059717
# 0
# time="2018-03-06 16:14:58.839538428Z" level=debug msg="backingFs=extfs,  projectQuotaSupported=false"
# time="2018-03-06 16:14:58.839595328Z" level=info msg="[graphdriver] using prior storage driver: overlay"
# time="2018-03-06 16:14:58.841142938Z" level=info msg="CNI network crionet (type=bridge) is used from /tmp/tmp.J7rPNAGLve/cni/net.d/10-crio.conf"
# time="2018-03-06 16:14:58.841381440Z" level=info msg="CNI network crionet (type=bridge) is used from /tmp/tmp.J7rPNAGLve/cni/net.d/10-crio.conf"
# time="2018-03-06 16:14:58.850758100Z" level=debug msg="seccomp status: true"
# time="2018-03-06 16:14:58.851500105Z" level=debug msg="Golang's threads limit set to 115110"
# time="2018-03-06 16:14:58.855194329Z" level=debug msg="sandboxes: []"
# time="2018-03-06 16:14:58.855354030Z" level=error msg="Failed to start streaming server: listen tcp 10.1.10.4:10010: bind: address already in use"
# time="2018-03-06 16:14:58.907345266Z" level=debug msg="closed stream server"
# time="2018-03-06 16:14:58.949734640Z" level=debug msg="ImageStatusRequest: &ImageStatusRequest{Image:&ImageSpec{Image:redis:alpine,},Verbose:false,}"
# time="2018-03-06 16:14:58.949814240Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.J7rPNAGLve/crio+/tmp/tmp.J7rPNAGLve/crio-run]docker.io/library/redis:alpine""
# time="2018-03-06 16:14:58.950521345Z" level=debug msg="[graphdriver] trying provided driver "overlay""
# time="2018-03-06 16:14:58.972592687Z" level=debug msg="backingFs=extfs,  projectQuotaSupported=false"
# time="2018-03-06 16:14:58.973430293Z" level=debug msg="ImageStatusResponse: &ImageStatusResponse{Image:&Image{Id:c35354e5b807c22da222dc6d8ad61d091874724dfded0cff211637e45e57c2c8,RepoTags:[docker.io/library/redis:alpine redis:alpine],RepoDigests:[],Size_:27082854,Uid:nil,Username:,},Info:map[string]string{},}"
# time="2018-03-06 16:14:59.051163895Z" level=debug msg="ImageStatusRequest: &ImageStatusRequest{Image:&ImageSpec{Image:redis:alpine,},Verbose:false,}"
# time="2018-03-06 16:14:59.051220895Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.J7rPNAGLve/crio+/tmp/tmp.J7rPNAGLve/crio-run]docker.io/library/redis:alpine""
# time="2018-03-06 16:14:59.051613898Z" level=debug msg="ImageStatusResponse: &ImageStatusResponse{Image:&Image{Id:c35354e5b807c22da222dc6d8ad61d091874724dfded0cff211637e45e57c2c8,RepoTags:[docker.io/library/redis:alpine redis:alpine],RepoDigests:[],Size_:27082854,Uid:nil,Username:,},Info:map[string]string{},}"
# time="2018-03-06 16:14:59.102311425Z" level=debug msg="ImageStatusRequest: &ImageStatusRequest{Image:&ImageSpec{Image:mrunalp/oom,},Verbose:false,}"
# time="2018-03-06 16:14:59.102408626Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.J7rPNAGLve/crio+/tmp/tmp.J7rPNAGLve/crio-run]docker.io/mrunalp/oom:latest""
# time="2018-03-06 16:14:59.102693528Z" level=debug msg="ImageStatusResponse: &ImageStatusResponse{Image:&Image{Id:bf07983d6c64f91fd274f6dd356a106ddbfb4ff0fc9f70a4a3d85bcf39e5ea51,RepoTags:[docker.io/mrunalp/oom:latest mrunalp/oom docker.io/library/mrunalp/oom],RepoDigests:[],Size_:5719481,Uid:nil,Username:,},Info:map[string]string{},}"
# time="2018-03-06 16:14:59.156249774Z" level=debug msg="ImageStatusRequest: &ImageStatusRequest{Image:&ImageSpec{Image:redis@sha256:03789f402b2ecfb98184bf128d180f398f81c63364948ff1454583b02442f73b,},Verbose:false,}"
# time="2018-03-06 16:14:59.156365175Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.J7rPNAGLve/crio+/tmp/tmp.J7rPNAGLve/crio-run]docker.io/library/redis:latest@03789f402b2ecfb98184bf128d180f398f81c63364948ff1454583b02442f73b""
# time="2018-03-06 16:14:59.156782377Z" level=debug msg="ImageStatusResponse: &ImageStatusResponse{Image:&Image{Id:03789f402b2ecfb98184bf128d180f398f81c63364948ff1454583b02442f73b,RepoTags:[docker.io/library/redis:latest redis@sha256:03789f402b2ecfb98184bf128d180f398f81c63364948ff1454583b02442f73b docker.io/library/redis@sha256:03789f402b2ecfb98184bf128d180f398f81c63364948ff1454583b02442f73b],RepoDigests:[],Size_:19826844,Uid:nil,Username:,},Info:map[string]string{},}"
# time="2018-03-06 16:14:59.210641025Z" level=debug msg="ImageStatusRequest: &ImageStatusRequest{Image:&ImageSpec{Image:runcom/stderr-test,},Verbose:false,}"
# time="2018-03-06 16:14:59.210726026Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.J7rPNAGLve/crio+/tmp/tmp.J7rPNAGLve/crio-run]docker.io/runcom/stderr-test:latest""
# time="2018-03-06 16:14:59.211183929Z" level=debug msg="ImageStatusResponse: &ImageStatusResponse{Image:&Image{Id:c4d4ba443b58e24e56d13c2dbda24aa402b3826ba5ea55e728e95998042afeac,RepoTags:[docker.io/runcom/stderr-test:latest runcom/stderr-test:latest],RepoDigests:[],Size_:4906035,Uid:nil,Username:,},Info:map[string]string{},}"
# time="2018-03-06 16:14:59.278343163Z" level=debug msg="ImageStatusRequest: &ImageStatusRequest{Image:&ImageSpec{Image:runcom/stderr-test,},Verbose:false,}"
# time="2018-03-06 16:14:59.278400163Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.J7rPNAGLve/crio+/tmp/tmp.J7rPNAGLve/crio-run]docker.io/runcom/stderr-test:latest""
# time="2018-03-06 16:14:59.278693965Z" level=debug msg="ImageStatusResponse: &ImageStatusResponse{Image:&Image{Id:c4d4ba443b58e24e56d13c2dbda24aa402b3826ba5ea55e728e95998042afeac,RepoTags:[docker.io/runcom/stderr-test:latest runcom/stderr-test:latest],RepoDigests:[],Size_:4906035,Uid:nil,Username:,},Info:map[string]string{},}"
# time="2018-03-06 16:14:59.330782101Z" level=debug msg="ImageStatusRequest: &ImageStatusRequest{Image:&ImageSpec{Image:busybox,},Verbose:false,}"
# time="2018-03-06 16:14:59.330831502Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.J7rPNAGLve/crio+/tmp/tmp.J7rPNAGLve/crio-run]docker.io/library/busybox:latest""
# time="2018-03-06 16:14:59.331375405Z" level=debug msg="ImageStatusResponse: &ImageStatusResponse{Image:&Image{Id:8af6eae0084273d62e30b13d4f495006c92d99f88465d35818cb2af8d776e38e,RepoTags:[docker.io/library/busybox:latest busybox:latest],RepoDigests:[],Size_:1148393,Uid:nil,Username:,},Info:map[string]string{},}"
# time="2018-03-06 16:14:59.405063481Z" level=debug msg="ImageStatusRequest: &ImageStatusRequest{Image:&ImageSpec{Image:busybox,},Verbose:false,}"
# time="2018-03-06 16:14:59.405142182Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.J7rPNAGLve/crio+/tmp/tmp.J7rPNAGLve/crio-run]docker.io/library/busybox:latest""
# time="2018-03-06 16:14:59.405506684Z" level=debug msg="ImageStatusResponse: &ImageStatusResponse{Image:&Image{Id:8af6eae0084273d62e30b13d4f495006c92d99f88465d35818cb2af8d776e38e,RepoTags:[docker.io/library/busybox:latest busybox:latest],RepoDigests:[],Size_:1148393,Uid:nil,Username:,},Info:map[string]string{},}"
# time="2018-03-06 16:14:59.463156157Z" level=debug msg="ImageStatusRequest: &ImageStatusRequest{Image:&ImageSpec{Image:mrunalp/image-volume-test,},Verbose:false,}"
# time="2018-03-06 16:14:59.463231657Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.J7rPNAGLve/crio+/tmp/tmp.J7rPNAGLve/crio-run]docker.io/mrunalp/image-volume-test:latest""
# time="2018-03-06 16:14:59.463512059Z" level=debug msg="ImageStatusResponse: &ImageStatusResponse{Image:&Image{Id:2a6e4fddfeabc3216a4767324e0b1e11e1e5c3968e256115df1d6a4b74574092,RepoTags:[docker.io/mrunalp/image-volume-test:latest mrunalp/image-volume-test docker.io/library/mrunalp/image-volume-test],RepoDigests:[],Size_:1095628,Uid:nil,Username:,},Info:map[string]string{},}"
# time="2018-03-06 16:14:59.529085783Z" level=debug msg="ImageStatusRequest: &ImageStatusRequest{Image:&ImageSpec{Image:mrunalp/image-volume-test,},Verbose:false,}"
# time="2018-03-06 16:14:59.529143783Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.J7rPNAGLve/crio+/tmp/tmp.J7rPNAGLve/crio-run]docker.io/mrunalp/image-volume-test:latest""
# time="2018-03-06 16:14:59.529385385Z" level=debug msg="ImageStatusResponse: &ImageStatusResponse{Image:&Image{Id:2a6e4fddfeabc3216a4767324e0b1e11e1e5c3968e256115df1d6a4b74574092,RepoTags:[docker.io/mrunalp/image-volume-test:latest mrunalp/image-volume-test docker.io/library/mrunalp/image-volume-test],RepoDigests:[],Size_:1095628,Uid:nil,Username:,},Info:map[string]string{},}"
# time="2018-03-06 16:14:59.578771104Z" level=debug msg="RunPodSandboxRequest &RunPodSandboxRequest{Config:&PodSandboxConfig{Metadata:&PodSandboxMetadata{Name:podsandbox1,Uid:redhat-test-crio,Namespace:redhat.test.crio,Attempt:1,},Hostname:crictl_host,LogDirectory:,DnsConfig:&DNSConfig{Servers:[],Searches:[8.8.8.8],Options:[],},PortMappings:[],Labels:map[string]string{group: test,},Annotations:map[string]string{owner: hmeng,security.alpha.kubernetes.io/seccomp/pod: unconfined,},Linux:&LinuxPodSandboxConfig{CgroupParent:/Burstable/pod_123-456,SecurityContext:&LinuxSandboxSecurityContext{NamespaceOptions:&NamespaceOption{HostNetwork:false,HostPid:false,HostIpc:false,},SelinuxOptions:&SELinuxOption{User:system_u,Role:system_r,Type:svirt_lxc_net_t,Level:s0:c4,c5,},RunAsUser:nil,ReadonlyRootfs:false,SupplementalGroups:[],Privileged:false,SeccompProfilePath:,},Sysctls:map[string]string{},},},}"
# time="2018-03-06 16:14:59.578823804Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.J7rPNAGLve/crio+/tmp/tmp.J7rPNAGLve/crio-run]docker.io/kubernetes/pause:latest""
# time="2018-03-06 16:14:59.579227807Z" level=debug msg="exporting opaque data as blob "sha256:c713bfa1b66381820184edb919bad71ac5aa56a93a1f2fc2c4c7f4d69effe351""
# time="2018-03-06 16:14:59.659570026Z" level=debug msg="created pod sandbox "4bb936f5c6c600e7cd97a69ef7c5dcf795a124f5166fbd6770e5e49264059717""
# time="2018-03-06 16:14:59.721123323Z" level=debug msg="pod sandbox "4bb936f5c6c600e7cd97a69ef7c5dcf795a124f5166fbd6770e5e49264059717" has work directory "/tmp/tmp.J7rPNAGLve/crio/overlay-containers/4bb936f5c6c600e7cd97a69ef7c5dcf795a124f5166fbd6770e5e49264059717/userdata""
# time="2018-03-06 16:14:59.721195624Z" level=debug msg="pod sandbox "4bb936f5c6c600e7cd97a69ef7c5dcf795a124f5166fbd6770e5e49264059717" has run directory "/tmp/tmp.J7rPNAGLve/crio-run/overlay-containers/4bb936f5c6c600e7cd97a69ef7c5dcf795a124f5166fbd6770e5e49264059717/userdata""
# time="2018-03-06 16:14:59.862137034Z" level=debug msg="mounted container "4bb936f5c6c600e7cd97a69ef7c5dcf795a124f5166fbd6770e5e49264059717" at "/tmp/tmp.J7rPNAGLve/crio/overlay/f4cddb31e0a8dc17457f403314bd55ce5e7c6980388698a686a2e477427b20b7/merged""
# time="2018-03-06 16:14:59.862240335Z" level=info msg="Got pod network {Name:podsandbox1 Namespace:redhat.test.crio ID:4bb936f5c6c600e7cd97a69ef7c5dcf795a124f5166fbd6770e5e49264059717 NetNS:/var/run/netns/k8s_podsandbox1_redhat.test.crio_redhat-test-crio_1-fc0aa455 PortMappings:[]}"
# time="2018-03-06 16:14:59.862253435Z" level=info msg="About to add CNI network cni-loopback (type=loopback)"
# time="2018-03-06 16:14:59.865581957Z" level=info msg="Got pod network {Name:podsandbox1 Namespace:redhat.test.crio ID:4bb936f5c6c600e7cd97a69ef7c5dcf795a124f5166fbd6770e5e49264059717 NetNS:/var/run/netns/k8s_podsandbox1_redhat.test.crio_redhat-test-crio_1-fc0aa455 PortMappings:[]}"
# time="2018-03-06 16:14:59.865598157Z" level=info msg="About to add CNI network crionet (type=bridge)"
# time="2018-03-06 16:14:59.945884375Z" level=debug msg="running conmon: /root/go/src/github.com/kubernetes-incubator/cri-o/bin/conmon" args=[-c 4bb936f5c6c600e7cd97a69ef7c5dcf795a124f5166fbd6770e5e49264059717 -u 4bb936f5c6c600e7cd97a69ef7c5dcf795a124f5166fbd6770e5e49264059717 -r /usr/local/bin/cc-runtime -b /tmp/tmp.J7rPNAGLve/crio-run/overlay-containers/4bb936f5c6c600e7cd97a69ef7c5dcf795a124f5166fbd6770e5e49264059717/userdata -p /tmp/tmp.J7rPNAGLve/crio-run/overlay-containers/4bb936f5c6c600e7cd97a69ef7c5dcf795a124f5166fbd6770e5e49264059717/userdata/pidfile -l /var/log/crio/pods/4bb936f5c6c600e7cd97a69ef7c5dcf795a124f5166fbd6770e5e49264059717/4bb936f5c6c600e7cd97a69ef7c5dcf795a124f5166fbd6770e5e49264059717.log --exit-dir /var/run/crio/exits --socket-dir-path /var/run/crio]
# time="2018-03-06 16:15:01.370651780Z" level=debug msg="Received container pid: 65827"
# time="2018-03-06 16:15:01.721613947Z" level=debug msg="RunPodSandboxResponse: &RunPodSandboxResponse{PodSandboxId:4bb936f5c6c600e7cd97a69ef7c5dcf795a124f5166fbd6770e5e49264059717,}"
# 4bb936f5c6c600e7cd97a69ef7c5dcf795a124f5166fbd6770e5e49264059717
# NAME:
#    crictl create - Create a new container
#
# USAGE:
#    crictl create SANDBOX container-config.[json|yaml] sandbox-config.[json|yaml]
# time="2018-03-06 16:15:01.870119307Z" level=debug msg="ListContainersRequest &ListContainersRequest{Filter:&ContainerFilter{Id:,State:nil,PodSandboxId:,LabelSelector:map[string]string{},},}"
# time="2018-03-06 16:15:01.870154007Z" level=debug msg="ListContainersResponse: &ListContainersResponse{Containers:[],}"
# time="2018-03-06 16:15:01.942839777Z" level=debug msg="ListPodSandboxRequest &ListPodSandboxRequest{Filter:&PodSandboxFilter{Id:,State:nil,LabelSelector:map[string]string{},},}"
# time="2018-03-06 16:15:01.942893577Z" level=debug msg="ListPodSandboxResponse &ListPodSandboxResponse{Items:[&PodSandbox{Id:4bb936f5c6c600e7cd97a69ef7c5dcf795a124f5166fbd6770e5e49264059717,Metadata:&PodSandboxMetadata{Name:podsandbox1,Uid:redhat-test-crio,Namespace:redhat.test.crio,Attempt:1,},State:SANDBOX_READY,CreatedAt:1520352899769104133,Labels:map[string]string{group: test,io.kubernetes.container.name: POD,},Annotations:map[string]string{owner: hmeng,security.alpha.kubernetes.io/seccomp/pod: unconfined,},}],}"
# time="2018-03-06 16:15:02.018719867Z" level=debug msg="StopPodSandboxRequest &StopPodSandboxRequest{PodSandboxId:4bb936f5c6c600e7cd97a69ef7c5dcf795a124f5166fbd6770e5e49264059717,}"
# time="2018-03-06 16:15:02.018773667Z" level=info msg="Got pod network {Name:podsandbox1 Namespace:redhat.test.crio ID:4bb936f5c6c600e7cd97a69ef7c5dcf795a124f5166fbd6770e5e49264059717 NetNS:/var/run/netns/k8s_podsandbox1_redhat.test.crio_redhat-test-crio_1-fc0aa455 PortMappings:[]}"
# time="2018-03-06 16:15:02.018784867Z" level=info msg="About to del CNI network crionet (type=bridge)"
# time="2018-03-06 16:15:02.218670258Z" level=debug msg="event: "/var/run/crio/exits/4bb936f5c6c600e7cd97a69ef7c5dcf795a124f5166fbd6770e5e49264059717.3FKFFZ": RENAME"
# time="2018-03-06 16:15:02.218707259Z" level=debug msg="event: "/var/run/crio/exits/4bb936f5c6c600e7cd97a69ef7c5dcf795a124f5166fbd6770e5e49264059717": CREATE"
# time="2018-03-06 16:15:02.218720359Z" level=debug msg="container or sandbox exited: 4bb936f5c6c600e7cd97a69ef7c5dcf795a124f5166fbd6770e5e49264059717"
# time="2018-03-06 16:15:02.218778659Z" level=debug msg="sandbox exited and found: 4bb936f5c6c600e7cd97a69ef7c5dcf795a124f5166fbd6770e5e49264059717"
# time="2018-03-06 16:15:02.522360420Z" level=debug msg="unmounted container "4bb936f5c6c600e7cd97a69ef7c5dcf795a124f5166fbd6770e5e49264059717""
# time="2018-03-06 16:15:02.522394821Z" level=debug msg="StopPodSandboxResponse 4bb936f5c6c600e7cd97a69ef7c5dcf795a124f5166fbd6770e5e49264059717: &StopPodSandboxResponse{}"
# Stopped sandbox 4bb936f5c6c600e7cd97a69ef7c5dcf795a124f5166fbd6770e5e49264059717
# time="2018-03-06 16:15:02.613142807Z" level=debug msg="RemovePodSandboxRequest &RemovePodSandboxRequest{PodSandboxId:4bb936f5c6c600e7cd97a69ef7c5dcf795a124f5166fbd6770e5e49264059717,}"
# time="2018-03-06 16:15:02.782822603Z" level=debug msg="Failed to unmount f4cddb31e0a8dc17457f403314bd55ce5e7c6980388698a686a2e477427b20b7 overlay: /tmp/tmp.J7rPNAGLve/crio/overlay/f4cddb31e0a8dc17457f403314bd55ce5e7c6980388698a686a2e477427b20b7/merged - invalid argument"
# time="2018-03-06 16:15:02.822054256Z" level=debug msg="unmounted container "4bb936f5c6c600e7cd97a69ef7c5dcf795a124f5166fbd6770e5e49264059717""
# time="2018-03-06 16:15:02.961962860Z" level=debug msg="RemovePodSandboxResponse &RemovePodSandboxResponse{}"
# Removed sandbox 4bb936f5c6c600e7cd97a69ef7c5dcf795a124f5166fbd6770e5e49264059717
# time="2018-03-06 16:15:02.977888763Z" level=debug msg="Caught SIGTERM"
# time="2018-03-06 16:15:02.978152165Z" level=debug msg="closing exit monitor..."
# time="2018-03-06 16:15:02.978203265Z" level=debug msg="closed exit monitor"
# time="2018-03-06 16:15:02.978219465Z" level=debug msg="closed main server"
# 0
# rm: cannot remove '/tmp/tmp.J7rPNAGLve/crio/overlay': Device or resource busy
not ok 23 run ctr with image with Config.Volumes
# (from function `cleanup_test' in file helpers.bash, line 357,
#  from function `teardown' in test file ctr.bats, line 6)
#   `cleanup_test' failed
#   a43f157329eb43b581b099ebb026093e6de3710b15c346d1ea0f9c2c9ee608ad
# 0
# time="2018-03-06 16:15:14.862225426Z" level=debug msg="backingFs=extfs,  projectQuotaSupported=false"
# time="2018-03-06 16:15:14.862293526Z" level=info msg="[graphdriver] using prior storage driver: overlay"
# time="2018-03-06 16:15:14.863878837Z" level=info msg="CNI network crionet (type=bridge) is used from /tmp/tmp.9p9cpoYk1j/cni/net.d/10-crio.conf"
# time="2018-03-06 16:15:14.864062038Z" level=info msg="CNI network crionet (type=bridge) is used from /tmp/tmp.9p9cpoYk1j/cni/net.d/10-crio.conf"
# time="2018-03-06 16:15:14.872455792Z" level=debug msg="seccomp status: true"
# time="2018-03-06 16:15:14.873114596Z" level=debug msg="Golang's threads limit set to 115110"
# time="2018-03-06 16:15:14.876686319Z" level=debug msg="sandboxes: []"
# time="2018-03-06 16:15:14.876793720Z" level=error msg="Failed to start streaming server: listen tcp 10.1.10.4:10010: bind: address already in use"
# time="2018-03-06 16:15:14.915203768Z" level=debug msg="closed stream server"
# time="2018-03-06 16:15:14.971318830Z" level=debug msg="ImageStatusRequest: &ImageStatusRequest{Image:&ImageSpec{Image:redis:alpine,},Verbose:false,}"
# time="2018-03-06 16:15:14.971436131Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.9p9cpoYk1j/crio+/tmp/tmp.9p9cpoYk1j/crio-run]docker.io/library/redis:alpine""
# time="2018-03-06 16:15:14.972023535Z" level=debug msg="[graphdriver] trying provided driver "overlay""
# time="2018-03-06 16:15:14.994737482Z" level=debug msg="backingFs=extfs,  projectQuotaSupported=false"
# time="2018-03-06 16:15:14.995564287Z" level=debug msg="ImageStatusResponse: &ImageStatusResponse{Image:&Image{Id:1ab388ebc306fdb71343081e08bfe024fffc7858635cabc11d7118809479c843,RepoTags:[docker.io/library/redis:alpine redis:alpine],RepoDigests:[],Size_:27082854,Uid:nil,Username:,},Info:map[string]string{},}"
# time="2018-03-06 16:15:15.069761766Z" level=debug msg="ImageStatusRequest: &ImageStatusRequest{Image:&ImageSpec{Image:redis:alpine,},Verbose:false,}"
# time="2018-03-06 16:15:15.069819567Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.9p9cpoYk1j/crio+/tmp/tmp.9p9cpoYk1j/crio-run]docker.io/library/redis:alpine""
# time="2018-03-06 16:15:15.070224269Z" level=debug msg="ImageStatusResponse: &ImageStatusResponse{Image:&Image{Id:1ab388ebc306fdb71343081e08bfe024fffc7858635cabc11d7118809479c843,RepoTags:[docker.io/library/redis:alpine redis:alpine],RepoDigests:[],Size_:27082854,Uid:nil,Username:,},Info:map[string]string{},}"
# time="2018-03-06 16:15:15.120352793Z" level=debug msg="ImageStatusRequest: &ImageStatusRequest{Image:&ImageSpec{Image:mrunalp/oom,},Verbose:false,}"
# time="2018-03-06 16:15:15.120450294Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.9p9cpoYk1j/crio+/tmp/tmp.9p9cpoYk1j/crio-run]docker.io/mrunalp/oom:latest""
# time="2018-03-06 16:15:15.120691995Z" level=debug msg="ImageStatusResponse: &ImageStatusResponse{Image:&Image{Id:0b9cae7ad0bbbfb511c5ee0f114aad3584c0e447928128145ce05028ec0e2b4c,RepoTags:[docker.io/mrunalp/oom:latest mrunalp/oom docker.io/library/mrunalp/oom],RepoDigests:[],Size_:5719481,Uid:nil,Username:,},Info:map[string]string{},}"
# time="2018-03-06 16:15:15.175391848Z" level=debug msg="ImageStatusRequest: &ImageStatusRequest{Image:&ImageSpec{Image:redis@sha256:03789f402b2ecfb98184bf128d180f398f81c63364948ff1454583b02442f73b,},Verbose:false,}"
# time="2018-03-06 16:15:15.175480249Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.9p9cpoYk1j/crio+/tmp/tmp.9p9cpoYk1j/crio-run]docker.io/library/redis:latest@03789f402b2ecfb98184bf128d180f398f81c63364948ff1454583b02442f73b""
# time="2018-03-06 16:15:15.175805351Z" level=debug msg="ImageStatusResponse: &ImageStatusResponse{Image:&Image{Id:03789f402b2ecfb98184bf128d180f398f81c63364948ff1454583b02442f73b,RepoTags:[docker.io/library/redis:latest redis@sha256:03789f402b2ecfb98184bf128d180f398f81c63364948ff1454583b02442f73b docker.io/library/redis@sha256:03789f402b2ecfb98184bf128d180f398f81c63364948ff1454583b02442f73b],RepoDigests:[],Size_:19826844,Uid:nil,Username:,},Info:map[string]string{},}"
# time="2018-03-06 16:15:15.229276296Z" level=debug msg="ImageStatusRequest: &ImageStatusRequest{Image:&ImageSpec{Image:runcom/stderr-test,},Verbose:false,}"
# time="2018-03-06 16:15:15.229347197Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.9p9cpoYk1j/crio+/tmp/tmp.9p9cpoYk1j/crio-run]docker.io/runcom/stderr-test:latest""
# time="2018-03-06 16:15:15.229695499Z" level=debug msg="ImageStatusResponse: &ImageStatusResponse{Image:&Image{Id:f2308032f546bd80b84e94b143aa8d61eea1c4e352fa4900f731406c979fdd3d,RepoTags:[docker.io/runcom/stderr-test:latest runcom/stderr-test:latest],RepoDigests:[],Size_:4906035,Uid:nil,Username:,},Info:map[string]string{},}"
# time="2018-03-06 16:15:15.292023502Z" level=debug msg="ImageStatusRequest: &ImageStatusRequest{Image:&ImageSpec{Image:runcom/stderr-test,},Verbose:false,}"
# time="2018-03-06 16:15:15.292123902Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.9p9cpoYk1j/crio+/tmp/tmp.9p9cpoYk1j/crio-run]docker.io/runcom/stderr-test:latest""
# time="2018-03-06 16:15:15.292457204Z" level=debug msg="ImageStatusResponse: &ImageStatusResponse{Image:&Image{Id:f2308032f546bd80b84e94b143aa8d61eea1c4e352fa4900f731406c979fdd3d,RepoTags:[docker.io/runcom/stderr-test:latest runcom/stderr-test:latest],RepoDigests:[],Size_:4906035,Uid:nil,Username:,},Info:map[string]string{},}"
# time="2018-03-06 16:15:15.342312926Z" level=debug msg="ImageStatusRequest: &ImageStatusRequest{Image:&ImageSpec{Image:busybox,},Verbose:false,}"
# time="2018-03-06 16:15:15.342430127Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.9p9cpoYk1j/crio+/tmp/tmp.9p9cpoYk1j/crio-run]docker.io/library/busybox:latest""
# time="2018-03-06 16:15:15.342656829Z" level=debug msg="ImageStatusResponse: &ImageStatusResponse{Image:&Image{Id:2041dc91b4028cb585daf3b400ca38f3549bcd8d857c5a9eb2ce183880f0c0cd,RepoTags:[docker.io/library/busybox:latest busybox:latest],RepoDigests:[],Size_:1148393,Uid:nil,Username:,},Info:map[string]string{},}"
# time="2018-03-06 16:15:15.404747230Z" level=debug msg="ImageStatusRequest: &ImageStatusRequest{Image:&ImageSpec{Image:busybox,},Verbose:false,}"
# time="2018-03-06 16:15:15.404800430Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.9p9cpoYk1j/crio+/tmp/tmp.9p9cpoYk1j/crio-run]docker.io/library/busybox:latest""
# time="2018-03-06 16:15:15.405007831Z" level=debug msg="ImageStatusResponse: &ImageStatusResponse{Image:&Image{Id:2041dc91b4028cb585daf3b400ca38f3549bcd8d857c5a9eb2ce183880f0c0cd,RepoTags:[docker.io/library/busybox:latest busybox:latest],RepoDigests:[],Size_:1148393,Uid:nil,Username:,},Info:map[string]string{},}"
# time="2018-03-06 16:15:15.456872066Z" level=debug msg="ImageStatusRequest: &ImageStatusRequest{Image:&ImageSpec{Image:mrunalp/image-volume-test,},Verbose:false,}"
# time="2018-03-06 16:15:15.456951467Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.9p9cpoYk1j/crio+/tmp/tmp.9p9cpoYk1j/crio-run]docker.io/mrunalp/image-volume-test:latest""
# time="2018-03-06 16:15:15.457360769Z" level=debug msg="ImageStatusResponse: &ImageStatusResponse{Image:&Image{Id:0bbf81f0d1421ae9827d24af5cecee23546f486f6773bd998188ce2d3b7f3af4,RepoTags:[docker.io/mrunalp/image-volume-test:latest mrunalp/image-volume-test docker.io/library/mrunalp/image-volume-test],RepoDigests:[],Size_:1095628,Uid:nil,Username:,},Info:map[string]string{},}"
# time="2018-03-06 16:15:15.521750785Z" level=debug msg="ImageStatusRequest: &ImageStatusRequest{Image:&ImageSpec{Image:mrunalp/image-volume-test,},Verbose:false,}"
# time="2018-03-06 16:15:15.521816986Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.9p9cpoYk1j/crio+/tmp/tmp.9p9cpoYk1j/crio-run]docker.io/mrunalp/image-volume-test:latest""
# time="2018-03-06 16:15:15.522071987Z" level=debug msg="ImageStatusResponse: &ImageStatusResponse{Image:&Image{Id:0bbf81f0d1421ae9827d24af5cecee23546f486f6773bd998188ce2d3b7f3af4,RepoTags:[docker.io/mrunalp/image-volume-test:latest mrunalp/image-volume-test docker.io/library/mrunalp/image-volume-test],RepoDigests:[],Size_:1095628,Uid:nil,Username:,},Info:map[string]string{},}"
# time="2018-03-06 16:15:15.570763802Z" level=debug msg="PullImageRequest: &PullImageRequest{Image:&ImageSpec{Image:gcr.io/k8s-testimages/redis:e2e,},Auth:nil,SandboxConfig:nil,}"
# time="2018-03-06 16:15:15.573110217Z" level=debug msg="Using registries.d directory /etc/containers/registries.d for sigstore configuration"
# time="2018-03-06 16:15:15.573139417Z" level=debug msg=" No signature storage configuration found for gcr.io/k8s-testimages/redis:e2e"
# time="2018-03-06 16:15:15.573157817Z" level=debug msg="Looking for TLS certificates and private keys in /etc/docker/certs.d/gcr.io"
# time="2018-03-06 16:15:15.573187017Z" level=debug msg="GET https://gcr.io/v2/"
# time="2018-03-06 16:15:15.684701138Z" level=debug msg="Ping https://gcr.io/v2/ err <nil>"
# time="2018-03-06 16:15:15.684718138Z" level=debug msg="Ping https://gcr.io/v2/ status 401"
# time="2018-03-06 16:15:15.773340610Z" level=debug msg="GET https://gcr.io/v2/k8s-testimages/redis/manifests/e2e"
# time="2018-03-06 16:15:16.101930832Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.9p9cpoYk1j/crio+/tmp/tmp.9p9cpoYk1j/crio-run]gcr.io/k8s-testimages/redis:e2e""
# time="2018-03-06 16:15:16.104233047Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.9p9cpoYk1j/crio+/tmp/tmp.9p9cpoYk1j/crio-run]gcr.io/k8s-testimages/redis:e2e""
# time="2018-03-06 16:15:16.104290847Z" level=debug msg="Using registries.d directory /etc/containers/registries.d for sigstore configuration"
# time="2018-03-06 16:15:16.104321747Z" level=debug msg=" No signature storage configuration found for gcr.io/k8s-testimages/redis:e2e"
# time="2018-03-06 16:15:16.104335248Z" level=debug msg="Looking for TLS certificates and private keys in /etc/docker/certs.d/gcr.io"
# time="2018-03-06 16:15:16.104349748Z" level=debug msg="IsRunningImageAllowed for image docker:gcr.io/k8s-testimages/redis:e2e"
# time="2018-03-06 16:15:16.104358948Z" level=debug msg=" Using default policy section"
# time="2018-03-06 16:15:16.104366748Z" level=debug msg=" Requirement 0: allowed"
# time="2018-03-06 16:15:16.104376548Z" level=debug msg="Overall: allowed"
# time="2018-03-06 16:15:16.104402848Z" level=debug msg="GET https://gcr.io/v2/"
# time="2018-03-06 16:15:16.156249483Z" level=debug msg="Ping https://gcr.io/v2/ err <nil>"
# time="2018-03-06 16:15:16.156271383Z" level=debug msg="Ping https://gcr.io/v2/ status 401"
# time="2018-03-06 16:15:16.245707261Z" level=debug msg="GET https://gcr.io/v2/k8s-testimages/redis/manifests/e2e"
# time="2018-03-06 16:15:16.535492132Z" level=debug msg="Downloading /v2/k8s-testimages/redis/blobs/sha256:efb2a4d85bb46d42299e8270c823d2557332f896ae0bcbeadbc542a32c0bf064"
# time="2018-03-06 16:15:16.535520132Z" level=debug msg="GET https://gcr.io/v2/k8s-testimages/redis/blobs/sha256:efb2a4d85bb46d42299e8270c823d2557332f896ae0bcbeadbc542a32c0bf064"
# time="2018-03-06 16:15:16.621308486Z" level=debug msg="Manifest has MIME type application/vnd.docker.distribution.manifest.v2+json, ordered candidate list [application/vnd.docker.distribution.manifest.v2+json, application/vnd.docker.distribution.manifest.v1+prettyjws, application/vnd.docker.distribution.manifest.v1+json]"
# time="2018-03-06 16:15:16.621332186Z" level=debug msg="... will first try using the original manifest unmodified"
# time="2018-03-06 16:15:16.621348686Z" level=debug msg="Downloading /v2/k8s-testimages/redis/blobs/sha256:627beaf3eaaff1c0bc3311d60fb933c17ad04fe377e1043d9593646d8ae3bfe1"
# time="2018-03-06 16:15:16.621365987Z" level=debug msg="GET https://gcr.io/v2/k8s-testimages/redis/blobs/sha256:627beaf3eaaff1c0bc3311d60fb933c17ad04fe377e1043d9593646d8ae3bfe1"
# time="2018-03-06 16:15:16.710359961Z" level=debug msg="Detected compression format gzip"
# time="2018-03-06 16:15:16.710492662Z" level=debug msg="Using original blob without modification"
# time="2018-03-06 16:15:16.761848994Z" level=debug msg="Applying tar in /tmp/tmp.9p9cpoYk1j/crio/overlay/0e6b308d3f79eb5ebaa1f9477de9aef407034e584f15a745357a4aceca44c5ce/diff"
# time="2018-03-06 16:15:17.219799551Z" level=debug msg="blob "sha256:627beaf3eaaff1c0bc3311d60fb933c17ad04fe377e1043d9593646d8ae3bfe1" imported as a filesystem layer "0e6b308d3f79eb5ebaa1f9477de9aef407034e584f15a745357a4aceca44c5ce""
# time="2018-03-06 16:15:17.219888252Z" level=debug msg="Downloading /v2/k8s-testimages/redis/blobs/sha256:44ec03acd10f9d89954de485ff0f96f9d9b876891c20f5250b6edd4729351e3f"
# time="2018-03-06 16:15:17.219915752Z" level=debug msg="GET https://gcr.io/v2/k8s-testimages/redis/blobs/sha256:44ec03acd10f9d89954de485ff0f96f9d9b876891c20f5250b6edd4729351e3f"
# time="2018-03-06 16:15:17.310902739Z" level=debug msg="Detected compression format gzip"
# time="2018-03-06 16:15:17.310958640Z" level=debug msg="Using original blob without modification"
# time="2018-03-06 16:15:17.361558867Z" level=debug msg="Applying tar in /tmp/tmp.9p9cpoYk1j/crio/overlay/edc0030a10315a3935b402734c09abe06a3be73d74a055dbe2f1a9a8c38134c4/diff"
# time="2018-03-06 16:15:17.761416349Z" level=debug msg="blob "sha256:44ec03acd10f9d89954de485ff0f96f9d9b876891c20f5250b6edd4729351e3f" imported as a filesystem layer "edc0030a10315a3935b402734c09abe06a3be73d74a055dbe2f1a9a8c38134c4""
# time="2018-03-06 16:15:17.761498349Z" level=debug msg="Downloading /v2/k8s-testimages/redis/blobs/sha256:b1ea3176af88147251afe85cfb3bd58214f11ad2b64d66518f0a067a703b7678"
# time="2018-03-06 16:15:17.761520049Z" level=debug msg="GET https://gcr.io/v2/k8s-testimages/redis/blobs/sha256:b1ea3176af88147251afe85cfb3bd58214f11ad2b64d66518f0a067a703b7678"
# time="2018-03-06 16:15:17.847096902Z" level=debug msg="Detected compression format gzip"
# time="2018-03-06 16:15:17.847165903Z" level=debug msg="Using original blob without modification"
# time="2018-03-06 16:15:17.873828775Z" level=debug msg="Applying tar in /tmp/tmp.9p9cpoYk1j/crio/overlay/7da07dc67d88fd652bb15cda3271cbfe5f3f03c7aecddda44ef1df6a5394efd3/diff"
# time="2018-03-06 16:15:18.220278712Z" level=debug msg="blob "sha256:b1ea3176af88147251afe85cfb3bd58214f11ad2b64d66518f0a067a703b7678" imported as a filesystem layer "7da07dc67d88fd652bb15cda3271cbfe5f3f03c7aecddda44ef1df6a5394efd3""
# time="2018-03-06 16:15:18.220424913Z" level=debug msg="No compression detected"
# time="2018-03-06 16:15:18.220483913Z" level=debug msg="Using original blob without modification"
# time="2018-03-06 16:15:18.220557214Z" level=debug msg="blob "sha256:efb2a4d85bb46d42299e8270c823d2557332f896ae0bcbeadbc542a32c0bf064" imported as opaque data "sha256:efb2a4d85bb46d42299e8270c823d2557332f896ae0bcbeadbc542a32c0bf064""
# time="2018-03-06 16:15:18.268402023Z" level=debug msg="created new image ID "951d3d356c9e546e2d6dce38a3364a79afe9144612f71e5f9870afd4d21cd491""
# time="2018-03-06 16:15:18.318909149Z" level=debug msg="set names of image "951d3d356c9e546e2d6dce38a3364a79afe9144612f71e5f9870afd4d21cd491" to [gcr.io/k8s-testimages/redis:e2e]"
# time="2018-03-06 16:15:18.568746662Z" level=debug msg="saved image metadata "{\"tag\":\"gcr.io/k8s-testimages/redis:e2e\",\"created-time\":\"2018-03-06T16:15:16.104254147Z\",\"id\":\"951d3d356c9e546e2d6dce38a3364a79afe9144612f71e5f9870afd4d21cd491\",\"blob-list\":[{\"Digest\":\"sha256:627beaf3eaaff1c0bc3311d60fb933c17ad04fe377e1043d9593646d8ae3bfe1\",\"Size\":1905270,\"URLs\":null,\"Annotations\":null},{\"Digest\":\"sha256:44ec03acd10f9d89954de485ff0f96f9d9b876891c20f5250b6edd4729351e3f\",\"Size\":880824,\"URLs\":null,\"Annotations\":null},{\"Digest\":\"sha256:b1ea3176af88147251afe85cfb3bd58214f11ad2b64d66518f0a067a703b7678\",\"Size\":160,\"URLs\":null,\"Annotations\":null},{\"Digest\":\"sha256:efb2a4d85bb46d42299e8270c823d2557332f896ae0bcbeadbc542a32c0bf064\",\"Size\":2271,\"URLs\":null,\"Annotations\":null}],\"layers\":{\"sha256:44ec03acd10f9d89954de485ff0f96f9d9b876891c20f5250b6edd4729351e3f\":[\"edc0030a10315a3935b402734c09abe06a3be73d74a055dbe2f1a9a8c38134c4\"],\"sha256:627beaf3eaaff1c0bc3311d60fb933c17ad04fe377e1043d9593646d8ae3bfe1\":[\"0e6b308d3f79eb5ebaa1f9477de9aef407034e584f15a745357a4aceca44c5ce\"],\"sha256:b1ea3176af88147251afe85cfb3bd58214f11ad2b64d66518f0a067a703b7678\":[\"7da07dc67d88fd652bb15cda3271cbfe5f3f03c7aecddda44ef1df6a5394efd3\"]},\"signature-sizes\":[]}""
# time="2018-03-06 16:15:18.568776062Z" level=debug msg="PullImageResponse: &PullImageResponse{ImageRef:gcr.io/k8s-testimages/redis:e2e,}"
# Image is update to date for gcr.io/k8s-testimages/redis:e2e
# time="2018-03-06 16:15:18.626019632Z" level=debug msg="RunPodSandboxRequest &RunPodSandboxRequest{Config:&PodSandboxConfig{Metadata:&PodSandboxMetadata{Name:podsandbox1,Uid:redhat-test-crio,Namespace:redhat.test.crio,Attempt:1,},Hostname:crictl_host,LogDirectory:,DnsConfig:&DNSConfig{Servers:[],Searches:[8.8.8.8],Options:[],},PortMappings:[],Labels:map[string]string{group: test,},Annotations:map[string]string{owner: hmeng,security.alpha.kubernetes.io/seccomp/pod: unconfined,},Linux:&LinuxPodSandboxConfig{CgroupParent:/Burstable/pod_123-456,SecurityContext:&LinuxSandboxSecurityContext{NamespaceOptions:&NamespaceOption{HostNetwork:false,HostPid:false,HostIpc:false,},SelinuxOptions:&SELinuxOption{User:system_u,Role:system_r,Type:svirt_lxc_net_t,Level:s0:c4,c5,},RunAsUser:nil,ReadonlyRootfs:false,SupplementalGroups:[],Privileged:false,SeccompProfilePath:,},Sysctls:map[string]string{},},},}"
# time="2018-03-06 16:15:18.626121033Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.9p9cpoYk1j/crio+/tmp/tmp.9p9cpoYk1j/crio-run]docker.io/kubernetes/pause:latest""
# time="2018-03-06 16:15:18.626601036Z" level=debug msg="exporting opaque data as blob "sha256:c713bfa1b66381820184edb919bad71ac5aa56a93a1f2fc2c4c7f4d69effe351""
# time="2018-03-06 16:15:18.709665372Z" level=debug msg="created pod sandbox "a43f157329eb43b581b099ebb026093e6de3710b15c346d1ea0f9c2c9ee608ad""
# time="2018-03-06 16:15:18.771276070Z" level=debug msg="pod sandbox "a43f157329eb43b581b099ebb026093e6de3710b15c346d1ea0f9c2c9ee608ad" has work directory "/tmp/tmp.9p9cpoYk1j/crio/overlay-containers/a43f157329eb43b581b099ebb026093e6de3710b15c346d1ea0f9c2c9ee608ad/userdata""
# time="2018-03-06 16:15:18.771355771Z" level=debug msg="pod sandbox "a43f157329eb43b581b099ebb026093e6de3710b15c346d1ea0f9c2c9ee608ad" has run directory "/tmp/tmp.9p9cpoYk1j/crio-run/overlay-containers/a43f157329eb43b581b099ebb026093e6de3710b15c346d1ea0f9c2c9ee608ad/userdata""
# time="2018-03-06 16:15:18.913277887Z" level=debug msg="mounted container "a43f157329eb43b581b099ebb026093e6de3710b15c346d1ea0f9c2c9ee608ad" at "/tmp/tmp.9p9cpoYk1j/crio/overlay/51b287520fbcf8468d7f41e9ba653098ecb105fedb65136a9230f50edddd1337/merged""
# time="2018-03-06 16:15:18.913432588Z" level=info msg="Got pod network {Name:podsandbox1 Namespace:redhat.test.crio ID:a43f157329eb43b581b099ebb026093e6de3710b15c346d1ea0f9c2c9ee608ad NetNS:/var/run/netns/k8s_podsandbox1_redhat.test.crio_redhat-test-crio_1-edcc7df6 PortMappings:[]}"
# time="2018-03-06 16:15:18.913475888Z" level=info msg="About to add CNI network cni-loopback (type=loopback)"
# time="2018-03-06 16:15:18.916979311Z" level=info msg="Got pod network {Name:podsandbox1 Namespace:redhat.test.crio ID:a43f157329eb43b581b099ebb026093e6de3710b15c346d1ea0f9c2c9ee608ad NetNS:/var/run/netns/k8s_podsandbox1_redhat.test.crio_redhat-test-crio_1-edcc7df6 PortMappings:[]}"
# time="2018-03-06 16:15:18.916996711Z" level=info msg="About to add CNI network crionet (type=bridge)"
# time="2018-03-06 16:15:18.995011415Z" level=debug msg="running conmon: /root/go/src/github.com/kubernetes-incubator/cri-o/bin/conmon" args=[-c a43f157329eb43b581b099ebb026093e6de3710b15c346d1ea0f9c2c9ee608ad -u a43f157329eb43b581b099ebb026093e6de3710b15c346d1ea0f9c2c9ee608ad -r /usr/local/bin/cc-runtime -b /tmp/tmp.9p9cpoYk1j/crio-run/overlay-containers/a43f157329eb43b581b099ebb026093e6de3710b15c346d1ea0f9c2c9ee608ad/userdata -p /tmp/tmp.9p9cpoYk1j/crio-run/overlay-containers/a43f157329eb43b581b099ebb026093e6de3710b15c346d1ea0f9c2c9ee608ad/userdata/pidfile -l /var/log/crio/pods/a43f157329eb43b581b099ebb026093e6de3710b15c346d1ea0f9c2c9ee608ad/a43f157329eb43b581b099ebb026093e6de3710b15c346d1ea0f9c2c9ee608ad.log --exit-dir /var/run/crio/exits --socket-dir-path /var/run/crio]
# time="2018-03-06 16:15:20.537607276Z" level=debug msg="Received container pid: 67139"
# time="2018-03-06 16:15:20.888353941Z" level=debug msg="RunPodSandboxResponse: &RunPodSandboxResponse{PodSandboxId:a43f157329eb43b581b099ebb026093e6de3710b15c346d1ea0f9c2c9ee608ad,}"
# a43f157329eb43b581b099ebb026093e6de3710b15c346d1ea0f9c2c9ee608ad
# time="2018-03-06 16:15:20.977188215Z" level=debug msg="CreateContainerRequest &CreateContainerRequest{PodSandboxId:a43f157329eb43b581b099ebb026093e6de3710b15c346d1ea0f9c2c9ee608ad,Config:&ContainerConfig{Metadata:&ContainerMetadata{Name:podsandbox1-redis,Attempt:0,},Image:&ImageSpec{Image:gcr.io/k8s-testimages/redis:e2e,},Command:[],Args:[],WorkingDir:/data,Envs:[&KeyValue{Key:PATH,Value:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin,} &KeyValue{Key:TERM,Value:xterm,} &KeyValue{Key:REDIS_VERSION,Value:3.2.3,} &KeyValue{Key:REDIS_DOWNLOAD_URL,Value:http://download.redis.io/releases/redis-3.2.3.tar.gz,} &KeyValue{Key:REDIS_DOWNLOAD_SHA1,Value:92d6d93ef2efc91e595c8bf578bf72baff397507,}],Mounts:[],Devices:[],Labels:map[string]string{tier: backend,},Annotations:map[string]string{pod: podsandbox1,},LogPath:,Stdin:false,StdinOnce:false,Tty:false,Linux:&LinuxContainerConfig{Resources:&LinuxContainerResources{CpuPeriod:10000,CpuQuota:20000,CpuShares:512,MemoryLimitInBytes:209715200,OomScoreAdj:30,CpusetCpus:,CpusetMems:,},SecurityContext:&LinuxContainerSecurityContext{Capabilities:&Capability{AddCapabilities:[sys_admin],DropCapabilities:[],},Privileged:false,NamespaceOptions:nil,SelinuxOptions:nil,RunAsUser:nil,RunAsUsername:,ReadonlyRootfs:false,SupplementalGroups:[],ApparmorProfile:,SeccompProfilePath:,NoNewPrivs:false,},},},SandboxConfig:&PodSandboxConfig{Metadata:&PodSandboxMetadata{Name:podsandbox1,Uid:redhat-test-crio,Namespace:redhat.test.crio,Attempt:1,},Hostname:crictl_host,LogDirectory:,DnsConfig:&DNSConfig{Servers:[],Searches:[8.8.8.8],Options:[],},PortMappings:[],Labels:map[string]string{group: test,},Annotations:map[string]string{owner: hmeng,security.alpha.kubernetes.io/seccomp/pod: unconfined,},Linux:&LinuxPodSandboxConfig{CgroupParent:/Burstable/pod_123-456,SecurityContext:&LinuxSandboxSecurityContext{NamespaceOptions:&NamespaceOption{HostNetwork:false,HostPid:false,HostIpc:false,},SelinuxOptions:&SELinuxOption{User:system_u,Role:system_r,Type:svirt_lxc_net_t,Level:s0:c4,c5,},RunAsUser:nil,ReadonlyRootfs:false,SupplementalGroups:[],Privileged:false,SeccompProfilePath:,},Sysctls:map[string]string{},},},}"
# time="2018-03-06 16:15:20.977320416Z" level=debug msg="setting container's log_path" ctr.logfile= log_path="/var/log/crio/pods/a43f157329eb43b581b099ebb026093e6de3710b15c346d1ea0f9c2c9ee608ad/59e0cb3027ac38f900f0d0843c525db46870158fa4b4b4e8271119b9184ce5d7.log" sbox.logdir="/var/log/crio/pods/a43f157329eb43b581b099ebb026093e6de3710b15c346d1ea0f9c2c9ee608ad"
# time="2018-03-06 16:15:20.977454316Z" level=debug msg="pod container state &{State:{Version:1.0.1 ID:a43f157329eb43b581b099ebb026093e6de3710b15c346d1ea0f9c2c9ee608ad Status:running Pid:67139 Bundle:/tmp/tmp.9p9cpoYk1j/crio-run/overlay-containers/a43f157329eb43b581b099ebb026093e6de3710b15c346d1ea0f9c2c9ee608ad/userdata Annotations:map[com.github.containers.virtcontainers.pkg.oci.bundle_path:/tmp/tmp.9p9cpoYk1j/crio-run/overlay-containers/a43f157329eb43b581b099ebb026093e6de3710b15c346d1ea0f9c2c9ee608ad/userdata com.github.containers.virtcontainers.pkg.oci.config:{"ociVersion":"1.0.0","root":{"path":"/tmp/tmp.9p9cpoYk1j/crio/overlay/51b287520fbcf8468d7f41e9ba653098ecb105fedb65136a9230f50edddd1337/merged","readonly":true},"hostname":"crictl_host","mounts":[{"destination":"/proc","type":"proc","source":"proc"},{"destination":"/dev","type":"tmpfs","source":"tmpfs","options":["nosuid","strictatime","mode=755","size=65536k"]},{"destination":"/dev/pts","type":"devpts","source":"devpts","options":["nosuid","noexec","newinstance","ptmxmode=0666","mode=0620","gid=5"]},{"destination":"/dev/shm","type":"tmpfs","source":"shm","options":["nosuid","noexec","nodev","mode=1777","size=65536k"]},{"destination":"/dev/mqueue","type":"mqueue","source":"mqueue","options":["nosuid","noexec","nodev"]},{"destination":"/sys","type":"sysfs","source":"sysfs","options":["nosuid","noexec","nodev","ro"]},{"destination":"/etc/resolv.conf","type":"bind","source":"/tmp/tmp.9p9cpoYk1j/crio-run/overlay-containers/a43f157329eb43b581b099ebb026093e6de3710b15c346d1ea0f9c2c9ee608ad/userdata/resolv.conf","options":["ro","bind"]},{"destination":"/etc/hostname","type":"bind","source":"/tmp/tmp.9p9cpoYk1j/crio-run/overlay-containers/a43f157329eb43b581b099ebb026093e6de3710b15c346d1ea0f9c2c9ee608ad/userdata/hostname","options":["ro","bind"]}],"annotations":{"group":"test","io.kubernetes.container.name":"POD","io.kubernetes.cri-o.Annotations":"{\"owner\":\"hmeng\",\"security.alpha.kubernetes.io/seccomp/pod\":\"unconfined\"}","io.kubernetes.cri-o.ContainerID":"a43f157329eb43b581b099ebb026093e6de3710b15c346d1ea0f9c2c9ee608ad","io.kubernetes.cri-o.ContainerName":"k8s_POD_podsandbox1_redhat.test.crio_redhat-test-crio_1","io.kubernetes.cri-o.ContainerType":"sandbox","io.kubernetes.cri-o.Created":"2018-03-06T16:15:18.818667576Z","io.kubernetes.cri-o.HostName":"crictl_host","io.kubernetes.cri-o.HostnamePath":"/tmp/tmp.9p9cpoYk1j/crio-run/overlay-containers/a43f157329eb43b581b099ebb026093e6de3710b15c346d1ea0f9c2c9ee608ad/userdata/hostname","io.kubernetes.cri-o.IP":"10.88.0.225","io.kubernetes.cri-o.KubeName":"podsandbox1","io.kubernetes.cri-o.Labels":"{\"group\":\"test\",\"io.kubernetes.container.name\":\"POD\"}","io.kubernetes.cri-o.LogPath":"/var/log/crio/pods/a43f157329eb43b581b099ebb026093e6de3710b15c346d1ea0f9c2c9ee608ad/a43f157329eb43b581b099ebb026093e6de3710b15c346d1ea0f9c2c9ee608ad.log","io.kubernetes.cri-o.Metadata":"{\"name\":\"podsandbox1\",\"uid\":\"redhat-test-crio\",\"namespace\":\"redhat.test.crio\",\"attempt\":1}","io.kubernetes.cri-o.MountPoint":"/tmp/tmp.9p9cpoYk1j/crio/overlay/51b287520fbcf8468d7f41e9ba653098ecb105fedb65136a9230f50edddd1337/merged","io.kubernetes.cri-o.Name":"k8s_podsandbox1_redhat.test.crio_redhat-test-crio_1","io.kubernetes.cri-o.PrivilegedRuntime":"false","io.kubernetes.cri-o.ResolvPath":"/tmp/tmp.9p9cpoYk1j/crio-run/overlay-containers/a43f157329eb43b581b099ebb026093e6de3710b15c346d1ea0f9c2c9ee608ad/userdata/resolv.conf","io.kubernetes.cri-o.SandboxID":"a43f157329eb43b581b099ebb026093e6de3710b15c346d1ea0f9c2c9ee608ad","io.kubernetes.cri-o.SeccompProfilePath":"","io.kubernetes.cri-o.ShmPath":"/tmp/tmp.9p9cpoYk1j/crio-run/overlay-containers/a43f157329eb43b581b099ebb026093e6de3710b15c346d1ea0f9c2c9ee608ad/userdata/shm","io.kubernetes.cri-o.TrustedSandbox":"true","owner":"hmeng","security.alpha.kubernetes.io/seccomp/pod":"unconfined"},"linux":{"resources":{"devices":[{"allow":false,"access":"rwm"}],"cpu":{"shares":2}},"cgroupsPath":"/Burstable/pod_123-456/crio-a43f157329eb43b581b099ebb026093e6de3710b15c346d1ea0f9c2c9ee608ad","namespaces":[{"type":"pid"},{"type":"network","path":"/var/run/netns/k8s_podsandbox1_redhat.test.crio_redhat-test-crio_1-edcc7df6"},{"type":"ipc"},{"type":"uts"},{"type":"mount"}]},"process":{"user":{"uid":0,"gid":0},"args":["/pause"],"env":["PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin","TERM=xterm"],"cwd":"/","rlimits":[{"type":"RLIMIT_NOFILE","hard":1024,"soft":1024}],"oomScoreAdj":-998,"capabilities":{"ambient":["CAP_CHOWN","CAP_DAC_OVERRIDE","CAP_FSETID","CAP_FOWNER","CAP_MKNOD","CAP_NET_RAW","CAP_SETGID","CAP_SETUID","CAP_SETFCAP","CAP_SETPCAP","CAP_NET_BIND_SERVICE","CAP_SYS_CHROOT","CAP_KILL","CAP_AUDIT_WRITE"],"bounding":["CAP_CHOWN","CAP_DAC_OVERRIDE","CAP_FSETID","CAP_FOWNER","CAP_MKNOD","CAP_NET_RAW","CAP_SETGID","CAP_SETUID","CAP_SETFCAP","CAP_SETPCAP","CAP_NET_BIND_SERVICE","CAP_SYS_CHROOT","CAP_KILL","CAP_AUDIT_WRITE"],"effective":["CAP_CHOWN","CAP_DAC_OVERRIDE","CAP_FSETID","CAP_FOWNER","CAP_MKNOD","CAP_NET_RAW","CAP_SETGID","CAP_SETUID","CAP_SETFCAP","CAP_SETPCAP","CAP_NET_BIND_SERVICE","CAP_SYS_CHROOT","CAP_KILL","CAP_AUDIT_WRITE"],"inheritable":["CAP_CHOWN","CAP_DAC_OVERRIDE","CAP_FSETID","CAP_FOWNER","CAP_MKNOD","CAP_NET_RAW","CAP_SETGID","CAP_SETUID","CAP_SETFCAP","CAP_SETPCAP","CAP_NET_BIND_SERVICE","CAP_SYS_CHROOT","CAP_KILL","CAP_AUDIT_WRITE"],"permitted":["CAP_CHOWN","CAP_DAC_OVERRIDE","CAP_FSETID","CAP_FOWNER","CAP_MKNOD","CAP_NET_RAW","CAP_SETGID","CAP_SETUID","CAP_SETFCAP","CAP_SETPCAP","CAP_NET_BIND_SERVICE","CAP_SYS_CHROOT","CAP_KILL","CAP_AUDIT_WRITE"]}}} com.github.containers.virtcontainers.pkg.oci.container_type:pod_sandbox]} Created:2018-03-06 16:15:18.818667576 +0000 UTC m=+4.049715454 Started:2018-03-06 16:15:20.782002854 +0000 UTC m=+6.013050732 Finished:0001-01-01 00:00:00 +0000 UTC ExitCode:0 OOMKilled:false Error:}"
# time="2018-03-06 16:15:20.977548117Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.9p9cpoYk1j/crio+/tmp/tmp.9p9cpoYk1j/crio-run]gcr.io/k8s-testimages/redis:e2e""
# time="2018-03-06 16:15:20.978030920Z" level=debug msg="parsed reference into "[overlay@/tmp/tmp.9p9cpoYk1j/crio+/tmp/tmp.9p9cpoYk1j/crio-run]gcr.io/k8s-testimages/redis:e2e""
# time="2018-03-06 16:15:20.978497923Z" level=debug msg="exporting opaque data as blob "sha256:efb2a4d85bb46d42299e8270c823d2557332f896ae0bcbeadbc542a32c0bf064""
# time="2018-03-06 16:15:21.061334058Z" level=debug msg="created container "59e0cb3027ac38f900f0d0843c525db46870158fa4b4b4e8271119b9184ce5d7""
# time="2018-03-06 16:15:21.120112938Z" level=debug msg="container "59e0cb3027ac38f900f0d0843c525db46870158fa4b4b4e8271119b9184ce5d7" has work directory "/tmp/tmp.9p9cpoYk1j/crio/overlay-containers/59e0cb3027ac38f900f0d0843c525db46870158fa4b4b4e8271119b9184ce5d7/userdata""
# time="2018-03-06 16:15:21.120193038Z" level=debug msg="container "59e0cb3027ac38f900f0d0843c525db46870158fa4b4b4e8271119b9184ce5d7" has run directory "/tmp/tmp.9p9cpoYk1j/crio-run/overlay-containers/59e0cb3027ac38f900f0d0843c525db46870158fa4b4b4e8271119b9184ce5d7/userdata""
# time="2018-03-06 16:15:21.212664335Z" level=debug msg="mounted container "59e0cb3027ac38f900f0d0843c525db46870158fa4b4b4e8271119b9184ce5d7" at "/tmp/tmp.9p9cpoYk1j/crio/overlay/6ee650cf794a1771bbf8d6c374375de1dc956432970a440a854c15861df73402/merged""
# time="2018-03-06 16:15:21.212763236Z" level=debug msg="OCI process args [redis-server /etc/redis.conf]"
# time="2018-03-06 16:15:21.212953237Z" level=debug msg="CONTAINER USER: "
# time="2018-03-06 16:15:21.213190739Z" level=debug msg="UID: 0, GID: 0, Groups: [0 1 2 3 4 6 10 11 20 26 27]"
# time="2018-03-06 16:15:21.213938843Z" level=debug msg="running conmon: /root/go/src/github.com/kubernetes-incubator/cri-o/bin/conmon" args=[-c 59e0cb3027ac38f900f0d0843c525db46870158fa4b4b4e8271119b9184ce5d7 -u 59e0cb3027ac38f900f0d0843c525db46870158fa4b4b4e8271119b9184ce5d7 -r /usr/local/bin/cc-runtime -b /tmp/tmp.9p9cpoYk1j/crio-run/overlay-containers/59e0cb3027ac38f900f0d0843c525db46870158fa4b4b4e8271119b9184ce5d7/userdata -p /tmp/tmp.9p9cpoYk1j/crio-run/overlay-containers/59e0cb3027ac38f900f0d0843c525db46870158fa4b4b4e8271119b9184ce5d7/userdata/pidfile -l /var/log/crio/pods/a43f157329eb43b581b099ebb026093e6de3710b15c346d1ea0f9c2c9ee608ad/59e0cb3027ac38f900f0d0843c525db46870158fa4b4b4e8271119b9184ce5d7.log --exit-dir /var/run/crio/exits --socket-dir-path /var/run/crio]
# time="2018-03-06 16:15:21.341899070Z" level=debug msg="Received container pid: 67223"
# time="2018-03-06 16:15:21.441568413Z" level=debug msg="CreateContainerResponse: &CreateContainerResponse{ContainerId:59e0cb3027ac38f900f0d0843c525db46870158fa4b4b4e8271119b9184ce5d7,}"
# 59e0cb3027ac38f900f0d0843c525db46870158fa4b4b4e8271119b9184ce5d7
# time="2018-03-06 16:15:21.513305277Z" level=debug msg="ListContainersRequest &ListContainersRequest{Filter:&ContainerFilter{Id:,State:nil,PodSandboxId:,LabelSelector:map[string]string{},},}"
# time="2018-03-06 16:15:21.513362177Z" level=debug msg="ListContainersResponse: &ListContainersResponse{Containers:[&Container{Id:59e0cb3027ac38f900f0d0843c525db46870158fa4b4b4e8271119b9184ce5d7,PodSandboxId:a43f157329eb43b581b099ebb026093e6de3710b15c346d1ea0f9c2c9ee608ad,Metadata:&ContainerMetadata{Name:podsandbox1-redis,Attempt:0,},Image:&ImageSpec{Image:gcr.io/k8s-testimages/redis:e2e,},ImageRef:,State:CONTAINER_CREATED,CreatedAt:1520352920977969020,Labels:map[string]string{tier: backend,},Annotations:map[string]string{pod: podsandbox1,},}],}"
# time="2018-03-06 16:15:21.581014414Z" level=debug msg="StopContainerRequest &StopContainerRequest{ContainerId:59e0cb3027ac38f900f0d0843c525db46870158fa4b4b4e8271119b9184ce5d7,Timeout:10,}"
# time="2018-03-06 16:15:31.696915526Z" level=debug msg="event: "/var/run/crio/exits/59e0cb3027ac38f900f0d0843c525db46870158fa4b4b4e8271119b9184ce5d7.8WS1EZ": CREATE"
# time="2018-03-06 16:15:31.696960626Z" level=debug msg="container or sandbox exited: 59e0cb3027ac38f900f0d0843c525db46870158fa4b4b4e8271119b9184ce5d7.8WS1EZ"
# time="2018-03-06 16:15:31.697004627Z" level=debug msg="event: "/var/run/crio/exits/59e0cb3027ac38f900f0d0843c525db46870158fa4b4b4e8271119b9184ce5d7.8WS1EZ": WRITE"
# time="2018-03-06 16:15:31.697857932Z" level=debug msg="event: "/var/run/crio/exits/59e0cb3027ac38f900f0d0843c525db46870158fa4b4b4e8271119b9184ce5d7.8WS1EZ": RENAME"
# time="2018-03-06 16:15:31.697888332Z" level=debug msg="event: "/var/run/crio/exits/59e0cb3027ac38f900f0d0843c525db46870158fa4b4b4e8271119b9184ce5d7": CREATE"
# time="2018-03-06 16:15:31.697899032Z" level=debug msg="container or sandbox exited: 59e0cb3027ac38f900f0d0843c525db46870158fa4b4b4e8271119b9184ce5d7"
# time="2018-03-06 16:15:31.697914132Z" level=debug msg="container exited and found: 59e0cb3027ac38f900f0d0843c525db46870158fa4b4b4e8271119b9184ce5d7"
# time="2018-03-06 16:15:32.224179930Z" level=debug msg="unmounted container "59e0cb3027ac38f900f0d0843c525db46870158fa4b4b4e8271119b9184ce5d7""
# time="2018-03-06 16:15:32.324074975Z" level=debug msg="StopContainerResponse 59e0cb3027ac38f900f0d0843c525db46870158fa4b4b4e8271119b9184ce5d7: &StopContainerResponse{}"
# 59e0cb3027ac38f900f0d0843c525db46870158fa4b4b4e8271119b9184ce5d7
# time="2018-03-06 16:15:32.385010868Z" level=debug msg="RemoveContainerRequest: &RemoveContainerRequest{ContainerId:59e0cb3027ac38f900f0d0843c525db46870158fa4b4b4e8271119b9184ce5d7,}"
# time="2018-03-06 16:15:32.476285257Z" level=debug msg="event: "/var/run/crio/exits/59e0cb3027ac38f900f0d0843c525db46870158fa4b4b4e8271119b9184ce5d7": REMOVE"
# time="2018-03-06 16:15:32.567755748Z" level=debug msg="RemoveContainerResponse: &RemoveContainerResponse{}"
# 59e0cb3027ac38f900f0d0843c525db46870158fa4b4b4e8271119b9184ce5d7
# time="2018-03-06 16:15:32.639625912Z" level=debug msg="ListPodSandboxRequest &ListPodSandboxRequest{Filter:&PodSandboxFilter{Id:,State:nil,LabelSelector:map[string]string{},},}"
# time="2018-03-06 16:15:32.639683012Z" level=debug msg="ListPodSandboxResponse &ListPodSandboxResponse{Items:[&PodSandbox{Id:a43f157329eb43b581b099ebb026093e6de3710b15c346d1ea0f9c2c9ee608ad,Metadata:&PodSandboxMetadata{Name:podsandbox1,Uid:redhat-test-crio,Namespace:redhat.test.crio,Attempt:1,},State:SANDBOX_READY,CreatedAt:1520352918818667576,Labels:map[string]string{group: test,io.kubernetes.container.name: POD,},Annotations:map[string]string{owner: hmeng,security.alpha.kubernetes.io/seccomp/pod: unconfined,},}],}"
# time="2018-03-06 16:15:32.710499469Z" level=debug msg="StopPodSandboxRequest &StopPodSandboxRequest{PodSandboxId:a43f157329eb43b581b099ebb026093e6de3710b15c346d1ea0f9c2c9ee608ad,}"
# time="2018-03-06 16:15:32.710539570Z" level=info msg="Got pod network {Name:podsandbox1 Namespace:redhat.test.crio ID:a43f157329eb43b581b099ebb026093e6de3710b15c346d1ea0f9c2c9ee608ad NetNS:/var/run/netns/k8s_podsandbox1_redhat.test.crio_redhat-test-crio_1-edcc7df6 PortMappings:[]}"
# time="2018-03-06 16:15:32.710552670Z" level=info msg="About to del CNI network crionet (type=bridge)"
# time="2018-03-06 16:15:32.890785433Z" level=debug msg="event: "/var/run/crio/exits/a43f157329eb43b581b099ebb026093e6de3710b15c346d1ea0f9c2c9ee608ad.VEG0EZ": CREATE"
# time="2018-03-06 16:15:32.890823133Z" level=debug msg="container or sandbox exited: a43f157329eb43b581b099ebb026093e6de3710b15c346d1ea0f9c2c9ee608ad.VEG0EZ"
# time="2018-03-06 16:15:32.890858134Z" level=debug msg="event: "/var/run/crio/exits/a43f157329eb43b581b099ebb026093e6de3710b15c346d1ea0f9c2c9ee608ad.VEG0EZ": WRITE"
# time="2018-03-06 16:15:32.890905334Z" level=debug msg="event: "/var/run/crio/exits/a43f157329eb43b581b099ebb026093e6de3710b15c346d1ea0f9c2c9ee608ad.VEG0EZ": RENAME"
# time="2018-03-06 16:15:32.890921734Z" level=debug msg="event: "/var/run/crio/exits/a43f157329eb43b581b099ebb026093e6de3710b15c346d1ea0f9c2c9ee608ad": CREATE"
# time="2018-03-06 16:15:32.890930734Z" level=debug msg="container or sandbox exited: a43f157329eb43b581b099ebb026093e6de3710b15c346d1ea0f9c2c9ee608ad"
# time="2018-03-06 16:15:32.890958734Z" level=debug msg="sandbox exited and found: a43f157329eb43b581b099ebb026093e6de3710b15c346d1ea0f9c2c9ee608ad"
# time="2018-03-06 16:15:33.211255102Z" level=debug msg="unmounted container "a43f157329eb43b581b099ebb026093e6de3710b15c346d1ea0f9c2c9ee608ad""
# time="2018-03-06 16:15:33.211295802Z" level=debug msg="StopPodSandboxResponse a43f157329eb43b581b099ebb026093e6de3710b15c346d1ea0f9c2c9ee608ad: &StopPodSandboxResponse{}"
# Stopped sandbox a43f157329eb43b581b099ebb026093e6de3710b15c346d1ea0f9c2c9ee608ad
# time="2018-03-06 16:15:33.296824754Z" level=debug msg="RemovePodSandboxRequest &RemovePodSandboxRequest{PodSandboxId:a43f157329eb43b581b099ebb026093e6de3710b15c346d1ea0f9c2c9ee608ad,}"
# time="2018-03-06 16:15:33.413629208Z" level=debug msg="Failed to unmount 51b287520fbcf8468d7f41e9ba653098ecb105fedb65136a9230f50edddd1337 overlay: /tmp/tmp.9p9cpoYk1j/crio/overlay/51b287520fbcf8468d7f41e9ba653098ecb105fedb65136a9230f50edddd1337/merged - invalid argument"
# time="2018-03-06 16:15:33.460390010Z" level=debug msg="unmounted container "a43f157329eb43b581b099ebb026093e6de3710b15c346d1ea0f9c2c9ee608ad""
# time="2018-03-06 16:15:33.567235000Z" level=debug msg="RemovePodSandboxResponse &RemovePodSandboxResponse{}"
# Removed sandbox a43f157329eb43b581b099ebb026093e6de3710b15c346d1ea0f9c2c9ee608ad
# time="2018-03-06 16:15:33.582380498Z" level=debug msg="Caught SIGTERM"
# time="2018-03-06 16:15:33.583353404Z" level=debug msg="closing exit monitor..."
# time="2018-03-06 16:15:33.583412904Z" level=debug msg="closed exit monitor"
# time="2018-03-06 16:15:33.583428004Z" level=debug msg="closed main server"
# 0
# rm: cannot remove '/tmp/tmp.9p9cpoYk1j/crio/overlay': Device or resource busy