Skip to contentSkip to conversation

JDIRTY JWAIT errors in 2.4.19

DISCUSSION Tupshin Harper 2 participants 5 messages Dec 14, 2002 – Dec 16, 2002

lore
Share & tools
lore
#0 lore raw

i'm getting the following error logged every 11 seconds or so:

Dec 14 01:00:49 phylum kernel: vs-3050: wait_buffer_until_released: nobody

releases buffer (dev 16:01, size 4096, blocknr 2916352, count 3, list 0, state 0x10019, page c1172108, (UPTODATE, CLEAN, UNLOCKED)). Still waiting (-1320000000) JDIRTY !JWAIT

Also, some processes are blocking, include ps (so I can't get a complete process list), and shutdown.

This is on an old PII that's been up with no problems for about a month.
Is this a known problem? Is there any way to force a clean shutdown in such circumstances? Is this associated with reiserfs which all partitions are running? A googling turned up one or two references to this problem, but never with a kernel as recent as 2.4.19, and associated with, though not necessarily blamed on reiserfs.

This machine is still up, so if more info would be useful, it can probably be provided. Most processes are still working fine.

-Tupshin

#1 lore raw

Hello!

Quoted 9 lines · Tupshin Harper

i'm getting the following error logged every 11 seconds or so:

Dec 14 01:00:49 phylum kernel: vs-3050: wait_buffer_until_released: nobody

releases buffer (dev 16:01, size 4096, blocknr 2916352, count 3, list 0, state
0x10019, page c1172108, (UPTODATE, CLEAN, UNLOCKED)). Still waiting (-1320000000) JDIRTY !JWAIT
Also, some processes are blocking, include ps (so I can't get a complete process list), and shutdown.

Can you please execute SysRq-T, decode it with ksymoops and send us the result?

Quoted 2 lines

such circumstances? Is this associated with reiserfs which all partitions are running? A googling turned up one or two references to

Yes, this is reiserfs error message.

Thank you.

Bye,
Oleg

#2 lore raw

Sigh...unfortunately that machine didn't have magic SysRq compiled in. I'm going to build an idential kernel with Sysrq and reboot. Maybe I'll see the error again in a month or so ;-(

-Tupshin

Quoted 4 lines · Oleg Drokin

Can you please execute SysRq-T, decode it with ksymoops and send us the result?

Sigh...unfortunately that machine didn't have magic SysRq compiled in. I'm going to build an identical kernel with Sysrq and reboot. Maybe I'll see the error again in a month or so ;-(

-Tupshin

#3 lore raw
In reply to Oleg Drokin
Quoted 16 lines · Oleg Drokin

Hello!

Quoted 9 lines · Tupshin Harper

i'm getting the following error logged every 11 seconds or so:

Dec 14 01:00:49 phylum kernel: vs-3050: wait_buffer_until_released: nobody

releases buffer (dev 16:01, size 4096, blocknr 2916352, count 3, list 0, state 0x10019, page c1172108, (UPTODATE, CLEAN, UNLOCKED)). Still waiting
(-1320000000) JDIRTY !JWAIT
Also, some processes are blocking, include ps (so I can't get a complete process list), and shutdown.

Quoted 3 lines

Can you please execute SysRq-T, decode it with ksymoops and send us the result?

I have succeeded in reproducing this problem at will (and deterministically) with a SysRq enabled kernel.

I can reproduce the problem consistently by doing a cp -av of a directory(haven't tried other cp permutations, guessing it wouldn't make a difference) containing three mp3 files from one location on a partition to another location on the same reiserfs partition. During the third file, cp hangs(continues to use 80%+ of the CPU), and is unkillable. A few minutes later, it starts generating the same JDIRTY error messages that I reported before.

The attached file is a ksymoops processed version of SysRq-T after this problem has started to occur. There is one cp taking place which is the one that triggered the problem, and there is also a sync command that is blocking as well which I tried to do after the cp hung.

I have not tried to do any kind of reiserfs check or repair on the filesystem, and I can trigger this problem at any point if you would like me to test further.

-Tupshin

ksymoops 2.4.6 on i686 2.4.19-sysrq. Options used
-V (default)
-k /proc/ksyms (specified)
-l /proc/modules (specified)
-o /lib/modules/2.4.19-sysrq/ (specified) -m /boot/System.map-2.4.19-sysrq (specified)

Dec 15 12:08:30 phylum kernel: init          S C12F5F28  4500     1      0  1135               (NOTLB)
Using defaults from ksymoops -t elf32-i386 -a i386
Dec 15 12:08:30 phylum kernel: Call Trace:    [schedule_timeout+115/148] [process_timeout+0/72] [do_select+412/468] [sys_select+800/1100] [system_call+51/56]
Dec 15 12:08:30 phylum kernel: keventd       S C12D8000  6152     2      1             3       (L-TLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [context_thread+253/424] [kernel_thread+40/56]
Dec 15 12:08:30 phylum kernel: ksoftirqd_CPU S C12D4000  6388     3      1             4     2 (L-TLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [ksoftirqd+116/180] [kernel_thread+40/56]
Dec 15 12:08:30 phylum kernel: kswapd        S C12D2000  6108     4      1             5     3 (L-TLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [kswapd+127/196] [kernel_thread+40/56]
Dec 15 12:08:30 phylum kernel: bdflush       S 00000282  6560     5      1             6     4 (L-TLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [interruptible_sleep_on+61/80] [bdflush+166/168] [kernel_thread+40/56]
Dec 15 12:08:30 phylum kernel: kupdated      D 00000282  5664     6      1             9     5 (L-TLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [sleep_on+61/80] [check_journal_end+294/556] [do_journal_end+178/2584] [flush_old_commits+267/304] [flush_old_commits+281/304]
Dec 15 12:08:30 phylum kernel: mdrecoveryd   S C138C000  6580     9      1             8     6 (L-TLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [md_thread+201/316] [kernel_thread+40/56]
Dec 15 12:08:30 phylum kernel: khubd         S C139DFDC  6000     8      1            10     9 (L-TLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [usb_hub_thread+127/168] [kernel_thread+40/56]
Dec 15 12:08:30 phylum kernel: kreiserfsd    S CA33DFB0  6064    10      1            24     8 (L-TLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [schedule_timeout+115/148] [process_timeout+0/72] [interruptible_sleep_on_timeout+66/92] [reiserfs_journal_commit_thread+153/208] [kernel_thread+40/56]
Dec 15 12:08:30 phylum kernel: devfsd        S C9DB4000  5964    24      1           206    10 (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [devfsd_read+238/868] [sys_read+152/244] [system_call+51/56]
Dec 15 12:08:30 phylum kernel: portmap       S 7FFFFFFF     0   206      1           328    24 (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [schedule_timeout+23/148] [do_poll+117/204] [do_poll+168/204] [sys_poll+473/748] [sys_socketcall+383/408]
Dec 15 12:08:30 phylum kernel: syslogd       S 7FFFFFFF  4752   328      1           331   206 (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [schedule_timeout+23/148] [do_select+412/468] [sys_select+800/1100] [system_call+51/56]
Dec 15 12:08:30 phylum kernel: klogd         R C455C000  4752   331      1           339   328 (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [do_syslog+208/776] [kmsg_read+18/24] [sys_read+152/244] [system_call+51/56]
Dec 15 12:08:30 phylum kernel: named         S C42A9FAC  5408   339      1   340     361   331 (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [sys_rt_sigsuspend+238/264] [system_call+51/56]
Dec 15 12:08:30 phylum kernel: named         S CA16FF28  1248   340    339   343               (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [schedule_timeout+115/148] [process_timeout+0/72] [do_poll+168/204] [sys_poll+473/748] [do_softirq+75/160]
Dec 15 12:08:30 phylum kernel: named         S C4645FAC     0   341    340           342       (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [sys_rt_sigsuspend+238/264] [system_call+51/56]
Dec 15 12:08:30 phylum kernel: named         S CA1F9F88     0   342    340           343   341 (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [schedule_timeout+115/148] [process_timeout+0/72] [sys_nanosleep+268/388] [system_call+51/56]
Dec 15 12:08:30 phylum kernel: named         S 7FFFFFFF     0   343    340                 342 (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [schedule_timeout+23/148] [do_select+412/468] [sys_select+800/1100] [system_call+51/56]
Dec 15 12:08:30 phylum kernel: slapd         S C3E97FAC  4752   361      1   364     366   339 (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [sys_rt_sigsuspend+238/264] [system_call+51/56]
Dec 15 12:08:30 phylum kernel: slapd         S C3E09F28  6292   364    361   365               (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [schedule_timeout+115/148] [process_timeout+0/72] [do_poll+168/204] [sys_poll+473/748] [schedule+714/756]
Dec 15 12:08:30 phylum kernel: slapd         S 7FFFFFFF  2660   365    364                     (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [schedule_timeout+23/148] [do_select+412/468] [sys_select+800/1100] [system_call+51/56]
Dec 15 12:08:30 phylum kernel: spamd         S 7FFFFFFF  4752   366      1           517   361 (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [schedule_timeout+23/148] [wait_for_connect+205/372] [tcp_accept+137/428] [inet_accept+42/308] [sys_accept+97/252]
Dec 15 12:08:30 phylum kernel: proxytest     S C2A07F88  5172   517      1           525   366 (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [schedule_timeout+115/148] [process_timeout+0/72] [sys_nanosleep+268/388] [system_call+51/56]
Dec 15 12:08:30 phylum kernel: authdaemond.p S C29C1F28  1024   525      1   531     538   517 (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [schedule_timeout+115/148] [process_timeout+0/72] [do_select+412/468] [sys_select+800/1100] [system_call+51/56]
Dec 15 12:08:30 phylum kernel: authdaemond.p S C299BF28  6332   526    525           527       (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [schedule_timeout+115/148] [process_timeout+0/72] [do_select+412/468] [sys_select+800/1100] [system_call+51/56]
Dec 15 12:08:30 phylum kernel: authdaemond.p S C2993F28  6332   527    525           528   526 (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [schedule_timeout+115/148] [process_timeout+0/72] [do_select+412/468] [sys_select+800/1100] [system_call+51/56]
Dec 15 12:08:30 phylum kernel: authdaemond.p S C298BF28  6332   528    525           529   527 (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [schedule_timeout+115/148] [process_timeout+0/72] [do_select+412/468] [sys_select+800/1100] [system_call+51/56]
Dec 15 12:08:30 phylum kernel: authdaemond.p S C2983F28  6332   529    525           531   528 (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [schedule_timeout+115/148] [process_timeout+0/72] [do_select+412/468] [sys_select+800/1100] [system_call+51/56]
Dec 15 12:08:30 phylum kernel: authdaemond.p S C2979F28  6392   531    525                 529 (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [schedule_timeout+115/148] [process_timeout+0/72] [do_select+412/468] [sys_select+800/1100] [system_call+51/56]
Dec 15 12:08:30 phylum kernel: couriertcpd   S 7FFFFFFF     4   538      1           542   525 (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [schedule_timeout+23/148] [do_select+412/468] [sys_select+800/1100] [system_call+51/56]
Dec 15 12:08:30 phylum kernel: courierlogger S C29CA000     0   542      1           552   538 (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [pipe_wait+121/164] [pipe_read+191/544] [sys_read+152/244] [system_call+51/56]
Dec 15 12:08:30 phylum kernel: couriertcpd   S 7FFFFFFF     0   552      1           555   542 (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [schedule_timeout+23/148] [do_select+412/468] [sys_select+800/1100] [system_call+51/56]
Dec 15 12:08:30 phylum kernel: courierlogger S C456A000     0   555      1           559   552 (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [pipe_wait+121/164] [pipe_read+191/544] [sys_read+152/244] [system_call+51/56]
Dec 15 12:08:30 phylum kernel: cupsd         S C2863F28  4752   559      1           783   555 (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [schedule_timeout+115/148] [process_timeout+0/72] [do_select+412/468] [sys_select+800/1100] [system_call+51/56]
Dec 15 12:08:30 phylum kernel: dhcpd         S 7FFFFFFF  2656   783      1           793   559 (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [schedule_timeout+23/148] [do_select+412/468] [sys_select+800/1100] [system_call+51/56]
Dec 15 12:08:30 phylum kernel: inetd         S 7FFFFFFF   104   793      1           811   783 (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [schedule_timeout+23/148] [do_select+412/468] [sys_select+800/1100] [system_call+51/56]
Dec 15 12:08:30 phylum kernel: safe_mysqld   S 00000000  2656   811      1   843     870   793 (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [sys_wait4+860/908] [system_call+51/56]
Dec 15 12:08:30 phylum kernel: mysqld        S 7FFFFFFF     0   843    811   844               (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [schedule_timeout+23/148] [do_select+412/468] [sys_select+800/1100] [system_call+51/56]
Dec 15 12:08:30 phylum kernel: mysqld        S C1967F28  6292   844    843   846               (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [schedule_timeout+115/148] [process_timeout+0/72] [do_poll+168/204] [sys_poll+473/748] [sys_wait4+896/908]
Dec 15 12:08:30 phylum kernel: mysqld        S C1963FAC  4752   845    844           846       (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [sys_rt_sigsuspend+238/264] [system_call+51/56]
Dec 15 12:08:30 phylum kernel: mysqld        S C18F3FAC  6600   846    844                 845 (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [sys_rt_sigsuspend+238/264] [system_call+51/56]
Dec 15 12:08:30 phylum kernel: netsaint      S C1871F88     0   870      1           916   811 (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [schedule_timeout+115/148] [process_timeout+0/72] [sys_nanosleep+268/388] [system_call+51/56]
Dec 15 12:08:30 phylum kernel: pptpd         S 7FFFFFFF     0   916      1           919   870 (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [schedule_timeout+23/148] [do_select+412/468] [sys_select+800/1100] [system_call+51/56]
Dec 15 12:08:30 phylum kernel: privoxy       S 7FFFFFFF     0   919      1           924   916 (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [schedule_timeout+23/148] [wait_for_connect+205/372] [tcp_accept+137/428] [inet_accept+42/308] [sys_accept+97/252]
Dec 15 12:08:30 phylum kernel: qmail-send    S C168DF28  4600   924      1   934     929   919 (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [schedule_timeout+115/148] [process_timeout+0/72] [do_select+412/468] [sys_select+800/1100] [system_call+51/56]
Dec 15 12:08:30 phylum kernel: tcpserver     S 7FFFFFFF  4600   928      1           936   929 (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [schedule_timeout+23/148] [wait_for_connect+205/372] [tcp_accept+137/428] [inet_accept+42/308] [sys_accept+97/252]
Dec 15 12:08:30 phylum kernel: splogger      S C1656000   160   929      1           928   924 (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [pipe_wait+121/164] [pipe_read+191/544] [sys_read+152/244] [system_call+51/56]
Dec 15 12:08:30 phylum kernel: splogger      S C164A000     0   931    924           932       (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [pipe_wait+121/164] [pipe_read+191/544] [sys_read+152/244] [system_call+51/56]
Dec 15 12:08:30 phylum kernel: qmail-lspawn  S 7FFFFFFF  2532   932    924           933   931 (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [schedule_timeout+23/148] [do_select+412/468] [sys_select+800/1100] [system_call+51/56]
Dec 15 12:08:30 phylum kernel: qmail-rspawn  S 7FFFFFFF     0   933    924           934   932 (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [schedule_timeout+23/148] [do_select+412/468] [sys_select+800/1100] [system_call+51/56]
Dec 15 12:08:30 phylum kernel: qmail-clean   S C1688000     0   934    924                 933 (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [pipe_wait+121/164] [pipe_read+191/544] [sys_read+152/244] [system_call+51/56]
Dec 15 12:08:30 phylum kernel: rebootmgr     S 7FFFFFFF  4600   936      1           949   928 (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [free_page_and_swap_cache+53/60] [schedule_timeout+23/148] [do_select+30/468] [do_select+412/468] [sys_select+800/1100]
Dec 15 12:08:30 phylum kernel: nmbd          S C0DEFF28  4584   949      1           951   936 (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [schedule_timeout+115/148] [process_timeout+0/72] [do_select+412/468] [sys_select+800/1100] [system_call+51/56]
Dec 15 12:08:30 phylum kernel: smbd          S 7FFFFFFF  5076   951      1           954   949 (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [schedule_timeout+23/148] [do_select+412/468] [sys_select+800/1100] [system_call+51/56]
Dec 15 12:08:30 phylum kernel: slpd          S 7FFFFFFF  6068   954      1           957   951 (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [schedule_timeout+23/148] [do_select+412/468] [sys_select+800/1100] [system_call+51/56]
Dec 15 12:08:30 phylum kernel: smartd        S C09C3F88  5680   957      1           966   954 (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [schedule_timeout+115/148] [process_timeout+0/72] [sys_nanosleep+268/388] [system_call+51/56]
Dec 15 12:08:30 phylum kernel: sshd          S 7FFFFFFF  2656   966      1  1327     991   957 (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [schedule_timeout+23/148] [do_select+412/468] [sys_select+800/1100] [system_call+51/56]
Dec 15 12:08:30 phylum kernel: rpc.statd     S 7FFFFFFF  2656   991      1           995   966 (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [schedule_timeout+23/148] [do_select+412/468] [sys_select+800/1100] [system_call+51/56]
Dec 15 12:08:30 phylum kernel: ntpd          S 7FFFFFFF  4692   995      1  1005    1001   991 (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [schedule_timeout+23/148] [do_select+412/468] [sys_select+800/1100] [system_call+51/56]
Dec 15 12:08:30 phylum kernel: rpc.nfsd      S 7FFFFFFF     0  1001      1          1007   995 (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [schedule_timeout+23/148] [do_poll+117/204] [do_poll+168/204] [sys_poll+473/748] [sys_sigreturn+182/224]
Dec 15 12:08:30 phylum kernel: ntpd          S CBD67FAC     0  1005    995                     (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [sys_rt_sigsuspend+238/264] [system_call+51/56]
Dec 15 12:08:30 phylum kernel: rpc.mountd    S 7FFFFFFF     0  1007      1          1026  1001 (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [schedule_timeout+23/148] [do_poll+117/204] [do_poll+168/204] [sys_poll+473/748] [sys_sigreturn+182/224]
Dec 15 12:08:30 phylum kernel: squid         S 00000000     0  1026      1  1031    1052  1007 (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [sys_wait4+860/908] [system_call+51/56]
Dec 15 12:08:30 phylum kernel: squid         S CBC55F28     0  1031   1026  1043               (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [schedule_timeout+115/148] [process_timeout+0/72] [do_poll+168/204] [sys_poll+473/748] [filp_close+89/104]
Dec 15 12:08:30 phylum kernel: squid_redirec S 7FFFFFFF  2656  1036   1031          1039       (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [schedule_timeout+23/148] [tcp_data_wait+212/304] [tcp_recvmsg+1060/1956] [lru_cache_del+8/24] [inet_recvmsg+58/84]
Dec 15 12:08:30 phylum kernel: squid_redirec S 7FFFFFFF  2656  1039   1031          1040  1036 (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [schedule_timeout+23/148] [tcp_data_wait+212/304] [tcp_recvmsg+1060/1956] [lru_cache_del+8/24] [inet_recvmsg+58/84]
Dec 15 12:08:30 phylum kernel: squid_redirec S 7FFFFFFF  2656  1040   1031          1041  1039 (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [schedule_timeout+23/148] [tcp_data_wait+212/304] [tcp_recvmsg+1060/1956] [lru_cache_del+8/24] [inet_recvmsg+58/84]
Dec 15 12:08:30 phylum kernel: squid_redirec S 7FFFFFFF     0  1041   1031          1042  1040 (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [schedule_timeout+23/148] [tcp_data_wait+212/304] [tcp_recvmsg+1060/1956] [lru_cache_del+8/24] [inet_recvmsg+58/84]
Dec 15 12:08:30 phylum kernel: squid_redirec S 7FFFFFFF  2656  1042   1031          1043  1041 (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [schedule_timeout+23/148] [tcp_data_wait+212/304] [tcp_recvmsg+1060/1956] [lru_cache_del+8/24] [inet_recvmsg+58/84]
Dec 15 12:08:30 phylum kernel: unlinkd       S CB808000     0  1043   1031                1042 (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [pipe_wait+121/164] [pipe_read+191/544] [sys_read+152/244] [system_call+51/56]
Dec 15 12:08:30 phylum kernel: proftpd       S CB559F28     0  1052      1          1062  1026 (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [schedule_timeout+115/148] [process_timeout+0/72] [do_select+412/468] [sys_select+800/1100] [system_call+51/56]
Dec 15 12:08:30 phylum kernel: usb_perms     S CB42BF88     0  1062      1          1066  1052 (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [schedule_timeout+115/148] [process_timeout+0/72] [sys_nanosleep+268/388] [system_call+51/56]
Dec 15 12:08:30 phylum kernel: atd           S CB0E7F88   152  1066      1          1069  1062 (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [schedule_timeout+115/148] [process_timeout+0/72] [sys_nanosleep+268/388] [system_call+51/56]
Dec 15 12:08:30 phylum kernel: cron          S CB02DF88  2656  1069      1          1097  1066 (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [schedule_timeout+115/148] [process_timeout+0/72] [sys_nanosleep+268/388] [system_call+51/56]
Dec 15 12:08:30 phylum kernel: apache        S C8739F28     0  1097      1  1119    1102  1069 (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [schedule_timeout+115/148] [process_timeout+0/72] [do_select+412/468] [generic_file_write+1217/1624] [sys_select+800/1100]
Dec 15 12:08:30 phylum kernel: apache-ssl    S C8417F28     8  1102      1  1141    1128  1097 (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [schedule_timeout+115/148] [process_timeout+0/72] [do_select+412/468] [generic_file_write+1217/1624] [sys_select+800/1100]
Dec 15 12:08:30 phylum kernel: apache        S 7FFFFFFF     0  1103   1097          1105       (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [schedule_timeout+23/148] [wait_for_connect+205/372] [tcp_accept+137/428] [inet_accept+42/308] [sys_accept+97/252]
Dec 15 12:08:30 phylum kernel: apache        S 7FFFFFFF     0  1105   1097          1106  1103 (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [schedule_timeout+23/148] [wait_for_connect+205/372] [tcp_accept+137/428] [inet_accept+42/308] [sys_accept+97/252]
Dec 15 12:08:30 phylum kernel: apache        S 7FFFFFFF  2656  1106   1097          1107  1105 (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [schedule_timeout+23/148] [wait_for_connect+205/372] [tcp_accept+137/428] [inet_accept+42/308] [sys_accept+97/252]
Dec 15 12:08:30 phylum kernel: apache        S 7FFFFFFF     0  1107   1097          1119  1106 (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [schedule_timeout+23/148] [wait_for_connect+205/372] [tcp_accept+137/428] [inet_accept+42/308] [sys_accept+97/252]
Dec 15 12:08:30 phylum kernel: apache        S 7FFFFFFF     0  1119   1097                1107 (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [schedule_timeout+23/148] [wait_for_connect+205/372] [tcp_accept+137/428] [inet_accept+42/308] [sys_accept+97/252]
Dec 15 12:08:30 phylum kernel: bash          S 00000000  4600  1128      1  1175    1129  1102 (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [sys_wait4+860/908] [system_call+51/56]
Dec 15 12:08:30 phylum kernel: bash          S 00000000     0  1129      1  1201    1130  1128 (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [sys_wait4+860/908] [system_call+51/56]
Dec 15 12:08:30 phylum kernel: bash          S 00000000     0  1130      1  1205    1131  1129 (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [sys_wait4+860/908] [system_call+51/56]
Dec 15 12:08:30 phylum kernel: getty         S 7FFFFFFF     0  1131      1          1132  1130 (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [schedule_timeout+23/148] [read_chan+834/1644] [tty_read+174/216] [sys_read+152/244] [system_call+51/56]
Dec 15 12:08:30 phylum kernel: getty         S 7FFFFFFF     0  1132      1          1135  1131 (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [schedule_timeout+23/148] [read_chan+834/1644] [tty_read+174/216] [sys_read+152/244] [system_call+51/56]
Dec 15 12:08:30 phylum kernel: getty         S 7FFFFFFF  2656  1135      1                1132 (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [schedule_timeout+23/148] [read_chan+834/1644] [tty_read+174/216] [sys_read+152/244] [system_call+51/56]
Dec 15 12:08:30 phylum kernel: gcache        S 7FFFFFFF     0  1136   1102          1137       (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [schedule_timeout+23/148] [wait_for_packet+206/320] [skb_recv_datagram+175/204] [unix_accept+73/192] [sys_accept+97/252]
Dec 15 12:08:30 phylum kernel: apache-ssl    S 7FFFFFFF    44  1137   1102          1138  1136 (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [schedule_timeout+23/148] [wait_for_connect+205/372] [tcp_accept+137/428] [inet_accept+42/308] [sys_accept+97/252]
Dec 15 12:08:30 phylum kernel: apache-ssl    S 7FFFFFFF  2656  1138   1102          1139  1137 (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [schedule_timeout+23/148] [wait_for_connect+205/372] [tcp_accept+137/428] [inet_accept+42/308] [sys_accept+97/252]
Dec 15 12:08:30 phylum kernel: apache-ssl    S 7FFFFFFF  2656  1139   1102          1140  1138 (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [schedule_timeout+23/148] [wait_for_connect+205/372] [tcp_accept+137/428] [inet_accept+42/308] [sys_accept+97/252]
Dec 15 12:08:30 phylum kernel: apache-ssl    S 7FFFFFFF  2656  1140   1102          1141  1139 (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [schedule_timeout+23/148] [wait_for_connect+205/372] [tcp_accept+137/428] [inet_accept+42/308] [sys_accept+97/252]
Dec 15 12:08:30 phylum kernel: apache-ssl    S 7FFFFFFF  2656  1141   1102                1140 (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [schedule_timeout+23/148] [wait_for_connect+205/372] [tcp_accept+137/428] [inet_accept+42/308] [sys_accept+97/252]
Dec 15 12:08:30 phylum kernel: cp            R current      0  1175   1128                     (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [schedule+100/756] [wait_buffer_until_released+174/216] [get_empty_nodes+292/432] [fix_nodes+347/1068] [is_tree_node+71/80]
Dec 15 12:08:30 phylum kernel: sync          D CA0CBC44  1632  1201   1129                     (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [__down+81/152] [__down_failed+11/20] [.text.lock.super+123/237] [fsync_dev+34/52] [sys_sync+10/16]
Dec 15 12:08:30 phylum kernel: tail          S C72A1F88     0  1205   1130                     (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [schedule_timeout+115/148] [process_timeout+0/72] [sys_nanosleep+268/388] [system_call+51/56]
Dec 15 12:08:30 phylum kernel: sshd          S 7FFFFFFF   196  1327    966  1328               (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [schedule_timeout+23/148] [unix_stream_data_wait+168/224] [unix_stream_recvmsg+389/928] [sock_recvmsg+55/168] [sock_read+135/144]
Dec 15 12:08:30 phylum kernel: sshd          S 7FFFFFFF  2532  1328   1327                     (NOTLB)
Dec 15 12:08:30 phylum kernel: Call Trace:    [schedule_timeout+23/148] [do_select+412/468] [sys_select+800/1100] [system_call+51/56]

Warning (Oops_read): Code line not seen, dumping what data is available

Proc; init

Quoted 1 line
Quoted 1 line

EIP; c12f5f28 <_end+f838e4/c593a1c> <=====

Proc; keventd

Quoted 1 line
Quoted 1 line

EIP; c12d8000 <_end+f659bc/c593a1c> <=====

Proc; ksoftirqd_CPU

Quoted 1 line
Quoted 1 line

EIP; c12d4000 <_end+f619bc/c593a1c> <=====

Proc; kswapd

Quoted 1 line
Quoted 1 line

EIP; c12d2000 <_end+f5f9bc/c593a1c> <=====

Proc; bdflush

Quoted 1 line
Quoted 1 line

EIP; 00000282 Before first symbol <=====

Proc; kupdated

Quoted 1 line
Quoted 1 line

EIP; 00000282 Before first symbol <=====

Proc; mdrecoveryd

Quoted 1 line
Quoted 1 line

EIP; c138c000 <_end+10199bc/c593a1c> <=====

Proc; khubd

Quoted 1 line
Quoted 1 line

EIP; c139dfdc <_end+102b998/c593a1c> <=====

Proc; kreiserfsd

Quoted 1 line
Quoted 1 line

EIP; ca33dfb0 <_end+9fcb96c/c593a1c> <=====

Proc; devfsd

Quoted 1 line
Quoted 1 line

EIP; c9db4000 <_end+9a419bc/c593a1c> <=====

Proc; portmap

Quoted 1 line
Quoted 1 line

EIP; 7fffffff Before first symbol <=====

Proc; syslogd

Quoted 1 line
Quoted 1 line

EIP; 7fffffff Before first symbol <=====

Proc; klogd

Quoted 1 line
Quoted 1 line

EIP; c455c000 <_end+41e99bc/c593a1c> <=====

Proc; named

Quoted 1 line
Quoted 1 line

EIP; c42a9fac <_end+3f37968/c593a1c> <=====

Proc; named

Quoted 1 line
Quoted 1 line

EIP; ca16ff28 <_end+9dfd8e4/c593a1c> <=====

Proc; named

Quoted 1 line
Quoted 1 line

EIP; c4645fac <_end+42d3968/c593a1c> <=====

Proc; named

Quoted 1 line
Quoted 1 line

EIP; ca1f9f88 <_end+9e87944/c593a1c> <=====

Proc; named

Quoted 1 line
Quoted 1 line

EIP; 7fffffff Before first symbol <=====

Proc; slapd

Quoted 1 line
Quoted 1 line

EIP; c3e97fac <_end+3b25968/c593a1c> <=====

Proc; slapd

Quoted 1 line
Quoted 1 line

EIP; c3e09f28 <_end+3a978e4/c593a1c> <=====

Proc; slapd

Quoted 1 line
Quoted 1 line

EIP; 7fffffff Before first symbol <=====

Proc; spamd

Quoted 1 line
Quoted 1 line

EIP; 7fffffff Before first symbol <=====

Proc; proxytest

Quoted 1 line
Quoted 1 line

EIP; c2a07f88 <_end+2695944/c593a1c> <=====

Proc; authdaemond.p

Quoted 1 line
Quoted 1 line

EIP; c29c1f28 <_end+264f8e4/c593a1c> <=====

Proc; authdaemond.p

Quoted 1 line
Quoted 1 line

EIP; c299bf28 <_end+26298e4/c593a1c> <=====

Proc; authdaemond.p

Quoted 1 line
Quoted 1 line

EIP; c2993f28 <_end+26218e4/c593a1c> <=====

Proc; authdaemond.p

Quoted 1 line
Quoted 1 line

EIP; c298bf28 <_end+26198e4/c593a1c> <=====

Proc; authdaemond.p

Quoted 1 line
Quoted 1 line

EIP; c2983f28 <_end+26118e4/c593a1c> <=====

Proc; authdaemond.p

Quoted 1 line
Quoted 1 line

EIP; c2979f28 <_end+26078e4/c593a1c> <=====

Proc; couriertcpd

Quoted 1 line
Quoted 1 line

EIP; 7fffffff Before first symbol <=====

Proc; courierlogger

Quoted 1 line
Quoted 1 line

EIP; c29ca000 <_end+26579bc/c593a1c> <=====

Proc; couriertcpd

Quoted 1 line
Quoted 1 line

EIP; 7fffffff Before first symbol <=====

Proc; courierlogger

Quoted 1 line
Quoted 1 line

EIP; c456a000 <_end+41f79bc/c593a1c> <=====

Proc; cupsd

Quoted 1 line
Quoted 1 line

EIP; c2863f28 <_end+24f18e4/c593a1c> <=====

Proc; dhcpd

Quoted 1 line
Quoted 1 line

EIP; 7fffffff Before first symbol <=====

Proc; inetd

Quoted 1 line
Quoted 1 line

EIP; 7fffffff Before first symbol <=====

Proc; safe_mysqld

Quoted 1 line
Quoted 1 line

EIP; 00000000 Before first symbol

Proc; mysqld

Quoted 1 line
Quoted 1 line

EIP; 7fffffff Before first symbol <=====

Proc; mysqld

Quoted 1 line
Quoted 1 line

EIP; c1967f28 <_end+15f58e4/c593a1c> <=====

Proc; mysqld

Quoted 1 line
Quoted 1 line

EIP; c1963fac <_end+15f1968/c593a1c> <=====

Proc; mysqld

Quoted 1 line
Quoted 1 line

EIP; c18f3fac <_end+1581968/c593a1c> <=====

Proc; netsaint

Quoted 1 line
Quoted 1 line

EIP; c1871f88 <_end+14ff944/c593a1c> <=====

Proc; pptpd

Quoted 1 line
Quoted 1 line

EIP; 7fffffff Before first symbol <=====

Proc; privoxy

Quoted 1 line
Quoted 1 line

EIP; 7fffffff Before first symbol <=====

Proc; qmail-send

Quoted 1 line
Quoted 1 line

EIP; c168df28 <_end+131b8e4/c593a1c> <=====

Proc; tcpserver

Quoted 1 line
Quoted 1 line

EIP; 7fffffff Before first symbol <=====

Proc; splogger

Quoted 1 line
Quoted 1 line

EIP; c1656000 <_end+12e39bc/c593a1c> <=====

Proc; splogger

Quoted 1 line
Quoted 1 line

EIP; c164a000 <_end+12d79bc/c593a1c> <=====

Proc; qmail-lspawn

Quoted 1 line
Quoted 1 line

EIP; 7fffffff Before first symbol <=====

Proc; qmail-rspawn

Quoted 1 line
Quoted 1 line

EIP; 7fffffff Before first symbol <=====

Proc; qmail-clean

Quoted 1 line
Quoted 1 line

EIP; c1688000 <_end+13159bc/c593a1c> <=====

Proc; rebootmgr

Quoted 1 line
Quoted 1 line

EIP; 7fffffff Before first symbol <=====

Proc; nmbd

Quoted 1 line
Quoted 1 line

EIP; c0deff28 <_end+a7d8e4/c593a1c> <=====

Proc; smbd

Quoted 1 line
Quoted 1 line

EIP; 7fffffff Before first symbol <=====

Proc; slpd

Quoted 1 line
Quoted 1 line

EIP; 7fffffff Before first symbol <=====

Proc; smartd

Quoted 1 line
Quoted 1 line

EIP; c09c3f88 <_end+651944/c593a1c> <=====

Proc; sshd

Quoted 1 line
Quoted 1 line

EIP; 7fffffff Before first symbol <=====

Proc; rpc.statd

Quoted 1 line
Quoted 1 line

EIP; 7fffffff Before first symbol <=====

Proc; ntpd

Quoted 1 line
Quoted 1 line

EIP; 7fffffff Before first symbol <=====

Proc; rpc.nfsd

Quoted 1 line
Quoted 1 line

EIP; 7fffffff Before first symbol <=====

Proc; ntpd

Quoted 1 line
Quoted 1 line

EIP; cbd67fac <_end+b9f5968/c593a1c> <=====

Proc; rpc.mountd

Quoted 1 line
Quoted 1 line

EIP; 7fffffff Before first symbol <=====

Proc; squid

Quoted 1 line
Quoted 1 line

EIP; 00000000 Before first symbol

Proc; squid

Quoted 1 line
Quoted 1 line

EIP; cbc55f28 <_end+b8e38e4/c593a1c> <=====

Proc; squid_redirec

Quoted 1 line
Quoted 1 line

EIP; 7fffffff Before first symbol <=====

Proc; squid_redirec

Quoted 1 line
Quoted 1 line

EIP; 7fffffff Before first symbol <=====

Proc; squid_redirec

Quoted 1 line
Quoted 1 line

EIP; 7fffffff Before first symbol <=====

Proc; squid_redirec

Quoted 1 line
Quoted 1 line

EIP; 7fffffff Before first symbol <=====

Proc; squid_redirec

Quoted 1 line
Quoted 1 line

EIP; 7fffffff Before first symbol <=====

Proc; unlinkd

Quoted 1 line
Quoted 1 line

EIP; cb808000 <_end+b4959bc/c593a1c> <=====

Proc; proftpd

Quoted 1 line
Quoted 1 line

EIP; cb559f28 <_end+b1e78e4/c593a1c> <=====

Proc; usb_perms

Quoted 1 line
Quoted 1 line

EIP; cb42bf88 <_end+b0b9944/c593a1c> <=====

Proc; atd

Quoted 1 line
Quoted 1 line

EIP; cb0e7f88 <_end+ad75944/c593a1c> <=====

Proc; cron

Quoted 1 line
Quoted 1 line

EIP; cb02df88 <_end+acbb944/c593a1c> <=====

Proc; apache

Quoted 1 line
Quoted 1 line

EIP; c8739f28 <_end+83c78e4/c593a1c> <=====

Proc; apache-ssl

Quoted 1 line
Quoted 1 line

EIP; c8417f28 <_end+80a58e4/c593a1c> <=====

Proc; apache

Quoted 1 line
Quoted 1 line

EIP; 7fffffff Before first symbol <=====

Proc; apache

Quoted 1 line
Quoted 1 line

EIP; 7fffffff Before first symbol <=====

Proc; apache

Quoted 1 line
Quoted 1 line

EIP; 7fffffff Before first symbol <=====

Proc; apache

Quoted 1 line
Quoted 1 line

EIP; 7fffffff Before first symbol <=====

Proc; apache

Quoted 1 line
Quoted 1 line

EIP; 7fffffff Before first symbol <=====

Proc; bash

Quoted 1 line
Quoted 1 line

EIP; 00000000 Before first symbol

Proc; bash

Quoted 1 line
Quoted 1 line

EIP; 00000000 Before first symbol

Proc; bash

Quoted 1 line
Quoted 1 line

EIP; 00000000 Before first symbol

Proc; getty

Quoted 1 line
Quoted 1 line

EIP; 7fffffff Before first symbol <=====

Proc; getty

Quoted 1 line
Quoted 1 line

EIP; 7fffffff Before first symbol <=====

Proc; getty

Quoted 1 line
Quoted 1 line

EIP; 7fffffff Before first symbol <=====

Proc; gcache

Quoted 1 line
Quoted 1 line

EIP; 7fffffff Before first symbol <=====

Proc; apache-ssl

Quoted 1 line
Quoted 1 line

EIP; 7fffffff Before first symbol <=====

Proc; apache-ssl

Quoted 1 line
Quoted 1 line

EIP; 7fffffff Before first symbol <=====

Proc; apache-ssl

Quoted 1 line
Quoted 1 line

EIP; 7fffffff Before first symbol <=====

Proc; apache-ssl

Quoted 1 line
Quoted 1 line

EIP; 7fffffff Before first symbol <=====

Proc; apache-ssl

Quoted 1 line
Quoted 1 line

EIP; 7fffffff Before first symbol <=====

Proc; cp

Quoted 1 line
Quoted 1 line

EIP; 0000000c Before first symbol <=====

Proc; sync

Quoted 1 line
Quoted 1 line

EIP; ca0cbc44 <_end+9d59600/c593a1c> <=====

Proc; tail

Quoted 1 line
Quoted 1 line

EIP; c72a1f88 <_end+6f2f944/c593a1c> <=====

Proc; sshd

Quoted 1 line
Quoted 1 line

EIP; 7fffffff Before first symbol <=====

Proc; sshd

Quoted 1 line
Quoted 1 line

EIP; 7fffffff Before first symbol <=====

1 warning issued. Results may not be reliable.

#4 lore raw

Hello!

Quoted 4 lines · Tupshin Harper
Quoted 2 lines

Can you please execute SysRq-T, decode it with ksymoops and send us the result?

I have succeeded in reproducing this problem at will (and deterministically) with a SysRq enabled kernel.

Ok.

Quoted 7 lines

I can reproduce the problem consistently by doing a cp -av of a directory(haven't tried other cp permutations, guessing it wouldn't make a difference) containing three mp3 files from one location on a partition to another location on the same reiserfs partition. During the third file, cp hangs(continues to use 80%+ of the CPU), and is unkillable. A few minutes later, it starts generating the same JDIRTY error messages that I reported before.

Can you please produce a metadump data for us?
use debugreiserfs -p /dev/yourdevice | gzip -9c >metadata.gz
then tell us where we can get that file.
Also please use recent reiserfsprogs for that operation
(e.g. version 3.6.4 available from us: ftp://ftp.namesys.com/pub/reiserprogs )

Quoted 3 lines

I have not tried to do any kind of reiserfs check or repair on the filesystem, and I can trigger this problem at any point if you would like me to test further.

Let's see if we can reproduce it locally after you give us the metadata.

Thank you.

Bye,
Oleg