canonical-ubuntu-qa team mailing list archive

[Po-Hsu Lin <2027647@xxxxxxxxxxxxxxxxxx>]

Public bug reported:

The lxc-test-api-reboot test will fail on s390x LPAR (passed with s390x
zVM), this is not a regression but something can be at least traced back
to 5.15.0-47.51 in sru-20220808.

Test log:
$ sudo ./lxc-test-api-reboot 
api_reboot.c: 57: main: Using "/tmp/reboot_kaCalL" as temporary log file for container lxc-api-reboot

reboot: lxc-api-reboot: commands_utils.c: lxc_cmd_sock_rcv_state: 49 Resource temporarily unavailable - Failed to receive message
api_reboot.c: 119: main: Failed to reboot container "reboot"

reboot lxc-api-reboot 20230713035632.902 TRACE    lxccontainer - lxccontainer.c:create_partial:197 - Created "4/partial" to mark container as partially created
reboot lxc-api-reboot 20230713035632.902 TRACE    dir - storage/dir.c:dir_create:87 - Created directory "/var/lib/lxc/lxc-api-reboot/rootfs"
reboot lxc-api-reboot 20230713035632.902 TRACE    lxccontainer - lxccontainer.c:do_lxcapi_save_config:2672 - Saved config file "/var/lib/lxc/lxc-api-reboot/config"
reboot lxc-api-reboot 20230713035632.902 TRACE    utils - utils.c:wait_exited:349 - Reaped child process 103752
reboot lxc-api-reboot 20230713035632.902 DEBUG    storage - storage/storage.c:get_storage_by_name:209 - Detected rootfs type "dir"
reboot lxc-api-reboot 20230713035633.225 TRACE    start - start.c:lxc_init_handler:741 - Created anonymous pair {4,5} of unix sockets
reboot lxc-api-reboot 20230713035633.225 TRACE    commands - commands.c:lxc_server_init:2078 - Created abstract unix socket "/var/lib/lxc/lxc-api-reboot/command"
reboot lxc-api-reboot 20230713035633.225 TRACE    start - start.c:lxc_init_handler:757 - Unix domain socket 6 for command server is ready
reboot lxc-api-reboot 20230713035633.225 INFO     lxccontainer - lxccontainer.c:do_lxcapi_start:997 - Set process title to [lxc monitor] /var/lib/lxc lxc-api-reboot
reboot lxc-api-reboot 20230713035633.225 DEBUG    lxccontainer - lxccontainer.c:wait_on_daemonized_start:858 - First child 104053 exited
reboot lxc-api-reboot 20230713035633.226 TRACE    start - start.c:lxc_start:2188 - Doing lxc_start
reboot lxc-api-reboot 20230713035633.226 INFO     lsm - lsm/lsm.c:lsm_init_static:38 - Initialized LSM security driver AppArmor
reboot lxc-api-reboot 20230713035633.226 TRACE    start - start.c:lxc_init:781 - Initialized LSM
reboot lxc-api-reboot 20230713035633.226 TRACE    start - start.c:lxc_serve_state_clients:486 - Set container state to STARTING
reboot lxc-api-reboot 20230713035633.226 TRACE    start - start.c:lxc_serve_state_clients:489 - No state clients registered
reboot lxc-api-reboot 20230713035633.226 TRACE    start - start.c:lxc_init:787 - Set container state to "STARTING"
reboot lxc-api-reboot 20230713035633.226 TRACE    start - start.c:lxc_init:843 - Set environment variables
reboot lxc-api-reboot 20230713035633.226 TRACE    start - start.c:lxc_init:848 - Ran pre-start hooks
reboot lxc-api-reboot 20230713035633.226 TRACE    start - start.c:setup_signal_fd:373 - Created signal file descriptor 9
reboot lxc-api-reboot 20230713035633.226 TRACE    start - start.c:lxc_init:861 - Set up signal fd
reboot lxc-api-reboot 20230713035633.226 TRACE    cgfsng - cgroups/cgfsng.c:cgroup_hierarchy_add:456 - Adding cgroup hierarchy mounted at  and base cgroup (null)
reboot lxc-api-reboot 20230713035633.226 TRACE    cgfsng - cgroups/cgfsng.c:cgroup_hierarchy_add:459 - The hierarchy contains the cpuset controller
reboot lxc-api-reboot 20230713035633.226 TRACE    cgfsng - cgroups/cgfsng.c:cgroup_hierarchy_add:459 - The hierarchy contains the cpu controller
reboot lxc-api-reboot 20230713035633.226 TRACE    cgfsng - cgroups/cgfsng.c:cgroup_hierarchy_add:459 - The hierarchy contains the io controller
reboot lxc-api-reboot 20230713035633.226 TRACE    cgfsng - cgroups/cgfsng.c:cgroup_hierarchy_add:459 - The hierarchy contains the memory controller
reboot lxc-api-reboot 20230713035633.226 TRACE    cgfsng - cgroups/cgfsng.c:cgroup_hierarchy_add:459 - The hierarchy contains the hugetlb controller
reboot lxc-api-reboot 20230713035633.226 TRACE    cgfsng - cgroups/cgfsng.c:cgroup_hierarchy_add:459 - The hierarchy contains the pids controller
reboot lxc-api-reboot 20230713035633.226 TRACE    cgfsng - cgroups/cgfsng.c:cgroup_hierarchy_add:459 - The hierarchy contains the rdma controller
reboot lxc-api-reboot 20230713035633.226 TRACE    cgfsng - cgroups/cgfsng.c:cgroup_hierarchy_add:459 - The hierarchy contains the misc controller
reboot lxc-api-reboot 20230713035633.226 TRACE    cgroup2_devices - cgroups/cgroup2_devices.c:bpf_program_load_kernel:335 - Loaded bpf program: func#0 @0
0: R1=ctx(id=0,off=0,imm=0) R10=fp0
0: (61) r2 = *(u32 *)(r1 +0)
1: R1=ctx(id=0,off=0,imm=0) R2_w=inv(id=0,umax_value=4294967295,var_off=(0x0; 0xffffffff)) R10=fp0
1: (54) w2 &= 65535
2: R1=ctx(id=0,off=0,imm=0) R2_w=inv(id=0,umax_value=65535,var_off=(0x0; 0xffff)) R10=fp0
2: (61) r3 = *(u32 *)(r1 +0)
3: R1=ctx(id=0,off=0,imm=0) R2_w=inv(id=0,umax_value=65535,var_off=(0x0; 0xffff)) R3_w=inv(id=0,umax_value=4294967295,var_off=(0x0; 0xffffffff)) R10=fp0
3: (74) w3 >>= 16
4: R1=ctx(id=0,off=0,imm=0) R2_w=inv(id=0,umax_value=65535,var_off=(0x0; 0xffff)) R3_w=inv(id=0,umax_value=65535,var_off=(0x0; 0xffff)) R10=fp0
4: (61) r4 = *(u32 *)(r1 +4)
5: R1=ctx(id=0,off=0,imm=0) R2_w=inv(id=0,umax_value=65535,var_off=(0x0; 0xffff)) R3_w=inv(id=0,umax_value=65535,var_off=(0x0; 0xffff)) R4_w=inv(id=0,umax_value=4294967295,var_off=(0x0; 0xffffffff)) R10=fp0
5: (61) r5 = *(u32 *)(r1 +8)
6: R1=ctx(id=0,off=0,imm=0) R2_w=inv(id=0,umax_value=65535,var_off=(0x0; 0xffff)) R3_w=inv(id=0,umax_value=65535,var_off=(0x0; 0xffff)) R4_w=inv(id=0,umax_value=4294967295,var_off=(0x0; 0xffffffff)) R5_w=inv(id=0,umax_value=4294967295,var_off=(0x0; 0xffffffff)) R10=fp0
6: (b7) r0 = 1
7: R0_w=inv1 R1=ctx(id=0,off=0,imm=0) R2_w=inv(id=0,umax_value=65535,var_off=(0x0; 0xffff)) R3_w=inv(id=0,umax_value=65535,var_off=(0x0; 0xffff)) R4_w=inv(id=0,umax_value=4294967295,var_off=(0x0; 0xffffffff)) R5_w=inv(id=0,umax_value=4294967295,var_off=(0x0; 0xffffffff)) R10=fp0
7: (95) exit
processed 8 insns (limit 1000000) max_states_per_insn 0 total_states 0 peak_states 0 mark_read 0

