Start error in lxc-4.0.9

Not able to start the unprivilaged container in lxc-4.0.9 version

/var/lib/lxc$ **lxc-start -n testSystem -f config -l DEBUG -o ll.txt**
lxc-start: testSystem: lxccontainer.c: wait_on_daemonized_start: 869 Received container state "ABORTING" instead of "RUNNING"
lxc-start: testSystem: tools/lxc_start.c: main: 308 The container failed to start
lxc-start: testSystem: tools/lxc_start.c: main: 311 To get more details, run the container in foreground mode
lxc-start: testSystem: tools/lxc_start.c: main: 314 Additional information can be obtained by setting the --logfile and --logpriority options

/var/lib/lxc$ **cat ll.txt** 
lxc-start testSystem 20210812124222.843 INFO     utils - utils.c:get_rundir:260 - XDG_RUNTIME_DIR isn't set in the environment
lxc-start testSystem 20210812124222.843 INFO     confile - confile.c:set_config_idmaps:2049 - Read uid map: type u nsid 0 hostid 231072 range 1002
lxc-start testSystem 20210812124222.843 INFO     confile - confile.c:set_config_idmaps:2049 - Read uid map: type g nsid 0 hostid 231072 range 1003
lxc-start testSystem 20210812124222.843 INFO     confile - confile.c:set_config_idmaps:2049 - Read uid map: type u nsid 1002 hostid 1002 range 1
lxc-start testSystem 20210812124222.843 INFO     confile - confile.c:set_config_idmaps:2049 - Read uid map: type g nsid 1003 hostid 1003 range 1
lxc-start testSystem 20210812124222.843 INFO     confile - confile.c:set_config_idmaps:2049 - Read uid map: type g nsid 1005 hostid 232076 range 64532
lxc-start testSystem 20210812124222.844 INFO     lxccontainer - lxccontainer.c:do_lxcapi_start:988 - Set process title to [lxc monitor] /home/appfw1/.local/share/lxc testSystem
lxc-start testSystem 20210812124222.846 DEBUG    lxccontainer - lxccontainer.c:wait_on_daemonized_start:849 - First child 18992 exited
lxc-start testSystem 20210812124222.848 WARN     apparmor - lsm/apparmor.c:lsm_apparmor_ops_init:1275 - Per-container AppArmor profiles are disabled because the mac_admin capability is missing
lxc-start testSystem 20210812124222.848 INFO     lsm - lsm/lsm.c:lsm_init_static:40 - Initialized LSM security driver AppArmor
lxc-start testSystem 20210812124222.848 INFO     utils - utils.c:get_rundir:260 - XDG_RUNTIME_DIR isn't set in the environment
lxc-start testSystem 20210812124222.848 INFO     terminal - terminal.c:lxc_terminal_setup:972 - No terminal requested
lxc-start testSystem 20210812124222.848 INFO     cgfsng - cgroups/cgfsng.c:legacy_hierarchy_delegated:3084 - Permission denied - The cgroup.procs file is not writable, skipping legacy hierarchy
lxc-start testSystem 20210812124222.848 INFO     cgfsng - cgroups/cgfsng.c:legacy_hierarchy_delegated:3084 - Permission denied - The cgroup.procs file is not writable, skipping legacy hierarchy
lxc-start testSystem 20210812124222.848 INFO     cgfsng - cgroups/cgfsng.c:legacy_hierarchy_delegated:3084 - Permission denied - The cgroup.procs file is not writable, skipping legacy hierarchy
lxc-start testSystem 20210812124222.848 INFO     cgfsng - cgroups/cgfsng.c:legacy_hierarchy_delegated:3084 - Permission denied - The cgroup.procs file is not writable, skipping legacy hierarchy
lxc-start testSystem 20210812124222.848 INFO     cgfsng - cgroups/cgfsng.c:legacy_hierarchy_delegated:3084 - Permission denied - The cgroup.procs file is not writable, skipping legacy hierarchy
lxc-start testSystem 20210812124222.848 INFO     cgfsng - cgroups/cgfsng.c:legacy_hierarchy_delegated:3084 - Permission denied - The cgroup.procs file is not writable, skipping legacy hierarchy
lxc-start testSystem 20210812124222.848 INFO     cgfsng - cgroups/cgfsng.c:legacy_hierarchy_delegated:3084 - Permission denied - The cgroup.procs file is not writable, skipping legacy hierarchy
lxc-start testSystem 20210812124222.848 INFO     cgfsng - cgroups/cgfsng.c:legacy_hierarchy_delegated:3084 - Permission denied - The cgroup.procs file is not writable, skipping legacy hierarchy
lxc-start testSystem 20210812124222.848 INFO     cgfsng - cgroups/cgfsng.c:legacy_hierarchy_delegated:3084 - Permission denied - The cgroup.procs file is not writable, skipping legacy hierarchy
lxc-start testSystem 20210812124222.848 INFO     cgfsng - cgroups/cgfsng.c:legacy_hierarchy_delegated:3084 - Permission denied - The cgroup.procs file is not writable, skipping legacy hierarchy
lxc-start testSystem 20210812124222.848 INFO     cgfsng - cgroups/cgfsng.c:legacy_hierarchy_delegated:3084 - Permission denied - The cgroup.procs file is not writable, skipping legacy hierarchy
lxc-start testSystem 20210812124222.848 INFO     cgfsng - cgroups/cgfsng.c:legacy_hierarchy_delegated:3084 - Permission denied - The cgroup.procs file is not writable, skipping legacy hierarchy
lxc-start testSystem 20210812124222.848 INFO     cgfsng - cgroups/cgfsng.c:unified_hierarchy_delegated:3074 - Permission denied - The cgroup.threads file is not writable, skipping unified hierarchy
lxc-start testSystem 20210812124222.848 WARN     cgroup - cgroups/cgroup.c:cgroup_init:51 - Unsupported cgroup layout
lxc-start testSystem 20210812124222.848 INFO     start - start.c:lxc_init:855 - Container "testSystem" is initialized
lxc-start testSystem 20210812124222.848 ERROR    conf - conf.c:lxc_storage_prepare:490 - Operation not permitted - Failed to recursively turn root mount tree into dependent mount
lxc-start testSystem 20210812124222.848 ERROR    conf - conf.c:lxc_rootfs_init:536 - Invalid argument - Failed to prepare rootfs storage
lxc-start testSystem 20210812124222.848 ERROR    start - start.c:__lxc_start:2045 - Failed to handle rootfs pinning for container "testSystem"
lxc-start testSystem 20210812124222.848 INFO     utils - utils.c:get_rundir:260 - XDG_RUNTIME_DIR isn't set in the environment
lxc-start testSystem 20210812124222.848 INFO     utils - utils.c:get_rundir:260 - XDG_RUNTIME_DIR isn't set in the environment
lxc-start testSystem 20210812124222.848 INFO     utils - utils.c:get_rundir:260 - XDG_RUNTIME_DIR isn't set in the environment
lxc-start testSystem 20210812124222.848 ERROR    lxccontainer - lxccontainer.c:wait_on_daemonized_start:869 - Received container state "ABORTING" instead of "RUNNING"
lxc-start testSystem 20210812124222.848 ERROR    lxc_start - tools/lxc_start.c:main:308 - The container failed to start
lxc-start testSystem 20210812124222.848 ERROR    lxc_start - tools/lxc_start.c:main:311 - To get more details, run the container in foreground mode
lxc-start testSystem 20210812124222.848 ERROR    lxc_start - tools/lxc_start.c:main:314 - Additional information can be obtained by setting the --logfile and --logpriority options
lxc-start testSystem 20210812124646.188 INFO     utils - utils.c:get_rundir:260 - XDG_RUNTIME_DIR isn't set in the environment
lxc-start testSystem 20210812124646.189 INFO     confile - confile.c:set_config_idmaps:2049 - Read uid map: type u nsid 0 hostid 231072 range 1002
lxc-start testSystem 20210812124646.189 INFO     confile - confile.c:set_config_idmaps:2049 - Read uid map: type g nsid 0 hostid 231072 range 1003
lxc-start testSystem 20210812124646.190 INFO     confile - confile.c:set_config_idmaps:2049 - Read uid map: type u nsid 1002 hostid 1002 range 1
lxc-start testSystem 20210812124646.190 INFO     confile - confile.c:set_config_idmaps:2049 - Read uid map: type g nsid 1003 hostid 1003 range 1
lxc-start testSystem 20210812124646.190 INFO     confile - confile.c:set_config_idmaps:2049 - Read uid map: type g nsid 1005 hostid 232076 range 64532
lxc-start testSystem 20210812124646.271 INFO     lxccontainer - lxccontainer.c:do_lxcapi_start:988 - Set process title to [lxc monitor] /home/appfw1/.local/share/lxc testSystem
lxc-start testSystem 20210812124646.317 WARN     apparmor - lsm/apparmor.c:lsm_apparmor_ops_init:1275 - Per-container AppArmor profiles are disabled because the mac_admin capability is missing
lxc-start testSystem 20210812124646.318 INFO     lsm - lsm/lsm.c:lsm_init_static:40 - Initialized LSM security driver AppArmor
lxc-start testSystem 20210812124646.318 INFO     utils - utils.c:get_rundir:260 - XDG_RUNTIME_DIR isn't set in the environment
lxc-start testSystem 20210812124646.321 INFO     terminal - terminal.c:lxc_terminal_setup:972 - No terminal requested
lxc-start testSystem 20210812124646.324 INFO     cgfsng - cgroups/cgfsng.c:legacy_hierarchy_delegated:3084 - Permission denied - The cgroup.procs file is not writable, skipping legacy hierarchy
lxc-start testSystem 20210812124646.324 INFO     cgfsng - cgroups/cgfsng.c:legacy_hierarchy_delegated:3084 - Permission denied - The cgroup.procs file is not writable, skipping legacy hierarchy
lxc-start testSystem 20210812124646.326 INFO     cgfsng - cgroups/cgfsng.c:legacy_hierarchy_delegated:3084 - Permission denied - The cgroup.procs file is not writable, skipping legacy hierarchy
lxc-start testSystem 20210812124646.326 INFO     cgfsng - cgroups/cgfsng.c:legacy_hierarchy_delegated:3084 - Permission denied - The cgroup.procs file is not writable, skipping legacy hierarchy
lxc-start testSystem 20210812124646.326 INFO     cgfsng - cgroups/cgfsng.c:legacy_hierarchy_delegated:3084 - Permission denied - The cgroup.procs file is not writable, skipping legacy hierarchy
lxc-start testSystem 20210812124646.326 INFO     cgfsng - cgroups/cgfsng.c:legacy_hierarchy_delegated:3084 - Permission denied - The cgroup.procs file is not writable, skipping legacy hierarchy
lxc-start testSystem 20210812124646.326 INFO     cgfsng - cgroups/cgfsng.c:legacy_hierarchy_delegated:3084 - Permission denied - The cgroup.procs file is not writable, skipping legacy hierarchy
lxc-start testSystem 20210812124646.327 INFO     cgfsng - cgroups/cgfsng.c:legacy_hierarchy_delegated:3084 - Permission denied - The cgroup.procs file is not writable, skipping legacy hierarchy
lxc-start testSystem 20210812124646.327 INFO     cgfsng - cgroups/cgfsng.c:legacy_hierarchy_delegated:3084 - Permission denied - The cgroup.procs file is not writable, skipping legacy hierarchy
lxc-start testSystem 20210812124646.327 INFO     cgfsng - cgroups/cgfsng.c:legacy_hierarchy_delegated:3084 - Permission denied - The cgroup.procs file is not writable, skipping legacy hierarchy
lxc-start testSystem 20210812124646.327 INFO     cgfsng - cgroups/cgfsng.c:legacy_hierarchy_delegated:3084 - Permission denied - The cgroup.procs file is not writable, skipping legacy hierarchy
lxc-start testSystem 20210812124646.327 INFO     cgfsng - cgroups/cgfsng.c:legacy_hierarchy_delegated:3084 - Permission denied - The cgroup.procs file is not writable, skipping legacy hierarchy
lxc-start testSystem 20210812124646.327 INFO     cgfsng - cgroups/cgfsng.c:unified_hierarchy_delegated:3074 - Permission denied - The cgroup.threads file is not writable, skipping unified hierarchy
lxc-start testSystem 20210812124646.328 WARN     cgroup - cgroups/cgroup.c:cgroup_init:51 - Unsupported cgroup layout
lxc-start testSystem 20210812124646.328 INFO     start - start.c:lxc_init:855 - Container "testSystem" is initialized
lxc-start testSystem 20210812124646.328 ERROR    conf - conf.c:lxc_storage_prepare:490 - Operation not permitted - Failed to recursively turn root mount tree into dependent mount
lxc-start testSystem 20210812124646.328 ERROR    conf - conf.c:lxc_rootfs_init:536 - Invalid argument - Failed to prepare rootfs storage
lxc-start testSystem 20210812124646.328 ERROR    start - start.c:__lxc_start:2045 - Failed to handle rootfs pinning for container "testSystem"
lxc-start testSystem 20210812124646.328 INFO     utils - utils.c:get_rundir:260 - XDG_RUNTIME_DIR isn't set in the environment
lxc-start testSystem 20210812124646.328 INFO     utils - utils.c:get_rundir:260 - XDG_RUNTIME_DIR isn't set in the environment
lxc-start testSystem 20210812124646.329 INFO     utils - utils.c:get_rundir:260 - XDG_RUNTIME_DIR isn't set in the environment
lxc-start testSystem 20210812124646.335 DEBUG    lxccontainer - lxccontainer.c:wait_on_daemonized_start:849 - First child 19026 exited
lxc-start testSystem 20210812124646.335 ERROR    lxccontainer - lxccontainer.c:wait_on_daemonized_start:869 - Received container state "ABORTING" instead of "RUNNING"
lxc-start testSystem 20210812124646.336 ERROR    lxc_start - tools/lxc_start.c:main:308 - The container failed to start
lxc-start testSystem 20210812124646.336 ERROR    lxc_start - tools/lxc_start.c:main:311 - To get more details, run the container in foreground mode
lxc-start testSystem 20210812124646.336 ERROR    lxc_start - tools/lxc_start.c:main:314 - Additional information can be obtained by setting the --logfile and --logpriority options

