Ok, very stable kernel bug found.

Egor Egorov (lkernel@fastware.kiev.ua)
Tue, 26 Nov 1996 15:50:39 -0300 (GMT+3)


Hi. Just found stable kernel bug (occurs in 100%). Requirement:

2.0.26, libc 5.4.7, installed on RedHat 4.0, RAR archiver 2.00 beta 3 (the
latest) for Linux. If we're archiving Perl 5.003 sources, and stdout from rar
is redirected to file or /dev/null, kernel says about gpf in 10-20 seconds.
This does not occurs if rar is not redirected.

Nov 26 14:47:10 fastware syslogd 1.3-3: restart.
Nov 26 14:47:10 fastware kernel: klogd 1.3-3, log source = /proc/kmsg started.
Nov 26 14:47:11 fastware kernel: Loaded 2603 symbols from /usr/src/linux/System.map.
Nov 26 14:47:11 fastware kernel: Symbols match kernel version 2.0.26.
Nov 26 14:47:11 fastware kernel: Loaded 9 symbols from 2 modules.
Nov 26 14:47:11 fastware kernel: Console: 16 point font, 400 scans
Nov 26 14:47:11 fastware kernel: Console: colour VGA+ 80x25, 1 virtual console (max 63)
Nov 26 14:47:11 fastware kernel: pcibios_init : BIOS32 Service Directory structure at 0x000f7800
Nov 26 14:47:11 fastware kernel: pcibios_init : BIOS32 Service Directory entry at 0xf7810
Nov 26 14:47:11 fastware kernel: pcibios_init : PCI BIOS revision 2.00 entry at 0xf7831
Nov 26 14:47:11 fastware kernel: Probing PCI hardware.
Nov 26 14:47:11 fastware kernel: Calibrating delay loop.. ok - 60.01 BogoMIPS
Nov 26 14:47:11 fastware kernel: Memory: 6952k/8192k available (524k kernel code, 384k reserved, 332k data)
Nov 26 14:47:11 fastware kernel: This processor honours the WP bit even when in supervisor mode. Good.
Nov 26 14:47:11 fastware kernel: Swansea University Computer Society NET3.035 for Linux 2.0
Nov 26 14:47:11 fastware kernel: NET3: Unix domain sockets 0.13 for Linux NET3.035.
Nov 26 14:47:11 fastware kernel: Swansea University Computer Society TCP/IP for NET3.034
Nov 26 14:47:11 fastware kernel: IP Protocols: ICMP, UDP, TCP
Nov 26 14:47:11 fastware kernel: Checking 386/387 coupling... Ok, fpu using exception 16 error reporting.
Nov 26 14:47:11 fastware kernel: Checking 'hlt' instruction... Ok.
Nov 26 14:47:11 fastware kernel: Linux version 2.0..26 (root@fastware) (gcc version 2.7.2) #8 Sat Nov 23 16:02:23 GMT+3 1996
Nov 26 14:47:11 fastware kernel: Serial driver version 4.13 with no serial options enabled
Nov 26 14:47:11 fastware kernel: tty00 at 0x03f8 (irq = 4) is a 16450
Nov 26 14:47:11 fastware kernel: tty01 at 0x02f8 (irq = 3) is a 16550A
Nov 26 14:47:11 fastware kernel: hda: WDC AC2700F, 696MB w/64kB Cache, LBA, CHS=1416/16/63
Nov 26 14:47:11 fastware kernel: hdb: OTI-SCYLLA, ATAPI CDROM drive
Nov 26 14:47:11 fastware kernel: ide0 at 0x1f0-0x1f7,0x3f6 on irq 14
Nov 26 14:47:11 fastware kernel: md driver 0.35 MAX_MD_DEV=4, MAX_REAL=8
Nov 26 14:47:11 fastware kernel: Partition check:
Nov 26 14:47:11 fastware kernel: hda:Started kswapd v 1.4.2.2
Nov 26 14:47:11 fastware kernel: [PTBL] [708/32/63] hda1 < hda5 hda6 hda7 > hda2 hda3 hda4
Nov 26 14:47:11 fastware kernel: VFS: Mounted root (ext2 filesystem) readonly.
Nov 26 14:47:11 fastware kernel: Adding Swap: 13068k swap-space
Nov 26 14:47:11 fastware kernel: CSLIP: code copyright 1989 Regents of the University of California
Nov 26 14:47:11 fastware kernel: PPP: version 2.2.0 (dynamic channel allocation)
Nov 26 14:47:11 fastware kernel: PPP Dynamic channel allocation code copyright 1995 Caldera, Inc.
Nov 26 14:47:11 fastware kernel: PPP line discipline registered.

