Re: futex_cmpxchg_enabled not set in futex_init on pentium3
From: Joseph Parmelee
Date: Mon Nov 30 2009 - 23:27:59 EST
On Mon, 30 Nov 2009, Thomas Gleixner wrote:
Can you please printk the return value of that cmpxchg() test and
provide a full bootlog (dmesg) of your machine ?
Thanks for the responses, which verify that my understanding of the expected
behavior was correct. That means that we have a real bug.
Attached is the complete output of dmesg from the most recent boot run.
This line due to added printk in futex_init:
[ 0.147626] futex_init curval = F0006AA0
These lines due to added printk's in
arch/x86/include/asm/futex.h:futex_atomic_cmpxchg_inatomic().
[ 0.147384] cmpxchg: ax before=cf80e000, ax after=f0006aa0
[ 0.147444] cmpxchg: bx before=0, bx after=0
[ 0.147536] cmpxchg: cx before=0, cx after=0
The compiler generates cmpxchg %ecx,(%ebx), so I added extended asm to
dump the registers involved just before and after the cmpxchg into variables
for printk.
All is consistent with the fact that the fault is not occurring and the
cmpxchg is working "as expected" at address 0. Examining /proc/kcore with
gdb shows that address c0000000 contains f0006aa0. Direct access with gdb
to address 0 fails as expected.
To completely eliminate any possibility that the fault was getting lost in
the fixup code somehow, I removed all the fixup code from the cmpxchg
extended asm, and the results are exactly the same. In fact this run is
with the fixup code removed. The fault is not occurring.
That'd be a serious bug as it would let every NULL pointer dereference
in the kernel proceed.
Interestingly, a printk inserted in futex_init that attempts a null
dereference results in an oops as expected.
Could you also please do a quick check in which kernel version this
got introduced ?
This was known to be working in 2.6.28.6. Unfortunately, I didn't find it
until I updated glibc and ran its test suite on 2.6.31.5. However, I have
been noticing some nasty log messages about page allocation failures in pppd
with plenty of available memory starting from sometime in the 2.6.31 series.
One of them is also attached FWIW. But these didn't seem to be causing any
problems other than making me nervous.
I am located in the mountains of Costa Rica with only a very slow dialup, so
git bisect is not an option for me. But I do have old copies of vmlinuz
lying around that go back to the 2.6.29 series. Unfortunately, I don't have
the matching unstripped vmlinux which would allow debugging, but I might be
able to test other ways. I will post again as soon as I have something.
In the meantime, if you can think of any tests that you want to run, I will
be most happy to help.
Best regards,
Joseph
[ 0.000000] Linux version 2.6.31.6 (config@bruno) (gcc version 4.4.2 (GCC) ) #16 PREEMPT Mon Nov 30 19:51:29 CST 2009
[ 0.000000] KERNEL supported cpus:
[ 0.000000] Intel GenuineIntel
[ 0.000000] AMD AuthenticAMD
[ 0.000000] NSC Geode by NSC
[ 0.000000] Cyrix CyrixInstead
[ 0.000000] Centaur CentaurHauls
[ 0.000000] Transmeta GenuineTMx86
[ 0.000000] Transmeta TransmetaCPU
[ 0.000000] UMC UMC UMC UMC
[ 0.000000] BIOS-provided physical RAM map:
[ 0.000000] BIOS-e820: 0000000000000000 - 000000000009fc00 (usable)
[ 0.000000] BIOS-e820: 000000000009fc00 - 00000000000a0000 (reserved)
[ 0.000000] BIOS-e820: 00000000000e0000 - 0000000000100000 (reserved)
[ 0.000000] BIOS-e820: 0000000000100000 - 0000000010000000 (usable)
[ 0.000000] BIOS-e820: 00000000fec00000 - 00000000fec01000 (reserved)
[ 0.000000] BIOS-e820: 00000000fee00000 - 00000000fee01000 (reserved)
[ 0.000000] BIOS-e820: 00000000fffc0000 - 0000000100000000 (reserved)
[ 0.000000] DMI 2.1 present.
[ 0.000000] AMI BIOS detected: BIOS may corrupt low RAM, working around it.
[ 0.000000] e820 update range: 0000000000000000 - 0000000000010000 (usable) ==> (reserved)
[ 0.000000] last_pfn = 0x10000 max_arch_pfn = 0x100000
[ 0.000000] MTRR default type: uncachable
[ 0.000000] MTRR fixed ranges enabled:
[ 0.000000] 00000-9FFFF write-back
[ 0.000000] A0000-FFFFF uncachable
[ 0.000000] MTRR variable ranges enabled:
[ 0.000000] 0 base 000000000 mask FF0000000 write-back
[ 0.000000] 1 disabled
[ 0.000000] 2 disabled
[ 0.000000] 3 disabled
[ 0.000000] 4 disabled
[ 0.000000] 5 disabled
[ 0.000000] 6 disabled
[ 0.000000] 7 disabled
[ 0.000000] PAT not supported by CPU.
[ 0.000000] initial memory mapped : 0 - 01800000
[ 0.000000] init_memory_mapping: 0000000000000000-0000000010000000
[ 0.000000] 0000000000 - 0000400000 page 4k
[ 0.000000] 0000400000 - 0010000000 page 2M
[ 0.000000] kernel direct mapping tables up to 10000000 @ 10000-14000
[ 0.000000] 256MB LOWMEM available.
[ 0.000000] mapped low ram: 0 - 10000000
[ 0.000000] low ram: 0 - 10000000
[ 0.000000] node 0 low ram: 00000000 - 10000000
[ 0.000000] node 0 bootmap 00010000 - 00012000
[ 0.000000] (5 early reservations) ==> bootmem [0000000000 - 0010000000]
[ 0.000000] #0 [0000000000 - 0000001000] BIOS data page ==> [0000000000 - 0000001000]
[ 0.000000] #1 [0001000000 - 00013511dc] TEXT DATA BSS ==> [0001000000 - 00013511dc]
[ 0.000000] #2 [000009fc00 - 0000100000] BIOS reserved ==> [000009fc00 - 0000100000]
[ 0.000000] #3 [0001352000 - 0001358150] BRK ==> [0001352000 - 0001358150]
[ 0.000000] #4 [0000010000 - 0000012000] BOOTMAP ==> [0000010000 - 0000012000]
[ 0.000000] Zone PFN ranges:
[ 0.000000] DMA 0x00000010 -> 0x00001000
[ 0.000000] Normal 0x00001000 -> 0x00010000
[ 0.000000] Movable zone start PFN for each node
[ 0.000000] early_node_map[2] active PFN ranges
[ 0.000000] 0: 0x00000010 -> 0x0000009f
[ 0.000000] 0: 0x00000100 -> 0x00010000
[ 0.000000] On node 0 totalpages: 65423
[ 0.000000] free_area_init_node: node 0, pgdat c12ecec0, node_mem_map c1359200
[ 0.000000] DMA zone: 32 pages used for memmap
[ 0.000000] DMA zone: 0 pages reserved
[ 0.000000] DMA zone: 3951 pages, LIFO batch:0
[ 0.000000] Normal zone: 480 pages used for memmap
[ 0.000000] Normal zone: 60960 pages, LIFO batch:15
[ 0.000000] Using APIC driver default
[ 0.000000] Found and enabled local APIC!
[ 0.000000] nr_irqs_gsi: 16
[ 0.000000] Allocating PCI resources starting at 10000000 (gap: 10000000:eec00000)
[ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. Total pages: 64911
[ 0.000000] Kernel command line: root=/dev/md0
[ 0.000000] PID hash table entries: 1024 (order: 10, 4096 bytes)
[ 0.000000] Dentry cache hash table entries: 32768 (order: 5, 131072 bytes)
[ 0.000000] Inode-cache hash table entries: 16384 (order: 4, 65536 bytes)
[ 0.000000] Enabling fast FPU save and restore... done.
[ 0.000000] Enabling unmasked SIMD FPU exception support... done.
[ 0.000000] Initializing CPU#0
[ 0.000000] Memory: 255804k/262144k available (2253k kernel code, 5676k reserved, 754k data, 216k init, 0k highmem)
[ 0.000000] virtual kernel memory layout:
[ 0.000000] fixmap : 0xfffa5000 - 0xfffff000 ( 360 kB)
[ 0.000000] vmalloc : 0xd0800000 - 0xfffa3000 ( 759 MB)
[ 0.000000] lowmem : 0xc0000000 - 0xd0000000 ( 256 MB)
[ 0.000000] .init : 0xc12f0000 - 0xc1326000 ( 216 kB)
[ 0.000000] .data : 0xc1233447 - 0xc12efcd4 ( 754 kB)
[ 0.000000] .text : 0xc1000000 - 0xc1233447 (2253 kB)
[ 0.000000] Checking if this processor honours the WP bit even in supervisor mode...Ok.
[ 0.000000] SLUB: Genslabs=13, HWalign=32, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
[ 0.000000] NR_IRQS:288
[ 0.000000] CPU 0 irqstacks, hard=c12c5000 soft=c12c6000
[ 0.000000] Fast TSC calibration using PIT
[ 0.000000] Detected 601.487 MHz processor.
[ 0.000999] Console: colour VGA+ 80x25
[ 0.000999] console [tty0] enabled
[ 0.000999] Calibrating delay loop (skipped), value calculated using timer frequency.. 1202.97 BogoMIPS (lpj=601487)
[ 0.000999] Security Framework initialized
[ 0.000999] Mount-cache hash table entries: 512
[ 0.000999] CPU: L1 I cache: 16K, L1 D cache: 16K
[ 0.000999] CPU: L2 cache: 512K
[ 0.000999] mce: CPU supports 5 MCE banks
[ 0.000999] Performance Counters: p6 PMU driver.
[ 0.001086] ... version: 0
[ 0.001146] ... bit width: 32
[ 0.001205] ... generic counters: 2
[ 0.001262] ... value mask: 00000000ffffffff
[ 0.001324] ... max period: 000000007fffffff
[ 0.001386] ... fixed-purpose counters: 0
[ 0.001443] ... counter mask: 0000000000000003
[ 0.001504] CPU: Intel Pentium III (Katmai) stepping 03
[ 0.001676] Checking 'hlt' instruction... OK.
[ 0.108348] NET: Registered protocol family 16
[ 0.126638] PCI: PCI BIOS revision 2.10 entry at 0xfdb71, last bus=1
[ 0.126711] PCI: Using configuration type 1 for base access
[ 0.134581] bio: create slab <bio-0> at 0
[ 0.135667] PCI: Probing PCI hardware
[ 0.135733] PCI: Probing PCI hardware (bus 00)
[ 0.135948] pci 0000:00:00.0: reg 10 32bit mmio: [0xf4000000-0xf7ffffff]
[ 0.136205] pci 0000:00:07.1: reg 20 io port: [0xffa0-0xffaf]
[ 0.136283] pci 0000:00:07.2: reg 20 io port: [0xef80-0xef9f]
[ 0.136375] pci 0000:00:07.3: quirk: region 0400-043f claimed by PIIX4 ACPI
[ 0.136450] pci 0000:00:07.3: quirk: region 0440-044f claimed by PIIX4 SMB
[ 0.136524] pci 0000:00:07.3: PIIX4 devres B PIO at 0290-0297
[ 0.136660] pci 0000:00:11.0: reg 10 io port: [0xe400-0xe4ff]
[ 0.136677] pci 0000:00:11.0: reg 14 32bit mmio: [0xfebeff00-0xfebeffff]
[ 0.136710] pci 0000:00:11.0: reg 30 32bit mmio: [0xfeb80000-0xfebbffff]
[ 0.136739] pci 0000:00:11.0: supports D1 D2
[ 0.136749] pci 0000:00:11.0: PME# supported from D1 D2 D3hot D3cold
[ 0.136820] pci 0000:00:11.0: PME# disabled
[ 0.136920] pci 0000:00:12.0: reg 10 io port: [0xefe0-0xefe7]
[ 0.136973] pci 0000:00:12.0: supports D2
[ 0.136989] pci 0000:00:12.0: PME# supported from D0 D2 D3hot
[ 0.137059] pci 0000:00:12.0: PME# disabled
[ 0.137162] pci 0000:00:13.0: reg 10 32bit mmio: [0xfc000000-0xfdffffff]
[ 0.137179] pci 0000:00:13.0: reg 14 32bit mmio: [0xf8000000-0xf9ffffff]
[ 0.137195] pci 0000:00:13.0: reg 18 io port: [0xe800-0xe8ff]
[ 0.137222] pci 0000:00:13.0: reg 30 32bit mmio: [0xfebf0000-0xfebfffff]
[ 0.137324] pci 0000:00:01.0: bridge io port: [0xd000-0xdfff]
[ 0.137337] pci 0000:00:01.0: bridge 32bit mmio: [0xfaa00000-0xfaafffff]
[ 0.137350] pci 0000:00:01.0: bridge 32bit mmio pref: [0xee800000-0xee8fffff]
[ 0.142290] pci 0000:00:01.0: PCI bridge, secondary bus 0000:01
[ 0.142364] pci 0000:00:01.0: IO window: 0xd000-0xdfff
[ 0.142431] pci 0000:00:01.0: MEM window: 0xfaa00000-0xfaafffff
[ 0.142501] pci 0000:00:01.0: PREFETCH window: 0xee800000-0xee8fffff
[ 0.142586] pci_bus 0000:00: resource 0 io: [0x00-0xffff]
[ 0.142597] pci_bus 0000:00: resource 1 mem: [0x000000-0xffffffff]
[ 0.142609] pci_bus 0000:01: resource 0 io: [0xd000-0xdfff]
[ 0.142619] pci_bus 0000:01: resource 1 mem: [0xfaa00000-0xfaafffff]
[ 0.142630] pci_bus 0000:01: resource 2 pref mem [0xee800000-0xee8fffff]
[ 0.142742] NET: Registered protocol family 2
[ 0.143199] IP route cache hash table entries: 2048 (order: 1, 8192 bytes)
[ 0.144265] TCP established hash table entries: 8192 (order: 4, 65536 bytes)
[ 0.144579] TCP bind hash table entries: 8192 (order: 3, 32768 bytes)
[ 0.144768] TCP: Hash tables configured (established 8192 bind 8192)
[ 0.144836] TCP reno registered
[ 0.145115] NET: Registered protocol family 1
[ 0.145936] platform rtc_cmos: registered platform RTC device (no PNP device found)
[ 0.147384] cmpxchg: ax before=cf80e000, ax after=f0006aa0
[ 0.147444] cmpxchg: bx before=0, bx after=0
[ 0.147536] cmpxchg: cx before=0, cx after=0
[ 0.147626] futex_init curval = F0006AA0
[ 0.161208] Installing knfsd (copyright (C) 1996 okir@xxxxxxxxxxxx).
[ 0.161699] msgmni has been set to 500
[ 0.162703] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 254)
[ 0.162788] io scheduler noop registered
[ 0.163181] io scheduler cfq registered (default)
[ 0.163263] pci 0000:00:00.0: Limiting direct PCI/PCI transfers
[ 0.163380] pci 0000:00:13.0: Boot video device
[ 0.176250] Serial: 8250/16550 driver, 4 ports, IRQ sharing disabled
[ 0.176592] serial8250: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A
[ 0.176944] serial8250: ttyS1 at I/O 0x2f8 (irq = 3) is a NS16550A
[ 0.178301] 0000:00:12.0: ttyS2 at I/O 0xefe0 (irq = 11) is a 16550A
[ 0.178870] Uniform Multi-Platform E-IDE driver
[ 0.179317] piix 0000:00:07.1: IDE controller (0x8086:0x7111 rev 0x01)
[ 0.179440] piix 0000:00:07.1: not 100% native mode: will probe irqs later
[ 0.179521] ide0: BM-DMA at 0xffa0-0xffa7
[ 0.179611] ide1: BM-DMA at 0xffa8-0xffaf
[ 0.179698] Probing IDE interface ide0...
[ 0.443095] hda: Maxtor 33073H3, ATA DISK drive
[ 0.901683] hdb: TOSHIBA CD-ROM XM-6002B, ATAPI CD/DVD-ROM drive
[ 0.902044] hda: host max PIO4 wanted PIO255(auto-tune) selected PIO4
[ 0.902238] hda: UDMA/33 mode selected
[ 0.902536] hdb: host max PIO4 wanted PIO255(auto-tune) selected PIO3
[ 0.902698] hdb: MWDMA1 mode selected
[ 0.902937] Probing IDE interface ide1...
[ 1.166806] hdc: SAMSUNG SP0411N, ATA DISK drive
[ 1.472456] hdd: Maxtor 33073H3, ATA DISK drive
[ 1.472724] hdc: host max PIO4 wanted PIO255(auto-tune) selected PIO4
[ 1.472807] hdc: UDMA/33 mode selected
[ 1.472944] hdd: host max PIO4 wanted PIO255(auto-tune) selected PIO4
[ 1.473155] hdd: UDMA/33 mode selected
[ 1.473502] ide0 at 0x1f0-0x1f7,0x3f6 on irq 14
[ 1.473674] ide1 at 0x170-0x177,0x376 on irq 15
[ 1.474720] ide-gd driver 1.18
[ 1.474838] hda: max request size: 128KiB
[ 1.485682] hda: 60032448 sectors (30736 MB) w/2048KiB Cache, CHS=59556/16/63
[ 1.485829] hda: cache flushes not supported
[ 1.485992] hda: hda1 hda2 hda3
[ 1.496798] hdc: max request size: 512KiB
[ 1.505539] hdc: Host Protected Area detected.
[ 1.505544] current capacity is 66055248 sectors (33820 MB)
[ 1.505549] native capacity is 78242976 sectors (40060 MB)
[ 1.505726] hdc: 66055248 sectors (33820 MB) w/2048KiB Cache, CHS=16383/255/63
[ 1.506097] hdc: cache flushes supported
[ 1.506241] hdc: hdc1 hdc2 hdc3 hdc4
[ 1.510881] hdc: p4 size 18209520 exceeds device capacity, enabling native capacity
[ 1.511228] hdc: detected capacity change from 33820286976 to 40060403712
[ 1.512342] hdd: max request size: 128KiB
[ 1.549632] hdd: 60032448 sectors (30736 MB) w/2048KiB Cache, CHS=59556/16/63
[ 1.549775] hdd: cache flushes not supported
[ 1.549938] hdd: hdd1 hdd2 hdd3
[ 1.553115] Linux Tulip driver version 1.1.15-NAPI (Feb 27, 2007)
[ 1.560312] eth0: Lite-On PNIC-II rev 37 at MMIO 0xfebeff00, 00:a0:cc:e4:48:db, IRQ 9.
[ 1.561165] PNP: No PS/2 controller found. Probing ports directly.
[ 1.564432] serio: i8042 KBD port at 0x60,0x64 irq 1
[ 1.564511] serio: i8042 AUX port at 0x60,0x64 irq 12
[ 1.565469] mice: PS/2 mouse device common for all mice
[ 1.565987] md: raid1 personality registered for level 1
[ 1.566053] EDAC MC: Ver: 2.1.0 Nov 23 2009
[ 1.567972] TCP cubic registered
[ 1.568652] RPC: Registered udp transport module.
[ 1.568719] RPC: Registered tcp transport module.
[ 1.568787] Using IPI Shortcut mode
[ 1.587909] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0
[ 1.880382] input: PS2++ Logitech Wheel Mouse as /devices/platform/i8042/serio1/input/input1
[ 1.883351] md: Waiting for all devices to be available before autodetect
[ 1.883424] md: If you don't use raid, use raid=noautodetect
[ 1.884012] md: Autodetecting RAID arrays.
[ 1.956710] md: Scanned 4 and added 4 devices.
[ 1.956773] md: autorun ...
[ 1.956829] md: considering hdc3 ...
[ 1.956917] md: adding hdc3 ...
[ 1.956986] md: hdc2 has different UUID to hdc3
[ 1.957073] md: adding hda3 ...
[ 1.957143] md: hda2 has different UUID to hdc3
[ 1.957206] md: created md0
[ 1.957275] md: bind<hda3>
[ 1.957365] md: bind<hdc3>
[ 1.957443] md: running: <hdc3><hda3>
[ 1.959186] raid1: raid set md0 active with 2 out of 2 mirrors
[ 1.959342] md0: detected capacity change from 0 to 30106910720
[ 1.959587] md: considering hdc2 ...
[ 1.959677] md: adding hdc2 ...
[ 1.959757] md: adding hda2 ...
[ 1.960286] md: created md1
[ 1.960348] md: bind<hda2>
[ 1.960431] md: bind<hdc2>
[ 1.960508] md: running: <hdc2><hda2>
[ 1.961036] raid1: raid set md1 active with 2 out of 2 mirrors
[ 1.961178] md1: detected capacity change from 0 to 524288000
[ 1.961466] md: ... autorun DONE.
[ 1.961722] md0: unknown partition table
[ 2.031811] EXT3-fs: mounted filesystem with writeback data mode.
[ 2.031910] VFS: Mounted root (ext3 filesystem) readonly on device 9:0.
[ 2.032058] Freeing unused kernel memory: 216k freed
[ 2.032978] kjournald starting. Commit interval 5 seconds
[ 4.858419] hda: UDMA/33 mode selected
[ 5.088136] ide-cd driver 5.00
[ 5.088973] ide-cd: hdb: ATAPI 16X CD-ROM drive, 256kB Cache
[ 5.089192] Uniform CD-ROM driver Revision: 3.20
[ 5.098165] hdb: MWDMA1 mode selected
[ 5.125313] hdc: UDMA/33 mode selected
[ 5.132320] hdd: UDMA/33 mode selected
[ 6.941398] md1: unknown partition table
[ 6.981885] Adding 511992k swap on /dev/md1. Priority:-1 extents:1 across:511992k
[ 7.305199] EXT3 FS on md0, internal journal
[ 10.469132] kjournald starting. Commit interval 5 seconds
[ 10.470849] EXT3 FS on hdc4, internal journal
[ 10.470995] EXT3-fs: mounted filesystem with writeback data mode.
[ 10.584828] kjournald starting. Commit interval 5 seconds
[ 10.585813] EXT3 FS on hdd3, internal journal
[ 10.585998] EXT3-fs: mounted filesystem with writeback data mode.
[ 11.056042] input: PC Speaker as /devices/platform/pcspkr/input/input2
[ 13.416095] piix4_smbus 0000:00:07.3: SMBus Host Controller at 0x440, revision 0
[ 13.516969] lm78: Found an LM79 chip at 0x290
[ 13.975665] lm75 0-004c: hwmon1: sensor 'lm75'
[ 14.767222] ip_tables: (C) 2000-2006 Netfilter Core Team
[ 16.388214] warning: `named' uses 32-bit capabilities (legacy support in use)
[ 400.600713] PPP generic driver version 2.4.2
[ 753.743988] PPP BSD Compression module registered
[ 753.799308] PPP Deflate Compression module registered
Nov 25 09:04:33 bruno kernel: [90226.678789] pppd: page allocation failure. order:4, mode:0x44d0
Nov 25 09:04:33 bruno kernel: [90226.678957] Pid: 4850, comm: pppd Not tainted 2.6.31.6 #3
Nov 25 09:04:33 bruno kernel: [90226.679027] Call Trace:
Nov 25 09:04:33 bruno kernel: [90226.679104] [<c12307e0>] ? printk+0x18/0x20
Nov 25 09:04:33 bruno kernel: [90226.679189] [<c105a38a>] __alloc_pages_nodemask+0x47a/0x510
Nov 25 09:04:33 bruno kernel: [90226.679267] [<c105a477>] __get_free_pages+0x17/0x40
Nov 25 09:04:33 bruno kernel: [90226.679349] [<c1078e7e>] __kmalloc+0xce/0xd0
Nov 25 09:04:33 bruno kernel: [90226.679426] [<d0d49467>] z_decomp_alloc+0x87/0xc0 [ppp_deflate]
Nov 25 09:04:33 bruno kernel: [90226.679541] [<d0cf5673>] ppp_ioctl+0xde3/0xf20 [ppp_generic]
Nov 25 09:04:33 bruno kernel: [90226.679634] [<c1092d06>] ? mntput_no_expire+0x16/0xb0
Nov 25 09:04:33 bruno kernel: [90226.679717] [<c1083e20>] ? path_put+0x20/0x30
Nov 25 09:04:33 bruno pppd[4850]: sent [CCP ConfReq id=0x1 <deflate 12> <deflate(old#) 12> <bsd v1 12>]
Nov 25 09:04:33 bruno kernel: [90226.679785] [<c1087212>] ? user_path_at+0x42/0x70
Nov 25 09:04:33 bruno pppd[4850]: sent [IPCP ConfReq id=0x1 <compress VJ 0f 01> <addr 0.0.0.0>]
Nov 25 09:04:33 bruno kernel: [90226.679861] [<d0cf4890>] ? ppp_ioctl+0x0/0xf20 [ppp_generic]
Nov 25 09:04:33 bruno pppd[4850]: rcvd [LCP ProtRej id=0xf4 80 fd 01 01 00 0f 1a 04 48 00 18 04 48 00 15 03 2c]
Nov 25 09:04:33 bruno kernel: [90226.679934] [<c1088c9c>] vfs_ioctl+0x1c/0x90
Nov 25 09:04:33 bruno pppd[4850]: Protocol-Reject for 'Compression Control Protocol' (0x80fd) received
Nov 25 09:04:33 bruno kernel: [90226.680001] [<c1088e5a>] do_vfs_ioctl+0x6a/0x5a0
Nov 25 09:04:33 bruno pppd[4850]: rcvd [IPCP ConfReq id=0x1 <addr 196.40.51.1>]
Nov 25 09:04:33 bruno kernel: [90226.680071] [<c107f775>] ? vfs_fstatat+0x35/0x70
Nov 25 09:04:33 bruno pppd[4850]: sent [IPCP ConfAck id=0x1 <addr 196.40.51.1>]
Nov 25 09:04:33 bruno kernel: [90226.680140] [<c107f8bb>] ? vfs_stat+0x1b/0x20
Nov 25 09:04:33 bruno pppd[4850]: rcvd [IPCP ConfRej id=0x1 <compress VJ 0f 01>]
Nov 25 09:04:33 bruno kernel: [90226.680207] [<c107f8d4>] ? sys_stat64+0x14/0x30
Nov 25 09:04:33 bruno pppd[4850]: sent [IPCP ConfReq id=0x2 <addr 0.0.0.0>]
Nov 25 09:04:33 bruno kernel: [90226.680289] [<c106a5aa>] ? remove_vma+0x3a/0x50
Nov 25 09:04:33 bruno kernel: [90226.680358] [<c106b44c>] ? do_munmap+0x21c/0x280
Nov 25 09:04:33 bruno kernel: [90226.680426] [<c10893ef>] sys_ioctl+0x5f/0x80
Nov 25 09:04:33 bruno kernel: [90226.680511] [<c1002cf4>] sysenter_do_call+0x12/0x26
Nov 25 09:04:33 bruno kernel: [90226.680580] Mem-Info:
Nov 25 09:04:33 bruno kernel: [90226.680634] DMA per-cpu:
Nov 25 09:04:33 bruno kernel: [90226.680692] CPU 0: hi: 0, btch: 1 usd: 0
Nov 25 09:04:33 bruno kernel: [90226.680754] Normal per-cpu:
Nov 25 09:04:33 bruno kernel: [90226.680810] CPU 0: hi: 90, btch: 15 usd: 0
Nov 25 09:04:33 bruno kernel: [90226.680881] Active_anon:11369 active_file:15998 inactive_anon:15690
Nov 25 09:04:33 bruno kernel: [90226.680887] inactive_file:15420 unevictable:0 dirty:16 writeback:36 unstable:0
Nov 25 09:04:33 bruno kernel: [90226.680893] free:1736 slab:2720 mapped:2872 pagetables:540 bounce:0
Nov 25 09:04:33 bruno kernel: [90226.681096] DMA free:1876kB min:120kB low:148kB high:180kB active_anon:2404kB inactive_anon:3108kB active_file:4216kB inactive_file:3476kB unevictable:0kB present:15804kB pages_scanned:0 all_unreclaimable? no
Nov 25 09:04:33 bruno kernel: [90226.681221] lowmem_reserve[]: 0 238 238
Nov 25 09:04:33 bruno kernel: [90226.681423] Normal free:5068kB min:1912kB low:2388kB high:2868kB active_anon:43072kB inactive_anon:59652kB active_file:59776kB inactive_file:58204kB unevictable:0kB present:243840kB pages_scanned:0 all_unreclaimable? no
Nov 25 09:04:33 bruno kernel: [90226.681564] lowmem_reserve[]: 0 0 0
Nov 25 09:04:33 bruno kernel: [90226.681760] DMA: 427*4kB 21*8kB 0*16kB 0*32kB 0*64kB 0*128kB 0*256kB 0*512kB 0*1024kB 0*2048kB 0*4096kB = 1876kB
Nov 25 09:04:33 bruno kernel: [90226.682264] Normal: 553*4kB 179*8kB 31*16kB 25*32kB 2*64kB 0*128kB 0*256kB 0*512kB 0*1024kB 0*2048kB 0*4096kB = 5068kB
Nov 25 09:04:33 bruno kernel: [90226.682785] 33186 total pagecache pages
Nov 25 09:04:33 bruno kernel: [90226.682843] 1768 pages in swap cache
Nov 25 09:04:33 bruno kernel: [90226.682903] Swap cache stats: add 16770, delete 15002, find 3161/3912
Nov 25 09:04:33 bruno kernel: [90226.682970] Free swap = 470080kB
Nov 25 09:04:33 bruno kernel: [90226.683026] Total swap = 511992kB
Nov 25 09:04:33 bruno kernel: [90226.690480] 65520 pages RAM
Nov 25 09:04:33 bruno kernel: [90226.690548] 1462 pages reserved
Nov 25 09:04:33 bruno kernel: [90226.690604] 28801 pages shared