Hi @brauner @stgraber

Any update on this error?

Sounds like a kernel thing, what kernel are you running?

Kernel version is 4.15.0. Tried with lxc 4.0.10 and the same error is observed.

 (
lxc-start test 20211220165655.263 ERROR    conf - conf.c:lxc_storage_prepare:497 - Operation not permitted - Failed to recursively turn root mount tree into dependent mount
lxc-start test 20211220165655.263 ERROR    conf - conf.c:lxc_rootfs_init:543 - Invalid argument - Failed to prepare rootfs storage
lxc-start test 20211220165655.263 ERROR    start - start.c:__lxc_start:2025 - Failed to handle rootfs pinning for container "test"
)

Is there any place where we can refer to the kernel versions supported each lxc versions released?

@brauner any idea about this particular error?

PS: All the Canonical staff is on holiday break until the 4th of January so responses may be delayed.

What is the linux kernal version required for 4.0.x versions of lxc?

3.13 I believe

Can you show me the output of cat /proc/1/mountinfo from the host you’re starting the container from?

Tried with lxc version 4.0.6 also. below is the error in lxc-execute

$ lxc-execute -n test -f config -- /bin/bash
lxc-execute: test: utils.c: lxc_can_use_pidfd: 1853 Kernel does not support pidfds
lxc-execute: test: conf.c: lxc_setup: 3354 No such file or directory - Unable to open lxc.init.static
lxc-execute: test: start.c: do_start: 1218 Failed to setup container "test"
lxc-execute: test: sync.c: __sync_wait: 36 An error occurred in another process (expected sequence number 5)
lxc-execute: test: start.c: __lxc_start: 1999 Failed to spawn container "test"
lxc-execute: test: tools/lxc_execute.c: main: 226 Failed run an application inside container
cat /proc/1/mountinfo                                                                                                                                                          ✘
21 26 0:20 / /sys rw,nosuid,nodev,noexec,relatime shared:7 - sysfs sysfs rw
22 26 0:4 / /proc rw,nosuid,nodev,noexec,relatime shared:13 - proc proc rw
23 26 0:6 / /dev rw,nosuid,relatime shared:2 - devtmpfs udev rw,size=8144784k,nr_inodes=2036196,mode=755
24 23 0:21 / /dev/pts rw,nosuid,noexec,relatime shared:3 - devpts devpts rw,gid=5,mode=620,ptmxmode=000
25 26 0:22 / /run rw,nosuid,noexec,relatime shared:5 - tmpfs tmpfs rw,size=1633872k,mode=755
26 0 253:0 / / rw,relatime shared:1 - ext4 /dev/mapper/hpservizio2--vg-root rw,errors=remount-ro,data=ordered
27 21 0:7 / /sys/kernel/security rw,nosuid,nodev,noexec,relatime shared:8 - securityfs securityfs rw
28 23 0:23 / /dev/shm rw,nosuid,nodev shared:4 - tmpfs tmpfs rw
29 25 0:24 / /run/lock rw,nosuid,nodev,noexec,relatime shared:6 - tmpfs tmpfs rw,size=5120k
30 21 0:25 / /sys/fs/cgroup ro,nosuid,nodev,noexec shared:9 - tmpfs tmpfs ro,mode=755
31 30 0:26 / /sys/fs/cgroup/unified rw,nosuid,nodev,noexec,relatime shared:10 - cgroup2 cgroup rw
32 30 0:27 / /sys/fs/cgroup/systemd rw,nosuid,nodev,noexec,relatime shared:11 - cgroup cgroup rw,xattr,name=systemd
33 21 0:28 / /sys/fs/pstore rw,nosuid,nodev,noexec,relatime shared:12 - pstore pstore rw
34 30 0:29 / /sys/fs/cgroup/cpuset rw,nosuid,nodev,noexec,relatime shared:14 - cgroup cgroup rw,cpuset,clone_children
35 30 0:30 / /sys/fs/cgroup/rdma rw,nosuid,nodev,noexec,relatime shared:15 - cgroup cgroup rw,rdma
36 30 0:31 / /sys/fs/cgroup/freezer rw,nosuid,nodev,noexec,relatime shared:16 - cgroup cgroup rw,freezer
37 30 0:32 / /sys/fs/cgroup/net_cls,net_prio rw,nosuid,nodev,noexec,relatime shared:17 - cgroup cgroup rw,net_cls,net_prio
38 30 0:33 / /sys/fs/cgroup/devices rw,nosuid,nodev,noexec,relatime shared:18 - cgroup cgroup rw,devices
39 30 0:34 / /sys/fs/cgroup/perf_event rw,nosuid,nodev,noexec,relatime shared:19 - cgroup cgroup rw,perf_event
40 30 0:35 / /sys/fs/cgroup/cpu,cpuacct rw,nosuid,nodev,noexec,relatime shared:20 - cgroup cgroup rw,cpu,cpuacct
41 30 0:36 / /sys/fs/cgroup/blkio rw,nosuid,nodev,noexec,relatime shared:21 - cgroup cgroup rw,blkio
42 30 0:37 / /sys/fs/cgroup/pids rw,nosuid,nodev,noexec,relatime shared:22 - cgroup cgroup rw,pids
43 30 0:38 / /sys/fs/cgroup/memory rw,nosuid,nodev,noexec,relatime shared:23 - cgroup cgroup rw,memory
44 30 0:39 / /sys/fs/cgroup/hugetlb rw,nosuid,nodev,noexec,relatime shared:24 - cgroup cgroup rw,hugetlb
45 22 0:40 / /proc/sys/fs/binfmt_misc rw,relatime shared:25 - autofs systemd-1 rw,fd=24,pgrp=1,timeout=0,minproto=5,maxproto=5,direct,pipe_ino=16631
46 21 0:8 / /sys/kernel/debug rw,relatime shared:26 - debugfs debugfs rw
47 23 0:41 / /dev/hugepages rw,relatime shared:27 - hugetlbfs hugetlbfs rw,pagesize=2M
48 23 0:18 / /dev/mqueue rw,relatime shared:28 - mqueue mqueue rw
49 21 0:42 / /sys/fs/fuse/connections rw,relatime shared:29 - fusectl fusectl rw
50 21 0:19 / /sys/kernel/config rw,relatime shared:30 - configfs configfs rw
51 25 0:43 / /run/rpc_pipefs rw,relatime shared:31 - rpc_pipefs sunrpc rw
84 45 0:44 / /proc/sys/fs/binfmt_misc rw,relatime shared:32 - binfmt_misc binfmt_misc rw
250 26 0:48 / /var/lib/lxcfs rw,nosuid,nodev,relatime shared:203 - fuse.lxcfs lxcfs rw,user_id=0,group_id=0,allow_other
258 26 0:49 / /vagrant rw,nodev,relatime shared:208 - vboxsf /vagrant rw,iocharset=utf8,uid=1000,gid=1000
267 258 0:50 / /vagrant/host_home rw,nodev,relatime shared:214 - vboxsf vagrant_host_home rw,iocharset=utf8,uid=1000,gid=1000
268 258 0:51 / /vagrant/host_desktop rw,nodev,relatime shared:220 - vboxsf vagrant_host_desktop rw,iocharset=utf8,uid=1000,gid=1000
378 25 0:52 / /run/user/1001 rw,nosuid,nodev,relatime shared:226 - tmpfs tmpfs rw,size=1633868k,mode=700,uid=1001,gid=1002
388 378 0:53 / /run/user/1001/gvfs rw,nosuid,nodev,relatime shared:233 - fuse.gvfsd-fuse gvfsd-fuse rw,user_id=1001,group_id=1002
617 46 0:11 / /sys/kernel/debug/tracing rw,relatime shared:457 - tracefs tracefs rw