[skipped]

Nov 26 15:27:38 fastware kernel: Unable to handle kernel paging request at virtual address c81a0330
Nov 26 15:27:38 fastware kernel: current->tss.cr3 = 00352000, dr3 = 00352000
Nov 26 15:27:38 fastware kernel: *pde = 00000000
Nov 26 15:27:38 fastware kernel: Oops: 0002
Nov 26 15:27:38 fastware kernel: CPU: 0
Nov 26 15:27:38 fastware kernel: EIP: 0010:[dcache_lookup+311/384]
Nov 26 15:27:38 fastware kernel: EFLAGS: 00010202
Nov 26 15:27:38 fastware kernel: eax: 001a080c ebx: 081a032c ecx: 00645f04 edx: 001a2274
Nov 26 15:27:38 fastware kernel: esi: 0019f172 edi: 0019f14c ebp: 001a24d0 esp: 00645ec4
Nov 26 15:27:38 fastware kernel: ds: 0018 es: 0018 fs: 002b gs: 002b ss: 0018
Nov 26 15:27:38 fastware kernel: Process rar (pid: 499, process nr: 6, stackpage=00645000)
Nov 26 15:27:38 fastware kernel: Stack: 003ecba0 00000001 0000000d 00641010 0019f14c 00000304 001550cd 003ecba0
Nov 26 15:27:38 fastware kernel: 00641010 0000000d 00645f04 003ecba0 00000001 00645f54 0000000d 003ecba0
Nov 26 15:27:38 fastware kernel: 0000d369 0012cc9e 003ecba0 00641010 0000000d 00645f54 0000000d 00000001
Nov 26 15:27:38 fastware kernel: Call Trace: [ext2_lookup+141/416] [lookup+238/272] [open_namei+577/1088] [do_open+93/304] [sys_open+63/128] [system_call+82/128]
Nov 26 15:27:38 fastware kernel: Code: 89 43 04 89 18 c7 02 00 00 00 00 8d 5a 08 8b 44 24 10 83 c0
Nov 26 15:27:38 fastware kernel: Unable to handle kernel NULL pointer dereference at virtual address c000000e
Nov 26 15:27:38 fastware kernel: current->tss.cr3 = 00101000, dr3 = 00101000
Nov 26 15:27:38 fastware kernel: *pde = 00102067
Nov 26 15:27:38 fastware kernel: *pte = 00000027
Nov 26 15:27:38 fastware kernel: Oops: 0000
Nov 26 15:27:38 fastware kernel: CPU: 0
Nov 26 15:27:38 fastware kernel: EIP: 0010:[close_fp+7/112]
Nov 26 15:27:38 fastware kernel: EFLAGS: 00010202
Nov 26 15:27:38 fastware kernel: eax: 00000000 ebx: 00000001 ecx: 001910dc edx: 007983e0
Nov 26 15:27:38 fastware kernel: esi: 00000000 edi: 00000001 ebp: 00683018 esp: 00645dfc
Nov 26 15:27:38 fastware kernel: ds: 0018 es: 0018 fs: 002b gs: 002b ss: 0018
Nov 26 15:27:38 fastware kernel: Process rar (pid: 499, process nr: 6, stackpage=00645000)
Nov 26 15:27:38 fastware kernel: Stack: 00000001 00000004 00000001 00116422 00000000 0009002b 00000014 00646000
Nov 26 15:27:38 fastware kernel: 00645e88 0010adf2 0000000b 00180741 00000000 c81a0330 00150002 001a24d0
Nov 26 15:27:38 fastware kernel: 000000c0 01800000 01000000 00180018 00110892 00181174 00645e88 00150002
Nov 26 15:27:38 fastware kernel: Call Trace: [do_exit+274/496] [die_if_kernel+706/736] [inet_ioctl+146/944] [<01800000>] [ppp:ppp_register_compressor+-72436/128] [vsprintf+888/1232] [do_page_fault+754/784]
Nov 26 15:27:38 fastware kernel: [inet_ioctl+146/944] [do_page_fault+0/784] [do_ide0_request+11/16] [error_code+61/80] [inet_ioctl+146/944] [dcache_lookup+311/384] [inet_ioctl+160/944] [ext2_lookup+141/416]
Nov 26 15:27:38 fastware kernel: [lookup+238/272] [open_namei+577/1088] [do_open+93/304] [sys_open+63/128] [system_call+82/128]
Nov 26 15:27:38 fastware kernel: Code: 66 83 7e 0e 00 75 22 68 92 30 18 00 e8 c8 02 ff ff 31 c0 83
Nov 26 15:28:01 fastware kernel: Unable to handle kernel paging request at virtual address c31a1504
Nov 26 15:28:01 fastware kernel: current->tss.cr3 = 006ff000, dr3 = 006ff000
Nov 26 15:28:01 fastware kernel: *pde = 00000000
Nov 26 15:28:01 fastware kernel: Oops: 0002
Nov 26 15:28:01 fastware kernel: CPU: 0
Nov 26 15:28:01 fastware kernel: EIP: 0010:[dcache_lookup+270/384]
Nov 26 15:28:01 fastware kernel: EFLAGS: 00010202
Nov 26 15:28:01 fastware kernel: eax: 001a220c ebx: 00000003 ecx: 005e9ed8 edx: 031a14d8
Nov 26 15:28:01 fastware kernel: esi: 001a0ec0 edi: 001a0ec0 ebp: 001a24b8 esp: 005e9e98
Nov 26 15:28:01 fastware kernel: ds: 0018 es: 0018 fs: 002b gs: 002b ss: 0018
Nov 26 15:28:01 fastware kernel: Process ifcico (pid: 512, process nr: 6, stackpage=005e9000)
Nov 26 15:28:01 fastware kernel: Stack: 0008ee88 00664005 00000003 00664001 001a0ec0 00060304 001550cd 0008ee88
Nov 26 15:28:01 fastware kernel: 00664001 00000003 005e9ed8 0008ee88 00664005 005e9f28 00000003 0008ee88
Nov 26 15:28:01 fastware kernel: 00009473 0012cc9e 0008ee88 00664001 00000003 005e9f28 00664001 00664005
Nov 26 15:28:01 fastware kernel: Call Trace: [ext2_lookup+141/416] [lookup+238/272] [dir_namei+149/320] [open_namei+82/1088] [do_open+93/304] [sys_open+63/128] [system_call+82/128]
Nov 26 15:28:01 fastware kernel: Code: 89 72 2c 89 70 30 eb 50 8d 76 00 8d b4 26 00 00 00 00 8b 15
Nov 26 15:28:01 fastware kernel: general protection: 0000
Nov 26 15:28:01 fastware kernel: CPU: 0
Nov 26 15:28:01 fastware kernel: EIP: 0010:[locks_remove_locks+12/64]
Nov 26 15:28:01 fastware kernel: EFLAGS: 00010282
Nov 26 15:28:01 fastware kernel: eax: f000f7c8 ebx: 00681810 ecx: 00000000 edx: 002e27c0
Nov 26 15:28:01 fastware kernel: esi: 00000000 edi: f000f7c8 ebp: 00513018 esp: 005e9dc0
Nov 26 15:28:01 fastware kernel: ds: 0018 es: 0018 fs: 002b gs: 002b ss: 0018
Nov 26 15:28:01 fastware kernel: Process ifcico (pid: 512, process nr: 6, stackpage=005e9000)
Nov 26 15:28:01 fastware kernel: Stack: 00000001 00122d33 00681810 00000000 00000001 00000005 00000001 00116422
Nov 26 15:28:01 fastware kernel: 00000000 0009002b 00000014 005ea000 005e9e5c 0010adf2 0000000b 00180741
Nov 26 15:28:01 fastware kernel: 00000000 c31a1504 00010002 001a24b8 00000015 01800000 01000000 00180018
Nov 26 15:28:01 fastware kernel: Call Trace: [close_fp+67/112] [do_exit+274/496] [die_if_kernel+706/736] [<01800000>] [ppp:ppp_register_compressor+-72436/128] [vsprintf+888/1232] [do_page_fault+754/784]
Nov 26 15:28:01 fastware kernel: [do_page_fault+0/784] [ip_build_xmit+1728/3040] [make_request+1139/1152] [error_code+61/80] [ide_dump_status+472/752] [dcache_lookup+270/384] [ext2_lookup+141/416] [lookup+238/272]
Nov 26 15:28:01 fastware kernel: [dir_namei+149/320] [open_namei+82/1088] [do_open+93/304] [sys_open+63/128] [system_call+82/128]
Nov 26 15:28:01 fastware kernel: Code: 8b 50 4c 85 d2 74 2a f6 42 1c 01 74 17 53 83 c0 4c 50 e8 1d
Nov 26 15:28:02 fastware ifcico[511]: caught SIGSEGV at offset 0x400745e5
Nov 26 15:28:02 fastware ifcico[511]: called from 0x080024b8
Nov 26 15:28:02 fastware ifcico[511]: called from 0x08002054
Nov 26 15:28:02 fastware ifcico[511]: called from 0xbffffe50
Nov 26 15:28:02 fastware ifcico[511]: called from 0x20400062
Nov 26 15:28:11 fastware kernel: Unable to handle kernel paging request at virtual address c31a1504
Nov 26 15:28:11 fastware kernel: current->tss.cr3 = 00785000, dr3 = 00785000
Nov 26 15:28:11 fastware kernel: *pde = 00000000
Nov 26 15:28:11 fastware kernel: Oops: 0002
Nov 26 15:28:11 fastware kernel: CPU: 0
Nov 26 15:28:11 fastware kernel: EIP: 0010:[dcache_lookup+253/384]
Nov 26 15:28:11 fastware kernel: EFLAGS: 00010202
Nov 26 15:28:11 fastware kernel: eax: 031a14d8 ebx: 00000003 ecx: 006f4efc edx: 001a220c
Nov 26 15:28:11 fastware kernel: esi: 001a0ec0 edi: 001a0ec0 ebp: 001a24b8 esp: 006f4ebc
Nov 26 15:28:11 fastware kernel: ds: 0018 es: 0018 fs: 002b gs: 002b ss: 0018
Nov 26 15:28:11 fastware kernel: Process bash (pid: 264, process nr: 4, stackpage=006f4000)
Nov 26 15:28:11 fastware kernel: Stack: 0008ee88 00034005 00000003 00034001 001a0ec0 00000304 001550cd 0008ee88
Nov 26 15:28:11 fastware kernel: 00034001 00000003 006f4efc 0008ee88 00034005 006f4f4c 00000003 0008ee88
Nov 26 15:28:11 fastware kernel: 00009473 0012cc9e 0008ee88 00034001 00000003 006f4f4c 00034001 00034005
Nov 26 15:28:11 fastware kernel: Call Trace: [ext2_lookup+141/416] [lookup+238/272] [dir_namei+149/320] [_namei+46/208] [namei+48/80] [sys_newstat+41/96] [system_call+82/128]
Nov 26 15:28:11 fastware kernel: Code: 89 50 2c 8b 46 28 8b 00 8b 50 30 89 46 2c 89 56 30 89 72 2c
Nov 26 15:28:11 fastware kernel: Unable to handle kernel paging request at virtual address c31a1504
Nov 26 15:28:11 fastware kernel: current->tss.cr3 = 00086000, dr3 = 00086000
Nov 26 15:28:11 fastware kernel: *pde = 00000000
Nov 26 15:28:11 fastware kernel: Oops: 0002
Nov 26 15:28:11 fastware kernel: CPU: 0
Nov 26 15:28:11 fastware kernel: EIP: 0010:[dcache_lookup+253/384]
Nov 26 15:28:11 fastware kernel: EFLAGS: 00010202
Nov 26 15:28:11 fastware kernel: eax: 031a14d8 ebx: 00000003 ecx: 00006ed8 edx: 001a220c
Nov 26 15:28:11 fastware kernel: esi: 001a0ec0 edi: 001a0ec0 ebp: 001a24b8 esp: 00006e98
Nov 26 15:28:11 fastware kernel: ds: 0018 es: 0018 fs: 002b gs: 002b ss: 0018
Nov 26 15:28:11 fastware kernel: Process init (pid: 1, process nr: 1, stackpage=00006000)
Nov 26 15:28:11 fastware kernel: Stack: 0008ee88 006f4005 00000003 006f4001 001a0ec0 00000304 001550cd 0008ee88
Nov 26 15:28:11 fastware kernel: 006f4001 00000003 00006ed8 0008ee88 006f4005 00006f28 00000003 0008ee88
Nov 26 15:28:11 fastware kernel: 00009473 0012cc9e 0008ee88 006f4001 00000003 00006f28 006f4001 006f4005
Nov 26 15:28:11 fastware kernel: Call Trace: [ext2_lookup+141/416] [lookup+238/272] [dir_namei+149/320] [open_namei+82/1088] [do_open+93/304] [sys_open+63/128] [system_call+82/128]
Nov 26 15:28:11 fastware kernel: Code: 89 50 2c 8b 46 28 8b 00 8b 50 30 89 46 2c 89 56 30 89 72 2c
Nov 26 15:28:11 fastware kernel: general protection: 0000
Nov 26 15:28:11 fastware kernel: CPU: 0
Nov 26 15:28:11 fastware kernel: EIP: 0010:[locks_remove_locks+12/64]
Nov 26 15:28:11 fastware kernel: EFLAGS: 00010282
Nov 26 15:28:11 fastware kernel: eax: f000f7c8 ebx: 00007018 ecx: 00000000 edx: 001dc000
Nov 26 15:28:11 fastware kernel: esi: 00000000 edi: f000f7c8 ebp: 00005018 esp: 00006dc0
Nov 26 15:28:11 fastware kernel: ds: 0018 es: 0018 fs: 002b gs: 002b ss: 0018
Nov 26 15:28:11 fastware kernel: Process init (pid: 1, process nr: 1, stackpage=00006000)
Nov 26 15:28:11 fastware kernel: Stack: 00000401 00122d33 00007018 00000000 00000401 00000000 00000001 00116422
Nov 26 15:28:11 fastware kernel: 00000000 0009002b 00000014 00007000 00006e5c 0010adf2 0000000b 00180741
Nov 26 15:28:11 fastware kernel: 00000000 c31a1504 00000002 001a24b8 0000002c 01800000 01000000 00180018
Nov 26 15:28:11 fastware kernel: Call Trace: [close_fp+67/112] [do_exit+274/496] [die_if_kernel+706/736] [<01800000>] [ppp:ppp_register_compressor+-72436/128] [vsprintf+888/1232] [do_page_fault+754/784]
Nov 26 15:28:11 fastware kernel: [do_page_fault+0/784] [error_code+61/80] [dcache_lookup+253/384] [ext2_lookup+141/416] [lookup+238/272] [dir_namei+149/320] [open_namei+82/1088] [do_open+93/304]
Nov 26 15:28:11 fastware kernel: [sys_open+63/128] [system_call+82/128]
Nov 26 15:28:11 fastware kernel: Code: 8b 50 4c 85 d2 74 2a f6 42 1c 01 74 17 53 83 c0 4c 50 e8 1d

Just after kernel crash, every starting process causes gpf. This was the
first occurence, and the next is:

Nov 26 15:30:22 fastware syslogd 1.3-3: restart.

[skip]

Nov 26 15:30:23 fastware kernel: PPP line discipline registered.

Ups, died even without announcement.

Nov 26 15:34:25 fastware syslogd 1.3-3: restart.
Nov 26 15:34:26 fastware kernel: klogd 1.3-3, log source = /proc/kmsg started.

[skip]

Nov 26 15:34:26 fastware kernel: PPP line discipline registered.

[skipped]

Nov 26 15:35:07 fastware ifcico[235]: 0 of 7 calls, maxrc=8

Again.

Nov 26 15:37:44 fastware syslogd 1.3-3: restart.

System is 486 DX4-120 (works perfectly), 8 mb ram (buggy simm, by the way,
but works. This is not the gpf source), umc chipset, no scsi, no ethernet,
etc. A trivial configuration.

PS: Can anoybody say me, why my LILO stopped to work? Just "LI" when
booting, and nothing more. Nothing helps - even "fdisk /mbr" from dos and
then lilo from The Perfect Operating System does not helps. :( But it
worked!