Activity log for bug #1991519

Date Who What changed Old value New value Message
2022-10-03 10:11:20 Po-Hsu Lin bug added bug
2022-10-03 10:11:30 Po-Hsu Lin tags 4.15 bionic gcp ubuntu-lxc 4.15 bionic gcp sru-20220829 ubuntu-lxc
2022-10-03 10:33:26 Po-Hsu Lin description Issue found on B-gcp-fips 4.15.0-2045.50 with instance n1-standard-64 This test will pass, but it will cause call trace in the dmesg. $ sudo python3 /usr/share/doc/python3-lxc/examples/api_test.py Getting instance for '48241ae0-4302-11ed-9fcd-42010af0000f' Creating rootfs using 'download', arch=amd64 Using image from local cache Unpacking the rootfs --- You just created an Ubuntu xenial amd64 (20220930_07:42) container. To enable SSH, run: apt install openssh-server No default root or user password are set by LXC. Testing the configuration Testing the networking Starting the container Getting the interface names Getting the IP addresses eth0: flags=4163<UP,BROADCAST,RUNNING,MULTICAST> mtu 1500 inet 10.0.3.12 netmask 255.255.255.0 broadcast 10.0.3.255 inet6 fe80::70cb:69ff:fe49:f793 prefixlen 64 scopeid 0x20<link> ether 72:cb:69:49:f7:93 txqueuelen 1000 (Ethernet) RX packets 19 bytes 2260 (2.2 KB) RX errors 0 dropped 0 overruns 0 frame 0 TX packets 9 bytes 1551 (1.5 KB) TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0 Testing cgroup API Freezing the container Unfreezing the container Shutting down the container Snapshotting the container Cloning the container as '48241ae1-4302-11ed-9fcd-42010af0000f' Renaming the clone to '48241ae2-4302-11ed-9fcd-42010af0000f' Destroying the container $ echo $? 0 dmesg: [ 169.784125] lxcbr0: port 1(veth74VNXT) entered blocking state [ 169.784129] lxcbr0: port 1(veth74VNXT) entered disabled state [ 169.784280] device veth74VNXT entered promiscuous mode [ 169.784363] IPv6: ADDRCONF(NETDEV_UP): veth74VNXT: link is not ready [ 169.794281] cgroup: cgroup: disabling cgroup2 socket matching due to net_prio or net_cls activation [ 169.844666] eth0: renamed from vethKQTAXN [ 170.006559] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready [ 170.006578] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready [ 170.006615] IPv6: ADDRCONF(NETDEV_CHANGE): veth74VNXT: link becomes ready [ 170.006664] lxcbr0: port 1(veth74VNXT) entered blocking state [ 170.006666] lxcbr0: port 1(veth74VNXT) entered forwarding state [ 170.006712] IPv6: ADDRCONF(NETDEV_CHANGE): lxcbr0: link becomes ready [ 176.060702] lxcbr0: port 1(veth74VNXT) entered disabled state [ 176.074994] systemctl invoked oom-killer: gfp_mask=0x14000c0(GFP_KERNEL), nodemask=(null), order=0, oom_score_adj=0 [ 176.074996] systemctl cpuset=48241ae0-4302-11ed-9fcd-42010af0000f mems_allowed=0 [ 176.075000] CPU: 7 PID: 3415 Comm: systemctl Not tainted 4.15.0-2045-gcp-fips #50-Ubuntu [ 176.075001] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 08/26/2022 [ 176.075002] Call Trace: [ 176.075013] dump_stack+0x98/0xd2 [ 176.075015] dump_header+0x71/0x282 [ 176.075018] oom_kill_process+0x21f/0x420 [ 176.075020] out_of_memory+0x116/0x4e0 [ 176.075024] mem_cgroup_out_of_memory+0xbb/0xd0 [ 176.075026] mem_cgroup_oom_synchronize+0x2e8/0x320 [ 176.075028] ? mem_cgroup_css_reset+0xe0/0xe0 [ 176.075029] pagefault_out_of_memory+0x13/0x60 [ 176.075033] mm_fault_error+0x90/0x180 [ 176.075034] __do_page_fault+0x479/0x4c0 [ 176.075036] do_page_fault+0x2e/0xe0 [ 176.075040] ? page_fault+0x2f/0x50 [ 176.075041] page_fault+0x45/0x50 [ 176.075043] RIP: 0033:0x7f0add113c04 [ 176.075044] RSP: 002b:00007ffd7e5b4be8 EFLAGS: 00010202 [ 176.075045] RAX: 00007f0adbd6d1f0 RBX: 0000000000000003 RCX: 00007f0adbd6d230 [ 176.075046] RDX: 0000000000000048 RSI: 0000000000000000 RDI: 00007f0adbd6d1f0 [ 176.075047] RBP: 00007ffd7e5b4e90 R08: 00007f0adbd6d238 R09: 0000000000012000 [ 176.075048] R10: 00007ffd7e5b4c20 R11: 00007f0adbd6d238 R12: 00007f0add314aa8 [ 176.075048] R13: 00007ffd7e5b4f78 R14: 0000000000000002 R15: 0000000000000801 [ 176.075050] Task in /lxc/48241ae0-4302-11ed-9fcd-42010af0000f killed as a result of limit of /lxc/48241ae0-4302-11ed-9fcd-42010af0000f [ 176.075054] memory: usage 33376kB, limit 33376kB, failcnt 108 [ 176.075054] memory+swap: usage 0kB, limit 9007199254740988kB, failcnt 0 [ 176.075055] kmem: usage 20512kB, limit 9007199254740988kB, failcnt 0 [ 176.075055] Memory cgroup stats for /lxc/48241ae0-4302-11ed-9fcd-42010af0000f: cache:8184KB rss:4064KB rss_huge:0KB shmem:8184KB mapped_file:3828KB dirty:0KB writeback:0KB inactive_anon:8280KB active_anon:4300KB inactive_file:16KB active_file:44KB unevictable:0KB [ 176.075061] [ pid ] uid tgid total_vm rss pgtables_bytes swapents oom_score_adj name [ 176.075163] [ 3122] 0 3122 9307 1288 118784 0 0 systemd [ 176.075165] [ 3190] 0 3190 8818 1623 110592 0 0 systemd-journal [ 176.075166] [ 3260] 0 3260 1126 437 53248 0 0 ondemand [ 176.075168] [ 3270] 0 3270 1822 162 61440 0 0 sleep [ 176.075169] [ 3403] 0 3403 6249 336 94208 0 0 umount [ 176.075170] [ 3404] 0 3404 6249 319 94208 0 0 umount [ 176.075172] [ 3405] 0 3405 6249 297 94208 0 0 umount [ 176.075173] [ 3406] 0 3406 6249 298 94208 0 0 umount [ 176.075175] [ 3407] 0 3407 6249 288 90112 0 0 umount [ 176.075176] [ 3408] 0 3408 6249 336 94208 0 0 umount [ 176.075178] [ 3409] 0 3409 6249 314 90112 0 0 umount [ 176.075180] [ 3411] 0 3411 6249 314 90112 0 0 umount [ 176.075181] [ 3412] 0 3412 6249 297 98304 0 0 umount [ 176.075182] [ 3413] 0 3413 6249 298 98304 0 0 umount [ 176.075183] [ 3414] 0 3414 6249 336 90112 0 0 umount [ 176.075184] [ 3415] 0 3415 5781 220 73728 0 0 systemctl [ 176.075186] [ 3416] 0 3416 9307 307 106496 0 0 (umount) [ 176.075187] Memory cgroup out of memory: Kill process 3190 (systemd-journal) score 191 or sacrifice child [ 176.076186] SLUB: Unable to allocate memory on node -1, gfp=0x14000c0(GFP_KERNEL) [ 176.085069] cache: proc_inode_cache(1511:48241ae0-4302-11ed-9fcd-42010af0000f), object size: 680, buffer size: 688, default order: 3, min order: 0 [ 176.085070] node 0: slabs: 43, objs: 2021, free: 0 [ 176.085113] Killed process 3190 (systemd-journal) total-vm:35272kB, anon-rss:280kB, file-rss:2332kB, shmem-rss:3880kB [ 176.711070] lxcbr0: port 1(veth74VNXT) entered disabled state [ 176.714952] device veth74VNXT left promiscuous mode [ 176.714962] lxcbr0: port 1(veth74VNXT) entered disabled state [ 189.145063] lxcbr0: port 1(veth6V9JPE) entered blocking state [ 189.145066] lxcbr0: port 1(veth6V9JPE) entered disabled state [ 189.145131] device veth6V9JPE entered promiscuous mode [ 189.145209] IPv6: ADDRCONF(NETDEV_UP): veth6V9JPE: link is not ready [ 189.195296] eth0: renamed from vethSAON3V [ 189.826113] lxcbr0: port 1(veth6V9JPE) entered disabled state [ 189.830664] device veth6V9JPE left promiscuous mode [ 189.830672] lxcbr0: port 1(veth6V9JPE) entered disabled state Issue found on B-gcp-fips 4.15.0-2045.50 with instance n1-standard-64 This issue can be found on other instances as well, but only 100% reproducible on this node (6 out of 6 attempts) This test will pass, but it will cause call trace in the dmesg. $ sudo python3 /usr/share/doc/python3-lxc/examples/api_test.py Getting instance for '48241ae0-4302-11ed-9fcd-42010af0000f' Creating rootfs using 'download', arch=amd64 Using image from local cache Unpacking the rootfs --- You just created an Ubuntu xenial amd64 (20220930_07:42) container. To enable SSH, run: apt install openssh-server No default root or user password are set by LXC. Testing the configuration Testing the networking Starting the container Getting the interface names Getting the IP addresses eth0: flags=4163<UP,BROADCAST,RUNNING,MULTICAST> mtu 1500         inet 10.0.3.12 netmask 255.255.255.0 broadcast 10.0.3.255         inet6 fe80::70cb:69ff:fe49:f793 prefixlen 64 scopeid 0x20<link>         ether 72:cb:69:49:f7:93 txqueuelen 1000 (Ethernet)         RX packets 19 bytes 2260 (2.2 KB)         RX errors 0 dropped 0 overruns 0 frame 0         TX packets 9 bytes 1551 (1.5 KB)         TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0 Testing cgroup API Freezing the container Unfreezing the container Shutting down the container Snapshotting the container Cloning the container as '48241ae1-4302-11ed-9fcd-42010af0000f' Renaming the clone to '48241ae2-4302-11ed-9fcd-42010af0000f' Destroying the container $ echo $? 0 dmesg: [ 169.784125] lxcbr0: port 1(veth74VNXT) entered blocking state [ 169.784129] lxcbr0: port 1(veth74VNXT) entered disabled state [ 169.784280] device veth74VNXT entered promiscuous mode [ 169.784363] IPv6: ADDRCONF(NETDEV_UP): veth74VNXT: link is not ready [ 169.794281] cgroup: cgroup: disabling cgroup2 socket matching due to net_prio or net_cls activation [ 169.844666] eth0: renamed from vethKQTAXN [ 170.006559] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready [ 170.006578] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready [ 170.006615] IPv6: ADDRCONF(NETDEV_CHANGE): veth74VNXT: link becomes ready [ 170.006664] lxcbr0: port 1(veth74VNXT) entered blocking state [ 170.006666] lxcbr0: port 1(veth74VNXT) entered forwarding state [ 170.006712] IPv6: ADDRCONF(NETDEV_CHANGE): lxcbr0: link becomes ready [ 176.060702] lxcbr0: port 1(veth74VNXT) entered disabled state [ 176.074994] systemctl invoked oom-killer: gfp_mask=0x14000c0(GFP_KERNEL), nodemask=(null), order=0, oom_score_adj=0 [ 176.074996] systemctl cpuset=48241ae0-4302-11ed-9fcd-42010af0000f mems_allowed=0 [ 176.075000] CPU: 7 PID: 3415 Comm: systemctl Not tainted 4.15.0-2045-gcp-fips #50-Ubuntu [ 176.075001] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 08/26/2022 [ 176.075002] Call Trace: [ 176.075013] dump_stack+0x98/0xd2 [ 176.075015] dump_header+0x71/0x282 [ 176.075018] oom_kill_process+0x21f/0x420 [ 176.075020] out_of_memory+0x116/0x4e0 [ 176.075024] mem_cgroup_out_of_memory+0xbb/0xd0 [ 176.075026] mem_cgroup_oom_synchronize+0x2e8/0x320 [ 176.075028] ? mem_cgroup_css_reset+0xe0/0xe0 [ 176.075029] pagefault_out_of_memory+0x13/0x60 [ 176.075033] mm_fault_error+0x90/0x180 [ 176.075034] __do_page_fault+0x479/0x4c0 [ 176.075036] do_page_fault+0x2e/0xe0 [ 176.075040] ? page_fault+0x2f/0x50 [ 176.075041] page_fault+0x45/0x50 [ 176.075043] RIP: 0033:0x7f0add113c04 [ 176.075044] RSP: 002b:00007ffd7e5b4be8 EFLAGS: 00010202 [ 176.075045] RAX: 00007f0adbd6d1f0 RBX: 0000000000000003 RCX: 00007f0adbd6d230 [ 176.075046] RDX: 0000000000000048 RSI: 0000000000000000 RDI: 00007f0adbd6d1f0 [ 176.075047] RBP: 00007ffd7e5b4e90 R08: 00007f0adbd6d238 R09: 0000000000012000 [ 176.075048] R10: 00007ffd7e5b4c20 R11: 00007f0adbd6d238 R12: 00007f0add314aa8 [ 176.075048] R13: 00007ffd7e5b4f78 R14: 0000000000000002 R15: 0000000000000801 [ 176.075050] Task in /lxc/48241ae0-4302-11ed-9fcd-42010af0000f killed as a result of limit of /lxc/48241ae0-4302-11ed-9fcd-42010af0000f [ 176.075054] memory: usage 33376kB, limit 33376kB, failcnt 108 [ 176.075054] memory+swap: usage 0kB, limit 9007199254740988kB, failcnt 0 [ 176.075055] kmem: usage 20512kB, limit 9007199254740988kB, failcnt 0 [ 176.075055] Memory cgroup stats for /lxc/48241ae0-4302-11ed-9fcd-42010af0000f: cache:8184KB rss:4064KB rss_huge:0KB shmem:8184KB mapped_file:3828KB dirty:0KB writeback:0KB inactive_anon:8280KB active_anon:4300KB inactive_file:16KB active_file:44KB unevictable:0KB [ 176.075061] [ pid ] uid tgid total_vm rss pgtables_bytes swapents oom_score_adj name [ 176.075163] [ 3122] 0 3122 9307 1288 118784 0 0 systemd [ 176.075165] [ 3190] 0 3190 8818 1623 110592 0 0 systemd-journal [ 176.075166] [ 3260] 0 3260 1126 437 53248 0 0 ondemand [ 176.075168] [ 3270] 0 3270 1822 162 61440 0 0 sleep [ 176.075169] [ 3403] 0 3403 6249 336 94208 0 0 umount [ 176.075170] [ 3404] 0 3404 6249 319 94208 0 0 umount [ 176.075172] [ 3405] 0 3405 6249 297 94208 0 0 umount [ 176.075173] [ 3406] 0 3406 6249 298 94208 0 0 umount [ 176.075175] [ 3407] 0 3407 6249 288 90112 0 0 umount [ 176.075176] [ 3408] 0 3408 6249 336 94208 0 0 umount [ 176.075178] [ 3409] 0 3409 6249 314 90112 0 0 umount [ 176.075180] [ 3411] 0 3411 6249 314 90112 0 0 umount [ 176.075181] [ 3412] 0 3412 6249 297 98304 0 0 umount [ 176.075182] [ 3413] 0 3413 6249 298 98304 0 0 umount [ 176.075183] [ 3414] 0 3414 6249 336 90112 0 0 umount [ 176.075184] [ 3415] 0 3415 5781 220 73728 0 0 systemctl [ 176.075186] [ 3416] 0 3416 9307 307 106496 0 0 (umount) [ 176.075187] Memory cgroup out of memory: Kill process 3190 (systemd-journal) score 191 or sacrifice child [ 176.076186] SLUB: Unable to allocate memory on node -1, gfp=0x14000c0(GFP_KERNEL) [ 176.085069] cache: proc_inode_cache(1511:48241ae0-4302-11ed-9fcd-42010af0000f), object size: 680, buffer size: 688, default order: 3, min order: 0 [ 176.085070] node 0: slabs: 43, objs: 2021, free: 0 [ 176.085113] Killed process 3190 (systemd-journal) total-vm:35272kB, anon-rss:280kB, file-rss:2332kB, shmem-rss:3880kB [ 176.711070] lxcbr0: port 1(veth74VNXT) entered disabled state [ 176.714952] device veth74VNXT left promiscuous mode [ 176.714962] lxcbr0: port 1(veth74VNXT) entered disabled state [ 189.145063] lxcbr0: port 1(veth6V9JPE) entered blocking state [ 189.145066] lxcbr0: port 1(veth6V9JPE) entered disabled state [ 189.145131] device veth6V9JPE entered promiscuous mode [ 189.145209] IPv6: ADDRCONF(NETDEV_UP): veth6V9JPE: link is not ready [ 189.195296] eth0: renamed from vethSAON3V [ 189.826113] lxcbr0: port 1(veth6V9JPE) entered disabled state [ 189.830664] device veth6V9JPE left promiscuous mode [ 189.830672] lxcbr0: port 1(veth6V9JPE) entered disabled state