In data venerdì 31 gennaio 2020 03:29:20 CET, Carlos E. R. ha scritto:
On 30/01/2020 11.18, stakanov wrote:
I did create a paste in
https://paste.opensuse.org/22334414
I am not good enough to understand what the system actually complains. I do not think it is a memory problem, could be related to the OS instead. The swap fills although the memory remains free. If you sudo swapoff -a the swap turns into memory, the system turns responsive. sudo dmesg reveals a flood of entries (disregard the martians, these are due to a vpn.)
I do not understand if, and if which, hardware is failing. Memory does nor reveal errors. Sometimes I get a complaint about CPU3 should not be sleeping. This is all I know. Thanks in advance if somebody understands (and maybe can a bit explain) the output. Why does the swap not return to memory once memory is abundantly available?
I just noticed that today (actually yesterday) I had a similar incident.
Log:
<0.6> 2020-01-30 15:02:31 Telcontar kernel - - - [282156.986472] usb 4-2: USB disconnect, device number 3
That was the scanner, means I powered off the auxiliaries before leaving the room.
<3.6> 2020-01-30 15:17:45 Telcontar smartd 1471 - - Device: /dev/sdc [SAT], SMART Prefailure Attribute: 1 Raw_Read_Error_Rate changed from 76 to 77 <3.6> 2020-01-30 15:17:45 Telcontar smartd 1471 - - Device: /dev/sdc [SAT], SMART Usage Attribute: 195 Hardware_ECC_Recovered changed from 76 to 77 <3.6> 2020-01-30 15:17:47 Telcontar smartd 1471 - - Device: /dev/sde [SAT], SMART Usage Attribute: 190 Airflow_Temperature_Cel changed from 72 to 75 <10.6> 2020-01-30 15:20:01 Telcontar cron 22415 - - pam_unix(crond:session): session opened for user cer by (uid=0) <10.6> 2020-01-30 15:20:01 Telcontar CRON 22415 - - pam_unix(crond:session): session closed for user cer <0.4> 2020-01-30 15:20:37 Telcontar kernel - - - [283242.920913] lxterminal: page allocation stalls for 13732ms, order:0, mode:0x14000c0(GFP_KERNEL), nodemask=(null) <0.6> 2020-01-30 15:20:37 Telcontar kernel - - - [283242.920919] lxterminal cpuset=/ mems_allowed=0 <0.4> 2020-01-30 15:20:37 Telcontar kernel - - - [283242.920924] CPU: 3 PID: 5491 Comm: lxterminal Tainted: P O 4.12.14-lp151.28.36-default #1 openSUSE Leap 15.1 <0.4> 2020-01-30 15:20:37 Telcontar kernel - - - [283242.920925] Hardware name: MICRO-STAR INTERNATIONAL CO.,LTD MS-7516/MS-7516, BIOS V1.5 10/10/2008 <0.4> 2020-01-30 15:20:37 Telcontar kernel - - - [283242.920926] Call Trace: <0.4> 2020-01-30 15:20:37 Telcontar kernel - - - [283242.920935] dump_stack+0x5c/0x86 <0.4> 2020-01-30 15:20:37 Telcontar kernel - - - [283242.920938] warn_alloc+0xe0/0x170 <0.4> 2020-01-30 15:20:37 Telcontar kernel - - - [283242.920940] __alloc_pages_slowpath+0x7e2/0xc10 <0.4> 2020-01-30 15:20:37 Telcontar kernel - - - [283242.920943] ? __switch_to_asm+0x40/0x70 <0.4> 2020-01-30 15:20:37 Telcontar kernel - - - [283242.920944] ? __switch_to_asm+0x34/0x70 <0.4> 2020-01-30 15:20:37 Telcontar kernel - - - [283242.920946] __alloc_pages_nodemask+0x246/0x260 <0.4> 2020-01-30 15:20:37 Telcontar kernel - - - [283242.920948] alloc_pages_current+0x72/0x140 <0.4> 2020-01-30 15:20:37 Telcontar kernel - - - [283242.920950] __get_free_pages+0xa/0x40 <0.4> 2020-01-30 15:20:37 Telcontar kernel - - - [283242.920953] __pollwait+0x4d/0xe0 <0.4> 2020-01-30 15:20:37 Telcontar kernel - - - [283242.920956] n_tty_poll+0x3f/0x1d0 <0.4> 2020-01-30 15:20:37 Telcontar kernel - - - [283242.920957] tty_poll+0x6d/0x90 <0.4> 2020-01-30 15:20:37 Telcontar kernel - - - [283242.920959] do_sys_poll+0x239/0x530 <0.4> 2020-01-30 15:20:37 Telcontar kernel - - - [283242.920963] ? mutex_lock+0xe/0x30 <0.4> 2020-01-30 15:20:37 Telcontar kernel - - - [283242.920965] ? unix_stream_read_generic+0x1e8/0x860 <0.4> 2020-01-30 15:20:37 Telcontar kernel - - - [283242.920966] ? compat_set_fd_set+0x80/0x80 <0.4> 2020-01-30 15:20:37 Telcontar kernel - - - [283242.920968] ? compat_poll_select_copy_remaining+0x100/0x100 <0.4> 2020-01-30 15:20:37 Telcontar kernel - - - [283242.920969] ? compat_poll_select_copy_remaining+0x100/0x100 <0.4> 2020-01-30 15:20:37 Telcontar kernel - - - [283242.920971] ? compat_poll_select_copy_remaining+0x100/0x100 <0.4> 2020-01-30 15:20:37 Telcontar kernel - - - [283242.920972] ? compat_poll_select_copy_remaining+0x100/0x100 <0.4> 2020-01-30 15:20:37 Telcontar kernel - - - [283242.920973] ? compat_poll_select_copy_remaining+0x100/0x100 <0.4> 2020-01-30 15:20:37 Telcontar kernel - - - [283242.920975] ? compat_poll_select_copy_remaining+0x100/0x100 <0.4> 2020-01-30 15:20:37 Telcontar kernel - - - [283242.920976] ? compat_poll_select_copy_remaining+0x100/0x100 <0.4> 2020-01-30 15:20:37 Telcontar kernel - - - [283242.920978] ? compat_poll_select_copy_remaining+0x100/0x100 <0.4> 2020-01-30 15:20:37 Telcontar kernel - - - [283242.920979] ? compat_poll_select_copy_remaining+0x100/0x100 <0.4> 2020-01-30 15:20:37 Telcontar kernel - - - [283242.920980] ? SyS_poll+0x70/0x100 <0.4> 2020-01-30 15:20:37 Telcontar kernel - - - [283242.920982] SyS_poll+0x70/0x100 <0.4> 2020-01-30 15:20:37 Telcontar kernel - - - [283242.920985] do_syscall_64+0x7b/0x160 <0.4> 2020-01-30 15:20:37 Telcontar kernel - - - [283242.920986] entry_SYSCALL_64_after_hwframe+0x3d/0xa2 <0.4> 2020-01-30 15:20:37 Telcontar kernel - - - [283242.920989] RIP: 0033:0x7f2433ee419b <0.4> 2020-01-30 15:20:37 Telcontar kernel - - - [283242.920990] RSP: 002b:00007fff89cf0ce0 EFLAGS: 00000293 ORIG_RAX: 0000000000000007 <0.4> 2020-01-30 15:20:37 Telcontar kernel - - - [283242.920992] RAX: ffffffffffffffda RBX: 000055dc7b0f60c0 RCX: 00007f2433ee419b <0.4> 2020-01-30 15:20:37 Telcontar kernel - - - [283242.920993] RDX: 000000000000001e RSI: 000000000000000d RDI: 000055dc7b0f60c0 <0.4> 2020-01-30 15:20:37 Telcontar kernel - - - [283242.920993] RBP: 000000000000000d R08: 0000000000000000 R09: 000055dc7ae28f80 <0.4> 2020-01-30 15:20:37 Telcontar kernel - - - [283242.920994] R10: 000055dc7ad93b60 R11: 0000000000000293 R12: 000055dc7b0f60c0 <0.4> 2020-01-30 15:20:37 Telcontar kernel - - - [283242.920995] R13: 000000000000001e R14: 00007f2435684930 R15: 000000000000000d <0.4> 2020-01-30 15:20:37 Telcontar kernel - - - [283242.920996] Mem-Info: <0.4> 2020-01-30 15:20:37 Telcontar kernel - - - [283242.921001] active_anon:640024 inactive_anon:333971 isolated_anon:0 <0.4> 2020-01-30 15:20:37 Telcontar kernel - - - [283242.921001] active_file:115412 inactive_file:696705 isolated_file:64 <0.4> 2020-01-30 15:20:37 Telcontar kernel - - - [283242.921001] unevictable:2720 dirty:5939 writeback:97792 unstable:0 <0.4> 2020-01-30 15:20:37 Telcontar kernel - - - [283242.921001] slab_reclaimable:65197 slab_unreclaimable:52386 <0.4> 2020-01-30 15:20:37 Telcontar kernel - - - [283242.921001] mapped:123973 shmem:53171 pagetables:25742 bounce:0 <0.4> 2020-01-30 15:20:37 Telcontar kernel - - - [283242.921001] free:50936 free_pcp:1095 free_cma:0 <0.4> 2020-01-30 15:20:37 Telcontar kernel - - - [283242.921004] Node 0 active_anon:2560096kB inactive_anon:1335884kB active_file:461648kB inactive_file:2786820kB unevictable:10880kB isolated(anon):0kB isolated(file):256kB mapped:495892kB dirty:23756kB writeback:391168kB shmem:212684kB shmem_thp: 0kB shmem_pmdmapped: 0kB anon_thp: 382976kB writeback_tmp:0kB unstable:0kB all_unreclaimable? no <0.4> 2020-01-30 15:20:37 Telcontar kernel - - - [283242.921005] Node 0 DMA free:15876kB min:132kB low:164kB high:196kB active_anon:0kB inactive_anon:0kB active_file:0kB inactive_file:0kB unevictable:0kB writepending:0kB present:15992kB managed:15908kB mlocked:0kB slab_reclaimable:0kB slab_unreclaimable:0kB kernel_stack:0kB pagetables:0kB bounce:0kB free_pcp:0kB local_pcp:0kB free_cma:0kB <0.4> 2020-01-30 15:20:37 Telcontar kernel - - - [283242.921008] lowmem_reserve[]: 0 2945 7936 7936 7936 <0.4> 2020-01-30 15:20:37 Telcontar kernel - - - [283242.921011] Node 0 DMA32 free:82164kB min:62576kB low:68832kB high:75088kB active_anon:674324kB inactive_anon:709408kB active_file:89800kB inactive_file:1309124kB unevictable:0kB writepending:174448kB present:3128896kB managed:3034868kB mlocked:0kB slab_reclaimable:90688kB slab_unreclaimable:47408kB kernel_stack:3048kB pagetables:11944kB bounce:0kB free_pcp:1604kB local_pcp:668kB free_cma:0kB <0.4> 2020-01-30 15:20:37 Telcontar kernel - - - [283242.921015] lowmem_reserve[]: 0 0 4990 4990 4990 <0.4> 2020-01-30 15:20:37 Telcontar kernel - - - [283242.921018] Node 0 Normal free:105704kB min:106040kB low:116644kB high:127248kB active_anon:1885752kB inactive_anon:626456kB active_file:371848kB inactive_file:1478016kB unevictable:10880kB writepending:239740kB present:5242880kB managed:5110480kB mlocked:10880kB slab_reclaimable:170100kB slab_unreclaimable:162136kB kernel_stack:21080kB pagetables:91024kB bounce:0kB free_pcp:2776kB local_pcp:1280kB free_cma:0kB <0.4> 2020-01-30 15:20:37 Telcontar kernel - - - [283242.921022] lowmem_reserve[]: 0 0 0 0 0 <0.4> 2020-01-30 15:20:37 Telcontar kernel - - - [283242.921024] Node 0 DMA: 1*4kB (U) 0*8kB 0*16kB 2*32kB (U) 1*64kB (U) 1*128kB (U) 1*256kB (U) 0*512kB 1*1024kB (U) 1*2048kB (M) 3*4096kB (M) = 15876kB <0.4> 2020-01-30 15:20:37 Telcontar kernel - - - [283242.921034] Node 0 DMA32: 1318*4kB (UME) 5388*8kB (UME) 1571*16kB (UE) 235*32kB (UE) 2*64kB (U) 0*128kB 1*256kB (M) 2*512kB (M) 0*1024kB 0*2048kB 0*4096kB = 82440kB <0.4> 2020-01-30 15:20:37 Telcontar kernel - - - [283242.921043] Node 0 Normal: 7530*4kB (UMH) 5345*8kB (UMEH) 1659*16kB (UMEH) 200*32kB (UMEH) 4*64kB (M) 3*128kB (M) 1*256kB (M) 0*512kB 0*1024kB 0*2048kB 0*4096kB = 106720kB <0.6> 2020-01-30 15:20:37 Telcontar kernel - - - [283242.921053] Node 0 hugepages_total=0 hugepages_free=0 hugepages_surp=0 hugepages_size=2048kB <0.4> 2020-01-30 15:20:37 Telcontar kernel - - - [283242.921054] 1064990 total pagecache pages <0.4> 2020-01-30 15:20:37 Telcontar kernel - - - [283242.921061] 197345 pages in swap cache <0.4> 2020-01-30 15:20:37 Telcontar kernel - - - [283242.921062] Swap cache stats: add 24031290, delete 23835992, find 116458533/122287290 <0.4> 2020-01-30 15:20:37 Telcontar kernel - - - [283242.921063] Free swap = 18066196kB <0.4> 2020-01-30 15:20:37 Telcontar kernel - - - [283242.921063] Total swap = 25165820kB <0.4> 2020-01-30 15:20:37 Telcontar kernel - - - [283242.921064] 2096942 pages RAM <0.4> 2020-01-30 15:20:37 Telcontar kernel - - - [283242.921065] 0 pages HighMem/MovableOnly <0.4> 2020-01-30 15:20:37 Telcontar kernel - - - [283242.921065] 56628 pages reserved <0.4> 2020-01-30 15:20:37 Telcontar kernel - - - [283242.921066] 0 pages hwpoisoned <10.6> 2020-01-30 15:30:01 Telcontar cron 22750 - - pam_unix(crond:session): session opened for user cer by (uid=0) <9.6> 2020-01-30 15:30:01 Telcontar CRON 22759 - - (cer) CMD (/home/cer/bin/dar_la_hora_en_cron hora)
I think I was having lunch at the time, nobody was using the machine. Maybe I was copying many files with 'mc' in the lxterminal. I suspect it finished at 15:20:42 (because of timestamp of files as shown with "ls -ct --full-time", just seconds after the incident:
drwxr-xr-x 3 root root 63 2020-01-30 15:20:42.546288581 +0100 Jazz2_small/ drwxr-xr-x 4 root root 50 2020-01-30 15:20:42.518289652 +0100 Jazz2_Big/
-- Cheers / Saludos,
Carlos E. R. (from 15.1 x86_64 at Telcontar)
For me this condition is much more frequent when the machine previously idled and when it has been suspended the day before. Do not know if this helps. _________________________________________________________________ ________________________________________________________ Ihre E-Mail-Postfächer sicher & zentral an einem Ort. Jetzt wechseln und alte E-Mail-Adresse mitnehmen! https://www.eclipso.de -- To unsubscribe, e-mail: opensuse+unsubscribe@opensuse.org To contact the owner, e-mail: opensuse+owner@opensuse.org