That’s a different error. lxc-execute complains that it doesn’t find our custom init.lxc.static binary which is a stub init process for application containers. So I think that’s unrelated.

Can you please also provide me with the trace log from one of those failing containers?

lxc-start <container-name> -l trace -o mylog.log

and paste that here, please?

How do i solve this error? because i need to use lxc-execute

$ lxc-start -n test -l trace -o /tmp/log.txt -f config
$ cat /tmp/log.txt
lxc-start test 20220106132323.771 INFO     utils - utils.c:get_rundir:260 - XDG_RUNTIME_DIR isn't set in the environment
lxc-start test 20220106132323.774 TRACE    commands - commands.c:lxc_cmd:302 - Connection refused - Command "get_init_pid" failed to connect command socket
lxc-start test 20220106132323.774 TRACE    commands - commands.c:lxc_cmd:302 - Connection refused - Command "get_state" failed to connect command socket
lxc-start test 20220106132323.774 ERROR    lxc_start - tools/lxc_start.c:main:268 - No container config specified
lxc-start test 20220106132338.703 INFO     utils - utils.c:get_rundir:260 - XDG_RUNTIME_DIR isn't set in the environment
lxc-start test 20220106132338.704 INFO     confile - confile.c:set_config_idmaps:1942 - Read uid map: type u nsid 0 hostid 231072 range 10
lxc-start test 20220106132338.704 INFO     confile - confile.c:set_config_idmaps:1942 - Read uid map: type g nsid 0 hostid 231072 range 10
lxc-start test 20220106132338.704 INFO     confile - confile.c:set_config_idmaps:1942 - Read uid map: type u nsid 1002 hostid 1002 range 1
lxc-start test 20220106132338.704 INFO     confile - confile.c:set_config_idmaps:1942 - Read uid map: type g nsid 1003 hostid 1003 range 1
lxc-start test 20220106132338.704 TRACE    commands - commands.c:lxc_cmd:302 - Connection refused - Command "get_init_pid" failed to connect command socket
lxc-start test 20220106132338.704 TRACE    commands - commands.c:lxc_cmd:302 - Connection refused - Command "get_state" failed to connect command socket
lxc-start test 20220106132338.704 TRACE    start - start.c:lxc_init_handler:694 - Created anonymous pair {4,5} of unix sockets
lxc-start test 20220106132338.704 TRACE    commands - commands.c:lxc_cmd_init:1770 - Created abstract unix socket "/home/userA/.local/share/lxc/test/command"
lxc-start test 20220106132338.704 TRACE    start - start.c:lxc_init_handler:709 - Unix domain socket 6 for command server is ready
lxc-start test 20220106132338.704 INFO     lxccontainer - lxccontainer.c:do_lxcapi_start:979 - Set process title to [lxc monitor] /home/userA/.local/share/lxc test
lxc-start test 20220106132338.705 DEBUG    lxccontainer - lxccontainer.c:wait_on_daemonized_start:840 - First child 6961 exited
lxc-start test 20220106132338.705 TRACE    start - start.c:lxc_start:2114 - Doing lxc_start
lxc-start test 20220106132338.705 INFO     lsm - lsm/lsm.c:lsm_init:40 - Initialized LSM security driver nop
lxc-start test 20220106132338.705 TRACE    start - start.c:lxc_init:732 - Initialized LSM
lxc-start test 20220106132338.705 TRACE    start - start.c:lxc_serve_state_clients:438 - Set container state to STARTING
lxc-start test 20220106132338.705 TRACE    start - start.c:lxc_serve_state_clients:441 - No state clients registered
lxc-start test 20220106132338.705 INFO     utils - utils.c:get_rundir:260 - XDG_RUNTIME_DIR isn't set in the environment
lxc-start test 20220106132338.705 TRACE    start - start.c:lxc_init:738 - Set container state to "STARTING"
lxc-start test 20220106132338.705 TRACE    start - start.c:lxc_init:794 - Set environment variables
lxc-start test 20220106132338.705 TRACE    start - start.c:lxc_init:799 - Ran pre-start hooks
lxc-start test 20220106132338.705 TRACE    start - start.c:setup_signal_fd:331 - Created signal file descriptor 8
lxc-start test 20220106132338.705 TRACE    start - start.c:lxc_init:808 - Set up signal fd
lxc-start test 20220106132338.705 INFO     terminal - terminal.c:lxc_terminal_setup:974 - No terminal requested
lxc-start test 20220106132338.705 TRACE    start - start.c:lxc_init:816 - Created console
lxc-start test 20220106132338.705 TRACE    cgfsng - cgroups/cgfsng.c:lxc_cgfsng_print_basecg_debuginfo:941 - basecginfo is:
lxc-start test 20220106132338.705 TRACE    cgfsng - cgroups/cgfsng.c:lxc_cgfsng_print_basecg_debuginfo:942 - 12:perf_event:/
11:memory:/user/userA/0
10:cpuset:/
9:freezer:/user/userA/0
8:cpu,cpuacct:/user.slice
7:devices:/user.slice
6:pids:/user.slice/user-1001.slice/session-4.scope
5:rdma:/
4:net_cls,net_prio:/
3:hugetlb:/
2:blkio:/user.slice
1:name=systemd:/user/userA/0
0::/user.slice/user-1001.slice/session-4.scope

