DEBU[2018-08-02T20:42:19.659196509+09:30] Calling POST /v1.38/containers/create?name=test
DEBU[2018-08-02T20:42:19.659451801+09:30] form data: {"AttachStderr":false,"AttachStdin":false,"AttachStdout":false,"Cmd":null,"Domainname":"","Entrypoint":null,"Env":[],"HostConfig":{"AutoRemove":false,"Binds":null,"BlkioDeviceReadBps":null,"BlkioDeviceReadIOps":null,"BlkioDeviceWriteBps":null,"BlkioDeviceWriteIOps":null,"BlkioWeight":0,"BlkioWeightDevice":[],"CapAdd":null,"CapDrop":null,"Cgroup":"","CgroupParent":"","ConsoleSize":[0,0],"ContainerIDFile":"","CpuCount":0,"CpuPercent":0,"CpuPeriod":0,"CpuQuota":0,"CpuRealtimePeriod":0,"CpuRealtimeRuntime":0,"CpuShares":0,"CpusetCpus":"","CpusetMems":"","DeviceCgroupRules":null,"Devices":[],"DiskQuota":0,"Dns":[],"DnsOptions":[],"DnsSearch":[],"ExtraHosts":null,"GroupAdd":null,"IOMaximumBandwidth":0,"IOMaximumIOps":0,"IpcMode":"","Isolation":"","KernelMemory":0,"Links":null,"LogConfig":{"Config":{},"Type":""},"MaskedPaths":null,"Memory":0,"MemoryReservation":0,"MemorySwap":0,"MemorySwappiness":-1,"NanoCpus":0,"NetworkMode":"default","OomKillDisable":false,"OomScoreAdj":0,"PidMode":"","PidsLimit":0,"PortBindings":{},"Privileged":false,"PublishAllPorts":false,"ReadonlyPaths":null,"ReadonlyRootfs":false,"RestartPolicy":{"MaximumRetryCount":0,"Name":"no"},"SecurityOpt":null,"ShmSize":0,"UTSMode":"","Ulimits":null,"UsernsMode":"","VolumeDriver":"","VolumesFrom":null},"Hostname":"","Image":"test-img","Labels":{},"NetworkingConfig":{"EndpointsConfig":{}},"OnBuild":null,"OpenStdin":false,"StdinOnce":false,"Tty":true,"User":"","Volumes":{},"WorkingDir":""}
DEBU[2018-08-02T20:42:19.685710720+09:30] container mounted via layerStore: &{/var/lib/docker/overlay2/43c56650941b5aa4555675a2a03454d5ba39e004b5a35485579082f27e67a284/merged 0x563659550620 0x563659550620}
DEBU[2018-08-02T20:42:19.710269942+09:30] Calling POST /v1.38/containers/a76510949f21b4b412d542da6a8f7ed8d1272196e61b7563ec55e86d830acbab/wait?condition=next-exit
DEBU[2018-08-02T20:42:19.710968103+09:30] Calling POST /v1.38/containers/a76510949f21b4b412d542da6a8f7ed8d1272196e61b7563ec55e86d830acbab/start
DEBU[2018-08-02T20:42:19.711729425+09:30] container mounted via layerStore: &{/var/lib/docker/overlay2/43c56650941b5aa4555675a2a03454d5ba39e004b5a35485579082f27e67a284/merged 0x563659550620 0x563659550620}
DEBU[2018-08-02T20:42:19.712029857+09:30] Assigning addresses for endpoint test's interface on network bridge
DEBU[2018-08-02T20:42:19.712050804+09:30] RequestAddress(LocalDefault/172.17.0.0/16, <nil>, map[])
DEBU[2018-08-02T20:42:19.712093045+09:30] Request address PoolID:172.17.0.0/16 App: ipam/default/data, ID: LocalDefault/172.17.0.0/16, DBIndex: 0x0, Bits: 65536, Unselected: 65533, Sequence: (0xc0000000, 1)->(0x0, 2046)->(0x1, 1)->end Curr:0 Serial:false PrefAddress:<nil>
DEBU[2018-08-02T20:42:19.725566123+09:30] Assigning addresses for endpoint test's interface on network bridge
DEBU[2018-08-02T20:42:19.730531897+09:30] Programming external connectivity on endpoint test (18cc85b2aa82bd2469e45a63848981a98f10f254af4c6d4a6f031d105678f90a)
DEBU[2018-08-02T20:42:19.731645681+09:30] EnableService a76510949f21b4b412d542da6a8f7ed8d1272196e61b7563ec55e86d830acbab START
DEBU[2018-08-02T20:42:19.731668197+09:30] EnableService a76510949f21b4b412d542da6a8f7ed8d1272196e61b7563ec55e86d830acbab DONE
DEBU[2018-08-02T20:42:19.735644936+09:30] bundle dir created                            bundle=/var/run/docker/containerd/a76510949f21b4b412d542da6a8f7ed8d1272196e61b7563ec55e86d830acbab module=libcontainerd namespace=moby root=/var/lib/docker/overlay2/43c56650941b5aa4555675a2a03454d5ba39e004b5a35485579082f27e67a284/merged
DEBU[0021] event published                               ns=moby topic="/containers/create" type=containerd.events.ContainerCreate
INFO[0021] shim docker-containerd-shim started           address="/containerd-shim/moby/a76510949f21b4b412d542da6a8f7ed8d1272196e61b7563ec55e86d830acbab/shim.sock" debug=true pid=31913
DEBU[0000] registering ttrpc server
DEBU[0000] serving api on unix socket                    socket="[inherited from parent]"
DEBU[2018-08-02T20:42:19.898440359+09:30] sandbox set key processing took 83.523785ms for container a76510949f21b4b412d542da6a8f7ed8d1272196e61b7563ec55e86d830acbab
DEBU[0021] event published                               ns=moby topic="/tasks/create" type=containerd.events.TaskCreate
DEBU[2018-08-02T20:42:20.031385310+09:30] event                                         module=libcontainerd namespace=moby topic=/tasks/create
DEBU[0021] event published                               ns=moby topic="/tasks/start" type=containerd.events.TaskStart
DEBU[2018-08-02T20:42:20.062022159+09:30] event                                         module=libcontainerd namespace=moby topic=/tasks/start
DEBU[2018-08-02T20:42:36.701663225+09:30] Calling GET /_ping
DEBU[2018-08-02T20:42:36.702263403+09:30] Calling GET /v1.38/containers/test/json
DEBU[2018-08-02T20:42:36.704444190+09:30] Calling POST /v1.38/containers/test/exec
DEBU[2018-08-02T20:42:36.704570435+09:30] form data: {"AttachStderr":true,"AttachStdin":true,"AttachStdout":true,"Cmd":["bash"],"Detach":false,"DetachKeys":"","Env":null,"Privileged":false,"Tty":true,"User":"root","WorkingDir":""}
DEBU[2018-08-02T20:42:36.705328270+09:30] Calling POST /v1.38/exec/9d582aa7fd66340d1d50a5b297e6dc1b36bf47ebe0969feb3fb78260ee65cb5b/start
DEBU[2018-08-02T20:42:36.705411634+09:30] form data: {"Detach":false,"Tty":true}
DEBU[2018-08-02T20:42:36.705734424+09:30] starting exec command 9d582aa7fd66340d1d50a5b297e6dc1b36bf47ebe0969feb3fb78260ee65cb5b in container a76510949f21b4b412d542da6a8f7ed8d1272196e61b7563ec55e86d830acbab
DEBU[2018-08-02T20:42:36.706298275+09:30] attach: stdin: begin
DEBU[2018-08-02T20:42:36.706370606+09:30] attach: stdout: begin
DEBU[2018-08-02T20:42:36.707112684+09:30] Calling POST /v1.38/exec/9d582aa7fd66340d1d50a5b297e6dc1b36bf47ebe0969feb3fb78260ee65cb5b/resize?h=78&w=295
ERRO[2018-08-02T20:42:36.772095381+09:30] stream copy error: reading from a closed fifo
DEBU[2018-08-02T20:42:36.772245744+09:30] attach: stdout: end
DEBU[2018-08-02T20:42:36.772269939+09:30] attach: stdin: end
DEBU[2018-08-02T20:42:36.772277942+09:30] attach done
ERRO[2018-08-02T20:42:36.800356562+09:30] Error running exec 9d582aa7fd66340d1d50a5b297e6dc1b36bf47ebe0969feb3fb78260ee65cb5b in container: OCI runtime exec failed: exec failed: container_linux.go:348: starting container process caused "chdir to cwd (\"/test-dir\") set in config.json failed: permission denied": unknown
DEBU[2018-08-02T20:42:36.800419637+09:30] Closing buffered stdin pipe
DEBU[2018-08-02T20:42:36.804904928+09:30] Calling GET /v1.38/exec/9d582aa7fd66340d1d50a5b297e6dc1b36bf47ebe0969feb3fb78260ee65cb5b/json