reboot lxc-api-reboot 20230713035633.226 TRACE    cgroup2_devices - cgroups/cgroup2_devices.c:bpf_devices_cgroup_supported:553 - The bpf device cgroup is supported
reboot lxc-api-reboot 20230713035633.226 TRACE    cgroup - cgroups/cgroup.c:cgroup_init:40 - Initialized cgroup driver cgfsng
reboot lxc-api-reboot 20230713035633.226 TRACE    cgroup - cgroups/cgroup.c:cgroup_init:47 - Unified cgroup layout
reboot lxc-api-reboot 20230713035633.226 TRACE    start - start.c:lxc_init:868 - Initialized cgroup driver
reboot lxc-api-reboot 20230713035633.226 TRACE    start - start.c:lxc_init:875 - Read seccomp policy
reboot lxc-api-reboot 20230713035633.226 TRACE    start - start.c:lxc_init:882 - Initialized LSM
reboot lxc-api-reboot 20230713035633.226 INFO     start - start.c:lxc_init:884 - Container "lxc-api-reboot" is initialized
reboot lxc-api-reboot 20230713035633.226 TRACE    cgfsng - cgroups/cgfsng.c:__cgroup_tree_create:715 - Reusing 12(lxc.monitor.lxc-api-reboot) cgroup
reboot lxc-api-reboot 20230713035633.226 TRACE    cgfsng - cgroups/cgfsng.c:__cgroup_tree_create:730 - Opened cgroup lxc.monitor.lxc-api-reboot as 13
reboot lxc-api-reboot 20230713035633.226 WARN     cgfsng - cgroups/cgfsng.c:__cgroup_tree_create:736 - File exists - Creating the final cgroup 12(lxc.monitor.lxc-api-reboot) failed
reboot lxc-api-reboot 20230713035633.226 WARN     cgfsng - cgroups/cgfsng.c:cgroup_tree_create:796 - File exists - Failed to create monitor cgroup 12(lxc.monitor.lxc-api-reboot)
reboot lxc-api-reboot 20230713035633.226 DEBUG    cgfsng - cgroups/cgfsng.c:cgfsng_monitor_create:1015 - Failed to create cgroup lxc.monitor.lxc-api-reboot)
reboot lxc-api-reboot 20230713035633.279 TRACE    cgfsng - cgroups/cgfsng.c:__cgroup_tree_create:715 - Created 12(lxc.monitor.lxc-api-reboot-1) cgroup
reboot lxc-api-reboot 20230713035633.279 TRACE    cgfsng - cgroups/cgfsng.c:__cgroup_tree_create:730 - Opened newly created cgroup lxc.monitor.lxc-api-reboot-1 as 13
reboot lxc-api-reboot 20230713035633.279 INFO     cgfsng - cgroups/cgfsng.c:cgfsng_monitor_create:1029 - The monitor process uses "lxc.monitor.lxc-api-reboot-1" as cgroup
reboot lxc-api-reboot 20230713035633.279 TRACE    cgfsng - cgroups/cgfsng.c:__cgfsng_delegate_controllers:2955 - Enabled "+cpuset +cpu +io +memory +hugetlb +pids +rdma +misc" controllers in the unified cgroup 12
reboot lxc-api-reboot 20230713035633.279 TRACE    cgfsng - cgroups/cgfsng.c:cgfsng_monitor_enter:1179 - Moved monitor into cgroup 13
reboot lxc-api-reboot 20230713035633.279 TRACE    cgfsng - cgroups/cgfsng.c:cgfsng_monitor_enter:1188 - Moved transient process into cgroup 13
reboot lxc-api-reboot 20230713035633.279 DEBUG    storage - storage/storage.c:get_storage_by_name:209 - Detected rootfs type "dir"
reboot lxc-api-reboot 20230713035633.279 TRACE    conf - conf.c:lxc_rootfs_init:587 - Pinned rootfs 15(.lxc_keep)
reboot lxc-api-reboot 20230713035633.279 TRACE    conf - conf.c:lxc_rootfs_init:603 - Unlinked pinned file 14(.lxc_keep)
reboot lxc-api-reboot 20230713035633.279 TRACE    sync - sync.c:lxc_sync_init:139 - Initialized synchronization infrastructure
reboot lxc-api-reboot 20230713035633.280 TRACE    cgfsng - cgroups/cgfsng.c:__cgroup_tree_create:715 - Created 12(lxc.payload.lxc-api-reboot) cgroup
reboot lxc-api-reboot 20230713035633.280 TRACE    cgfsng - cgroups/cgfsng.c:__cgroup_tree_create:730 - Opened newly created cgroup lxc.payload.lxc-api-reboot as 19
reboot lxc-api-reboot 20230713035633.280 INFO     cgfsng - cgroups/cgfsng.c:cgfsng_payload_create:1137 - The container process uses "lxc.payload.lxc-api-reboot" as inner and "lxc.payload.lxc-api-reboot" as limit cgroup
reboot lxc-api-reboot 20230713035633.285 TRACE    start - start.c:lxc_spawn:1705 - Spawned container directly into target cgroup via cgroup2 fd 19
reboot lxc-api-reboot 20230713035633.285 TRACE    start - start.c:lxc_spawn:1745 - Cloned child process 104055
reboot lxc-api-reboot 20230713035633.285 TRACE    start - start.c:core_scheduling:1563 - No new core scheduling domain requested
reboot lxc-api-reboot 20230713035633.285 TRACE    utils - utils.c:lxc_can_use_pidfd:1822 - Kernel supports pidfds
reboot lxc-api-reboot 20230713035633.285 INFO     start - start.c:lxc_spawn:1765 - Cloned CLONE_NEWNS
reboot lxc-api-reboot 20230713035633.285 INFO     start - start.c:lxc_spawn:1765 - Cloned CLONE_NEWPID
reboot lxc-api-reboot 20230713035633.285 TRACE    start - start.c:lxc_spawn:1705 - Spawned container directly into target cgroup via cgroup2 fd 19
reboot lxc-api-reboot 20230713035633.285 INFO     start - start.c:lxc_spawn:1765 - Cloned CLONE_NEWUTS
reboot lxc-api-reboot 20230713035633.285 INFO     start - start.c:lxc_spawn:1765 - Cloned CLONE_NEWIPC
reboot lxc-api-reboot 20230713035633.285 INFO     start - start.c:lxc_spawn:1765 - Cloned CLONE_NEWNET
reboot lxc-api-reboot 20230713035633.285 INFO     start - start.c:lxc_spawn:1765 - Cloned CLONE_NEWCGROUP
reboot lxc-api-reboot 20230713035633.285 DEBUG    start - start.c:lxc_try_preserve_namespace:139 - Preserved mnt namespace via fd 21 and stashed path as mnt:/proc/104054/fd/21
reboot lxc-api-reboot 20230713035633.285 DEBUG    start - start.c:lxc_try_preserve_namespace:139 - Preserved pid namespace via fd 22 and stashed path as pid:/proc/104054/fd/22
reboot lxc-api-reboot 20230713035633.285 DEBUG    start - start.c:lxc_try_preserve_namespace:139 - Preserved uts namespace via fd 23 and stashed path as uts:/proc/104054/fd/23
reboot lxc-api-reboot 20230713035633.285 DEBUG    start - start.c:lxc_try_preserve_namespace:139 - Preserved ipc namespace via fd 24 and stashed path as ipc:/proc/104054/fd/24
reboot lxc-api-reboot 20230713035633.285 DEBUG    start - start.c:lxc_try_preserve_namespace:139 - Preserved net namespace via fd 25 and stashed path as net:/proc/104054/fd/25
reboot lxc-api-reboot 20230713035633.285 DEBUG    start - start.c:lxc_try_preserve_namespace:139 - Preserved cgroup namespace via fd 26 and stashed path as cgroup:/proc/104054/fd/26
reboot lxc-api-reboot 20230713035633.285 TRACE    cgfsng - cgroups/cgfsng.c:__cgfsng_delegate_controllers:2955 - Enabled "+cpuset +cpu +io +memory +hugetlb +pids +rdma +misc" controllers in the unified cgroup 12
reboot lxc-api-reboot 20230713035633.285 TRACE    sync - sync.c:lxc_sync_barrier_child:97 - Parent waking child with sequence startup and waiting with sequence configure
reboot lxc-api-reboot 20230713035633.285 TRACE    sync - sync.c:lxc_sync_wait_parent:110 - Child waiting for parent with sequence startup
reboot lxc-api-reboot 20230713035633.285 TRACE    sync - sync.c:lxc_sync_wake_parent:104 - Child waking parent with sequence configure
reboot lxc-api-reboot 20230713035633.285 TRACE    sync - sync.c:lxc_sync_wait_parent:110 - Child waiting for parent with sequence post-configure
reboot lxc-api-reboot 20230713035633.285 TRACE    start - start.c:lxc_spawn:1837 - Allocated new network namespace id
reboot lxc-api-reboot 20230713035633.292 INFO     network - network.c:netdev_configure_server_veth:655 - Retrieved mtu 1500 from lxcbr0
reboot lxc-api-reboot 20230713035633.311 INFO     network - network.c:netdev_configure_server_veth:721 - Attached "vethzkixON" to bridge "lxcbr0"
reboot lxc-api-reboot 20230713035633.312 DEBUG    network - network.c:netdev_configure_server_veth:852 - Instantiated veth tunnel "vethzkixON <--> vethVmOxOI"
reboot lxc-api-reboot 20230713035633.312 TRACE    sync - sync.c:lxc_sync_wake_child:122 - Parent waking child with sequence post-configure
reboot lxc-api-reboot 20230713035633.312 TRACE    network - network.c:lxc_network_send_to_child:4060 - Sent network device name "vethVmOxOI" to child
reboot lxc-api-reboot 20230713035633.312 TRACE    sync - sync.c:lxc_sync_wait_child:116 - Parent waiting for child with sequence idmapped-mounts
reboot lxc-api-reboot 20230713035633.312 TRACE    conf - conf.c:turn_into_dependent_mounts:3923 - Turned all mount table entries into dependent mount
reboot lxc-api-reboot 20230713035633.312 TRACE    mount_utils - mount_utils.c:can_use_mount_api:582 - Kernel supports mount api
reboot lxc-api-reboot 20230713035633.312 TRACE    mount_utils - mount_utils.c:can_use_bind_mounts:607 - Kernel supports bind mounts in the new mount api
reboot lxc-api-reboot 20230713035633.312 TRACE    mount_utils - mount_utils.c:move_detached_mount:328 - Attach detached mount 23 to filesystem at 21
reboot lxc-api-reboot 20230713035633.312 TRACE    dir - storage/dir.c:dir_mount:196 - Mounted "/var/lib/lxc/lxc-api-reboot/rootfs" onto "/usr/lib/s390x-linux-gnu/lxc"
reboot lxc-api-reboot 20230713035633.312 DEBUG    conf - conf.c:lxc_mount_rootfs:1436 - Mounted rootfs "/var/lib/lxc/lxc-api-reboot/rootfs" onto "/usr/lib/s390x-linux-gnu/lxc" with options "(null)"
reboot lxc-api-reboot 20230713035633.312 TRACE    conf - conf.c:lxc_mount_rootfs:1444 - Container uses separate rootfs. Opened container's rootfs
reboot lxc-api-reboot 20230713035633.312 INFO     conf - conf.c:setup_utsname:875 - Set hostname to "lxc-api-reboot"
reboot lxc-api-reboot 20230713035633.312 TRACE    network - network.c:lxc_network_recv_from_parent:4085 - Received network device name "vethVmOxOI" from parent
reboot lxc-api-reboot 20230713035633.312 INFO     network - network.c:lxc_setup_network_in_child_namespaces:4019 - Finished setting up network devices with caller assigned names
reboot lxc-api-reboot 20230713035633.377 TRACE    network - network.c:__netdev_configure_container_common:1291 - Renamed network device from "vethVmOxOI" to "eth%d"
reboot lxc-api-reboot 20230713035633.378 DEBUG    network - network.c:lxc_network_setup_in_child_namespaces_common:3962 - Network device "eth0" has been setup
reboot lxc-api-reboot 20230713035633.378 INFO     network - network.c:lxc_setup_network_in_child_namespaces:4035 - Finished setting up network devices with kernel assigned names
reboot lxc-api-reboot 20230713035633.378 INFO     conf - conf.c:mount_autodev:1219 - Preparing "/dev"
reboot lxc-api-reboot 20230713035633.378 TRACE    mount_utils - mount_utils.c:__fs_prepare:177 - Finished initializing new tmpfs filesystem context 22
reboot lxc-api-reboot 20230713035633.378 TRACE    mount_utils - mount_utils.c:fs_set_property:215 - Set "mode" to "0755" on filesystem context 22
reboot lxc-api-reboot 20230713035633.378 TRACE    mount_utils - mount_utils.c:fs_set_property:215 - Set "size" to "500000" on filesystem context 22
reboot lxc-api-reboot 20230713035633.378 TRACE    mount_utils - mount_utils.c:fs_attach:266 - Mounted 24 onto 23
reboot lxc-api-reboot 20230713035633.378 INFO     conf - conf.c:mount_autodev:1280 - Prepared "/dev"
reboot lxc-api-reboot 20230713035633.378 DEBUG    conf - conf.c:lxc_mount_auto_mounts:735 - Invalid argument - Tried to ensure procfs is unmounted
reboot lxc-api-reboot 20230713035633.378 TRACE    conf - conf.c:lxc_mount_auto_mounts:742 - Created procfs mountpoint under 21
reboot lxc-api-reboot 20230713035633.378 DEBUG    conf - conf.c:lxc_mount_auto_mounts:758 - Invalid argument - Tried to ensure sysfs is unmounted
reboot lxc-api-reboot 20230713035633.378 TRACE    conf - conf.c:lxc_mount_auto_mounts:765 - Created sysfs mountpoint under 21
reboot lxc-api-reboot 20230713035633.378 TRACE    conf - conf.c:lxc_mount_auto_mounts:819 - Mounted automount "proc" on "/usr/lib/s390x-linux-gnu/lxc/proc" read-write with flags 14
reboot lxc-api-reboot 20230713035633.378 TRACE    conf - conf.c:lxc_mount_auto_mounts:819 - Mounted automount "/usr/lib/s390x-linux-gnu/lxc/proc/sys/net" on "/usr/lib/s390x-linux-gnu/lxc/proc/tty" read-write with flags 4096
reboot lxc-api-reboot 20230713035633.378 TRACE    conf - conf.c:lxc_mount_auto_mounts:819 - Mounted automount "/usr/lib/s390x-linux-gnu/lxc/proc/sys" on "/usr/lib/s390x-linux-gnu/lxc/proc/sys" read-write with flags 4096
reboot lxc-api-reboot 20230713035633.378 TRACE    conf - conf.c:lxc_mount_auto_mounts:817 - Remounted automount "(null)" on "/usr/lib/s390x-linux-gnu/lxc/proc/sys" read-only with flags 4129
reboot lxc-api-reboot 20230713035633.378 TRACE    conf - conf.c:lxc_mount_auto_mounts:819 - Mounted automount "/usr/lib/s390x-linux-gnu/lxc/proc/tty" on "/usr/lib/s390x-linux-gnu/lxc/proc/sys/net" read-write with flags 8192
reboot lxc-api-reboot 20230713035633.378 TRACE    conf - conf.c:lxc_mount_auto_mounts:819 - Mounted automount "/usr/lib/s390x-linux-gnu/lxc/proc/sysrq-trigger" on "/usr/lib/s390x-linux-gnu/lxc/proc/sysrq-trigger" read-write with flags 4096
reboot lxc-api-reboot 20230713035633.378 TRACE    conf - conf.c:lxc_mount_auto_mounts:817 - Remounted automount "(null)" on "/usr/lib/s390x-linux-gnu/lxc/proc/sysrq-trigger" read-only with flags 4129
reboot lxc-api-reboot 20230713035633.378 TRACE    conf - conf.c:lxc_mount_auto_mounts:819 - Mounted automount "sysfs" on "/usr/lib/s390x-linux-gnu/lxc/sys" read-only with flags 1
reboot lxc-api-reboot 20230713035633.378 TRACE    conf - conf.c:parse_vfs_attr:2287 - Raising defaults
reboot lxc-api-reboot 20230713035633.379 DEBUG    conf - conf.c:mount_entry:2479 - Mounted "shm" on "/usr/lib/s390x-linux-gnu/lxc/dev/shm" with filesystem type "tmpfs"
reboot lxc-api-reboot 20230713035633.379 TRACE    conf - conf.c:parse_vfs_attr:2287 - Raising defaults
reboot lxc-api-reboot 20230713035633.379 DEBUG    conf - conf.c:mount_entry:2479 - Mounted "mqueue" on "/usr/lib/s390x-linux-gnu/lxc/dev/mqueue" with filesystem type "mqueue"
reboot lxc-api-reboot 20230713035633.379 TRACE    conf - conf.c:parse_vfs_attr:2287 - Raising ro
reboot lxc-api-reboot 20230713035633.379 DEBUG    conf - conf.c:mount_entry:2416 - Remounting "/lib" on "/usr/lib/s390x-linux-gnu/lxc/lib" to respect bind or remount options
reboot lxc-api-reboot 20230713035633.379 DEBUG    conf - conf.c:mount_entry:2435 - Flags for "/lib" were 4096, required extra flags are 0
reboot lxc-api-reboot 20230713035633.379 DEBUG    conf - conf.c:mount_entry:2479 - Mounted "/lib" on "/usr/lib/s390x-linux-gnu/lxc/lib" with filesystem type "none"
reboot lxc-api-reboot 20230713035633.379 TRACE    conf - conf.c:parse_vfs_attr:2287 - Raising ro
reboot lxc-api-reboot 20230713035633.379 DEBUG    conf - conf.c:mount_entry:2416 - Remounting "/usr/lib" on "/usr/lib/s390x-linux-gnu/lxc/usr/lib" to respect bind or remount options
reboot lxc-api-reboot 20230713035633.379 DEBUG    conf - conf.c:mount_entry:2435 - Flags for "/usr/lib" were 4096, required extra flags are 0
reboot lxc-api-reboot 20230713035633.379 DEBUG    conf - conf.c:mount_entry:2479 - Mounted "/usr/lib" on "/usr/lib/s390x-linux-gnu/lxc/usr/lib" with filesystem type "none"
reboot lxc-api-reboot 20230713035633.379 TRACE    conf - conf.c:parse_vfs_attr:2287 - Raising ro
reboot lxc-api-reboot 20230713035633.379 DEBUG    conf - conf.c:mount_entry:2416 - Remounting "/sys/kernel/security" on "/usr/lib/s390x-linux-gnu/lxc/sys/kernel/security" to respect bind or remount options
reboot lxc-api-reboot 20230713035633.379 DEBUG    conf - conf.c:mount_entry:2435 - Flags for "/sys/kernel/security" were 4110, required extra flags are 14
reboot lxc-api-reboot 20230713035633.379 DEBUG    conf - conf.c:mount_entry:2479 - Mounted "/sys/kernel/security" on "/usr/lib/s390x-linux-gnu/lxc/sys/kernel/security" with filesystem type "none"
reboot lxc-api-reboot 20230713035633.379 TRACE    sync - sync.c:lxc_sync_wake_parent:104 - Child waking parent with sequence idmapped-mounts
reboot lxc-api-reboot 20230713035633.379 TRACE    conf - conf.c:parse_vfs_attr:2287 - Raising defaults
reboot lxc-api-reboot 20230713035633.379 TRACE    conf - conf.c:parse_vfs_attr:2287 - Raising defaults
reboot lxc-api-reboot 20230713035633.379 TRACE    conf - conf.c:parse_vfs_attr:2287 - Raising ro
reboot lxc-api-reboot 20230713035633.379 TRACE    conf - conf.c:parse_vfs_attr:2287 - Raising ro
reboot lxc-api-reboot 20230713035633.379 TRACE    conf - conf.c:parse_vfs_attr:2287 - Raising ro
reboot lxc-api-reboot 20230713035633.379 TRACE    conf - conf.c:lxc_idmapped_mounts_child:3100 - Finished setting up idmapped mounts
reboot lxc-api-reboot 20230713035633.379 TRACE    cgfsng - cgroups/cgfsng.c:cgfsng_mount:1612 - Mixed cgroup mounts requested
reboot lxc-api-reboot 20230713035633.379 TRACE    mount_utils - mount_utils.c:__fs_prepare:177 - Finished initializing new cgroup2 filesystem context 24
reboot lxc-api-reboot 20230713035633.379 TRACE    mount_utils - mount_utils.c:fs_attach:266 - Mounted 25 onto 23
reboot lxc-api-reboot 20230713035633.379 DEBUG    cgfsng - cgroups/cgfsng.c:__cgroupfs_mount:1542 - Mounted cgroup filesystem cgroup2 onto 23((null))
reboot lxc-api-reboot 20230713035633.379 TRACE    cgfsng - cgroups/cgfsng.c:cgfsng_mount:1710 - Force mounted cgroup filesystem in new cgroup namespace
reboot lxc-api-reboot 20230713035633.379 INFO     conf - conf.c:lxc_fill_autodev:1317 - Populating "/dev"
reboot lxc-api-reboot 20230713035633.379 DEBUG    conf - conf.c:lxc_fill_autodev:1326 - Created device node "full"
reboot lxc-api-reboot 20230713035633.379 DEBUG    conf - conf.c:lxc_fill_autodev:1326 - Created device node "null"
reboot lxc-api-reboot 20230713035633.379 DEBUG    conf - conf.c:lxc_fill_autodev:1326 - Created device node "random"
reboot lxc-api-reboot 20230713035633.379 DEBUG    conf - conf.c:lxc_fill_autodev:1326 - Created device node "tty"
reboot lxc-api-reboot 20230713035633.379 DEBUG    conf - conf.c:lxc_fill_autodev:1326 - Created device node "urandom"
reboot lxc-api-reboot 20230713035633.379 DEBUG    conf - conf.c:lxc_fill_autodev:1326 - Created device node "zero"
reboot lxc-api-reboot 20230713035633.379 INFO     conf - conf.c:lxc_fill_autodev:1405 - Populated "/dev"
reboot lxc-api-reboot 20230713035633.379 INFO     conf - conf.c:lxc_transient_proc:3775 - Caller's PID is 1; /proc/self points to 1
reboot lxc-api-reboot 20230713035633.379 TRACE    conf - conf.c:lxc_transient_proc:3783 - Correct procfs instance mounted
reboot lxc-api-reboot 20230713035633.379 TRACE    mount_utils - mount_utils.c:__fs_prepare:177 - Finished initializing new devpts filesystem context 23
reboot lxc-api-reboot 20230713035633.379 TRACE    mount_utils - mount_utils.c:fs_set_property:215 - Set "source" to "devpts" on filesystem context 23
reboot lxc-api-reboot 20230713035633.379 TRACE    mount_utils - mount_utils.c:fs_set_property:215 - Set "gid" to "5" on filesystem context 23
reboot lxc-api-reboot 20230713035633.379 TRACE    mount_utils - mount_utils.c:fs_set_flag:227 - Set "newinstance" flag on filesystem context 23
reboot lxc-api-reboot 20230713035633.379 TRACE    mount_utils - mount_utils.c:fs_set_property:215 - Set "ptmxmode" to "0666" on filesystem context 23
reboot lxc-api-reboot 20230713035633.379 TRACE    mount_utils - mount_utils.c:fs_set_property:215 - Set "mode" to "0620" on filesystem context 23
reboot lxc-api-reboot 20230713035633.379 TRACE    mount_utils - mount_utils.c:fs_set_property:215 - Set "max" to "6" on filesystem context 23
reboot lxc-api-reboot 20230713035633.379 TRACE    conf - conf.c:lxc_idmapped_mounts_parent:4123 - Finished receiving idmapped mount file descriptors from child
reboot lxc-api-reboot 20230713035633.379 TRACE    sync - sync.c:lxc_sync_wait_child:116 - Parent waiting for child with sequence cgroup-limits
reboot lxc-api-reboot 20230713035633.379 TRACE    conf - conf.c:lxc_setup_devpts_child:1745 - Created detached devpts mount 24
reboot lxc-api-reboot 20230713035633.379 DEBUG    conf - conf.c:lxc_setup_devpts_child:1751 - Attached detached devpts mount 24 to 22/pts
reboot lxc-api-reboot 20230713035633.379 DEBUG    conf - conf.c:lxc_setup_devpts_child:1837 - Created "/dev/ptmx" file as bind mount target
reboot lxc-api-reboot 20230713035633.379 TRACE    mount_utils - mount_utils.c:mount_at:663 - Mounted "/proc/self/fd/25" to "/proc/self/fd/26"
reboot lxc-api-reboot 20230713035633.379 DEBUG    conf - conf.c:lxc_setup_devpts_child:1844 - Bind mounted "/dev/pts/ptmx" to "/dev/ptmx"
reboot lxc-api-reboot 20230713035633.379 TRACE    mount_utils - mount_utils.c:move_detached_mount:328 - Attach detached mount 27 to filesystem at 25
reboot lxc-api-reboot 20230713035633.379 TRACE    conf - conf.c:lxc_setup_dev_console:2003 - Setup console "/dev/pts/1"
reboot lxc-api-reboot 20230713035633.379 DEBUG    conf - conf.c:lxc_allocate_ttys:1104 - Created tty with ptx fd 8 and pty fd 10 and index 0
reboot lxc-api-reboot 20230713035633.379 DEBUG    conf - conf.c:lxc_allocate_ttys:1104 - Created tty with ptx fd 23 and pty fd 25 and index 1
reboot lxc-api-reboot 20230713035633.379 DEBUG    conf - conf.c:lxc_allocate_ttys:1104 - Created tty with ptx fd 26 and pty fd 27 and index 2
reboot lxc-api-reboot 20230713035633.379 DEBUG    conf - conf.c:lxc_allocate_ttys:1104 - Created tty with ptx fd 28 and pty fd 29 and index 3
reboot lxc-api-reboot 20230713035633.379 DEBUG    conf - conf.c:lxc_allocate_ttys:1104 - Created tty with ptx fd 30 and pty fd 31 and index 4
reboot lxc-api-reboot 20230713035633.379 INFO     conf - conf.c:lxc_allocate_ttys:1109 - Finished creating 5 tty devices
reboot lxc-api-reboot 20230713035633.379 TRACE    mount_utils - mount_utils.c:move_detached_mount:328 - Attach detached mount 33 to filesystem at 32
reboot lxc-api-reboot 20230713035633.379 DEBUG    conf - conf.c:lxc_setup_ttys:1065 - Bind mounted "pts/0" onto "tty1"
reboot lxc-api-reboot 20230713035633.380 TRACE    mount_utils - mount_utils.c:move_detached_mount:328 - Attach detached mount 33 to filesystem at 32
reboot lxc-api-reboot 20230713035633.380 DEBUG    conf - conf.c:lxc_setup_ttys:1065 - Bind mounted "pts/1" onto "tty2"
reboot lxc-api-reboot 20230713035633.380 TRACE    mount_utils - mount_utils.c:move_detached_mount:328 - Attach detached mount 33 to filesystem at 32
reboot lxc-api-reboot 20230713035633.380 DEBUG    conf - conf.c:lxc_setup_ttys:1065 - Bind mounted "pts/2" onto "tty3"
reboot lxc-api-reboot 20230713035633.380 TRACE    mount_utils - mount_utils.c:move_detached_mount:328 - Attach detached mount 33 to filesystem at 32
reboot lxc-api-reboot 20230713035633.380 DEBUG    conf - conf.c:lxc_setup_ttys:1065 - Bind mounted "pts/3" onto "tty4"
reboot lxc-api-reboot 20230713035633.380 TRACE    mount_utils - mount_utils.c:move_detached_mount:328 - Attach detached mount 33 to filesystem at 32
reboot lxc-api-reboot 20230713035633.380 DEBUG    conf - conf.c:lxc_setup_ttys:1065 - Bind mounted "pts/4" onto "tty5"
reboot lxc-api-reboot 20230713035633.380 INFO     conf - conf.c:lxc_setup_ttys:1072 - Finished setting up 5 /dev/tty<N> device(s)
reboot lxc-api-reboot 20230713035633.380 TRACE    conf - conf.c:lxc_create_ttys:1186 - Set "container_ttys=container_ttys=pts/0 pts/1 pts/2 pts/3 pts/4"
reboot lxc-api-reboot 20230713035633.381 TRACE    conf - conf.c:lxc_pivot_root:1623 - Changed into new rootfs "/usr/lib/s390x-linux-gnu/lxc"
reboot lxc-api-reboot 20230713035633.381 DEBUG    conf - conf.c:setup_personality:1911 - Retaining original personality
reboot lxc-api-reboot 20230713035633.381 DEBUG    conf - conf.c:capabilities_deny:3200 - Dropped sys_module (16) capability
reboot lxc-api-reboot 20230713035633.381 DEBUG    conf - conf.c:capabilities_deny:3200 - Dropped mac_admin (33) capability
reboot lxc-api-reboot 20230713035633.381 DEBUG    conf - conf.c:capabilities_deny:3200 - Dropped mac_override (32) capability
reboot lxc-api-reboot 20230713035633.381 DEBUG    conf - conf.c:capabilities_deny:3200 - Dropped sys_time (25) capability
reboot lxc-api-reboot 20230713035633.381 DEBUG    conf - conf.c:capabilities_deny:3203 - Capabilities have been setup
reboot lxc-api-reboot 20230713035633.381 NOTICE   conf - conf.c:lxc_setup:4469 - The container "lxc-api-reboot" is set up
reboot lxc-api-reboot 20230713035633.381 TRACE    apparmor - lsm/apparmor.c:__apparmor_process_label_open:403 - On-exec not supported with AppArmor
reboot lxc-api-reboot 20230713035633.381 TRACE    apparmor - lsm/apparmor.c:apparmor_process_label_set_at:1170 - Changing AppArmor profile on exec not supported
reboot lxc-api-reboot 20230713035633.381 INFO     apparmor - lsm/apparmor.c:apparmor_process_label_set_at:1186 - Set AppArmor label to "lxc-container-default-cgns"
reboot lxc-api-reboot 20230713035633.381 INFO     apparmor - lsm/apparmor.c:apparmor_process_label_set:1231 - Changed AppArmor profile to lxc-container-default-cgns
reboot lxc-api-reboot 20230713035633.382 TRACE    sync - sync.c:lxc_sync_barrier_parent:90 - Child waking parent with sequence cgroup-limits and waiting for sequence fds
reboot lxc-api-reboot 20230713035633.382 TRACE    start - start.c:lxc_spawn:1899 - Set up legacy device cgroup controller limits
reboot lxc-api-reboot 20230713035633.382 TRACE    start - start.c:lxc_spawn:1905 - Set up cgroup2 device controller limits
reboot lxc-api-reboot 20230713035633.382 TRACE    cgfsng - cgroups/cgfsng.c:cgfsng_finalize:1393 - Unified hierarchy supports freezer
reboot lxc-api-reboot 20230713035633.382 TRACE    start - start.c:lxc_spawn:1908 - Finished setting up cgroups
reboot lxc-api-reboot 20230713035633.382 TRACE    sync - sync.c:lxc_sync_wake_child:122 - Parent waking child with sequence fds
reboot lxc-api-reboot 20230713035633.382 TRACE    conf - conf.c:lxc_send_devpts_to_parent:1899 - Sent devpts file descriptor 24 to parent
reboot lxc-api-reboot 20230713035633.382 TRACE    conf - conf.c:lxc_recv_devpts_from_child:1681 - Received devpts file descriptor 7 from child
reboot lxc-api-reboot 20230713035633.382 TRACE    conf - conf.c:__lxc_send_ttys_to_parent:1149 - Sent tty "pts/0" with ptx fd 8 and pty fd 10 to parent
reboot lxc-api-reboot 20230713035633.382 TRACE    conf - conf.c:lxc_recv_ttys_from_child:4185 - Received pty with ptx fd 11 and pty fd 13 from child
reboot lxc-api-reboot 20230713035633.382 TRACE    conf - conf.c:__lxc_send_ttys_to_parent:1149 - Sent tty "pts/1" with ptx fd 23 and pty fd 25 to parent
reboot lxc-api-reboot 20230713035633.382 TRACE    conf - conf.c:lxc_recv_ttys_from_child:4185 - Received pty with ptx fd 14 and pty fd 27 from child
reboot lxc-api-reboot 20230713035633.382 TRACE    conf - conf.c:__lxc_send_ttys_to_parent:1149 - Sent tty "pts/2" with ptx fd 26 and pty fd 27 to parent
reboot lxc-api-reboot 20230713035633.382 TRACE    conf - conf.c:lxc_recv_ttys_from_child:4185 - Received pty with ptx fd 28 and pty fd 29 from child
reboot lxc-api-reboot 20230713035633.382 TRACE    conf - conf.c:__lxc_send_ttys_to_parent:1149 - Sent tty "pts/3" with ptx fd 28 and pty fd 29 to parent
reboot lxc-api-reboot 20230713035633.382 TRACE    conf - conf.c:lxc_recv_ttys_from_child:4185 - Received pty with ptx fd 30 and pty fd 31 from child
reboot lxc-api-reboot 20230713035633.382 TRACE    conf - conf.c:__lxc_send_ttys_to_parent:1149 - Sent tty "pts/4" with ptx fd 30 and pty fd 31 to parent
reboot lxc-api-reboot 20230713035633.382 TRACE    conf - conf.c:lxc_recv_ttys_from_child:4185 - Received pty with ptx fd 32 and pty fd 33 from child
reboot lxc-api-reboot 20230713035633.382 TRACE    conf - conf.c:__lxc_send_ttys_to_parent:1156 - Sent 5 ttys to parent
reboot lxc-api-reboot 20230713035633.382 TRACE    conf - conf.c:lxc_recv_ttys_from_child:4190 - Received 5 ttys from child
reboot lxc-api-reboot 20230713035633.382 TRACE    network - network.c:lxc_network_send_name_and_ifindex_to_parent:4115 - Sent network device eth0 with ifindex 2 to parent
reboot lxc-api-reboot 20230713035633.382 TRACE    network - network.c:lxc_network_recv_name_and_ifindex_from_child:4149 - Received network device eth0 with ifindex 2 from child
reboot lxc-api-reboot 20230713035633.382 TRACE    network - network.c:lxc_network_send_name_and_ifindex_to_parent:4119 - Sent network device names and ifindices to parent
reboot lxc-api-reboot 20230713035633.382 TRACE    conf - conf.c:lxc_sync_fds_parent:4271 - Finished syncing file descriptors with child
reboot lxc-api-reboot 20230713035633.382 TRACE    conf - conf.c:lxc_sync_fds_child:4301 - Finished syncing file descriptors with parent
reboot lxc-api-reboot 20230713035633.382 TRACE    sync - sync.c:lxc_sync_wait_parent:110 - Child waiting for parent with sequence ready-start
reboot lxc-api-reboot 20230713035633.382 DEBUG    terminal - terminal.c:lxc_terminal_peer_default:695 - No such device - The process does not have a controlling terminal
reboot lxc-api-reboot 20230713035633.382 TRACE    sync - sync.c:lxc_sync_barrier_child:97 - Parent waking child with sequence ready-start and waiting with sequence restart
reboot lxc-api-reboot 20230713035633.382 NOTICE   utils - utils.c:lxc_drop_groups:1368 - Dropped supplimentary groups
reboot lxc-api-reboot 20230713035633.382 NOTICE   start - start.c:start:2161 - Exec'ing "/sbin/init"
reboot lxc-api-reboot 20230713035633.382 TRACE    confile_utils - confile_utils.c:lxc_log_configured_netdevs:233 - index: 0
reboot lxc-api-reboot 20230713035633.382 TRACE    confile_utils - confile_utils.c:lxc_log_configured_netdevs:234 - ifindex: 2
reboot lxc-api-reboot 20230713035633.382 TRACE    confile_utils - confile_utils.c:lxc_log_configured_netdevs:238 - type: veth
reboot lxc-api-reboot 20230713035633.382 TRACE    confile_utils - confile_utils.c:lxc_log_configured_netdevs:239 - veth mode: 1
reboot lxc-api-reboot 20230713035633.382 TRACE    confile_utils - confile_utils.c:lxc_log_configured_netdevs:246 - veth1 : vethzkixON
reboot lxc-api-reboot 20230713035633.382 TRACE    confile_utils - confile_utils.c:lxc_log_configured_netdevs:250 - host side ifindex for veth device: 106
reboot lxc-api-reboot 20230713035633.382 TRACE    confile_utils - confile_utils.c:lxc_log_configured_netdevs:308 - flags: none
reboot lxc-api-reboot 20230713035633.382 TRACE    confile_utils - confile_utils.c:lxc_log_configured_netdevs:312 - link: lxcbr0
reboot lxc-api-reboot 20230713035633.382 TRACE    confile_utils - confile_utils.c:lxc_log_configured_netdevs:316 - l2proxy: false
reboot lxc-api-reboot 20230713035633.382 TRACE    confile_utils - confile_utils.c:lxc_log_configured_netdevs:319 - name: eth0
reboot lxc-api-reboot 20230713035633.382 TRACE    confile_utils - confile_utils.c:lxc_log_configured_netdevs:333 - ipv4 gateway auto: false
reboot lxc-api-reboot 20230713035633.382 TRACE    confile_utils - confile_utils.c:lxc_log_configured_netdevs:336 - ipv4 gateway dev: false
reboot lxc-api-reboot 20230713035633.382 TRACE    confile_utils - confile_utils.c:lxc_log_configured_netdevs:351 - ipv6 gateway auto: false
reboot lxc-api-reboot 20230713035633.382 TRACE    confile_utils - confile_utils.c:lxc_log_configured_netdevs:354 - ipv6 gateway dev: false
reboot lxc-api-reboot 20230713035633.382 NOTICE   start - start.c:post_start:2172 - Started "/sbin/init" with pid "104055"
reboot lxc-api-reboot 20230713035633.382 TRACE    start - start.c:lxc_serve_state_socket_pair:547 - Sent container state "RUNNING" to 5
reboot lxc-api-reboot 20230713035633.382 TRACE    start - start.c:lxc_serve_state_clients:486 - Set container state to RUNNING
reboot lxc-api-reboot 20230713035633.382 TRACE    start - start.c:lxc_serve_state_clients:489 - No state clients registered
reboot lxc-api-reboot 20230713035633.382 TRACE    lxccontainer - lxccontainer.c:wait_on_daemonized_start:882 - Container is in "RUNNING" state
reboot lxc-api-reboot 20230713035633.382 TRACE    mainloop - mainloop.c:__epoll_open:493 - Created epoll instance
reboot lxc-api-reboot 20230713035633.382 TRACE    mainloop - mainloop.c:__epoll_open:493 - Created epoll instance
reboot lxc-api-reboot 20230713035633.382 TRACE    start - start.c:lxc_poll:629 - Mainloop is ready
reboot lxc-api-reboot 20230713035638.382 TRACE    commands - commands.c:lxc_cmd_accept:2051 - Accepted new client as fd 16 on command server fd 6
reboot lxc-api-reboot 20230713035638.383 TRACE    commands - commands.c:lxc_cmd_handler:2014 - Processed command get_state; cleaning up client fd 16
reboot lxc-api-reboot 20230713035638.383 DEBUG    commands - commands.c:lxc_cmd_rsp_recv_fds:147 - Command "get_state" received response
reboot lxc-api-reboot 20230713035638.383 TRACE    mainloop - mainloop.c:disarm_handler:65 - Disarmed 16 for "lxc_cmd_handler" handler
reboot lxc-api-reboot 20230713035638.383 TRACE    commands - commands.c:lxc_cmd:520 - Opened new command socket connection fd 4 for command "get_state"
reboot lxc-api-reboot 20230713035638.383 TRACE    commands - commands.c:lxc_cmd_cleanup_handler:1954 - Closing client fd 16 for "lxc_cmd_cleanup_handler"
reboot lxc-api-reboot 20230713035638.383 DEBUG    commands - commands.c:lxc_cmd_get_state:1062 - Container "lxc-api-reboot" is in "RUNNING" state
reboot lxc-api-reboot 20230713035638.383 TRACE    mainloop - mainloop.c:delete_handler:79 - Deleted 16 for "lxc_cmd_handler" handler
reboot lxc-api-reboot 20230713035638.383 TRACE    commands - commands.c:lxc_cmd_accept:2051 - Accepted new client as fd 16 on command server fd 6
reboot lxc-api-reboot 20230713035638.383 TRACE    commands - commands.c:lxc_cmd_handler:2014 - Processed command get_init_pidfd; cleaning up client fd 16
reboot lxc-api-reboot 20230713035638.383 TRACE    mainloop - mainloop.c:disarm_handler:65 - Disarmed 16 for "lxc_cmd_handler" handler
reboot lxc-api-reboot 20230713035638.383 DEBUG    commands - commands.c:lxc_cmd_rsp_recv_fds:136 - Received exact number of file descriptors 1 == 1 for command "get_init_pidfd"
reboot lxc-api-reboot 20230713035638.383 TRACE    commands - commands.c:lxc_cmd_cleanup_handler:1954 - Closing client fd 16 for "lxc_cmd_cleanup_handler"
reboot lxc-api-reboot 20230713035638.383 DEBUG    commands - commands.c:lxc_cmd_rsp_recv:254 - Finished processing "get_init_pidfd" with file descriptor 5
reboot lxc-api-reboot 20230713035638.383 TRACE    mainloop - mainloop.c:delete_handler:79 - Deleted 16 for "lxc_cmd_handler" handler
reboot lxc-api-reboot 20230713035638.383 TRACE    commands - commands.c:lxc_cmd:520 - Opened new command socket connection fd 4 for command "get_init_pidfd"
reboot lxc-api-reboot 20230713035638.383 TRACE    commands - commands.c:lxc_cmd_accept:2051 - Accepted new client as fd 16 on command server fd 6
reboot lxc-api-reboot 20230713035638.383 TRACE    commands_utils - commands_utils.c:lxc_add_state_client:188 - Added state client fd 16 to state client list
reboot lxc-api-reboot 20230713035638.383 TRACE    commands - commands.c:lxc_cmd_handler:2018 - Processed command add_state_client; keeping client fd 16
reboot lxc-api-reboot 20230713035638.383 DEBUG    commands - commands.c:lxc_cmd_rsp_recv_fds:147 - Command "add_state_client" received response
reboot lxc-api-reboot 20230713035638.383 TRACE    commands - commands.c:lxc_cmd:520 - Opened new command socket connection fd 4 for command "add_state_client"
reboot lxc-api-reboot 20230713035638.383 TRACE    commands - commands.c:lxc_cmd_add_state_client:1355 - State connection fd 4 ready to listen for container state changes
reboot lxc-api-reboot 20230713035638.383 TRACE    lxccontainer - lxccontainer.c:do_lxcapi_reboot2:2046 - Sent signal 15 to pidfd(5)/pid(-1)
reboot lxc-api-reboot 20230713040143.467 ERROR    commands_utils - commands_utils.c:lxc_cmd_sock_rcv_state:49 - Resource temporarily unavailable - Failed to receive message
reboot lxc-api-reboot 20230713040143.467 TRACE    commands - commands.c:lxc_cmd_fd_cleanup:1928 - Found state client fd 16 in state client list for command "add_state_client"
reboot lxc-api-reboot 20230713040143.467 TRACE    commands - commands.c:lxc_cmd_fd_cleanup:1938 - Deleted state client fd 16 for command "add_state_client"
reboot lxc-api-reboot 20230713040143.467 TRACE    mainloop - mainloop.c:disarm_handler:65 - Disarmed 16 for "lxc_cmd_handler" handler
reboot lxc-api-reboot 20230713040143.467 TRACE    commands - commands.c:lxc_cmd_cleanup_handler:1954 - Closing client fd 16 for "lxc_cmd_cleanup_handler"
reboot lxc-api-reboot 20230713040143.467 TRACE    mainloop - mainloop.c:delete_handler:79 - Deleted 16 for "lxc_cmd_handler" handler
reboot lxc-api-reboot 20230713040143.467 TRACE    commands - commands.c:lxc_cmd_accept:2051 - Accepted new client as fd 16 on command server fd 6
reboot lxc-api-reboot 20230713040143.467 TRACE    commands - commands.c:lxc_cmd_handler:2014 - Processed command get_state; cleaning up client fd 16
reboot lxc-api-reboot 20230713040143.467 TRACE    mainloop - mainloop.c:disarm_handler:65 - Disarmed 16 for "lxc_cmd_handler" handler
reboot lxc-api-reboot 20230713040143.467 TRACE    commands - commands.c:lxc_cmd_cleanup_handler:1954 - Closing client fd 16 for "lxc_cmd_cleanup_handler"
reboot lxc-api-reboot 20230713040143.467 TRACE    mainloop - mainloop.c:delete_handler:79 - Deleted 16 for "lxc_cmd_handler" handler
reboot lxc-api-reboot 20230713040143.467 DEBUG    commands - commands.c:lxc_cmd_rsp_recv_fds:147 - Command "get_state" received response
reboot lxc-api-reboot 20230713040143.467 TRACE    commands - commands.c:lxc_cmd:520 - Opened new command socket connection fd 4 for command "get_state"
reboot lxc-api-reboot 20230713040143.467 DEBUG    commands - commands.c:lxc_cmd_get_state:1062 - Container "lxc-api-reboot" is in "RUNNING" state
reboot lxc-api-reboot 20230713040143.468 TRACE    commands - commands.c:lxc_cmd_accept:2051 - Accepted new client as fd 16 on command server fd 6
reboot lxc-api-reboot 20230713040143.468 TRACE    commands - commands.c:lxc_cmd_stop_callback:1131 - Sent signal 9 to pidfd 20
reboot lxc-api-reboot 20230713040143.468 TRACE    mainloop - mainloop.c:__epoll_open:493 - Created epoll instance
reboot lxc-api-reboot 20230713040143.468 TRACE    cgfsng - cgroups/cgfsng.c:do_cgroup_freeze:3752 - Container now unfrozen
reboot lxc-api-reboot 20230713040143.468 TRACE    commands - commands.c:lxc_cmd_handler:2018 - Processed command stop; keeping client fd 16
reboot lxc-api-reboot 20230713040143.517 TRACE    start - start.c:signal_handler:399 - Received signal ssi_signo(17) for ssi_pid(104055), si_signo(17), si_pid(104055)
reboot lxc-api-reboot 20230713040143.517 DEBUG    start - start.c:signal_handler:467 - Container init process 104055 exited
reboot lxc-api-reboot 20230713040143.517 TRACE    start - start.c:lxc_poll:646 - Closed console mainloop
reboot lxc-api-reboot 20230713040143.517 TRACE    start - start.c:lxc_poll:651 - Closed mainloop
reboot lxc-api-reboot 20230713040143.517 TRACE    start - start.c:lxc_poll:654 - Closed signal file descriptor 9
reboot lxc-api-reboot 20230713040143.517 DEBUG    start - start.c:__lxc_start:2114 - Killed(9) - Container "lxc-api-reboot" init exited
reboot lxc-api-reboot 20230713040143.517 TRACE    network - network.c:lxc_restore_phys_nics_to_netns:3759 - Moving physical network devices back to parent network namespace
reboot lxc-api-reboot 20230713040143.517 INFO     error - error.c:lxc_error_set_and_log:34 - Child <104055> ended on signal Killed(9)
reboot lxc-api-reboot 20230713040143.517 TRACE    start - start.c:lxc_expose_namespace_environment:909 - Set environment variable LXC_MNT_NS=/proc/104054/fd/21
reboot lxc-api-reboot 20230713040143.517 TRACE    start - start.c:lxc_expose_namespace_environment:909 - Set environment variable LXC_PID_NS=/proc/104054/fd/22
reboot lxc-api-reboot 20230713040143.517 TRACE    start - start.c:lxc_expose_namespace_environment:909 - Set environment variable LXC_UTS_NS=/proc/104054/fd/23
reboot lxc-api-reboot 20230713040143.517 TRACE    start - start.c:lxc_expose_namespace_environment:909 - Set environment variable LXC_IPC_NS=/proc/104054/fd/24
reboot lxc-api-reboot 20230713040143.517 TRACE    start - start.c:lxc_expose_namespace_environment:909 - Set environment variable LXC_NET_NS=/proc/104054/fd/25
reboot lxc-api-reboot 20230713040143.518 TRACE    start - start.c:lxc_expose_namespace_environment:909 - Set environment variable LXC_CGROUP_NS=/proc/104054/fd/26
reboot lxc-api-reboot 20230713040143.608 INFO     network - network.c:lxc_delete_network_priv:3680 - Removed interface "vethzkixON" from "lxcbr0"
reboot lxc-api-reboot 20230713040143.608 DEBUG    network - network.c:lxc_delete_network:4173 - Deleted network devices
reboot lxc-api-reboot 20230713040143.608 TRACE    start - start.c:lxc_serve_state_clients:486 - Set container state to STOPPING
reboot lxc-api-reboot 20230713040143.608 TRACE    start - start.c:lxc_serve_state_clients:489 - No state clients registered
reboot lxc-api-reboot 20230713040143.608 TRACE    cgfsng - cgroups/cgfsng.c:cgroup_tree_remove:485 - Removed cgroup tree 12(lxc.payload.lxc-api-reboot)
reboot lxc-api-reboot 20230713040143.608 TRACE    cgfsng - cgroups/cgfsng.c:__cgroup_tree_create:715 - Reusing 12(lxc.pivot) cgroup
reboot lxc-api-reboot 20230713040143.608 TRACE    cgfsng - cgroups/cgfsng.c:__cgroup_tree_create:730 - Opened cgroup lxc.pivot as 4
reboot lxc-api-reboot 20230713040143.678 WARN     cgfsng - cgroups/cgfsng.c:cgfsng_monitor_destroy:914 - Device or resource busy - Failed to destroy 12(lxc.monitor.lxc-api-reboot-1)
reboot lxc-api-reboot 20230713040143.678 TRACE    start - start.c:lxc_end:966 - Closed command socket
reboot lxc-api-reboot 20230713040143.678 TRACE    start - start.c:lxc_end:977 - Set container state to "STOPPED"
reboot lxc-api-reboot 20230713040143.789 DEBUG    commands - commands.c:lxc_cmd_rsp_recv_fds:147 - Command "stop" received response
reboot lxc-api-reboot 20230713040143.789 TRACE    commands - commands.c:lxc_cmd:520 - Opened new command socket connection fd 4 for command "stop"
reboot lxc-api-reboot 20230713040143.789 INFO     commands - commands.c:lxc_cmd_stop:1109 - Container "lxc-api-reboot" has stopped
reboot lxc-api-reboot 20230713040143.789 TRACE    commands - commands.c:lxc_cmd:513 - Connection refused - Command "get_state" failed to connect command socket
reboot lxc-api-reboot 20230713040143.789 DEBUG    storage - storage/storage.c:get_storage_by_name:209 - Detected rootfs type "dir"
reboot lxc-api-reboot 20230713040143.794 INFO     lxccontainer - lxccontainer.c:container_destroy:3009 - Destroyed rootfs for lxc-api-reboot
reboot lxc-api-reboot 20230713040143.794 INFO     lxccontainer - lxccontainer.c:container_destroy:3074 - Destroyed directory "/var/lib/lxc/lxc-api-reboot" for "lxc-api-reboot"