lxc-start test 20220106132338.705 TRACE    cgfsng - cgroups/cgfsng.c:lxc_cgfsng_print_basecg_debuginfo:945 - kernel subsystem 0: perf_event
lxc-start test 20220106132338.705 TRACE    cgfsng - cgroups/cgfsng.c:lxc_cgfsng_print_basecg_debuginfo:945 - kernel subsystem 1: memory
lxc-start test 20220106132338.705 TRACE    cgfsng - cgroups/cgfsng.c:lxc_cgfsng_print_basecg_debuginfo:945 - kernel subsystem 2: cpuset
lxc-start test 20220106132338.705 TRACE    cgfsng - cgroups/cgfsng.c:lxc_cgfsng_print_basecg_debuginfo:945 - kernel subsystem 3: freezer
lxc-start test 20220106132338.705 TRACE    cgfsng - cgroups/cgfsng.c:lxc_cgfsng_print_basecg_debuginfo:945 - kernel subsystem 4: cpu
lxc-start test 20220106132338.705 TRACE    cgfsng - cgroups/cgfsng.c:lxc_cgfsng_print_basecg_debuginfo:945 - kernel subsystem 5: cpuacct
lxc-start test 20220106132338.705 TRACE    cgfsng - cgroups/cgfsng.c:lxc_cgfsng_print_basecg_debuginfo:945 - kernel subsystem 6: devices
lxc-start test 20220106132338.705 TRACE    cgfsng - cgroups/cgfsng.c:lxc_cgfsng_print_basecg_debuginfo:945 - kernel subsystem 7: pids
lxc-start test 20220106132338.705 TRACE    cgfsng - cgroups/cgfsng.c:lxc_cgfsng_print_basecg_debuginfo:945 - kernel subsystem 8: rdma
lxc-start test 20220106132338.705 TRACE    cgfsng - cgroups/cgfsng.c:lxc_cgfsng_print_basecg_debuginfo:945 - kernel subsystem 9: net_cls
lxc-start test 20220106132338.705 TRACE    cgfsng - cgroups/cgfsng.c:lxc_cgfsng_print_basecg_debuginfo:945 - kernel subsystem 10: net_prio
lxc-start test 20220106132338.705 TRACE    cgfsng - cgroups/cgfsng.c:lxc_cgfsng_print_basecg_debuginfo:945 - kernel subsystem 11: hugetlb
lxc-start test 20220106132338.705 TRACE    cgfsng - cgroups/cgfsng.c:lxc_cgfsng_print_basecg_debuginfo:945 - kernel subsystem 12: blkio
lxc-start test 20220106132338.705 TRACE    cgfsng - cgroups/cgfsng.c:lxc_cgfsng_print_basecg_debuginfo:945 - kernel subsystem 13: cgroup2
lxc-start test 20220106132338.705 TRACE    cgfsng - cgroups/cgfsng.c:lxc_cgfsng_print_basecg_debuginfo:948 - named subsystem 0: name=systemd
lxc-start test 20220106132338.705 TRACE    cgfsng - cgroups/cgfsng.c:cg_hybrid_init:3255 - The /user.slice/user-1001.slice/session-4.scope group is not writeable
lxc-start test 20220106132338.705 TRACE    cgfsng - cgroups/cgfsng.c:cg_hybrid_init:3255 - The /user.slice group is not writeable
lxc-start test 20220106132338.705 TRACE    cgfsng - cgroups/cgfsng.c:cg_hybrid_init:3255 - The / group is not writeable
lxc-start test 20220106132338.705 TRACE    cgfsng - cgroups/cgfsng.c:cg_hybrid_init:3255 - The / group is not writeable
lxc-start test 20220106132338.705 TRACE    cgfsng - cgroups/cgfsng.c:cg_hybrid_init:3255 - The / group is not writeable
lxc-start test 20220106132338.705 TRACE    cgfsng - cgroups/cgfsng.c:cg_hybrid_init:3255 - The /user.slice/user-1001.slice/session-4.scope group is not writeable
lxc-start test 20220106132338.705 TRACE    cgfsng - cgroups/cgfsng.c:cg_hybrid_init:3255 - The /user.slice group is not writeable
lxc-start test 20220106132338.705 TRACE    cgfsng - cgroups/cgfsng.c:cg_hybrid_init:3255 - The /user.slice group is not writeable
lxc-start test 20220106132338.705 TRACE    cgfsng - cgroups/cgfsng.c:cg_hybrid_init:3255 - The / group is not writeable
lxc-start test 20220106132338.705 TRACE    cgfsng - cgroups/cgfsng.c:cg_hybrid_init:3255 - The / group is not writeable
lxc-start test 20220106132338.705 TRACE    cgfsng - cgroups/cgfsng.c:cg_hybrid_init:3289 - Writable cgroup hierarchies:
lxc-start test 20220106132338.705 TRACE    cgfsng - cgroups/cgfsng.c:lxc_cgfsng_print_hierarchies:922 -   Hierarchies:
lxc-start test 20220106132338.705 TRACE    cgfsng - cgroups/cgfsng.c:lxc_cgfsng_print_hierarchies:927 -   0: base_cgroup: /user/userA/0
lxc-start test 20220106132338.705 TRACE    cgfsng - cgroups/cgfsng.c:lxc_cgfsng_print_hierarchies:928 -       mountpoint:  /sys/fs/cgroup/systemd
lxc-start test 20220106132338.705 TRACE    cgfsng - cgroups/cgfsng.c:lxc_cgfsng_print_hierarchies:929 -       controllers:
lxc-start test 20220106132338.705 TRACE    cgfsng - cgroups/cgfsng.c:lxc_cgfsng_print_hierarchies:931 -       0: name=systemd
lxc-start test 20220106132338.705 TRACE    cgfsng - cgroups/cgfsng.c:lxc_cgfsng_print_hierarchies:927 -   1: base_cgroup: /user/userA/0
lxc-start test 20220106132338.705 TRACE    cgfsng - cgroups/cgfsng.c:lxc_cgfsng_print_hierarchies:928 -       mountpoint:  /sys/fs/cgroup/freezer
lxc-start test 20220106132338.705 TRACE    cgfsng - cgroups/cgfsng.c:lxc_cgfsng_print_hierarchies:929 -       controllers:
lxc-start test 20220106132338.705 TRACE    cgfsng - cgroups/cgfsng.c:lxc_cgfsng_print_hierarchies:931 -       0: freezer
lxc-start test 20220106132338.705 TRACE    cgfsng - cgroups/cgfsng.c:lxc_cgfsng_print_hierarchies:927 -   2: base_cgroup: /user/userA/0
lxc-start test 20220106132338.705 TRACE    cgfsng - cgroups/cgfsng.c:lxc_cgfsng_print_hierarchies:928 -       mountpoint:  /sys/fs/cgroup/memory
lxc-start test 20220106132338.705 TRACE    cgfsng - cgroups/cgfsng.c:lxc_cgfsng_print_hierarchies:929 -       controllers:
lxc-start test 20220106132338.705 TRACE    cgfsng - cgroups/cgfsng.c:lxc_cgfsng_print_hierarchies:931 -       0: memory
lxc-start test 20220106132338.705 TRACE    cgroup - cgroups/cgroup.c:cgroup_init:41 - Initialized cgroup driver cgfsng
lxc-start test 20220106132338.705 TRACE    cgroup - cgroups/cgroup.c:cgroup_init:46 - Running with hybrid cgroup layout
lxc-start test 20220106132338.705 TRACE    start - start.c:lxc_init:823 - Initialized cgroup driver
lxc-start test 20220106132338.705 TRACE    start - start.c:lxc_init:828 - Read seccomp policy
lxc-start test 20220106132338.705 TRACE    start - start.c:lxc_init:835 - Initialized LSM
lxc-start test 20220106132338.705 INFO     start - start.c:lxc_init:837 - Container "test" is initialized
lxc-start test 20220106132338.706 INFO     cgfsng - cgroups/cgfsng.c:cgfsng_monitor_create:1368 - The monitor process uses "lxc.monitor.test" as cgroup
lxc-start test 20220106132338.706 TRACE    sync - sync.c:lxc_sync_init:113 - Initialized synchronization infrastructure
lxc-start test 20220106132338.706 INFO     cgfsng - cgroups/cgfsng.c:cgfsng_payload_create:1471 - The container process uses "lxc.payload.test" as cgroup
lxc-start test 20220106132338.706 TRACE    start - start.c:lxc_spawn:1652 - Function not implemented - Failed to spawn container directly into target cgroup
lxc-start test 20220106132338.706 TRACE    start - start.c:lxc_spawn:1668 - Function not implemented - Failed to spawn container via clone3()
lxc-start test 20220106132338.707 TRACE    start - start.c:lxc_spawn:1684 - Cloned child process 6966
lxc-start test 20220106132338.707 ERROR    utils - utils.c:lxc_can_use_pidfd:1853 - Kernel does not support pidfds
lxc-start test 20220106132338.707 INFO     start - start.c:lxc_spawn:1700 - Cloned CLONE_NEWUSER
lxc-start test 20220106132338.707 INFO     start - start.c:lxc_spawn:1700 - Cloned CLONE_NEWNS
lxc-start test 20220106132338.707 INFO     start - start.c:lxc_spawn:1700 - Cloned CLONE_NEWPID
lxc-start test 20220106132338.707 INFO     start - start.c:lxc_spawn:1700 - Cloned CLONE_NEWUTS
lxc-start test 20220106132338.707 INFO     start - start.c:lxc_spawn:1700 - Cloned CLONE_NEWIPC
lxc-start test 20220106132338.707 DEBUG    start - start.c:lxc_try_preserve_namespaces:167 - Preserved user namespace via fd 16
lxc-start test 20220106132338.707 DEBUG    start - start.c:lxc_try_preserve_namespaces:167 - Preserved mnt namespace via fd 17
lxc-start test 20220106132338.707 DEBUG    start - start.c:lxc_try_preserve_namespaces:167 - Preserved pid namespace via fd 18
lxc-start test 20220106132338.707 DEBUG    start - start.c:lxc_try_preserve_namespaces:167 - Preserved uts namespace via fd 19
lxc-start test 20220106132338.707 DEBUG    start - start.c:lxc_try_preserve_namespaces:167 - Preserved ipc namespace via fd 20
lxc-start test 20220106132338.707 DEBUG    conf - conf.c:idmaptool_on_path_and_privileged:2728 - The binary "/usr/bin/newuidmap" does have the setuid bit set
lxc-start test 20220106132338.707 DEBUG    conf - conf.c:idmaptool_on_path_and_privileged:2728 - The binary "/usr/bin/newgidmap" does have the setuid bit set
lxc-start test 20220106132338.707 DEBUG    conf - conf.c:lxc_map_ids:2796 - Functional newuidmap and newgidmap binary found
lxc-start test 20220106132338.707 TRACE    process_utils - process_utils.c:lxc_raw_clone:110 - Function not implemented - Falling back to legacy clone
lxc-start test 20220106132338.708 TRACE    sync - sync.c:lxc_sync_wait_parent:84 - Parent waiting for child with sequence startup
lxc-start test 20220106132338.714 TRACE    conf - conf.c:lxc_map_ids:2866 - newuidmap wrote mapping "newuidmap 6966 0 231072 10 1002 1002 1"
lxc-start test 20220106132338.714 TRACE    process_utils - process_utils.c:lxc_raw_clone:110 - Function not implemented - Falling back to legacy clone
lxc-start test 20220106132338.717 TRACE    conf - conf.c:lxc_map_ids:2866 - newgidmap wrote mapping "newgidmap 6966 0 231072 10 1003 1003 1"
lxc-start test 20220106132338.717 TRACE    sync - sync.c:lxc_sync_wake_child:96 - Child waking parent with sequence startup
lxc-start test 20220106132338.717 TRACE    sync - sync.c:lxc_sync_wait_child:90 - Child waiting for parent with sequence configure
lxc-start test 20220106132338.718 INFO     start - start.c:do_start:1085 - Unshared CLONE_NEWNET
lxc-start test 20220106132338.718 TRACE    sync - sync.c:lxc_sync_barrier_parent:65 - Child waking parent with sequence configure and waiting for sequence post-configure
lxc-start test 20220106132338.718 TRACE    conf - conf.c:get_minimal_idmap:4036 - Allocated minimal idmapping for ns uid 0 and ns gid 0
lxc-start test 20220106132338.718 TRACE    process_utils - process_utils.c:lxc_raw_clone:110 - Function not implemented - Falling back to legacy clone
lxc-start test 20220106132338.718 TRACE    conf - conf.c:userns_exec_1:4102 - Establishing uid mapping for "6980" in new user namespace: nsuid 1002 - hostid 1002 - range 1
lxc-start test 20220106132338.718 TRACE    conf - conf.c:userns_exec_1:4102 - Establishing uid mapping for "6980" in new user namespace: nsuid 0 - hostid 231072 - range 10
lxc-start test 20220106132338.718 TRACE    conf - conf.c:userns_exec_1:4102 - Establishing gid mapping for "6980" in new user namespace: nsuid 1003 - hostid 1003 - range 1
lxc-start test 20220106132338.718 TRACE    conf - conf.c:userns_exec_1:4102 - Establishing gid mapping for "6980" in new user namespace: nsuid 0 - hostid 231072 - range 10
lxc-start test 20220106132338.718 DEBUG    conf - conf.c:idmaptool_on_path_and_privileged:2728 - The binary "/usr/bin/newuidmap" does have the setuid bit set
lxc-start test 20220106132338.718 DEBUG    conf - conf.c:idmaptool_on_path_and_privileged:2728 - The binary "/usr/bin/newgidmap" does have the setuid bit set
lxc-start test 20220106132338.718 DEBUG    conf - conf.c:lxc_map_ids:2796 - Functional newuidmap and newgidmap binary found
lxc-start test 20220106132338.718 TRACE    process_utils - process_utils.c:lxc_raw_clone:110 - Function not implemented - Falling back to legacy clone
lxc-start test 20220106132338.721 TRACE    conf - conf.c:lxc_map_ids:2866 - newuidmap wrote mapping "newuidmap 6980 1002 1002 1 0 231072 10"
lxc-start test 20220106132338.721 TRACE    process_utils - process_utils.c:lxc_raw_clone:110 - Function not implemented - Falling back to legacy clone
lxc-start test 20220106132338.722 TRACE    conf - conf.c:lxc_map_ids:2866 - newgidmap wrote mapping "newgidmap 6980 1003 1003 1 0 231072 10"
lxc-start test 20220106132338.722 TRACE    conf - conf.c:run_userns_fn:3878 - Calling function "chown_cgroup_wrapper"
lxc-start test 20220106132338.723 NOTICE   utils - utils.c:lxc_setgroups:1420 - Dropped additional groups
lxc-start test 20220106132338.723 DEBUG    start - start.c:lxc_spawn:1773 - Preserved net namespace via fd 7
lxc-start test 20220106132338.723 WARN     start - start.c:lxc_spawn:1778 - Operation not permitted - Failed to allocate new network namespace id
lxc-start test 20220106132338.723 TRACE    sync - sync.c:lxc_sync_barrier_child:72 - Parent waking child with sequence post-configure and waiting with sequence cgroup
lxc-start test 20220106132338.723 NOTICE   utils - utils.c:lxc_setgroups:1420 - Dropped additional groups
lxc-start test 20220106132338.723 NOTICE   utils - utils.c:lxc_switch_uid_gid:1398 - Switched to gid 0
lxc-start test 20220106132338.723 NOTICE   utils - utils.c:lxc_switch_uid_gid:1407 - Switched to uid 0
lxc-start test 20220106132338.723 TRACE    sync - sync.c:lxc_sync_barrier_parent:65 - Child waking parent with sequence cgroup and waiting for sequence cgroup-unshare
lxc-start test 20220106132338.724 TRACE    sync - sync.c:lxc_sync_barrier_child:72 - Parent waking child with sequence cgroup-unshare and waiting with sequence cgroup-limits
lxc-start test 20220106132338.724 INFO     start - start.c:do_start:1198 - Unshared CLONE_NEWCGROUP
lxc-start test 20220106132338.724 TRACE    conf - conf.c:turn_into_dependent_mounts:3054 - Turned all mount table entries into dependent mount
lxc-start test 20220106132338.724 INFO     conf - conf.c:mount_autodev:1056 - Preparing "/dev"
lxc-start test 20220106132338.724 DEBUG    conf - conf.c:mount_autodev:1059 - Using mount options: size=500000,mode=755
lxc-start test 20220106132338.725 TRACE    conf - conf.c:mount_autodev:1089 - Mounted tmpfs on "(null)"
lxc-start test 20220106132338.725 INFO     conf - conf.c:mount_autodev:1106 - Prepared "/dev"
lxc-start test 20220106132338.725 TRACE    conf - conf.c:lxc_mount_auto_mounts:662 - Container does not have CAP_NET_ADMIN. Skipping "%r/proc/sys/net" mount
lxc-start test 20220106132338.725 TRACE    conf - conf.c:lxc_mount_auto_mounts:662 - Container does not have CAP_NET_ADMIN. Skipping "%r/proc/tty" mount
lxc-start test 20220106132338.725 INFO     conf - conf.c:lxc_fill_autodev:1149 - Populating "/dev"
lxc-start test 20220106132338.725 DEBUG    conf - conf.c:lxc_fill_autodev:1159 - Created device node "full"
lxc-start test 20220106132338.725 DEBUG    conf - conf.c:lxc_fill_autodev:1159 - Created device node "null"
lxc-start test 20220106132338.725 DEBUG    conf - conf.c:lxc_fill_autodev:1159 - Created device node "random"
lxc-start test 20220106132338.725 DEBUG    conf - conf.c:lxc_fill_autodev:1159 - Created device node "tty"
lxc-start test 20220106132338.725 DEBUG    conf - conf.c:lxc_fill_autodev:1159 - Created device node "urandom"
lxc-start test 20220106132338.725 DEBUG    conf - conf.c:lxc_fill_autodev:1159 - Created device node "zero"
lxc-start test 20220106132338.725 INFO     conf - conf.c:lxc_fill_autodev:1221 - Populated "/dev"
lxc-start test 20220106132338.725 INFO     utils - utils.c:lxc_mount_proc_if_needed:1256 - I am 1, /proc/self points to "1"
lxc-start test 20220106132338.725 DEBUG    conf - conf.c:lxc_setup_rootfs_switch_root:1442 - Container does not have a rootfs
lxc-start test 20220106132338.725 ERROR    conf - conf.c:lxc_setup_boot_id:3200 - Permission denied - Failed to write uuid to /dev/.lxc-boot-id
lxc-start test 20220106132338.725 DEBUG    conf - conf.c:lxc_setup_devpts_child:1509 - No new devpts instance will be mounted since no pts devices are requested
lxc-start test 20220106132338.725 DEBUG    conf - conf.c:setup_caps:2422 - Capabilities have been setup
lxc-start test 20220106132338.725 NOTICE   conf - conf.c:lxc_setup:3446 - The container "test" is set up
lxc-start test 20220106132338.725 TRACE    sync - sync.c:lxc_sync_barrier_parent:65 - Child waking parent with sequence cgroup-limits and waiting for sequence ready-start
lxc-start test 20220106132338.725 TRACE    start - start.c:lxc_spawn:1831 - Set up legacy device cgroup controller limits
lxc-start test 20220106132338.725 TRACE    start - start.c:lxc_spawn:1837 - Set up cgroup2 device controller limits
lxc-start test 20220106132338.725 DEBUG    start - start.c:lxc_spawn:1849 - Preserved cgroup namespace via fd 9
lxc-start test 20220106132338.725 TRACE    start - start.c:lxc_spawn:1854 - Finished setting up cgroups
lxc-start test 20220106132338.725 TRACE    sync - sync.c:lxc_sync_barrier_child:72 - Parent waking child with sequence ready-start and waiting with sequence restart
lxc-start test 20220106132338.725 NOTICE   start - start.c:start:2087 - Exec'ing "/sbin/init"
lxc-start test 20220106132338.726 TRACE    confile_utils - confile_utils.c:lxc_log_configured_netdevs:233 - container has no networks configured
lxc-start test 20220106132338.726 NOTICE   start - start.c:post_start:2098 - Started "/sbin/init" with pid "6966"
lxc-start test 20220106132338.726 TRACE    start - start.c:lxc_serve_state_socket_pair:503 - Sent container state "RUNNING" to 5
lxc-start test 20220106132338.726 TRACE    start - start.c:lxc_serve_state_clients:438 - Set container state to RUNNING
lxc-start test 20220106132338.726 TRACE    start - start.c:lxc_serve_state_clients:441 - No state clients registered
lxc-start test 20220106132338.726 INFO     utils - utils.c:get_rundir:260 - XDG_RUNTIME_DIR isn't set in the environment
lxc-start test 20220106132338.726 TRACE    start - start.c:lxc_poll:592 - Mainloop is ready
lxc-start test 20220106132338.726 NOTICE   start - start.c:signal_handler:405 - Received 17 from pid 6970 instead of container init 6966
lxc-start test 20220106132338.727 TRACE    lxccontainer - lxccontainer.c:wait_on_daemonized_start:864 - Container is in "RUNNING" state
$ 

lxc-start is working but problem is with execute call

@brauner : please let me know how to solve the lxc-execute command error

What does which init.lxc.static show?
I’m not sure on what distro you are. You need to find out what package provides init.lxc.static and install that.

which init.lxc.static shows
/usr/sbin/init.lxc.static

[ working on 4.15.0-166-generic #174-Ubuntu SMP Wed Dec 8 19:07:44 UTC 2021 x86_64 x86_64 x86_64 GNU/Linux distribution]

Ok, then please show me the output of a failing lxc-execute <container-name> -l trace -o mylog.log, please!

lxc-execute test 20220110042112.667 INFO     utils - utils.c:get_rundir:260 - XDG_RUNTIME_DIR isn't set in the environment
lxc-execute test 20220110042112.668 INFO     confile - confile.c:set_config_idmaps:1942 - Read uid map: type u nsid 0 hostid 231072 range 10
lxc-execute test 20220110042112.668 INFO     confile - confile.c:set_config_idmaps:1942 - Read uid map: type g nsid 0 hostid 231072 range 10
lxc-execute test 20220110042112.668 INFO     confile - confile.c:set_config_idmaps:1942 - Read uid map: type u nsid 1002 hostid 1002 range 1
lxc-execute test 20220110042112.668 INFO     confile - confile.c:set_config_idmaps:1942 - Read uid map: type g nsid 1003 hostid 1003 range 1
lxc-execute test 20220110042112.669 TRACE    commands - commands.c:lxc_cmd_init:1770 - Created abstract unix socket "/home/userA/.local/share/lxc/test/command"
lxc-execute test 20220110042112.669 TRACE    start - start.c:lxc_init_handler:709 - Unix domain socket 4 for command server is ready
lxc-execute test 20220110042112.669 TRACE    execute - execute.c:lxc_execute:97 - Doing lxc_execute
lxc-execute test 20220110042112.669 INFO     lsm - lsm/lsm.c:lsm_init:40 - Initialized LSM security driver nop
lxc-execute test 20220110042112.669 TRACE    start - start.c:lxc_init:732 - Initialized LSM
lxc-execute test 20220110042112.669 TRACE    start - start.c:lxc_serve_state_clients:438 - Set container state to STARTING
lxc-execute test 20220110042112.669 TRACE    start - start.c:lxc_serve_state_clients:441 - No state clients registered
lxc-execute test 20220110042112.669 INFO     utils - utils.c:get_rundir:260 - XDG_RUNTIME_DIR isn't set in the environment
lxc-execute test 20220110042112.669 TRACE    start - start.c:lxc_init:738 - Set container state to "STARTING"
lxc-execute test 20220110042112.669 TRACE    start - start.c:lxc_init:794 - Set environment variables
lxc-execute test 20220110042112.669 TRACE    start - start.c:lxc_init:799 - Ran pre-start hooks
lxc-execute test 20220110042112.669 TRACE    start - start.c:setup_signal_fd:331 - Created signal file descriptor 6
lxc-execute test 20220110042112.669 TRACE    start - start.c:lxc_init:808 - Set up signal fd
lxc-execute test 20220110042112.669 INFO     terminal - terminal.c:lxc_terminal_setup:974 - No terminal requested
lxc-execute test 20220110042112.669 TRACE    start - start.c:lxc_init:816 - Created console
lxc-execute test 20220110042112.669 TRACE    cgfsng - cgroups/cgfsng.c:lxc_cgfsng_print_basecg_debuginfo:941 - basecginfo is:
lxc-execute test 20220110042112.669 TRACE    cgfsng - cgroups/cgfsng.c:lxc_cgfsng_print_basecg_debuginfo:942 - 12:perf_event:/
11:memory:/user/userA/0
10:cpuset:/
9:freezer:/user/userA/0
8:cpu,cpuacct:/user.slice
7:devices:/user.slice
6:pids:/user.slice/user-1001.slice/session-4.scope
5:rdma:/
4:net_cls,net_prio:/
3:hugetlb:/
2:blkio:/user.slice
1:name=systemd:/user/userA/0
0::/user.slice/user-1001.slice/session-4.scope

lxc-execute test 20220110042112.669 TRACE    cgfsng - cgroups/cgfsng.c:lxc_cgfsng_print_basecg_debuginfo:945 - kernel subsystem 0: perf_event
lxc-execute test 20220110042112.669 TRACE    cgfsng - cgroups/cgfsng.c:lxc_cgfsng_print_basecg_debuginfo:945 - kernel subsystem 1: memory
lxc-execute test 20220110042112.669 TRACE    cgfsng - cgroups/cgfsng.c:lxc_cgfsng_print_basecg_debuginfo:945 - kernel subsystem 2: cpuset
lxc-execute test 20220110042112.669 TRACE    cgfsng - cgroups/cgfsng.c:lxc_cgfsng_print_basecg_debuginfo:945 - kernel subsystem 3: freezer
lxc-execute test 20220110042112.669 TRACE    cgfsng - cgroups/cgfsng.c:lxc_cgfsng_print_basecg_debuginfo:945 - kernel subsystem 4: cpu
lxc-execute test 20220110042112.669 TRACE    cgfsng - cgroups/cgfsng.c:lxc_cgfsng_print_basecg_debuginfo:945 - kernel subsystem 5: cpuacct
lxc-execute test 20220110042112.669 TRACE    cgfsng - cgroups/cgfsng.c:lxc_cgfsng_print_basecg_debuginfo:945 - kernel subsystem 6: devices
lxc-execute test 20220110042112.669 TRACE    cgfsng - cgroups/cgfsng.c:lxc_cgfsng_print_basecg_debuginfo:945 - kernel subsystem 7: pids
lxc-execute test 20220110042112.669 TRACE    cgfsng - cgroups/cgfsng.c:lxc_cgfsng_print_basecg_debuginfo:945 - kernel subsystem 8: rdma
lxc-execute test 20220110042112.669 TRACE    cgfsng - cgroups/cgfsng.c:lxc_cgfsng_print_basecg_debuginfo:945 - kernel subsystem 9: net_cls
lxc-execute test 20220110042112.669 TRACE    cgfsng - cgroups/cgfsng.c:lxc_cgfsng_print_basecg_debuginfo:945 - kernel subsystem 10: net_prio
lxc-execute test 20220110042112.669 TRACE    cgfsng - cgroups/cgfsng.c:lxc_cgfsng_print_basecg_debuginfo:945 - kernel subsystem 11: hugetlb
lxc-execute test 20220110042112.669 TRACE    cgfsng - cgroups/cgfsng.c:lxc_cgfsng_print_basecg_debuginfo:945 - kernel subsystem 12: blkio
lxc-execute test 20220110042112.669 TRACE    cgfsng - cgroups/cgfsng.c:lxc_cgfsng_print_basecg_debuginfo:945 - kernel subsystem 13: cgroup2
lxc-execute test 20220110042112.669 TRACE    cgfsng - cgroups/cgfsng.c:lxc_cgfsng_print_basecg_debuginfo:948 - named subsystem 0: name=systemd
lxc-execute test 20220110042112.669 TRACE    cgfsng - cgroups/cgfsng.c:cg_hybrid_init:3255 - The /user.slice/user-1001.slice/session-4.scope group is not writeable
lxc-execute test 20220110042112.669 TRACE    cgfsng - cgroups/cgfsng.c:cg_hybrid_init:3255 - The /user.slice group is not writeable
lxc-execute test 20220110042112.669 TRACE    cgfsng - cgroups/cgfsng.c:cg_hybrid_init:3255 - The / group is not writeable
lxc-execute test 20220110042112.669 TRACE    cgfsng - cgroups/cgfsng.c:cg_hybrid_init:3255 - The / group is not writeable
lxc-execute test 20220110042112.669 TRACE    cgfsng - cgroups/cgfsng.c:cg_hybrid_init:3255 - The / group is not writeable
lxc-execute test 20220110042112.669 TRACE    cgfsng - cgroups/cgfsng.c:cg_hybrid_init:3255 - The /user.slice/user-1001.slice/session-4.scope group is not writeable
lxc-execute test 20220110042112.669 TRACE    cgfsng - cgroups/cgfsng.c:cg_hybrid_init:3255 - The /user.slice group is not writeable
lxc-execute test 20220110042112.670 TRACE    cgfsng - cgroups/cgfsng.c:cg_hybrid_init:3255 - The /user.slice group is not writeable
lxc-execute test 20220110042112.670 TRACE    cgfsng - cgroups/cgfsng.c:cg_hybrid_init:3255 - The / group is not writeable
lxc-execute test 20220110042112.670 TRACE    cgfsng - cgroups/cgfsng.c:cg_hybrid_init:3255 - The / group is not writeable
lxc-execute test 20220110042112.670 TRACE    cgfsng - cgroups/cgfsng.c:cg_hybrid_init:3289 - Writable cgroup hierarchies:
lxc-execute test 20220110042112.670 TRACE    cgfsng - cgroups/cgfsng.c:lxc_cgfsng_print_hierarchies:922 -   Hierarchies:
lxc-execute test 20220110042112.670 TRACE    cgfsng - cgroups/cgfsng.c:lxc_cgfsng_print_hierarchies:927 -   0: base_cgroup: /user/userA/0
lxc-execute test 20220110042112.670 TRACE    cgfsng - cgroups/cgfsng.c:lxc_cgfsng_print_hierarchies:928 -       mountpoint:  /sys/fs/cgroup/systemd
lxc-execute test 20220110042112.670 TRACE    cgfsng - cgroups/cgfsng.c:lxc_cgfsng_print_hierarchies:929 -       controllers:
lxc-execute test 20220110042112.670 TRACE    cgfsng - cgroups/cgfsng.c:lxc_cgfsng_print_hierarchies:931 -       0: name=systemd
lxc-execute test 20220110042112.670 TRACE    cgfsng - cgroups/cgfsng.c:lxc_cgfsng_print_hierarchies:927 -   1: base_cgroup: /user/userA/0
lxc-execute test 20220110042112.670 TRACE    cgfsng - cgroups/cgfsng.c:lxc_cgfsng_print_hierarchies:928 -       mountpoint:  /sys/fs/cgroup/freezer
lxc-execute test 20220110042112.670 TRACE    cgfsng - cgroups/cgfsng.c:lxc_cgfsng_print_hierarchies:929 -       controllers:
lxc-execute test 20220110042112.670 TRACE    cgfsng - cgroups/cgfsng.c:lxc_cgfsng_print_hierarchies:931 -       0: freezer
lxc-execute test 20220110042112.670 TRACE    cgfsng - cgroups/cgfsng.c:lxc_cgfsng_print_hierarchies:927 -   2: base_cgroup: /user/userA/0
lxc-execute test 20220110042112.670 TRACE    cgfsng - cgroups/cgfsng.c:lxc_cgfsng_print_hierarchies:928 -       mountpoint:  /sys/fs/cgroup/memory
lxc-execute test 20220110042112.670 TRACE    cgfsng - cgroups/cgfsng.c:lxc_cgfsng_print_hierarchies:929 -       controllers:
lxc-execute test 20220110042112.670 TRACE    cgfsng - cgroups/cgfsng.c:lxc_cgfsng_print_hierarchies:931 -       0: memory
lxc-execute test 20220110042112.670 TRACE    cgroup - cgroups/cgroup.c:cgroup_init:41 - Initialized cgroup driver cgfsng
lxc-execute test 20220110042112.670 TRACE    cgroup - cgroups/cgroup.c:cgroup_init:46 - Running with hybrid cgroup layout
lxc-execute test 20220110042112.670 TRACE    start - start.c:lxc_init:823 - Initialized cgroup driver
lxc-execute test 20220110042112.670 TRACE    start - start.c:lxc_init:828 - Read seccomp policy
lxc-execute test 20220110042112.670 TRACE    start - start.c:lxc_init:835 - Initialized LSM
lxc-execute test 20220110042112.670 INFO     start - start.c:lxc_init:837 - Container "test" is initialized
lxc-execute test 20220110042112.670 INFO     cgfsng - cgroups/cgfsng.c:cgfsng_monitor_create:1368 - The monitor process uses "lxc.monitor.test" as cgroup
lxc-execute test 20220110042112.670 TRACE    sync - sync.c:lxc_sync_init:113 - Initialized synchronization infrastructure
lxc-execute test 20220110042112.670 INFO     cgfsng - cgroups/cgfsng.c:cgfsng_payload_create:1471 - The container process uses "lxc.payload.test" as cgroup
lxc-execute test 20220110042112.670 TRACE    start - start.c:lxc_spawn:1652 - Function not implemented - Failed to spawn container directly into target cgroup
lxc-execute test 20220110042112.670 TRACE    start - start.c:lxc_spawn:1668 - Function not implemented - Failed to spawn container via clone3()
lxc-execute test 20220110042112.670 TRACE    start - start.c:lxc_spawn:1684 - Cloned child process 20804
lxc-execute test 20220110042112.670 ERROR    utils - utils.c:lxc_can_use_pidfd:1853 - Kernel does not support pidfds
lxc-execute test 20220110042112.670 INFO     start - start.c:lxc_spawn:1700 - Cloned CLONE_NEWUSER
lxc-execute test 20220110042112.670 INFO     start - start.c:lxc_spawn:1700 - Cloned CLONE_NEWNS
lxc-execute test 20220110042112.670 INFO     start - start.c:lxc_spawn:1700 - Cloned CLONE_NEWPID
lxc-execute test 20220110042112.670 INFO     start - start.c:lxc_spawn:1700 - Cloned CLONE_NEWUTS
lxc-execute test 20220110042112.670 INFO     start - start.c:lxc_spawn:1700 - Cloned CLONE_NEWIPC
lxc-execute test 20220110042112.670 DEBUG    start - start.c:lxc_try_preserve_namespaces:167 - Preserved user namespace via fd 17
lxc-execute test 20220110042112.670 DEBUG    start - start.c:lxc_try_preserve_namespaces:167 - Preserved mnt namespace via fd 18
lxc-execute test 20220110042112.670 DEBUG    start - start.c:lxc_try_preserve_namespaces:167 - Preserved pid namespace via fd 19
lxc-execute test 20220110042112.670 DEBUG    start - start.c:lxc_try_preserve_namespaces:167 - Preserved uts namespace via fd 20
lxc-execute test 20220110042112.671 DEBUG    start - start.c:lxc_try_preserve_namespaces:167 - Preserved ipc namespace via fd 21
lxc-execute test 20220110042112.671 DEBUG    conf - conf.c:idmaptool_on_path_and_privileged:2728 - The binary "/usr/bin/newuidmap" does have the setuid bit set
lxc-execute test 20220110042112.671 DEBUG    conf - conf.c:idmaptool_on_path_and_privileged:2728 - The binary "/usr/bin/newgidmap" does have the setuid bit set
lxc-execute test 20220110042112.671 DEBUG    conf - conf.c:lxc_map_ids:2796 - Functional newuidmap and newgidmap binary found
lxc-execute test 20220110042112.671 TRACE    process_utils - process_utils.c:lxc_raw_clone:110 - Function not implemented - Falling back to legacy clone
lxc-execute test 20220110042112.671 TRACE    sync - sync.c:lxc_sync_wait_parent:84 - Parent waiting for child with sequence startup
lxc-execute test 20220110042112.674 TRACE    conf - conf.c:lxc_map_ids:2866 - newuidmap wrote mapping "newuidmap 20804 0 231072 10 1002 1002 1"
lxc-execute test 20220110042112.674 TRACE    process_utils - process_utils.c:lxc_raw_clone:110 - Function not implemented - Falling back to legacy clone
lxc-execute test 20220110042112.676 TRACE    conf - conf.c:lxc_map_ids:2866 - newgidmap wrote mapping "newgidmap 20804 0 231072 10 1003 1003 1"
lxc-execute test 20220110042112.676 TRACE    sync - sync.c:lxc_sync_wake_child:96 - Child waking parent with sequence startup
lxc-execute test 20220110042112.676 TRACE    sync - sync.c:lxc_sync_wait_child:90 - Child waiting for parent with sequence configure
lxc-execute test 20220110042112.677 INFO     start - start.c:do_start:1085 - Unshared CLONE_NEWNET
lxc-execute test 20220110042112.677 TRACE    sync - sync.c:lxc_sync_barrier_parent:65 - Child waking parent with sequence configure and waiting for sequence post-configure
lxc-execute test 20220110042112.677 TRACE    conf - conf.c:get_minimal_idmap:4036 - Allocated minimal idmapping for ns uid 0 and ns gid 0
lxc-execute test 20220110042112.677 TRACE    process_utils - process_utils.c:lxc_raw_clone:110 - Function not implemented - Falling back to legacy clone
lxc-execute test 20220110042112.677 TRACE    conf - conf.c:userns_exec_1:4102 - Establishing uid mapping for "20811" in new user namespace: nsuid 1002 - hostid 1002 - range 1
lxc-execute test 20220110042112.677 TRACE    conf - conf.c:userns_exec_1:4102 - Establishing uid mapping for "20811" in new user namespace: nsuid 0 - hostid 231072 - range 10
lxc-execute test 20220110042112.677 TRACE    conf - conf.c:userns_exec_1:4102 - Establishing gid mapping for "20811" in new user namespace: nsuid 1003 - hostid 1003 - range 1
lxc-execute test 20220110042112.677 TRACE    conf - conf.c:userns_exec_1:4102 - Establishing gid mapping for "20811" in new user namespace: nsuid 0 - hostid 231072 - range 10
lxc-execute test 20220110042112.677 DEBUG    conf - conf.c:idmaptool_on_path_and_privileged:2728 - The binary "/usr/bin/newuidmap" does have the setuid bit set
lxc-execute test 20220110042112.677 DEBUG    conf - conf.c:idmaptool_on_path_and_privileged:2728 - The binary "/usr/bin/newgidmap" does have the setuid bit set
lxc-execute test 20220110042112.677 DEBUG    conf - conf.c:lxc_map_ids:2796 - Functional newuidmap and newgidmap binary found
lxc-execute test 20220110042112.677 TRACE    process_utils - process_utils.c:lxc_raw_clone:110 - Function not implemented - Falling back to legacy clone
lxc-execute test 20220110042112.680 TRACE    conf - conf.c:lxc_map_ids:2866 - newuidmap wrote mapping "newuidmap 20811 1002 1002 1 0 231072 10"
lxc-execute test 20220110042112.680 TRACE    process_utils - process_utils.c:lxc_raw_clone:110 - Function not implemented - Falling back to legacy clone
lxc-execute test 20220110042112.681 TRACE    conf - conf.c:lxc_map_ids:2866 - newgidmap wrote mapping "newgidmap 20811 1003 1003 1 0 231072 10"
lxc-execute test 20220110042112.681 TRACE    conf - conf.c:run_userns_fn:3878 - Calling function "chown_cgroup_wrapper"
lxc-execute test 20220110042112.682 NOTICE   utils - utils.c:lxc_setgroups:1420 - Dropped additional groups
lxc-execute test 20220110042112.682 DEBUG    start - start.c:lxc_spawn:1773 - Preserved net namespace via fd 5
lxc-execute test 20220110042112.682 WARN     start - start.c:lxc_spawn:1778 - Operation not permitted - Failed to allocate new network namespace id
lxc-execute test 20220110042112.682 TRACE    sync - sync.c:lxc_sync_barrier_child:72 - Parent waking child with sequence post-configure and waiting with sequence cgroup
lxc-execute test 20220110042112.683 NOTICE   utils - utils.c:lxc_setgroups:1420 - Dropped additional groups
lxc-execute test 20220110042112.683 NOTICE   utils - utils.c:lxc_switch_uid_gid:1398 - Switched to gid 0
lxc-execute test 20220110042112.683 NOTICE   utils - utils.c:lxc_switch_uid_gid:1407 - Switched to uid 0
lxc-execute test 20220110042112.683 TRACE    sync - sync.c:lxc_sync_barrier_parent:65 - Child waking parent with sequence cgroup and waiting for sequence cgroup-unshare
lxc-execute test 20220110042112.683 TRACE    sync - sync.c:lxc_sync_barrier_child:72 - Parent waking child with sequence cgroup-unshare and waiting with sequence cgroup-limits
lxc-execute test 20220110042112.683 INFO     start - start.c:do_start:1198 - Unshared CLONE_NEWCGROUP
lxc-execute test 20220110042112.683 TRACE    conf - conf.c:turn_into_dependent_mounts:3054 - Turned all mount table entries into dependent mount
lxc-execute test 20220110042112.683 INFO     conf - conf.c:mount_autodev:1056 - Preparing "/dev"
lxc-execute test 20220110042112.683 DEBUG    conf - conf.c:mount_autodev:1059 - Using mount options: size=500000,mode=755
lxc-execute test 20220110042112.683 TRACE    conf - conf.c:mount_autodev:1089 - Mounted tmpfs on "(null)"
lxc-execute test 20220110042112.683 INFO     conf - conf.c:mount_autodev:1106 - Prepared "/dev"
lxc-execute test 20220110042112.683 TRACE    conf - conf.c:lxc_mount_auto_mounts:662 - Container does not have CAP_NET_ADMIN. Skipping "%r/proc/sys/net" mount
lxc-execute test 20220110042112.683 TRACE    conf - conf.c:lxc_mount_auto_mounts:662 - Container does not have CAP_NET_ADMIN. Skipping "%r/proc/tty" mount
lxc-execute test 20220110042112.684 ERROR    conf - conf.c:lxc_setup:3354 - No such file or directory - Unable to open lxc.init.static
lxc-execute test 20220110042112.684 ERROR    start - start.c:do_start:1218 - Failed to setup container "test"
lxc-execute test 20220110042112.684 TRACE    sync - sync.c:lxc_sync_wake_parent:78 - Child waking parent with sequence error
lxc-execute test 20220110042112.684 ERROR    sync - sync.c:__sync_wait:36 - An error occurred in another process (expected sequence number 5)
lxc-execute test 20220110042112.684 DEBUG    network - network.c:lxc_delete_network:3672 - Deleted network devices
lxc-execute test 20220110042112.684 TRACE    start - start.c:lxc_serve_state_clients:438 - Set container state to ABORTING
lxc-execute test 20220110042112.684 TRACE    start - start.c:lxc_serve_state_clients:441 - No state clients registered
lxc-execute test 20220110042112.684 INFO     utils - utils.c:get_rundir:260 - XDG_RUNTIME_DIR isn't set in the environment
lxc-execute test 20220110042112.684 ERROR    start - start.c:__lxc_start:1999 - Failed to spawn container "test"
lxc-execute test 20220110042112.684 TRACE    start - start.c:lxc_serve_state_clients:438 - Set container state to ABORTING
lxc-execute test 20220110042112.684 TRACE    start - start.c:lxc_serve_state_clients:441 - No state clients registered
lxc-execute test 20220110042112.684 INFO     utils - utils.c:get_rundir:260 - XDG_RUNTIME_DIR isn't set in the environment
lxc-execute test 20220110042112.684 WARN     start - start.c:lxc_abort:1018 - No such process - Failed to send SIGKILL to 20804
lxc-execute test 20220110042112.684 TRACE    start - start.c:lxc_serve_state_clients:438 - Set container state to STOPPING
lxc-execute test 20220110042112.684 TRACE    start - start.c:lxc_serve_state_clients:441 - No state clients registered
lxc-execute test 20220110042112.684 INFO     utils - utils.c:get_rundir:260 - XDG_RUNTIME_DIR isn't set in the environment
lxc-execute test 20220110042112.684 TRACE    conf - conf.c:get_minimal_idmap:4036 - Allocated minimal idmapping for ns uid 0 and ns gid 0
lxc-execute test 20220110042112.684 TRACE    process_utils - process_utils.c:lxc_raw_clone:110 - Function not implemented - Falling back to legacy clone
lxc-execute test 20220110042112.684 TRACE    conf - conf.c:userns_exec_1:4102 - Establishing uid mapping for "20822" in new user namespace: nsuid 1002 - hostid 1002 - range 1
lxc-execute test 20220110042112.684 TRACE    conf - conf.c:userns_exec_1:4102 - Establishing uid mapping for "20822" in new user namespace: nsuid 0 - hostid 231072 - range 10
lxc-execute test 20220110042112.684 TRACE    conf - conf.c:userns_exec_1:4102 - Establishing gid mapping for "20822" in new user namespace: nsuid 1003 - hostid 1003 - range 1
lxc-execute test 20220110042112.684 TRACE    conf - conf.c:userns_exec_1:4102 - Establishing gid mapping for "20822" in new user namespace: nsuid 0 - hostid 231072 - range 10
lxc-execute test 20220110042112.684 DEBUG    conf - conf.c:idmaptool_on_path_and_privileged:2728 - The binary "/usr/bin/newuidmap" does have the setuid bit set
lxc-execute test 20220110042112.684 DEBUG    conf - conf.c:idmaptool_on_path_and_privileged:2728 - The binary "/usr/bin/newgidmap" does have the setuid bit set
lxc-execute test 20220110042112.684 DEBUG    conf - conf.c:lxc_map_ids:2796 - Functional newuidmap and newgidmap binary found
lxc-execute test 20220110042112.684 TRACE    process_utils - process_utils.c:lxc_raw_clone:110 - Function not implemented - Falling back to legacy clone
lxc-execute test 20220110042112.687 TRACE    conf - conf.c:lxc_map_ids:2866 - newuidmap wrote mapping "newuidmap 20822 1002 1002 1 0 231072 10"
lxc-execute test 20220110042112.687 TRACE    process_utils - process_utils.c:lxc_raw_clone:110 - Function not implemented - Falling back to legacy clone
lxc-execute test 20220110042112.689 TRACE    conf - conf.c:lxc_map_ids:2866 - newgidmap wrote mapping "newgidmap 20822 1003 1003 1 0 231072 10"
lxc-execute test 20220110042112.689 TRACE    conf - conf.c:run_userns_fn:3878 - Calling function "cgroup_tree_remove_wrapper"
lxc-execute test 20220110042112.689 NOTICE   utils - utils.c:lxc_setgroups:1420 - Dropped additional groups
lxc-execute test 20220110042112.690 TRACE    start - start.c:lxc_end:936 - Closed command socket
lxc-execute test 20220110042112.690 INFO     utils - utils.c:get_rundir:260 - XDG_RUNTIME_DIR isn't set in the environment
lxc-execute test 20220110042112.690 TRACE    start - start.c:lxc_end:947 - Set container state to "STOPPED"
lxc-execute test 20220110042112.745 ERROR    lxc_execute - tools/lxc_execute.c:main:226 - Failed run an application inside container
$
```

LXC does not find the static init binary it needs to use.