It's a bit like bug 1776381 on Bionic but the test was not terminated
here.

** Affects: ubuntu-kernel-tests
     Importance: Undecided
         Status: New


** Tags: 5.15 jammy s390x sru-20230612 ubuntu-lxc

** Summary changed:

- lxc-test-api-reboot in ubuntu_lxc failed on F-5.4 s390x
+ lxc-test-api-reboot in ubuntu_lxc failed on J-5.15 s390x

-- 
You received this bug notification because you are a member of Canonical
Platform QA Team, which is subscribed to ubuntu-kernel-tests.
https://bugs.launchpad.net/bugs/2027647

Title:
  lxc-test-api-reboot in ubuntu_lxc failed on J-5.15 s390x

Status in ubuntu-kernel-tests:
  New

Bug description:
  The lxc-test-api-reboot test will fail on s390x LPAR (passed with
  s390x zVM), this is not a regression but something can be at least
  traced back to 5.15.0-47.51 in sru-20220808.

  Test log:
  $ sudo ./lxc-test-api-reboot 
  api_reboot.c: 57: main: Using "/tmp/reboot_kaCalL" as temporary log file for container lxc-api-reboot

  reboot: lxc-api-reboot: commands_utils.c: lxc_cmd_sock_rcv_state: 49 Resource temporarily unavailable - Failed to receive message
  api_reboot.c: 119: main: Failed to reboot container "reboot"

  reboot lxc-api-reboot 20230713035632.902 TRACE    lxccontainer - lxccontainer.c:create_partial:197 - Created "4/partial" to mark container as partially created
  reboot lxc-api-reboot 20230713035632.902 TRACE    dir - storage/dir.c:dir_create:87 - Created directory "/var/lib/lxc/lxc-api-reboot/rootfs"
  reboot lxc-api-reboot 20230713035632.902 TRACE    lxccontainer - lxccontainer.c:do_lxcapi_save_config:2672 - Saved config file "/var/lib/lxc/lxc-api-reboot/config"
  reboot lxc-api-reboot 20230713035632.902 TRACE    utils - utils.c:wait_exited:349 - Reaped child process 103752
  reboot lxc-api-reboot 20230713035632.902 DEBUG    storage - storage/storage.c:get_storage_by_name:209 - Detected rootfs type "dir"
  reboot lxc-api-reboot 20230713035633.225 TRACE    start - start.c:lxc_init_handler:741 - Created anonymous pair {4,5} of unix sockets
  reboot lxc-api-reboot 20230713035633.225 TRACE    commands - commands.c:lxc_server_init:2078 - Created abstract unix socket "/var/lib/lxc/lxc-api-reboot/command"
  reboot lxc-api-reboot 20230713035633.225 TRACE    start - start.c:lxc_init_handler:757 - Unix domain socket 6 for command server is ready
  reboot lxc-api-reboot 20230713035633.225 INFO     lxccontainer - lxccontainer.c:do_lxcapi_start:997 - Set process title to [lxc monitor] /var/lib/lxc lxc-api-reboot
  reboot lxc-api-reboot 20230713035633.225 DEBUG    lxccontainer - lxccontainer.c:wait_on_daemonized_start:858 - First child 104053 exited
  reboot lxc-api-reboot 20230713035633.226 TRACE    start - start.c:lxc_start:2188 - Doing lxc_start
  reboot lxc-api-reboot 20230713035633.226 INFO     lsm - lsm/lsm.c:lsm_init_static:38 - Initialized LSM security driver AppArmor
  reboot lxc-api-reboot 20230713035633.226 TRACE    start - start.c:lxc_init:781 - Initialized LSM
  reboot lxc-api-reboot 20230713035633.226 TRACE    start - start.c:lxc_serve_state_clients:486 - Set container state to STARTING
  reboot lxc-api-reboot 20230713035633.226 TRACE    start - start.c:lxc_serve_state_clients:489 - No state clients registered
  reboot lxc-api-reboot 20230713035633.226 TRACE    start - start.c:lxc_init:787 - Set container state to "STARTING"
  reboot lxc-api-reboot 20230713035633.226 TRACE    start - start.c:lxc_init:843 - Set environment variables
  reboot lxc-api-reboot 20230713035633.226 TRACE    start - start.c:lxc_init:848 - Ran pre-start hooks
  reboot lxc-api-reboot 20230713035633.226 TRACE    start - start.c:setup_signal_fd:373 - Created signal file descriptor 9
  reboot lxc-api-reboot 20230713035633.226 TRACE    start - start.c:lxc_init:861 - Set up signal fd
  reboot lxc-api-reboot 20230713035633.226 TRACE    cgfsng - cgroups/cgfsng.c:cgroup_hierarchy_add:456 - Adding cgroup hierarchy mounted at  and base cgroup (null)
  reboot lxc-api-reboot 20230713035633.226 TRACE    cgfsng - cgroups/cgfsng.c:cgroup_hierarchy_add:459 - The hierarchy contains the cpuset controller
  reboot lxc-api-reboot 20230713035633.226 TRACE    cgfsng - cgroups/cgfsng.c:cgroup_hierarchy_add:459 - The hierarchy contains the cpu controller
  reboot lxc-api-reboot 20230713035633.226 TRACE    cgfsng - cgroups/cgfsng.c:cgroup_hierarchy_add:459 - The hierarchy contains the io controller
  reboot lxc-api-reboot 20230713035633.226 TRACE    cgfsng - cgroups/cgfsng.c:cgroup_hierarchy_add:459 - The hierarchy contains the memory controller
  reboot lxc-api-reboot 20230713035633.226 TRACE    cgfsng - cgroups/cgfsng.c:cgroup_hierarchy_add:459 - The hierarchy contains the hugetlb controller
  reboot lxc-api-reboot 20230713035633.226 TRACE    cgfsng - cgroups/cgfsng.c:cgroup_hierarchy_add:459 - The hierarchy contains the pids controller
  reboot lxc-api-reboot 20230713035633.226 TRACE    cgfsng - cgroups/cgfsng.c:cgroup_hierarchy_add:459 - The hierarchy contains the rdma controller
  reboot lxc-api-reboot 20230713035633.226 TRACE    cgfsng - cgroups/cgfsng.c:cgroup_hierarchy_add:459 - The hierarchy contains the misc controller
  reboot lxc-api-reboot 20230713035633.226 TRACE    cgroup2_devices - cgroups/cgroup2_devices.c:bpf_program_load_kernel:335 - Loaded bpf program: func#0 @0
  0: R1=ctx(id=0,off=0,imm=0) R10=fp0
  0: (61) r2 = *(u32 *)(r1 +0)
  1: R1=ctx(id=0,off=0,imm=0) R2_w=inv(id=0,umax_value=4294967295,var_off=(0x0; 0xffffffff)) R10=fp0
  1: (54) w2 &= 65535
  2: R1=ctx(id=0,off=0,imm=0) R2_w=inv(id=0,umax_value=65535,var_off=(0x0; 0xffff)) R10=fp0
  2: (61) r3 = *(u32 *)(r1 +0)
  3: R1=ctx(id=0,off=0,imm=0) R2_w=inv(id=0,umax_value=65535,var_off=(0x0; 0xffff)) R3_w=inv(id=0,umax_value=4294967295,var_off=(0x0; 0xffffffff)) R10=fp0
  3: (74) w3 >>= 16
  4: R1=ctx(id=0,off=0,imm=0) R2_w=inv(id=0,umax_value=65535,var_off=(0x0; 0xffff)) R3_w=inv(id=0,umax_value=65535,var_off=(0x0; 0xffff)) R10=fp0
  4: (61) r4 = *(u32 *)(r1 +4)
  5: R1=ctx(id=0,off=0,imm=0) R2_w=inv(id=0,umax_value=65535,var_off=(0x0; 0xffff)) R3_w=inv(id=0,umax_value=65535,var_off=(0x0; 0xffff)) R4_w=inv(id=0,umax_value=4294967295,var_off=(0x0; 0xffffffff)) R10=fp0
  5: (61) r5 = *(u32 *)(r1 +8)
  6: R1=ctx(id=0,off=0,imm=0) R2_w=inv(id=0,umax_value=65535,var_off=(0x0; 0xffff)) R3_w=inv(id=0,umax_value=65535,var_off=(0x0; 0xffff)) R4_w=inv(id=0,umax_value=4294967295,var_off=(0x0; 0xffffffff)) R5_w=inv(id=0,umax_value=4294967295,var_off=(0x0; 0xffffffff)) R10=fp0
  6: (b7) r0 = 1
  7: R0_w=inv1 R1=ctx(id=0,off=0,imm=0) R2_w=inv(id=0,umax_value=65535,var_off=(0x0; 0xffff)) R3_w=inv(id=0,umax_value=65535,var_off=(0x0; 0xffff)) R4_w=inv(id=0,umax_value=4294967295,var_off=(0x0; 0xffffffff)) R5_w=inv(id=0,umax_value=4294967295,var_off=(0x0; 0xffffffff)) R10=fp0
  7: (95) exit
  processed 8 insns (limit 1000000) max_states_per_insn 0 total_states 0 peak_states 0 mark_read 0

  reboot lxc-api-reboot 20230713035633.226 TRACE    cgroup2_devices - cgroups/cgroup2_devices.c:bpf_devices_cgroup_supported:553 - The bpf device cgroup is supported
  reboot lxc-api-reboot 20230713035633.226 TRACE    cgroup - cgroups/cgroup.c:cgroup_init:40 - Initialized cgroup driver cgfsng
  reboot lxc-api-reboot 20230713035633.226 TRACE    cgroup - cgroups/cgroup.c:cgroup_init:47 - Unified cgroup layout
  reboot lxc-api-reboot 20230713035633.226 TRACE    start - start.c:lxc_init:868 - Initialized cgroup driver
  reboot lxc-api-reboot 20230713035633.226 TRACE    start - start.c:lxc_init:875 - Read seccomp policy
  reboot lxc-api-reboot 20230713035633.226 TRACE    start - start.c:lxc_init:882 - Initialized LSM
  reboot lxc-api-reboot 20230713035633.226 INFO     start - start.c:lxc_init:884 - Container "lxc-api-reboot" is initialized
  reboot lxc-api-reboot 20230713035633.226 TRACE    cgfsng - cgroups/cgfsng.c:__cgroup_tree_create:715 - Reusing 12(lxc.monitor.lxc-api-reboot) cgroup
  reboot lxc-api-reboot 20230713035633.226 TRACE    cgfsng - cgroups/cgfsng.c:__cgroup_tree_create:730 - Opened cgroup lxc.monitor.lxc-api-reboot as 13
  reboot lxc-api-reboot 20230713035633.226 WARN     cgfsng - cgroups/cgfsng.c:__cgroup_tree_create:736 - File exists - Creating the final cgroup 12(lxc.monitor.lxc-api-reboot) failed
  reboot lxc-api-reboot 20230713035633.226 WARN     cgfsng - cgroups/cgfsng.c:cgroup_tree_create:796 - File exists - Failed to create monitor cgroup 12(lxc.monitor.lxc-api-reboot)
  reboot lxc-api-reboot 20230713035633.226 DEBUG    cgfsng - cgroups/cgfsng.c:cgfsng_monitor_create:1015 - Failed to create cgroup lxc.monitor.lxc-api-reboot)
  reboot lxc-api-reboot 20230713035633.279 TRACE    cgfsng - cgroups/cgfsng.c:__cgroup_tree_create:715 - Created 12(lxc.monitor.lxc-api-reboot-1) cgroup
  reboot lxc-api-reboot 20230713035633.279 TRACE    cgfsng - cgroups/cgfsng.c:__cgroup_tree_create:730 - Opened newly created cgroup lxc.monitor.lxc-api-reboot-1 as 13
  reboot lxc-api-reboot 20230713035633.279 INFO     cgfsng - cgroups/cgfsng.c:cgfsng_monitor_create:1029 - The monitor process uses "lxc.monitor.lxc-api-reboot-1" as cgroup
  reboot lxc-api-reboot 20230713035633.279 TRACE    cgfsng - cgroups/cgfsng.c:__cgfsng_delegate_controllers:2955 - Enabled "+cpuset +cpu +io +memory +hugetlb +pids +rdma +misc" controllers in the unified cgroup 12
  reboot lxc-api-reboot 20230713035633.279 TRACE    cgfsng - cgroups/cgfsng.c:cgfsng_monitor_enter:1179 - Moved monitor into cgroup 13
  reboot lxc-api-reboot 20230713035633.279 TRACE    cgfsng - cgroups/cgfsng.c:cgfsng_monitor_enter:1188 - Moved transient process into cgroup 13
  reboot lxc-api-reboot 20230713035633.279 DEBUG    storage - storage/storage.c:get_storage_by_name:209 - Detected rootfs type "dir"
  reboot lxc-api-reboot 20230713035633.279 TRACE    conf - conf.c:lxc_rootfs_init:587 - Pinned rootfs 15(.lxc_keep)
  reboot lxc-api-reboot 20230713035633.279 TRACE    conf - conf.c:lxc_rootfs_init:603 - Unlinked pinned file 14(.lxc_keep)
  reboot lxc-api-reboot 20230713035633.279 TRACE    sync - sync.c:lxc_sync_init:139 - Initialized synchronization infrastructure
  reboot lxc-api-reboot 20230713035633.280 TRACE    cgfsng - cgroups/cgfsng.c:__cgroup_tree_create:715 - Created 12(lxc.payload.lxc-api-reboot) cgroup
  reboot lxc-api-reboot 20230713035633.280 TRACE    cgfsng - cgroups/cgfsng.c:__cgroup_tree_create:730 - Opened newly created cgroup lxc.payload.lxc-api-reboot as 19
  reboot lxc-api-reboot 20230713035633.280 INFO     cgfsng - cgroups/cgfsng.c:cgfsng_payload_create:1137 - The container process uses "lxc.payload.lxc-api-reboot" as inner and "lxc.payload.lxc-api-reboot" as limit cgroup
  reboot lxc-api-reboot 20230713035633.285 TRACE    start - start.c:lxc_spawn:1705 - Spawned container directly into target cgroup via cgroup2 fd 19
  reboot lxc-api-reboot 20230713035633.285 TRACE    start - start.c:lxc_spawn:1745 - Cloned child process 104055
  reboot lxc-api-reboot 20230713035633.285 TRACE    start - start.c:core_scheduling:1563 - No new core scheduling domain requested
  reboot lxc-api-reboot 20230713035633.285 TRACE    utils - utils.c:lxc_can_use_pidfd:1822 - Kernel supports pidfds
  reboot lxc-api-reboot 20230713035633.285 INFO     start - start.c:lxc_spawn:1765 - Cloned CLONE_NEWNS
  reboot lxc-api-reboot 20230713035633.285 INFO     start - start.c:lxc_spawn:1765 - Cloned CLONE_NEWPID
  reboot lxc-api-reboot 20230713035633.285 TRACE    start - start.c:lxc_spawn:1705 - Spawned container directly into target cgroup via cgroup2 fd 19
  reboot lxc-api-reboot 20230713035633.285 INFO     start - start.c:lxc_spawn:1765 - Cloned CLONE_NEWUTS
  reboot lxc-api-reboot 20230713035633.285 INFO     start - start.c:lxc_spawn:1765 - Cloned CLONE_NEWIPC
  reboot lxc-api-reboot 20230713035633.285 INFO     start - start.c:lxc_spawn:1765 - Cloned CLONE_NEWNET
  reboot lxc-api-reboot 20230713035633.285 INFO     start - start.c:lxc_spawn:1765 - Cloned CLONE_NEWCGROUP
  reboot lxc-api-reboot 20230713035633.285 DEBUG    start - start.c:lxc_try_preserve_namespace:139 - Preserved mnt namespace via fd 21 and stashed path as mnt:/proc/104054/fd/21
  reboot lxc-api-reboot 20230713035633.285 DEBUG    start - start.c:lxc_try_preserve_namespace:139 - Preserved pid namespace via fd 22 and stashed path as pid:/proc/104054/fd/22
  reboot lxc-api-reboot 20230713035633.285 DEBUG    start - start.c:lxc_try_preserve_namespace:139 - Preserved uts namespace via fd 23 and stashed path as uts:/proc/104054/fd/23
  reboot lxc-api-reboot 20230713035633.285 DEBUG    start - start.c:lxc_try_preserve_namespace:139 - Preserved ipc namespace via fd 24 and stashed path as ipc:/proc/104054/fd/24
  reboot lxc-api-reboot 20230713035633.285 DEBUG    start - start.c:lxc_try_preserve_namespace:139 - Preserved net namespace via fd 25 and stashed path as net:/proc/104054/fd/25
  reboot lxc-api-reboot 20230713035633.285 DEBUG    start - start.c:lxc_try_preserve_namespace:139 - Preserved cgroup namespace via fd 26 and stashed path as cgroup:/proc/104054/fd/26
  reboot lxc-api-reboot 20230713035633.285 TRACE    cgfsng - cgroups/cgfsng.c:__cgfsng_delegate_controllers:2955 - Enabled "+cpuset +cpu +io +memory +hugetlb +pids +rdma +misc" controllers in the unified cgroup 12
  reboot lxc-api-reboot 20230713035633.285 TRACE    sync - sync.c:lxc_sync_barrier_child:97 - Parent waking child with sequence startup and waiting with sequence configure
  reboot lxc-api-reboot 20230713035633.285 TRACE    sync - sync.c:lxc_sync_wait_parent:110 - Child waiting for parent with sequence startup
  reboot lxc-api-reboot 20230713035633.285 TRACE    sync - sync.c:lxc_sync_wake_parent:104 - Child waking parent with sequence configure
  reboot lxc-api-reboot 20230713035633.285 TRACE    sync - sync.c:lxc_sync_wait_parent:110 - Child waiting for parent with sequence post-configure
  reboot lxc-api-reboot 20230713035633.285 TRACE    start - start.c:lxc_spawn:1837 - Allocated new network namespace id
  reboot lxc-api-reboot 20230713035633.292 INFO     network - network.c:netdev_configure_server_veth:655 - Retrieved mtu 1500 from lxcbr0
  reboot lxc-api-reboot 20230713035633.311 INFO     network - network.c:netdev_configure_server_veth:721 - Attached "vethzkixON" to bridge "lxcbr0"
  reboot lxc-api-reboot 20230713035633.312 DEBUG    network - network.c:netdev_configure_server_veth:852 - Instantiated veth tunnel "vethzkixON <--> vethVmOxOI"
  reboot lxc-api-reboot 20230713035633.312 TRACE    sync - sync.c:lxc_sync_wake_child:122 - Parent waking child with sequence post-configure
  reboot lxc-api-reboot 20230713035633.312 TRACE    network - network.c:lxc_network_send_to_child:4060 - Sent network device name "vethVmOxOI" to child
  reboot lxc-api-reboot 20230713035633.312 TRACE    sync - sync.c:lxc_sync_wait_child:116 - Parent waiting for child with sequence idmapped-mounts
  reboot lxc-api-reboot 20230713035633.312 TRACE    conf - conf.c:turn_into_dependent_mounts:3923 - Turned all mount table entries into dependent mount
  reboot lxc-api-reboot 20230713035633.312 TRACE    mount_utils - mount_utils.c:can_use_mount_api:582 - Kernel supports mount api
  reboot lxc-api-reboot 20230713035633.312 TRACE    mount_utils - mount_utils.c:can_use_bind_mounts:607 - Kernel supports bind mounts in the new mount api
  reboot lxc-api-reboot 20230713035633.312 TRACE    mount_utils - mount_utils.c:move_detached_mount:328 - Attach detached mount 23 to filesystem at 21
  reboot lxc-api-reboot 20230713035633.312 TRACE    dir - storage/dir.c:dir_mount:196 - Mounted "/var/lib/lxc/lxc-api-reboot/rootfs" onto "/usr/lib/s390x-linux-gnu/lxc"
  reboot lxc-api-reboot 20230713035633.312 DEBUG    conf - conf.c:lxc_mount_rootfs:1436 - Mounted rootfs "/var/lib/lxc/lxc-api-reboot/rootfs" onto "/usr/lib/s390x-linux-gnu/lxc" with options "(null)"
  reboot lxc-api-reboot 20230713035633.312 TRACE    conf - conf.c:lxc_mount_rootfs:1444 - Container uses separate rootfs. Opened container's rootfs
  reboot lxc-api-reboot 20230713035633.312 INFO     conf - conf.c:setup_utsname:875 - Set hostname to "lxc-api-reboot"
  reboot lxc-api-reboot 20230713035633.312 TRACE    network - network.c:lxc_network_recv_from_parent:4085 - Received network device name "vethVmOxOI" from parent
  reboot lxc-api-reboot 20230713035633.312 INFO     network - network.c:lxc_setup_network_in_child_namespaces:4019 - Finished setting up network devices with caller assigned names
  reboot lxc-api-reboot 20230713035633.377 TRACE    network - network.c:__netdev_configure_container_common:1291 - Renamed network device from "vethVmOxOI" to "eth%d"
  reboot lxc-api-reboot 20230713035633.378 DEBUG    network - network.c:lxc_network_setup_in_child_namespaces_common:3962 - Network device "eth0" has been setup
  reboot lxc-api-reboot 20230713035633.378 INFO     network - network.c:lxc_setup_network_in_child_namespaces:4035 - Finished setting up network devices with kernel assigned names
  reboot lxc-api-reboot 20230713035633.378 INFO     conf - conf.c:mount_autodev:1219 - Preparing "/dev"
  reboot lxc-api-reboot 20230713035633.378 TRACE    mount_utils - mount_utils.c:__fs_prepare:177 - Finished initializing new tmpfs filesystem context 22
  reboot lxc-api-reboot 20230713035633.378 TRACE    mount_utils - mount_utils.c:fs_set_property:215 - Set "mode" to "0755" on filesystem context 22
  reboot lxc-api-reboot 20230713035633.378 TRACE    mount_utils - mount_utils.c:fs_set_property:215 - Set "size" to "500000" on filesystem context 22
  reboot lxc-api-reboot 20230713035633.378 TRACE    mount_utils - mount_utils.c:fs_attach:266 - Mounted 24 onto 23
  reboot lxc-api-reboot 20230713035633.378 INFO     conf - conf.c:mount_autodev:1280 - Prepared "/dev"
  reboot lxc-api-reboot 20230713035633.378 DEBUG    conf - conf.c:lxc_mount_auto_mounts:735 - Invalid argument - Tried to ensure procfs is unmounted
  reboot lxc-api-reboot 20230713035633.378 TRACE    conf - conf.c:lxc_mount_auto_mounts:742 - Created procfs mountpoint under 21
  reboot lxc-api-reboot 20230713035633.378 DEBUG    conf - conf.c:lxc_mount_auto_mounts:758 - Invalid argument - Tried to ensure sysfs is unmounted
  reboot lxc-api-reboot 20230713035633.378 TRACE    conf - conf.c:lxc_mount_auto_mounts:765 - Created sysfs mountpoint under 21
  reboot lxc-api-reboot 20230713035633.378 TRACE    conf - conf.c:lxc_mount_auto_mounts:819 - Mounted automount "proc" on "/usr/lib/s390x-linux-gnu/lxc/proc" read-write with flags 14
  reboot lxc-api-reboot 20230713035633.378 TRACE    conf - conf.c:lxc_mount_auto_mounts:819 - Mounted automount "/usr/lib/s390x-linux-gnu/lxc/proc/sys/net" on "/usr/lib/s390x-linux-gnu/lxc/proc/tty" read-write with flags 4096
  reboot lxc-api-reboot 20230713035633.378 TRACE    conf - conf.c:lxc_mount_auto_mounts:819 - Mounted automount "/usr/lib/s390x-linux-gnu/lxc/proc/sys" on "/usr/lib/s390x-linux-gnu/lxc/proc/sys" read-write with flags 4096
  reboot lxc-api-reboot 20230713035633.378 TRACE    conf - conf.c:lxc_mount_auto_mounts:817 - Remounted automount "(null)" on "/usr/lib/s390x-linux-gnu/lxc/proc/sys" read-only with flags 4129
  reboot lxc-api-reboot 20230713035633.378 TRACE    conf - conf.c:lxc_mount_auto_mounts:819 - Mounted automount "/usr/lib/s390x-linux-gnu/lxc/proc/tty" on "/usr/lib/s390x-linux-gnu/lxc/proc/sys/net" read-write with flags 8192
  reboot lxc-api-reboot 20230713035633.378 TRACE    conf - conf.c:lxc_mount_auto_mounts:819 - Mounted automount "/usr/lib/s390x-linux-gnu/lxc/proc/sysrq-trigger" on "/usr/lib/s390x-linux-gnu/lxc/proc/sysrq-trigger" read-write with flags 4096
  reboot lxc-api-reboot 20230713035633.378 TRACE    conf - conf.c:lxc_mount_auto_mounts:817 - Remounted automount "(null)" on "/usr/lib/s390x-linux-gnu/lxc/proc/sysrq-trigger" read-only with flags 4129
  reboot lxc-api-reboot 20230713035633.378 TRACE    conf - conf.c:lxc_mount_auto_mounts:819 - Mounted automount "sysfs" on "/usr/lib/s390x-linux-gnu/lxc/sys" read-only with flags 1
  reboot lxc-api-reboot 20230713035633.378 TRACE    conf - conf.c:parse_vfs_attr:2287 - Raising defaults
  reboot lxc-api-reboot 20230713035633.379 DEBUG    conf - conf.c:mount_entry:2479 - Mounted "shm" on "/usr/lib/s390x-linux-gnu/lxc/dev/shm" with filesystem type "tmpfs"
  reboot lxc-api-reboot 20230713035633.379 TRACE    conf - conf.c:parse_vfs_attr:2287 - Raising defaults
  reboot lxc-api-reboot 20230713035633.379 DEBUG    conf - conf.c:mount_entry:2479 - Mounted "mqueue" on "/usr/lib/s390x-linux-gnu/lxc/dev/mqueue" with filesystem type "mqueue"
  reboot lxc-api-reboot 20230713035633.379 TRACE    conf - conf.c:parse_vfs_attr:2287 - Raising ro
  reboot lxc-api-reboot 20230713035633.379 DEBUG    conf - conf.c:mount_entry:2416 - Remounting "/lib" on "/usr/lib/s390x-linux-gnu/lxc/lib" to respect bind or remount options
  reboot lxc-api-reboot 20230713035633.379 DEBUG    conf - conf.c:mount_entry:2435 - Flags for "/lib" were 4096, required extra flags are 0
  reboot lxc-api-reboot 20230713035633.379 DEBUG    conf - conf.c:mount_entry:2479 - Mounted "/lib" on "/usr/lib/s390x-linux-gnu/lxc/lib" with filesystem type "none"
  reboot lxc-api-reboot 20230713035633.379 TRACE    conf - conf.c:parse_vfs_attr:2287 - Raising ro
  reboot lxc-api-reboot 20230713035633.379 DEBUG    conf - conf.c:mount_entry:2416 - Remounting "/usr/lib" on "/usr/lib/s390x-linux-gnu/lxc/usr/lib" to respect bind or remount options
  reboot lxc-api-reboot 20230713035633.379 DEBUG    conf - conf.c:mount_entry:2435 - Flags for "/usr/lib" were 4096, required extra flags are 0
  reboot lxc-api-reboot 20230713035633.379 DEBUG    conf - conf.c:mount_entry:2479 - Mounted "/usr/lib" on "/usr/lib/s390x-linux-gnu/lxc/usr/lib" with filesystem type "none"
  reboot lxc-api-reboot 20230713035633.379 TRACE    conf - conf.c:parse_vfs_attr:2287 - Raising ro
  reboot lxc-api-reboot 20230713035633.379 DEBUG    conf - conf.c:mount_entry:2416 - Remounting "/sys/kernel/security" on "/usr/lib/s390x-linux-gnu/lxc/sys/kernel/security" to respect bind or remount options
  reboot lxc-api-reboot 20230713035633.379 DEBUG    conf - conf.c:mount_entry:2435 - Flags for "/sys/kernel/security" were 4110, required extra flags are 14
  reboot lxc-api-reboot 20230713035633.379 DEBUG    conf - conf.c:mount_entry:2479 - Mounted "/sys/kernel/security" on "/usr/lib/s390x-linux-gnu/lxc/sys/kernel/security" with filesystem type "none"
  reboot lxc-api-reboot 20230713035633.379 TRACE    sync - sync.c:lxc_sync_wake_parent:104 - Child waking parent with sequence idmapped-mounts
  reboot lxc-api-reboot 20230713035633.379 TRACE    conf - conf.c:parse_vfs_attr:2287 - Raising defaults
  reboot lxc-api-reboot 20230713035633.379 TRACE    conf - conf.c:parse_vfs_attr:2287 - Raising defaults
  reboot lxc-api-reboot 20230713035633.379 TRACE    conf - conf.c:parse_vfs_attr:2287 - Raising ro
  reboot lxc-api-reboot 20230713035633.379 TRACE    conf - conf.c:parse_vfs_attr:2287 - Raising ro
  reboot lxc-api-reboot 20230713035633.379 TRACE    conf - conf.c:parse_vfs_attr:2287 - Raising ro
  reboot lxc-api-reboot 20230713035633.379 TRACE    conf - conf.c:lxc_idmapped_mounts_child:3100 - Finished setting up idmapped mounts
  reboot lxc-api-reboot 20230713035633.379 TRACE    cgfsng - cgroups/cgfsng.c:cgfsng_mount:1612 - Mixed cgroup mounts requested
  reboot lxc-api-reboot 20230713035633.379 TRACE    mount_utils - mount_utils.c:__fs_prepare:177 - Finished initializing new cgroup2 filesystem context 24
  reboot lxc-api-reboot 20230713035633.379 TRACE    mount_utils - mount_utils.c:fs_attach:266 - Mounted 25 onto 23
  reboot lxc-api-reboot 20230713035633.379 DEBUG    cgfsng - cgroups/cgfsng.c:__cgroupfs_mount:1542 - Mounted cgroup filesystem cgroup2 onto 23((null))
  reboot lxc-api-reboot 20230713035633.379 TRACE    cgfsng - cgroups/cgfsng.c:cgfsng_mount:1710 - Force mounted cgroup filesystem in new cgroup namespace
  reboot lxc-api-reboot 20230713035633.379 INFO     conf - conf.c:lxc_fill_autodev:1317 - Populating "/dev"
  reboot lxc-api-reboot 20230713035633.379 DEBUG    conf - conf.c:lxc_fill_autodev:1326 - Created device node "full"
  reboot lxc-api-reboot 20230713035633.379 DEBUG    conf - conf.c:lxc_fill_autodev:1326 - Created device node "null"
  reboot lxc-api-reboot 20230713035633.379 DEBUG    conf - conf.c:lxc_fill_autodev:1326 - Created device node "random"
  reboot lxc-api-reboot 20230713035633.379 DEBUG    conf - conf.c:lxc_fill_autodev:1326 - Created device node "tty"
  reboot lxc-api-reboot 20230713035633.379 DEBUG    conf - conf.c:lxc_fill_autodev:1326 - Created device node "urandom"
  reboot lxc-api-reboot 20230713035633.379 DEBUG    conf - conf.c:lxc_fill_autodev:1326 - Created device node "zero"
  reboot lxc-api-reboot 20230713035633.379 INFO     conf - conf.c:lxc_fill_autodev:1405 - Populated "/dev"
  reboot lxc-api-reboot 20230713035633.379 INFO     conf - conf.c:lxc_transient_proc:3775 - Caller's PID is 1; /proc/self points to 1
  reboot lxc-api-reboot 20230713035633.379 TRACE    conf - conf.c:lxc_transient_proc:3783 - Correct procfs instance mounted
  reboot lxc-api-reboot 20230713035633.379 TRACE    mount_utils - mount_utils.c:__fs_prepare:177 - Finished initializing new devpts filesystem context 23
  reboot lxc-api-reboot 20230713035633.379 TRACE    mount_utils - mount_utils.c:fs_set_property:215 - Set "source" to "devpts" on filesystem context 23
  reboot lxc-api-reboot 20230713035633.379 TRACE    mount_utils - mount_utils.c:fs_set_property:215 - Set "gid" to "5" on filesystem context 23
  reboot lxc-api-reboot 20230713035633.379 TRACE    mount_utils - mount_utils.c:fs_set_flag:227 - Set "newinstance" flag on filesystem context 23
  reboot lxc-api-reboot 20230713035633.379 TRACE    mount_utils - mount_utils.c:fs_set_property:215 - Set "ptmxmode" to "0666" on filesystem context 23
  reboot lxc-api-reboot 20230713035633.379 TRACE    mount_utils - mount_utils.c:fs_set_property:215 - Set "mode" to "0620" on filesystem context 23
  reboot lxc-api-reboot 20230713035633.379 TRACE    mount_utils - mount_utils.c:fs_set_property:215 - Set "max" to "6" on filesystem context 23
  reboot lxc-api-reboot 20230713035633.379 TRACE    conf - conf.c:lxc_idmapped_mounts_parent:4123 - Finished receiving idmapped mount file descriptors from child
  reboot lxc-api-reboot 20230713035633.379 TRACE    sync - sync.c:lxc_sync_wait_child:116 - Parent waiting for child with sequence cgroup-limits
  reboot lxc-api-reboot 20230713035633.379 TRACE    conf - conf.c:lxc_setup_devpts_child:1745 - Created detached devpts mount 24
  reboot lxc-api-reboot 20230713035633.379 DEBUG    conf - conf.c:lxc_setup_devpts_child:1751 - Attached detached devpts mount 24 to 22/pts
  reboot lxc-api-reboot 20230713035633.379 DEBUG    conf - conf.c:lxc_setup_devpts_child:1837 - Created "/dev/ptmx" file as bind mount target
  reboot lxc-api-reboot 20230713035633.379 TRACE    mount_utils - mount_utils.c:mount_at:663 - Mounted "/proc/self/fd/25" to "/proc/self/fd/26"
  reboot lxc-api-reboot 20230713035633.379 DEBUG    conf - conf.c:lxc_setup_devpts_child:1844 - Bind mounted "/dev/pts/ptmx" to "/dev/ptmx"
  reboot lxc-api-reboot 20230713035633.379 TRACE    mount_utils - mount_utils.c:move_detached_mount:328 - Attach detached mount 27 to filesystem at 25
  reboot lxc-api-reboot 20230713035633.379 TRACE    conf - conf.c:lxc_setup_dev_console:2003 - Setup console "/dev/pts/1"
  reboot lxc-api-reboot 20230713035633.379 DEBUG    conf - conf.c:lxc_allocate_ttys:1104 - Created tty with ptx fd 8 and pty fd 10 and index 0
  reboot lxc-api-reboot 20230713035633.379 DEBUG    conf - conf.c:lxc_allocate_ttys:1104 - Created tty with ptx fd 23 and pty fd 25 and index 1
  reboot lxc-api-reboot 20230713035633.379 DEBUG    conf - conf.c:lxc_allocate_ttys:1104 - Created tty with ptx fd 26 and pty fd 27 and index 2
  reboot lxc-api-reboot 20230713035633.379 DEBUG    conf - conf.c:lxc_allocate_ttys:1104 - Created tty with ptx fd 28 and pty fd 29 and index 3
  reboot lxc-api-reboot 20230713035633.379 DEBUG    conf - conf.c:lxc_allocate_ttys:1104 - Created tty with ptx fd 30 and pty fd 31 and index 4
  reboot lxc-api-reboot 20230713035633.379 INFO     conf - conf.c:lxc_allocate_ttys:1109 - Finished creating 5 tty devices
  reboot lxc-api-reboot 20230713035633.379 TRACE    mount_utils - mount_utils.c:move_detached_mount:328 - Attach detached mount 33 to filesystem at 32
  reboot lxc-api-reboot 20230713035633.379 DEBUG    conf - conf.c:lxc_setup_ttys:1065 - Bind mounted "pts/0" onto "tty1"
  reboot lxc-api-reboot 20230713035633.380 TRACE    mount_utils - mount_utils.c:move_detached_mount:328 - Attach detached mount 33 to filesystem at 32
  reboot lxc-api-reboot 20230713035633.380 DEBUG    conf - conf.c:lxc_setup_ttys:1065 - Bind mounted "pts/1" onto "tty2"
  reboot lxc-api-reboot 20230713035633.380 TRACE    mount_utils - mount_utils.c:move_detached_mount:328 - Attach detached mount 33 to filesystem at 32
  reboot lxc-api-reboot 20230713035633.380 DEBUG    conf - conf.c:lxc_setup_ttys:1065 - Bind mounted "pts/2" onto "tty3"
  reboot lxc-api-reboot 20230713035633.380 TRACE    mount_utils - mount_utils.c:move_detached_mount:328 - Attach detached mount 33 to filesystem at 32
  reboot lxc-api-reboot 20230713035633.380 DEBUG    conf - conf.c:lxc_setup_ttys:1065 - Bind mounted "pts/3" onto "tty4"
  reboot lxc-api-reboot 20230713035633.380 TRACE    mount_utils - mount_utils.c:move_detached_mount:328 - Attach detached mount 33 to filesystem at 32
  reboot lxc-api-reboot 20230713035633.380 DEBUG    conf - conf.c:lxc_setup_ttys:1065 - Bind mounted "pts/4" onto "tty5"
  reboot lxc-api-reboot 20230713035633.380 INFO     conf - conf.c:lxc_setup_ttys:1072 - Finished setting up 5 /dev/tty<N> device(s)
  reboot lxc-api-reboot 20230713035633.380 TRACE    conf - conf.c:lxc_create_ttys:1186 - Set "container_ttys=container_ttys=pts/0 pts/1 pts/2 pts/3 pts/4"
  reboot lxc-api-reboot 20230713035633.381 TRACE    conf - conf.c:lxc_pivot_root:1623 - Changed into new rootfs "/usr/lib/s390x-linux-gnu/lxc"
  reboot lxc-api-reboot 20230713035633.381 DEBUG    conf - conf.c:setup_personality:1911 - Retaining original personality
  reboot lxc-api-reboot 20230713035633.381 DEBUG    conf - conf.c:capabilities_deny:3200 - Dropped sys_module (16) capability
  reboot lxc-api-reboot 20230713035633.381 DEBUG    conf - conf.c:capabilities_deny:3200 - Dropped mac_admin (33) capability
  reboot lxc-api-reboot 20230713035633.381 DEBUG    conf - conf.c:capabilities_deny:3200 - Dropped mac_override (32) capability
  reboot lxc-api-reboot 20230713035633.381 DEBUG    conf - conf.c:capabilities_deny:3200 - Dropped sys_time (25) capability
  reboot lxc-api-reboot 20230713035633.381 DEBUG    conf - conf.c:capabilities_deny:3203 - Capabilities have been setup
  reboot lxc-api-reboot 20230713035633.381 NOTICE   conf - conf.c:lxc_setup:4469 - The container "lxc-api-reboot" is set up
  reboot lxc-api-reboot 20230713035633.381 TRACE    apparmor - lsm/apparmor.c:__apparmor_process_label_open:403 - On-exec not supported with AppArmor
  reboot lxc-api-reboot 20230713035633.381 TRACE    apparmor - lsm/apparmor.c:apparmor_process_label_set_at:1170 - Changing AppArmor profile on exec not supported
  reboot lxc-api-reboot 20230713035633.381 INFO     apparmor - lsm/apparmor.c:apparmor_process_label_set_at:1186 - Set AppArmor label to "lxc-container-default-cgns"
  reboot lxc-api-reboot 20230713035633.381 INFO     apparmor - lsm/apparmor.c:apparmor_process_label_set:1231 - Changed AppArmor profile to lxc-container-default-cgns
  reboot lxc-api-reboot 20230713035633.382 TRACE    sync - sync.c:lxc_sync_barrier_parent:90 - Child waking parent with sequence cgroup-limits and waiting for sequence fds
  reboot lxc-api-reboot 20230713035633.382 TRACE    start - start.c:lxc_spawn:1899 - Set up legacy device cgroup controller limits
  reboot lxc-api-reboot 20230713035633.382 TRACE    start - start.c:lxc_spawn:1905 - Set up cgroup2 device controller limits
  reboot lxc-api-reboot 20230713035633.382 TRACE    cgfsng - cgroups/cgfsng.c:cgfsng_finalize:1393 - Unified hierarchy supports freezer
  reboot lxc-api-reboot 20230713035633.382 TRACE    start - start.c:lxc_spawn:1908 - Finished setting up cgroups
  reboot lxc-api-reboot 20230713035633.382 TRACE    sync - sync.c:lxc_sync_wake_child:122 - Parent waking child with sequence fds
  reboot lxc-api-reboot 20230713035633.382 TRACE    conf - conf.c:lxc_send_devpts_to_parent:1899 - Sent devpts file descriptor 24 to parent
  reboot lxc-api-reboot 20230713035633.382 TRACE    conf - conf.c:lxc_recv_devpts_from_child:1681 - Received devpts file descriptor 7 from child
  reboot lxc-api-reboot 20230713035633.382 TRACE    conf - conf.c:__lxc_send_ttys_to_parent:1149 - Sent tty "pts/0" with ptx fd 8 and pty fd 10 to parent
  reboot lxc-api-reboot 20230713035633.382 TRACE    conf - conf.c:lxc_recv_ttys_from_child:4185 - Received pty with ptx fd 11 and pty fd 13 from child
  reboot lxc-api-reboot 20230713035633.382 TRACE    conf - conf.c:__lxc_send_ttys_to_parent:1149 - Sent tty "pts/1" with ptx fd 23 and pty fd 25 to parent
  reboot lxc-api-reboot 20230713035633.382 TRACE    conf - conf.c:lxc_recv_ttys_from_child:4185 - Received pty with ptx fd 14 and pty fd 27 from child
  reboot lxc-api-reboot 20230713035633.382 TRACE    conf - conf.c:__lxc_send_ttys_to_parent:1149 - Sent tty "pts/2" with ptx fd 26 and pty fd 27 to parent
  reboot lxc-api-reboot 20230713035633.382 TRACE    conf - conf.c:lxc_recv_ttys_from_child:4185 - Received pty with ptx fd 28 and pty fd 29 from child
  reboot lxc-api-reboot 20230713035633.382 TRACE    conf - conf.c:__lxc_send_ttys_to_parent:1149 - Sent tty "pts/3" with ptx fd 28 and pty fd 29 to parent
  reboot lxc-api-reboot 20230713035633.382 TRACE    conf - conf.c:lxc_recv_ttys_from_child:4185 - Received pty with ptx fd 30 and pty fd 31 from child
  reboot lxc-api-reboot 20230713035633.382 TRACE    conf - conf.c:__lxc_send_ttys_to_parent:1149 - Sent tty "pts/4" with ptx fd 30 and pty fd 31 to parent
  reboot lxc-api-reboot 20230713035633.382 TRACE    conf - conf.c:lxc_recv_ttys_from_child:4185 - Received pty with ptx fd 32 and pty fd 33 from child
  reboot lxc-api-reboot 20230713035633.382 TRACE    conf - conf.c:__lxc_send_ttys_to_parent:1156 - Sent 5 ttys to parent
  reboot lxc-api-reboot 20230713035633.382 TRACE    conf - conf.c:lxc_recv_ttys_from_child:4190 - Received 5 ttys from child
  reboot lxc-api-reboot 20230713035633.382 TRACE    network - network.c:lxc_network_send_name_and_ifindex_to_parent:4115 - Sent network device eth0 with ifindex 2 to parent
  reboot lxc-api-reboot 20230713035633.382 TRACE    network - network.c:lxc_network_recv_name_and_ifindex_from_child:4149 - Received network device eth0 with ifindex 2 from child
  reboot lxc-api-reboot 20230713035633.382 TRACE    network - network.c:lxc_network_send_name_and_ifindex_to_parent:4119 - Sent network device names and ifindices to parent
  reboot lxc-api-reboot 20230713035633.382 TRACE    conf - conf.c:lxc_sync_fds_parent:4271 - Finished syncing file descriptors with child
  reboot lxc-api-reboot 20230713035633.382 TRACE    conf - conf.c:lxc_sync_fds_child:4301 - Finished syncing file descriptors with parent
  reboot lxc-api-reboot 20230713035633.382 TRACE    sync - sync.c:lxc_sync_wait_parent:110 - Child waiting for parent with sequence ready-start
  reboot lxc-api-reboot 20230713035633.382 DEBUG    terminal - terminal.c:lxc_terminal_peer_default:695 - No such device - The process does not have a controlling terminal
  reboot lxc-api-reboot 20230713035633.382 TRACE    sync - sync.c:lxc_sync_barrier_child:97 - Parent waking child with sequence ready-start and waiting with sequence restart
  reboot lxc-api-reboot 20230713035633.382 NOTICE   utils - utils.c:lxc_drop_groups:1368 - Dropped supplimentary groups
  reboot lxc-api-reboot 20230713035633.382 NOTICE   start - start.c:start:2161 - Exec'ing "/sbin/init"
  reboot lxc-api-reboot 20230713035633.382 TRACE    confile_utils - confile_utils.c:lxc_log_configured_netdevs:233 - index: 0
  reboot lxc-api-reboot 20230713035633.382 TRACE    confile_utils - confile_utils.c:lxc_log_configured_netdevs:234 - ifindex: 2
  reboot lxc-api-reboot 20230713035633.382 TRACE    confile_utils - confile_utils.c:lxc_log_configured_netdevs:238 - type: veth
  reboot lxc-api-reboot 20230713035633.382 TRACE    confile_utils - confile_utils.c:lxc_log_configured_netdevs:239 - veth mode: 1
  reboot lxc-api-reboot 20230713035633.382 TRACE    confile_utils - confile_utils.c:lxc_log_configured_netdevs:246 - veth1 : vethzkixON
  reboot lxc-api-reboot 20230713035633.382 TRACE    confile_utils - confile_utils.c:lxc_log_configured_netdevs:250 - host side ifindex for veth device: 106
  reboot lxc-api-reboot 20230713035633.382 TRACE    confile_utils - confile_utils.c:lxc_log_configured_netdevs:308 - flags: none
  reboot lxc-api-reboot 20230713035633.382 TRACE    confile_utils - confile_utils.c:lxc_log_configured_netdevs:312 - link: lxcbr0
  reboot lxc-api-reboot 20230713035633.382 TRACE    confile_utils - confile_utils.c:lxc_log_configured_netdevs:316 - l2proxy: false
  reboot lxc-api-reboot 20230713035633.382 TRACE    confile_utils - confile_utils.c:lxc_log_configured_netdevs:319 - name: eth0
  reboot lxc-api-reboot 20230713035633.382 TRACE    confile_utils - confile_utils.c:lxc_log_configured_netdevs:333 - ipv4 gateway auto: false
  reboot lxc-api-reboot 20230713035633.382 TRACE    confile_utils - confile_utils.c:lxc_log_configured_netdevs:336 - ipv4 gateway dev: false
  reboot lxc-api-reboot 20230713035633.382 TRACE    confile_utils - confile_utils.c:lxc_log_configured_netdevs:351 - ipv6 gateway auto: false
  reboot lxc-api-reboot 20230713035633.382 TRACE    confile_utils - confile_utils.c:lxc_log_configured_netdevs:354 - ipv6 gateway dev: false
  reboot lxc-api-reboot 20230713035633.382 NOTICE   start - start.c:post_start:2172 - Started "/sbin/init" with pid "104055"
  reboot lxc-api-reboot 20230713035633.382 TRACE    start - start.c:lxc_serve_state_socket_pair:547 - Sent container state "RUNNING" to 5
  reboot lxc-api-reboot 20230713035633.382 TRACE    start - start.c:lxc_serve_state_clients:486 - Set container state to RUNNING
  reboot lxc-api-reboot 20230713035633.382 TRACE    start - start.c:lxc_serve_state_clients:489 - No state clients registered
  reboot lxc-api-reboot 20230713035633.382 TRACE    lxccontainer - lxccontainer.c:wait_on_daemonized_start:882 - Container is in "RUNNING" state
  reboot lxc-api-reboot 20230713035633.382 TRACE    mainloop - mainloop.c:__epoll_open:493 - Created epoll instance
  reboot lxc-api-reboot 20230713035633.382 TRACE    mainloop - mainloop.c:__epoll_open:493 - Created epoll instance
  reboot lxc-api-reboot 20230713035633.382 TRACE    start - start.c:lxc_poll:629 - Mainloop is ready
  reboot lxc-api-reboot 20230713035638.382 TRACE    commands - commands.c:lxc_cmd_accept:2051 - Accepted new client as fd 16 on command server fd 6
  reboot lxc-api-reboot 20230713035638.383 TRACE    commands - commands.c:lxc_cmd_handler:2014 - Processed command get_state; cleaning up client fd 16
  reboot lxc-api-reboot 20230713035638.383 DEBUG    commands - commands.c:lxc_cmd_rsp_recv_fds:147 - Command "get_state" received response
  reboot lxc-api-reboot 20230713035638.383 TRACE    mainloop - mainloop.c:disarm_handler:65 - Disarmed 16 for "lxc_cmd_handler" handler
  reboot lxc-api-reboot 20230713035638.383 TRACE    commands - commands.c:lxc_cmd:520 - Opened new command socket connection fd 4 for command "get_state"
  reboot lxc-api-reboot 20230713035638.383 TRACE    commands - commands.c:lxc_cmd_cleanup_handler:1954 - Closing client fd 16 for "lxc_cmd_cleanup_handler"
  reboot lxc-api-reboot 20230713035638.383 DEBUG    commands - commands.c:lxc_cmd_get_state:1062 - Container "lxc-api-reboot" is in "RUNNING" state
  reboot lxc-api-reboot 20230713035638.383 TRACE    mainloop - mainloop.c:delete_handler:79 - Deleted 16 for "lxc_cmd_handler" handler
  reboot lxc-api-reboot 20230713035638.383 TRACE    commands - commands.c:lxc_cmd_accept:2051 - Accepted new client as fd 16 on command server fd 6
  reboot lxc-api-reboot 20230713035638.383 TRACE    commands - commands.c:lxc_cmd_handler:2014 - Processed command get_init_pidfd; cleaning up client fd 16
  reboot lxc-api-reboot 20230713035638.383 TRACE    mainloop - mainloop.c:disarm_handler:65 - Disarmed 16 for "lxc_cmd_handler" handler
  reboot lxc-api-reboot 20230713035638.383 DEBUG    commands - commands.c:lxc_cmd_rsp_recv_fds:136 - Received exact number of file descriptors 1 == 1 for command "get_init_pidfd"
  reboot lxc-api-reboot 20230713035638.383 TRACE    commands - commands.c:lxc_cmd_cleanup_handler:1954 - Closing client fd 16 for "lxc_cmd_cleanup_handler"
  reboot lxc-api-reboot 20230713035638.383 DEBUG    commands - commands.c:lxc_cmd_rsp_recv:254 - Finished processing "get_init_pidfd" with file descriptor 5
  reboot lxc-api-reboot 20230713035638.383 TRACE    mainloop - mainloop.c:delete_handler:79 - Deleted 16 for "lxc_cmd_handler" handler
  reboot lxc-api-reboot 20230713035638.383 TRACE    commands - commands.c:lxc_cmd:520 - Opened new command socket connection fd 4 for command "get_init_pidfd"
  reboot lxc-api-reboot 20230713035638.383 TRACE    commands - commands.c:lxc_cmd_accept:2051 - Accepted new client as fd 16 on command server fd 6
  reboot lxc-api-reboot 20230713035638.383 TRACE    commands_utils - commands_utils.c:lxc_add_state_client:188 - Added state client fd 16 to state client list
  reboot lxc-api-reboot 20230713035638.383 TRACE    commands - commands.c:lxc_cmd_handler:2018 - Processed command add_state_client; keeping client fd 16
  reboot lxc-api-reboot 20230713035638.383 DEBUG    commands - commands.c:lxc_cmd_rsp_recv_fds:147 - Command "add_state_client" received response
  reboot lxc-api-reboot 20230713035638.383 TRACE    commands - commands.c:lxc_cmd:520 - Opened new command socket connection fd 4 for command "add_state_client"
  reboot lxc-api-reboot 20230713035638.383 TRACE    commands - commands.c:lxc_cmd_add_state_client:1355 - State connection fd 4 ready to listen for container state changes
  reboot lxc-api-reboot 20230713035638.383 TRACE    lxccontainer - lxccontainer.c:do_lxcapi_reboot2:2046 - Sent signal 15 to pidfd(5)/pid(-1)
  reboot lxc-api-reboot 20230713040143.467 ERROR    commands_utils - commands_utils.c:lxc_cmd_sock_rcv_state:49 - Resource temporarily unavailable - Failed to receive message
  reboot lxc-api-reboot 20230713040143.467 TRACE    commands - commands.c:lxc_cmd_fd_cleanup:1928 - Found state client fd 16 in state client list for command "add_state_client"
  reboot lxc-api-reboot 20230713040143.467 TRACE    commands - commands.c:lxc_cmd_fd_cleanup:1938 - Deleted state client fd 16 for command "add_state_client"
  reboot lxc-api-reboot 20230713040143.467 TRACE    mainloop - mainloop.c:disarm_handler:65 - Disarmed 16 for "lxc_cmd_handler" handler
  reboot lxc-api-reboot 20230713040143.467 TRACE    commands - commands.c:lxc_cmd_cleanup_handler:1954 - Closing client fd 16 for "lxc_cmd_cleanup_handler"
  reboot lxc-api-reboot 20230713040143.467 TRACE    mainloop - mainloop.c:delete_handler:79 - Deleted 16 for "lxc_cmd_handler" handler
  reboot lxc-api-reboot 20230713040143.467 TRACE    commands - commands.c:lxc_cmd_accept:2051 - Accepted new client as fd 16 on command server fd 6
  reboot lxc-api-reboot 20230713040143.467 TRACE    commands - commands.c:lxc_cmd_handler:2014 - Processed command get_state; cleaning up client fd 16
  reboot lxc-api-reboot 20230713040143.467 TRACE    mainloop - mainloop.c:disarm_handler:65 - Disarmed 16 for "lxc_cmd_handler" handler
  reboot lxc-api-reboot 20230713040143.467 TRACE    commands - commands.c:lxc_cmd_cleanup_handler:1954 - Closing client fd 16 for "lxc_cmd_cleanup_handler"
  reboot lxc-api-reboot 20230713040143.467 TRACE    mainloop - mainloop.c:delete_handler:79 - Deleted 16 for "lxc_cmd_handler" handler
  reboot lxc-api-reboot 20230713040143.467 DEBUG    commands - commands.c:lxc_cmd_rsp_recv_fds:147 - Command "get_state" received response
  reboot lxc-api-reboot 20230713040143.467 TRACE    commands - commands.c:lxc_cmd:520 - Opened new command socket connection fd 4 for command "get_state"
  reboot lxc-api-reboot 20230713040143.467 DEBUG    commands - commands.c:lxc_cmd_get_state:1062 - Container "lxc-api-reboot" is in "RUNNING" state
  reboot lxc-api-reboot 20230713040143.468 TRACE    commands - commands.c:lxc_cmd_accept:2051 - Accepted new client as fd 16 on command server fd 6
  reboot lxc-api-reboot 20230713040143.468 TRACE    commands - commands.c:lxc_cmd_stop_callback:1131 - Sent signal 9 to pidfd 20
  reboot lxc-api-reboot 20230713040143.468 TRACE    mainloop - mainloop.c:__epoll_open:493 - Created epoll instance
  reboot lxc-api-reboot 20230713040143.468 TRACE    cgfsng - cgroups/cgfsng.c:do_cgroup_freeze:3752 - Container now unfrozen
  reboot lxc-api-reboot 20230713040143.468 TRACE    commands - commands.c:lxc_cmd_handler:2018 - Processed command stop; keeping client fd 16
  reboot lxc-api-reboot 20230713040143.517 TRACE    start - start.c:signal_handler:399 - Received signal ssi_signo(17) for ssi_pid(104055), si_signo(17), si_pid(104055)
  reboot lxc-api-reboot 20230713040143.517 DEBUG    start - start.c:signal_handler:467 - Container init process 104055 exited
  reboot lxc-api-reboot 20230713040143.517 TRACE    start - start.c:lxc_poll:646 - Closed console mainloop
  reboot lxc-api-reboot 20230713040143.517 TRACE    start - start.c:lxc_poll:651 - Closed mainloop
  reboot lxc-api-reboot 20230713040143.517 TRACE    start - start.c:lxc_poll:654 - Closed signal file descriptor 9
  reboot lxc-api-reboot 20230713040143.517 DEBUG    start - start.c:__lxc_start:2114 - Killed(9) - Container "lxc-api-reboot" init exited
  reboot lxc-api-reboot 20230713040143.517 TRACE    network - network.c:lxc_restore_phys_nics_to_netns:3759 - Moving physical network devices back to parent network namespace
  reboot lxc-api-reboot 20230713040143.517 INFO     error - error.c:lxc_error_set_and_log:34 - Child <104055> ended on signal Killed(9)
  reboot lxc-api-reboot 20230713040143.517 TRACE    start - start.c:lxc_expose_namespace_environment:909 - Set environment variable LXC_MNT_NS=/proc/104054/fd/21
  reboot lxc-api-reboot 20230713040143.517 TRACE    start - start.c:lxc_expose_namespace_environment:909 - Set environment variable LXC_PID_NS=/proc/104054/fd/22
  reboot lxc-api-reboot 20230713040143.517 TRACE    start - start.c:lxc_expose_namespace_environment:909 - Set environment variable LXC_UTS_NS=/proc/104054/fd/23
  reboot lxc-api-reboot 20230713040143.517 TRACE    start - start.c:lxc_expose_namespace_environment:909 - Set environment variable LXC_IPC_NS=/proc/104054/fd/24
  reboot lxc-api-reboot 20230713040143.517 TRACE    start - start.c:lxc_expose_namespace_environment:909 - Set environment variable LXC_NET_NS=/proc/104054/fd/25
  reboot lxc-api-reboot 20230713040143.518 TRACE    start - start.c:lxc_expose_namespace_environment:909 - Set environment variable LXC_CGROUP_NS=/proc/104054/fd/26
  reboot lxc-api-reboot 20230713040143.608 INFO     network - network.c:lxc_delete_network_priv:3680 - Removed interface "vethzkixON" from "lxcbr0"
  reboot lxc-api-reboot 20230713040143.608 DEBUG    network - network.c:lxc_delete_network:4173 - Deleted network devices
  reboot lxc-api-reboot 20230713040143.608 TRACE    start - start.c:lxc_serve_state_clients:486 - Set container state to STOPPING
  reboot lxc-api-reboot 20230713040143.608 TRACE    start - start.c:lxc_serve_state_clients:489 - No state clients registered
  reboot lxc-api-reboot 20230713040143.608 TRACE    cgfsng - cgroups/cgfsng.c:cgroup_tree_remove:485 - Removed cgroup tree 12(lxc.payload.lxc-api-reboot)
  reboot lxc-api-reboot 20230713040143.608 TRACE    cgfsng - cgroups/cgfsng.c:__cgroup_tree_create:715 - Reusing 12(lxc.pivot) cgroup
  reboot lxc-api-reboot 20230713040143.608 TRACE    cgfsng - cgroups/cgfsng.c:__cgroup_tree_create:730 - Opened cgroup lxc.pivot as 4
  reboot lxc-api-reboot 20230713040143.678 WARN     cgfsng - cgroups/cgfsng.c:cgfsng_monitor_destroy:914 - Device or resource busy - Failed to destroy 12(lxc.monitor.lxc-api-reboot-1)
  reboot lxc-api-reboot 20230713040143.678 TRACE    start - start.c:lxc_end:966 - Closed command socket
  reboot lxc-api-reboot 20230713040143.678 TRACE    start - start.c:lxc_end:977 - Set container state to "STOPPED"
  reboot lxc-api-reboot 20230713040143.789 DEBUG    commands - commands.c:lxc_cmd_rsp_recv_fds:147 - Command "stop" received response
  reboot lxc-api-reboot 20230713040143.789 TRACE    commands - commands.c:lxc_cmd:520 - Opened new command socket connection fd 4 for command "stop"
  reboot lxc-api-reboot 20230713040143.789 INFO     commands - commands.c:lxc_cmd_stop:1109 - Container "lxc-api-reboot" has stopped
  reboot lxc-api-reboot 20230713040143.789 TRACE    commands - commands.c:lxc_cmd:513 - Connection refused - Command "get_state" failed to connect command socket
  reboot lxc-api-reboot 20230713040143.789 DEBUG    storage - storage/storage.c:get_storage_by_name:209 - Detected rootfs type "dir"
  reboot lxc-api-reboot 20230713040143.794 INFO     lxccontainer - lxccontainer.c:container_destroy:3009 - Destroyed rootfs for lxc-api-reboot
  reboot lxc-api-reboot 20230713040143.794 INFO     lxccontainer - lxccontainer.c:container_destroy:3074 - Destroyed directory "/var/lib/lxc/lxc-api-reboot" for "lxc-api-reboot"

  It's a bit like bug 1776381 on Bionic but the test was not terminated
  here.

To manage notifications about this bug go to:
https://bugs.launchpad.net/ubuntu-kernel-tests/+bug/2027647/+subscriptions