Your message dated Sun, 01 Mar 2015 02:54:52 +0000 with message-id <1425178492.28544.106.camel@decadent.org.uk> and subject line Re: Bug#779469: linux-image-3.2.0-4-amd64: Call trace with 3.2.0-4-amd64 3.2.65-1+deb7u2 has caused the Debian Bug report #779469, regarding linux-image-3.2.0-4-amd64: Call trace with 3.2.0-4-amd64 3.2.65-1+deb7u2 to be marked as done. This means that you claim that the problem has been dealt with. If this is not the case it is now your responsibility to reopen the Bug report if necessary, and/or fix the problem forthwith. (NB: If you are a system administrator and have no idea what this message is talking about, this may indicate a serious mail system misconfiguration somewhere. Please contact owner@bugs.debian.org immediately.) -- 779469: http://bugs.debian.org/cgi-bin/bugreport.cgi?bug=779469 Debian Bug Tracking System Contact owner@bugs.debian.org with problems
--- Begin Message ---
- To: Debian Bug Tracking System <submit@bugs.debian.org>
- Subject: linux-image-3.2.0-4-amd64: Call trace with 3.2.0-4-amd64 3.2.65-1+deb7u2
- From: Daniel Fraga <fragabr@gmail.com>
- Date: Sat, 28 Feb 2015 23:16:46 -0300
- Message-id: <[🔎] 20150301021646.18580.97626.reportbug@tux.exchangewar.info>
Package: src:linux Version: 3.2.65-1+deb7u2 Severity: normal After I upgraded to the latest kernel (3.2.0-4-amd64 3.2.65-1+deb7u) I'm seeing my server (KVM VPS) turn unresponsive. The log shows the following: Feb 28 22:28:38 tux kernel: [114568.642541] sqlite3 invoked oom-killer: gfp_mask=0x200da, order=0, oom_adj=0, oom_score_adj=0 Feb 28 22:28:38 tux kernel: [114568.642554] sqlite3 cpuset=/ mems_allowed=0 Feb 28 22:28:38 tux kernel: [114568.642567] Pid: 7688, comm: sqlite3 Not tainted 3.2.0-4-amd64 #1 Debian 3.2.65-1+deb7u2 Feb 28 22:28:38 tux kernel: [114568.642571] Call Trace: Feb 28 22:28:39 tux kernel: [114568.642609] [<ffffffff810b789a>] ? dump_header+0x78/0x1bd Feb 28 22:28:40 tux kernel: [114568.642624] [<ffffffff811655c6>] ? security_real_capable_noaudit+0x40/0x4f Feb 28 22:28:40 tux kernel: [114568.642629] [<ffffffff810b76a4>] ? oom_unkillable_task+0x20/0x91 Feb 28 22:28:40 tux kernel: [114568.642647] [<ffffffff81350f2f>] ? _raw_spin_unlock_irqrestore+0xe/0xf Feb 28 22:28:40 tux kernel: [114568.642650] [<ffffffff810b7c6f>] ? oom_kill_process+0x49/0x271 Feb 28 22:28:40 tux kernel: [114568.642653] [<ffffffff810b836a>] ? out_of_memory+0x2ea/0x337 Feb 28 22:28:40 tux kernel: [114568.642656] [<ffffffff810bc002>] ? __alloc_pages_nodemask+0x62a/0x7ab Feb 28 22:28:40 tux kernel: [114568.642671] [<ffffffff810e6f09>] ? alloc_pages_vma+0x12d/0x136 Feb 28 22:28:40 tux kernel: [114568.642675] [<ffffffff810e0fe1>] ? map_swap_page+0x9/0xa Feb 28 22:28:40 tux kernel: [114568.642678] [<ffffffff810ded53>] ? read_swap_cache_async+0x67/0x142 Feb 28 22:28:40 tux kernel: [114568.642681] [<ffffffff810dee85>] ? swapin_readahead+0x57/0x9a Feb 28 22:28:40 tux kernel: [114568.642683] [<ffffffff81350ed5>] ? _raw_spin_trylock+0x5/0x13 Feb 28 22:28:40 tux kernel: [114568.642689] [<ffffffff810d1b66>] ? handle_pte_fault+0x347/0x79f Feb 28 22:28:40 tux kernel: [114568.642692] [<ffffffff810cf04d>] ? pte_offset_kernel+0x16/0x35 Feb 28 22:28:40 tux kernel: [114568.642695] [<ffffffff81353f34>] ? do_page_fault+0x320/0x345 Feb 28 22:28:40 tux kernel: [114568.642705] [<ffffffff810380ed>] ? set_next_entity+0x32/0x55 Feb 28 22:28:40 tux kernel: [114568.642713] [<ffffffff8100d025>] ? paravirt_write_msr+0xb/0xe Feb 28 22:28:40 tux kernel: [114568.642716] [<ffffffff8100d6f9>] ? __switch_to+0x18e/0x265 Feb 28 22:28:40 tux kernel: [114568.642719] [<ffffffff81039aa2>] ? finish_task_switch+0x4e/0xb9 Feb 28 22:28:40 tux kernel: [114568.642721] [<ffffffff8134fb09>] ? __schedule+0x5f9/0x610 Feb 28 22:28:40 tux kernel: [114568.642724] [<ffffffff813514d5>] ? page_fault+0x25/0x30 Feb 28 22:28:40 tux kernel: [114568.642726] Mem-Info: Feb 28 22:28:40 tux kernel: [114568.642730] Node 0 DMA per-cpu: Feb 28 22:28:40 tux kernel: [114568.642735] CPU 0: hi: 0, btch: 1 usd: 0 Feb 28 22:28:40 tux kernel: [114568.642737] CPU 1: hi: 0, btch: 1 usd: 0 Feb 28 22:28:40 tux kernel: [114568.642738] Node 0 DMA32 per-cpu: Feb 28 22:28:40 tux kernel: [114568.642740] CPU 0: hi: 186, btch: 31 usd: 0 Feb 28 22:28:40 tux kernel: [114568.642741] CPU 1: hi: 186, btch: 31 usd: 14 Feb 28 22:28:40 tux kernel: [114568.642745] active_anon:44499 inactive_anon:44605 isolated_anon:32 Feb 28 22:28:40 tux kernel: [114568.642749] active_file:19 inactive_file:63 isolated_file:32 Feb 28 22:28:40 tux kernel: [114568.642750] unevictable:0 dirty:0 writeback:92 unstable:0 Feb 28 22:28:40 tux kernel: [114568.642751] free:1219 slab_reclaimable:2129 slab_unreclaimable:8735 Feb 28 22:28:40 tux kernel: [114568.642752] mapped:34 shmem:15 pagetables:14272 bounce:0 Feb 28 22:28:40 tux kernel: [114568.642754] Node 0 DMA free:2048kB min:84kB low:104kB high:124kB active_anon:100kB inactive_anon:296kB active_file:0kB inactive_file:12kB unevictable:0kB isola ted(anon):0kB isolated(file):0kB present:15688kB mlocked:0kB dirty:0kB writeback:16kB mapped:20kB shmem:0kB slab_reclaimable:8kB slab_unreclaimable:3652kB kernel_stack:7432kB pagetables:1320k B unstable:0kB bounce:0kB writeback_tmp:0kB pages_scanned:0 all_unreclaimable? yes Feb 28 22:28:40 tux kernel: [114568.642765] lowmem_reserve[]: 0 489 489 489 Feb 28 22:28:40 tux kernel: [114568.642772] Node 0 DMA32 free:2828kB min:2784kB low:3480kB high:4176kB active_anon:177896kB inactive_anon:178124kB active_file:80kB inactive_file:240kB unevict able:0kB isolated(anon):128kB isolated(file):128kB present:500896kB mlocked:0kB dirty:0kB writeback:352kB mapped:116kB shmem:60kB slab_reclaimable:8508kB slab_unreclaimable:31288kB kernel_sta ck:2848kB pagetables:55768kB unstable:0kB bounce:0kB writeback_tmp:0kB pages_scanned:74 all_unreclaimable? no Feb 28 22:28:40 tux kernel: [114568.642780] lowmem_reserve[]: 0 0 0 0 Feb 28 22:28:40 tux kernel: [114568.642783] Node 0 DMA: 190*4kB 62*8kB 18*16kB 2*32kB 1*64kB 1*128kB 1*256kB 0*512kB 0*1024kB 0*2048kB 0*4096kB = 2056kB Feb 28 22:28:40 tux kernel: [114568.642798] Node 0 DMA32: 335*4kB 184*8kB 1*16kB 0*32kB 0*64kB 0*128kB 0*256kB 0*512kB 0*1024kB 0*2048kB 0*4096kB = 2828kB Feb 28 22:28:40 tux kernel: [114568.642805] 11647 total pagecache pages Feb 28 22:28:40 tux kernel: [114568.642806] 11507 pages in swap cache Feb 28 22:28:40 tux kernel: [114568.642808] Swap cache stats: add 5571578, delete 5560071, find 873084/1520211 Feb 28 22:28:40 tux kernel: [114568.642809] Free swap = 0kB Feb 28 22:28:40 tux kernel: [114568.642810] Total swap = 1045500kB Feb 28 22:28:40 tux kernel: [114568.645927] 131040 pages RAM Feb 28 22:28:40 tux kernel: [114568.645930] 4225 pages reserved Feb 28 22:28:40 tux kernel: [114568.645931] 2063 pages shared Feb 28 22:28:40 tux kernel: [114568.645932] 122333 pages non-shared Feb 28 22:28:40 tux kernel: [114568.645934] [ pid ] uid tgid total_vm rss cpu oom_adj oom_score_adj name Feb 28 22:28:40 tux kernel: [114568.645945] [ 299] 0 299 5330 1 0 -17 -1000 udevd Feb 28 22:28:40 tux kernel: [114568.645949] [ 400] 0 400 5329 0 1 -17 -1000 udevd Feb 28 22:28:40 tux kernel: [114568.645952] [ 401] 0 401 5329 0 0 -17 -1000 udevd Feb 28 22:28:40 tux kernel: [114568.645956] [ 1566] 0 1566 2490 0 1 -17 -1000 dhclient Feb 28 22:28:40 tux kernel: [114568.645960] [ 1808] 0 1808 29848 178 1 0 0 rsyslogd Feb 28 22:28:40 tux kernel: [114568.645963] [ 1849] 0 1849 1029 0 1 0 0 acpid Feb 28 22:28:40 tux kernel: [114568.645966] [ 1919] 0 1919 4168 0 0 0 0 atd Feb 28 22:28:40 tux kernel: [114568.645969] [ 1969] 0 1969 5102 15 1 0 0 cron Feb 28 22:28:40 tux kernel: [114568.645972] [ 1985] 104 1985 9769 19 0 0 0 ntpd Feb 28 22:28:40 tux kernel: [114568.645975] [ 2082] 0 2082 12483 0 1 -17 -1000 sshd Feb 28 22:28:40 tux kernel: [114568.645979] [ 2129] 0 2129 4064 1 0 0 0 getty Feb 28 22:28:40 tux kernel: [114568.645982] [ 2130] 0 2130 4064 1 1 0 0 getty Feb 28 22:28:40 tux kernel: [114568.645985] [ 2131] 0 2131 4064 1 1 0 0 getty Feb 28 22:28:40 tux kernel: [114568.645988] [ 2132] 0 2132 4064 1 1 0 0 getty Feb 28 22:28:40 tux kernel: [114568.645991] [ 2133] 0 2133 4064 1 0 0 0 getty Feb 28 22:28:40 tux kernel: [114568.645994] [ 2134] 0 2134 4064 1 1 0 0 getty Feb 28 22:28:40 tux kernel: [114568.645998] [24202] 0 24202 19540 15 0 0 0 apache2 Feb 28 22:28:40 tux kernel: [114568.646001] [ 6778] 33 6778 19546 20 1 0 0 apache2 Feb 28 22:28:40 tux kernel: [114568.646005] [15149] 33 15149 68357 44 1 0 0 apache2 Feb 28 22:28:40 tux kernel: [114568.646009] [16368] 33 16368 75180 85 1 0 0 apache2 Feb 28 22:28:40 tux kernel: [114568.646012] [16541] 33 16541 47444 66 1 0 0 apache2 Feb 28 22:28:40 tux kernel: [114568.646015] [17094] 1000 17094 2688 1 0 0 0 cron-exchangewa Feb 28 22:28:40 tux kernel: [114568.646018] [17096] 1000 17096 2694 1 1 0 0 grabdata.sh Feb 28 22:28:40 tux kernel: [114568.646027] [17381] 1000 17381 10873 174 0 0 0 perl Feb 28 22:28:40 tux kernel: [114568.646031] [25611] 1000 25611 1045 0 1 0 0 sh Feb 28 22:28:40 tux kernel: [114568.646034] [25612] 1000 25612 1045 0 0 0 0 sh Feb 28 22:28:40 tux kernel: [114568.646037] [25615] 1000 25615 2737 43 1 0 0 bleutrade.sh Feb 28 22:28:40 tux kernel: [114568.646041] [20892] 33 20892 1394 1 1 0 0 flock Feb 28 22:28:40 tux kernel: [114568.646049] [20902] 33 20902 1045 0 1 0 0 sh Feb 28 22:28:40 tux kernel: [114568.646052] [20916] 33 20916 4831 0 1 0 0 coinstart.sh Feb 28 22:28:40 tux kernel: [114568.646055] [20982] 33 20982 4833 0 1 0 0 coinstart.sh Feb 28 22:28:40 tux kernel: [114568.646059] [21005] 33 21005 1394 1 1 0 0 flock Feb 28 22:28:40 tux kernel: [114568.646062] [21033] 33 21033 1045 0 1 0 0 sh Feb 28 22:28:40 tux kernel: [114568.646065] [21067] 33 21067 4831 0 1 0 0 coinstart.sh Feb 28 22:28:40 tux kernel: [114568.646068] [21132] 33 21132 4833 0 0 0 0 coinstart.sh Feb 28 22:28:40 tux kernel: [114568.646072] [21527] 33 21527 1394 1 1 0 0 flock Feb 28 22:28:40 tux kernel: [114568.646075] [21539] 33 21539 1045 0 1 0 0 sh Feb 28 22:28:40 tux kernel: [114568.646079] [21559] 33 21559 4831 0 1 0 0 coinstart.sh Feb 28 22:28:40 tux kernel: [114568.646082] [21620] 33 21620 4834 39 1 0 0 coinstart.sh Feb 28 22:28:40 tux kernel: [114568.646085] [21794] 33 21794 1394 1 0 0 0 flock Feb 28 22:28:40 tux kernel: [114568.646092] [21832] 33 21832 1045 0 0 0 0 sh Feb 28 22:28:40 tux kernel: [114568.646095] [21863] 33 21863 4831 0 0 0 0 coinstart.sh Feb 28 22:28:40 tux kernel: [114568.646098] [21900] 33 21900 1394 1 0 0 0 flock Feb 28 22:28:40 tux kernel: [114568.646101] [21913] 33 21913 1045 0 0 0 0 sh Feb 28 22:28:40 tux kernel: [114568.646104] [21933] 33 21933 4831 0 0 0 0 coinstart.sh Feb 28 22:28:40 tux kernel: [114568.646108] [21936] 33 21936 4835 0 0 0 0 coinstart.sh Feb 28 22:28:40 tux kernel: [114568.646185] [22024] 33 22024 1394 1 0 0 0 flock Feb 28 22:28:40 tux kernel: [114568.646188] [22033] 33 22033 1045 0 0 0 0 sh And the list goes on... It seems it run out of memory? But why does it not happened before? This issue started just after upgrading the kernel... -- Package-specific info: ** Version: Linux version 3.2.0-4-amd64 (debian-kernel@lists.debian.org) (gcc version 4.6.3 (Debian 4.6.3-14) ) #1 SMP Debian 3.2.65-1+deb7u2 ** Command line: BOOT_IMAGE=/boot/vmlinuz-3.2.0-4-amd64 root=UUID=ea2d96d4-2b00-4c0f-9eaa-d65e45ba088e ro quiet ** Not tainted ** Kernel log: [ 0.850019] uhci_hcd 0000:00:01.2: UHCI Host Controller [ 0.850074] uhci_hcd 0000:00:01.2: new USB bus registered, assigned bus number 1 [ 0.850183] uhci_hcd 0000:00:01.2: irq 11, io base 0x0000c020 [ 0.850280] usb usb1: New USB device found, idVendor=1d6b, idProduct=0001 [ 0.850282] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 0.850284] usb usb1: Product: UHCI Host Controller [ 0.850285] usb usb1: Manufacturer: Linux 3.2.0-4-amd64 uhci_hcd [ 0.850287] usb usb1: SerialNumber: 0000:00:01.2 [ 0.850438] hub 1-0:1.0: USB hub found [ 0.850443] hub 1-0:1.0: 2 ports detected [ 0.850623] ata_piix 0000:00:01.1: version 2.13 [ 0.850966] ata_piix 0000:00:01.1: setting latency timer to 64 [ 0.853211] scsi0 : ata_piix [ 0.853350] scsi1 : ata_piix [ 0.853393] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc000 irq 14 [ 0.853396] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc008 irq 15 [ 1.010092] ata2.01: NODEV after polling detection [ 1.010520] ata1.01: NODEV after polling detection [ 1.010773] ata1.00: ATA-7: QEMU HARDDISK, 0.9.1, max UDMA/100 [ 1.010775] ata1.00: 52428800 sectors, multi 16: LBA48 [ 1.010992] ata2.00: ATAPI: QEMU DVD-ROM, 0.9.1, max UDMA/100 [ 1.011414] ata1.00: configured for MWDMA2 [ 1.011588] scsi 0:0:0:0: Direct-Access ATA QEMU HARDDISK 0.9. PQ: 0 ANSI: 5 [ 1.011976] ata2.00: configured for MWDMA2 [ 1.012574] scsi 1:0:0:0: CD-ROM QEMU QEMU DVD-ROM 0.9. PQ: 0 ANSI: 5 [ 1.023129] sd 0:0:0:0: [sda] 52428800 512-byte logical blocks: (26.8 GB/25.0 GiB) [ 1.023172] sd 0:0:0:0: [sda] Write Protect is off [ 1.023175] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00 [ 1.023191] sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA [ 1.024521] sda: sda1 sda2 < sda5 > [ 1.025335] sd 0:0:0:0: [sda] Attached SCSI disk [ 1.025403] sr0: scsi3-mmc drive: 4x/4x xa/form2 tray [ 1.025407] cdrom: Uniform CD-ROM driver Revision: 3.20 [ 1.025633] sr 1:0:0:0: Attached scsi CD-ROM sr0 [ 1.032046] sd 0:0:0:0: Attached scsi generic sg0 type 0 [ 1.032116] sr 1:0:0:0: Attached scsi generic sg1 type 5 [ 1.160958] usb 1-2: new full-speed USB device number 2 using uhci_hcd [ 1.328370] Refined TSC clocksource calibration: 2992.401 MHz. [ 1.479161] PM: Starting manual resume from disk [ 1.479165] PM: Hibernation image partition 8:5 present [ 1.479166] PM: Looking for hibernation image. [ 1.479439] PM: Image not found (code -22) [ 1.479441] PM: Hibernation image not present or could not be loaded. [ 1.499037] EXT4-fs (sda1): INFO: recovery required on readonly filesystem [ 1.499041] EXT4-fs (sda1): write access will be enabled during recovery [ 1.499085] usb 1-2: New USB device found, idVendor=0627, idProduct=0001 [ 1.499089] usb 1-2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 1.499091] usb 1-2: Product: QEMU USB Tablet [ 1.499093] usb 1-2: Manufacturer: QEMU 0.9.1 [ 1.499094] usb 1-2: SerialNumber: 1 [ 1.579774] input: QEMU 0.9.1 QEMU USB Tablet as /devices/pci0000:00/0000:00:01.2/usb1/1-2/1-2:1.0/input/input1 [ 1.580031] generic-usb 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Pointer [QEMU 0.9.1 QEMU USB Tablet] on usb-0000:00:01.2-2/input0 [ 1.580330] usbcore: registered new interface driver usbhid [ 1.580332] usbhid: USB HID core driver [ 2.603151] EXT4-fs (sda1): orphan cleanup on readonly fs [ 2.603169] EXT4-fs (sda1): ext4_orphan_cleanup: deleting unreferenced inode 663488 [ 2.603223] EXT4-fs (sda1): ext4_orphan_cleanup: deleting unreferenced inode 663386 [ 2.603232] EXT4-fs (sda1): ext4_orphan_cleanup: deleting unreferenced inode 661569 [ 2.607272] EXT4-fs (sda1): ext4_orphan_cleanup: deleting unreferenced inode 657421 [ 2.607327] EXT4-fs (sda1): 4 orphan inodes deleted [ 2.607329] EXT4-fs (sda1): recovery complete [ 2.612998] EXT4-fs (sda1): mounted filesystem with ordered data mode. Opts: (null) [ 5.127692] udevd[295]: starting version 175 [ 5.461941] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input2 [ 5.461953] ACPI: Power Button [PWRF] [ 5.541906] input: PC Speaker as /devices/platform/pcspkr/input/input3 [ 5.564376] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0xb100, revision 0 [ 6.030196] Error: Driver 'pcspkr' is already registered, aborting... [ 6.220265] input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input4 [ 7.374180] Adding 1045500k swap on /dev/sda5. Priority:-1 extents:1 across:1045500k [ 7.422720] EXT4-fs (sda1): re-mounted. Opts: (null) [ 7.681848] EXT4-fs (sda1): re-mounted. Opts: errors=remount-ro [ 7.833801] loop: module loaded [ 9.993936] ip_tables: (C) 2000-2006 Netfilter Core Team [ 10.053324] nf_conntrack version 0.5.0 (3962 buckets, 15848 max) [ 11.567043] ADDRCONF(NETDEV_UP): eth0: link is not ready [ 11.569813] e1000: eth0 NIC Link is Up 1000 Mbps Full Duplex, Flow Control: RX [ 11.570394] ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready [ 131.154536] iptables denied: IN=eth0 OUT= MAC=00:16:3c:85:ea:94:00:d0:00:a7:f0:00:08:00 SRC=178.19.108.164 DST=199.167.30.163 LEN=40 TOS=0x00 PREC=0x00 TTL=119 ID=256 DF PROTO=TCP SPT=12200 DPT=9064 WINDOW=8192 RES=0x00 SYN URGP=0 [ 240.224924] iptables denied: IN=eth0 OUT= MAC=00:16:3c:85:ea:94:00:d0:00:9e:24:00:08:00 SRC=74.122.198.123 DST=199.167.30.163 LEN=118 TOS=0x00 PREC=0x00 TTL=56 ID=0 DF PROTO=UDP SPT=52757 DPT=1900 LEN=98 [ 422.034358] iptables denied: IN=eth0 OUT= MAC=00:16:3c:85:ea:94:00:d0:00:9e:24:00:08:00 SRC=5.100.249.197 DST=199.167.30.163 LEN=40 TOS=0x00 PREC=0x00 TTL=115 ID=3869 DF PROTO=TCP SPT=443 DPT=57879 WINDOW=0 RES=0x00 ACK RST URGP=0 [ 422.034523] iptables denied: IN=eth0 OUT= MAC=00:16:3c:85:ea:94:00:d0:00:9e:24:00:08:00 SRC=5.100.249.197 DST=199.167.30.163 LEN=40 TOS=0x00 PREC=0x00 TTL=115 ID=3871 DF PROTO=TCP SPT=443 DPT=57881 WINDOW=0 RES=0x00 ACK RST URGP=0 [ 422.034604] iptables denied: IN=eth0 OUT= MAC=00:16:3c:85:ea:94:00:d0:00:a7:f0:00:08:00 SRC=5.100.249.197 DST=199.167.30.163 LEN=40 TOS=0x00 PREC=0x00 TTL=115 ID=3870 DF PROTO=TCP SPT=443 DPT=57880 WINDOW=0 RES=0x00 ACK RST URGP=0 [ 662.530569] iptables denied: IN=eth0 OUT= MAC=00:16:3c:85:ea:94:00:d0:00:a7:f0:00:08:00 SRC=202.56.13.138 DST=199.167.30.163 LEN=40 TOS=0x00 PREC=0x00 TTL=118 ID=12930 DF PROTO=TCP SPT=443 DPT=41057 WINDOW=0 RES=0x00 ACK RST URGP=0 [ 662.530582] iptables denied: IN=eth0 OUT= MAC=00:16:3c:85:ea:94:00:d0:00:9e:24:00:08:00 SRC=202.56.13.138 DST=199.167.30.163 LEN=40 TOS=0x00 PREC=0x00 TTL=118 ID=12931 DF PROTO=TCP SPT=443 DPT=41058 WINDOW=0 RES=0x00 ACK RST URGP=0 [ 662.530592] iptables denied: IN=eth0 OUT= MAC=00:16:3c:85:ea:94:00:d0:00:a7:f0:00:08:00 SRC=202.56.13.138 DST=199.167.30.163 LEN=40 TOS=0x00 PREC=0x00 TTL=118 ID=12932 DF PROTO=TCP SPT=443 DPT=41059 WINDOW=0 RES=0x00 ACK RST URGP=0 [ 1027.044915] iptables denied: IN=eth0 OUT= MAC=00:16:3c:85:ea:94:00:d0:00:a7:f0:00:08:00 SRC=5.100.249.197 DST=199.167.30.163 LEN=40 TOS=0x00 PREC=0x00 TTL=115 ID=6724 DF PROTO=TCP SPT=443 DPT=58414 WINDOW=0 RES=0x00 ACK RST URGP=0 [ 1027.060376] iptables denied: IN=eth0 OUT= MAC=00:16:3c:85:ea:94:00:d0:00:9e:24:00:08:00 SRC=5.100.249.197 DST=199.167.30.163 LEN=40 TOS=0x00 PREC=0x00 TTL=108 ID=6730 DF PROTO=TCP SPT=443 DPT=58415 WINDOW=0 RES=0x00 ACK RST URGP=0 [ 1027.061031] iptables denied: IN=eth0 OUT= MAC=00:16:3c:85:ea:94:00:d0:00:a7:f0:00:08:00 SRC=5.100.249.197 DST=199.167.30.163 LEN=40 TOS=0x00 PREC=0x00 TTL=108 ID=6731 DF PROTO=TCP SPT=443 DPT=58416 WINDOW=0 RES=0x00 ACK RST URGP=0 [ 1209.910200] iptables denied: IN=eth0 OUT= MAC=00:16:3c:85:ea:94:00:d0:00:a7:f0:00:08:00 SRC=216.218.206.87 DST=199.167.30.163 LEN=78 TOS=0x00 PREC=0x00 TTL=54 ID=31433 DF PROTO=UDP SPT=50194 DPT=137 LEN=58 [ 1267.547582] iptables denied: IN=eth0 OUT= MAC=00:16:3c:85:ea:94:00:d0:00:9e:24:00:08:00 SRC=202.56.13.138 DST=199.167.30.163 LEN=40 TOS=0x00 PREC=0x00 TTL=118 ID=18259 DF PROTO=TCP SPT=443 DPT=41596 WINDOW=0 RES=0x00 ACK RST URGP=0 [ 1267.547603] iptables denied: IN=eth0 OUT= MAC=00:16:3c:85:ea:94:00:d0:00:a7:f0:00:08:00 SRC=202.56.13.138 DST=199.167.30.163 LEN=40 TOS=0x00 PREC=0x00 TTL=118 ID=18260 DF PROTO=TCP SPT=443 DPT=41597 WINDOW=0 RES=0x00 ACK RST URGP=0 [ 1267.547613] iptables denied: IN=eth0 OUT= MAC=00:16:3c:85:ea:94:00:d0:00:9e:24:00:08:00 SRC=202.56.13.138 DST=199.167.30.163 LEN=40 TOS=0x00 PREC=0x00 TTL=118 ID=18261 DF PROTO=TCP SPT=443 DPT=41598 WINDOW=0 RES=0x00 ACK RST URGP=0 [ 1383.743219] iptables denied: IN=eth0 OUT= MAC=00:16:3c:85:ea:94:00:d0:00:9e:24:00:08:00 SRC=177.156.201.21 DST=199.167.30.163 LEN=60 TOS=0x00 PREC=0x00 TTL=50 ID=34213 DF PROTO=TCP SPT=3742 DPT=23 WINDOW=5808 RES=0x00 SYN URGP=0 [ 1386.742213] iptables denied: IN=eth0 OUT= MAC=00:16:3c:85:ea:94:00:d0:00:9e:24:00:08:00 SRC=177.156.201.21 DST=199.167.30.163 LEN=60 TOS=0x00 PREC=0x00 TTL=50 ID=34214 DF PROTO=TCP SPT=3742 DPT=23 WINDOW=5808 RES=0x00 SYN URGP=0 [ 1462.346116] iptables denied: IN=eth0 OUT= MAC=00:16:3c:85:ea:94:00:d0:00:a7:f0:00:08:00 SRC=222.186.21.201 DST=199.167.30.163 LEN=40 TOS=0x00 PREC=0x00 TTL=101 ID=256 PROTO=TCP SPT=6000 DPT=22001 WINDOW=16384 RES=0x00 SYN URGP=0 [ 1481.180766] iptables denied: IN=eth0 OUT= MAC=00:16:3c:85:ea:94:00:d0:00:9e:24:00:08:00 SRC=222.186.21.201 DST=199.167.30.163 LEN=40 TOS=0x00 PREC=0x00 TTL=101 ID=256 PROTO=TCP SPT=6000 DPT=22002 WINDOW=16384 RES=0x00 SYN URGP=0 [ 1483.703713] iptables denied: IN=eth0 OUT= MAC=00:16:3c:85:ea:94:00:d0:00:a7:f0:00:08:00 SRC=222.186.21.201 DST=199.167.30.163 LEN=40 TOS=0x00 PREC=0x00 TTL=101 ID=256 PROTO=TCP SPT=6000 DPT=22003 WINDOW=16384 RES=0x00 SYN URGP=0 [ 1487.365617] iptables denied: IN=eth0 OUT= MAC=00:16:3c:85:ea:94:00:d0:00:9e:24:00:08:00 SRC=222.186.21.201 DST=199.167.30.163 LEN=40 TOS=0x00 PREC=0x00 TTL=101 ID=256 PROTO=TCP SPT=6000 DPT=22331 WINDOW=16384 RES=0x00 SYN URGP=0 [ 1489.165314] iptables denied: IN=eth0 OUT= MAC=00:16:3c:85:ea:94:00:d0:00:a7:f0:00:08:00 SRC=222.186.21.201 DST=199.167.30.163 LEN=40 TOS=0x00 PREC=0x00 TTL=101 ID=256 PROTO=TCP SPT=6000 DPT=22102 WINDOW=16384 RES=0x00 SYN URGP=0 ** Model information sys_vendor: Red Hat product_name: KVM product_version: chassis_vendor: RED HAT chassis_version: bios_vendor: QEMU bios_version: QEMU ** Loaded modules: ipt_LOG xt_limit xt_tcpudp nf_conntrack_ipv4 nf_defrag_ipv4 xt_state nf_conntrack iptable_filter ip_tables x_tables loop snd_pcm snd_page_alloc snd_timer snd processor soundcore psmouse i2c_piix4 joydev serio_raw pcspkr thermal_sys evdev i2c_core button virtio_balloon usbhid hid ext4 crc16 jbd2 mbcache sg sr_mod sd_mod cdrom crc_t10dif ata_generic floppy ata_piix libata scsi_mod uhci_hcd ehci_hcd usbcore e1000 usb_common virtio_pci virtio_ring virtio ** Network interface configuration: # This file describes the network interfaces available on your system # and how to activate them. For more information, see interfaces(5). # The loopback network interface auto lo iface lo inet loopback # The primary network interface allow-hotplug eth0 iface eth0 inet dhcp ** Network status: *** IP interfaces and addresses: 1: lo: <LOOPBACK,UP,LOWER_UP> mtu 16436 qdisc noqueue state UNKNOWN link/loopback 00:00:00:00:00:00 brd 00:00:00:00:00:00 inet 127.0.0.1/8 scope host lo inet6 ::1/128 scope host valid_lft forever preferred_lft forever 2: eth0: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 qdisc pfifo_fast state UP qlen 1000 link/ether 00:16:3c:85:ea:94 brd ff:ff:ff:ff:ff:ff inet 199.167.30.163/24 brd 199.167.30.255 scope global eth0 inet6 fe80::216:3cff:fe85:ea94/64 scope link valid_lft forever preferred_lft forever *** Device statistics: Inter-| Receive | Transmit face |bytes packets errs drop fifo frame compressed multicast|bytes packets errs drop fifo colls carrier compressed lo: 300 6 0 0 0 0 0 0 300 6 0 0 0 0 0 0 eth0: 27408003 60907 0 0 0 0 0 0 13307648 36683 0 0 0 0 0 0 *** Protocol statistics: Ip: 39153 total packets received 2 with invalid addresses 0 forwarded 0 incoming packets discarded 39129 incoming packets delivered 34885 requests sent out Icmp: 1 ICMP messages received 0 input ICMP message failed. ICMP input histogram: echo requests: 1 1 ICMP messages sent 0 ICMP messages failed ICMP output histogram: echo replies: 1 IcmpMsg: InType8: 1 OutType0: 1 Tcp: 1190 active connections openings 281 passive connection openings 17 failed connection attempts 11 connection resets received 4 connections established 36602 segments received 34019 segments send out 105 segments retransmited 0 bad segments received. 130 resets sent Udp: 2526 packets received 0 packets to unknown port received. 0 packet receive errors 2528 packets sent UdpLite: TcpExt: 12 resets received for embryonic SYN_RECV sockets 1327 TCP sockets finished time wait in fast timer 120 delayed acks sent Quick ack mode was activated 197 times 1 packets directly queued to recvmsg prequeue. 15271 packet headers predicted 4978 acknowledgments not containing data payload received 8438 predicted acknowledgments 1 times recovered from packet loss by selective acknowledgements 7 congestion windows recovered without slow start after partial ack 3 timeouts after SACK recovery 1 fast retransmits 35 other TCP timeouts 198 DSACKs sent for old packets 1 DSACKs received 38 connections reset due to unexpected data 11 connections reset due to early user close 2 connections aborted due to timeout TCPSackShifted: 1 TCPSackMerged: 1 TCPSackShiftFallback: 10 TCPDeferAcceptDrop: 274 IpExt: InOctets: 25553795 OutOctets: 12716622 ** PCI devices: 00:00.0 Host bridge [0600]: Intel Corporation 440FX - 82441FX PMC [Natoma] [8086:1237] (rev 02) Subsystem: Red Hat, Inc Qemu virtual machine [1af4:1100] Control: I/O- Mem- BusMaster- SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx- Status: Cap- 66MHz- UDF- FastB2B- ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx- 00:01.0 ISA bridge [0601]: Intel Corporation 82371SB PIIX3 ISA [Natoma/Triton II] [8086:7000] Subsystem: Red Hat, Inc Qemu virtual machine [1af4:1100] Physical Slot: 1 Control: I/O+ Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx- Status: Cap- 66MHz- UDF- FastB2B- ParErr- DEVSEL=medium >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx- Latency: 0 00:01.1 IDE interface [0101]: Intel Corporation 82371SB PIIX3 IDE [Natoma/Triton II] [8086:7010] (prog-if 80 [Master]) Subsystem: Red Hat, Inc Qemu virtual machine [1af4:1100] Physical Slot: 1 Control: I/O+ Mem- BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx- Status: Cap- 66MHz- UDF- FastB2B+ ParErr- DEVSEL=medium >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx- Latency: 64 Region 0: [virtual] Memory at 000001f0 (32-bit, non-prefetchable) [size=8] Region 1: [virtual] Memory at 000003f0 (type 3, non-prefetchable) [size=1] Region 2: [virtual] Memory at 00000170 (32-bit, non-prefetchable) [size=8] Region 3: [virtual] Memory at 00000370 (type 3, non-prefetchable) [size=1] Region 4: I/O ports at c000 [size=16] Kernel driver in use: ata_piix 00:01.2 USB controller [0c03]: Intel Corporation 82371SB PIIX3 USB [Natoma/Triton II] [8086:7020] (rev 01) (prog-if 00 [UHCI]) Subsystem: Red Hat, Inc Qemu virtual machine [1af4:1100] Physical Slot: 1 Control: I/O+ Mem- BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx- Status: Cap- 66MHz- UDF- FastB2B- ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx- Latency: 64 Interrupt: pin D routed to IRQ 11 Region 4: I/O ports at c020 [size=32] Kernel driver in use: uhci_hcd 00:01.3 Bridge [0680]: Intel Corporation 82371AB/EB/MB PIIX4 ACPI [8086:7113] (rev 03) Subsystem: Red Hat, Inc Qemu virtual machine [1af4:1100] Physical Slot: 1 Control: I/O- Mem- BusMaster- SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx- Status: Cap- 66MHz- UDF- FastB2B+ ParErr- DEVSEL=medium >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx- Interrupt: pin A routed to IRQ 9 Kernel driver in use: piix4_smbus 00:02.0 VGA compatible controller [0300]: Cirrus Logic GD 5446 [1013:00b8] (prog-if 00 [VGA controller]) Subsystem: Red Hat, Inc Device [1af4:1100] Physical Slot: 2 Control: I/O+ Mem+ BusMaster- SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx- Status: Cap- 66MHz- UDF- FastB2B- ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx- Region 0: Memory at c2000000 (32-bit, prefetchable) [size=32M] Region 1: Memory at c4000000 (32-bit, non-prefetchable) [size=4K] Expansion ROM at <unassigned> [disabled] 00:03.0 Ethernet controller [0200]: Intel Corporation 82540EM Gigabit Ethernet Controller [8086:100e] (rev 03) Subsystem: Red Hat, Inc Device [1af4:1100] Physical Slot: 3 Control: I/O+ Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx- Status: Cap+ 66MHz- UDF- FastB2B- ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx- Latency: 64, Cache Line Size: 64 bytes Interrupt: pin A routed to IRQ 10 Region 0: Memory at c4020000 (32-bit, non-prefetchable) [size=128K] Region 1: I/O ports at c040 [size=64] Kernel driver in use: e1000 00:04.0 RAM memory [0500]: Red Hat, Inc Virtio memory balloon [1af4:1002] Subsystem: Red Hat, Inc Device [1af4:0005] Physical Slot: 4 Control: I/O+ Mem- BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx- Status: Cap- 66MHz- UDF- FastB2B- ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx- Latency: 64 Interrupt: pin A routed to IRQ 11 Region 0: I/O ports at c080 [size=32] Kernel driver in use: virtio-pci ** USB devices: Bus 001 Device 001: ID 1d6b:0001 Linux Foundation 1.1 root hub Bus 001 Device 002: ID 0627:0001 Adomax Technology Co., Ltd -- System Information: Debian Release: 7.8 APT prefers stable-updates APT policy: (500, 'stable-updates'), (500, 'stable') Architecture: amd64 (x86_64) Kernel: Linux 3.2.0-4-amd64 (SMP w/2 CPU cores) Locale: LANG=en_US.UTF-8, LC_CTYPE=en_US.UTF-8 (charmap=UTF-8) Shell: /bin/sh linked to /bin/dash Versions of packages linux-image-3.2.0-4-amd64 depends on: ii debconf [debconf-2.0] 1.5.49 ii initramfs-tools [linux-initramfs-tool] 0.109.1 ii kmod 9-3 ii linux-base 3.5 ii module-init-tools 9-3 Versions of packages linux-image-3.2.0-4-amd64 recommends: pn firmware-linux-free <none> Versions of packages linux-image-3.2.0-4-amd64 suggests: pn debian-kernel-handbook <none> ii grub-pc 1.99-27+deb7u2 pn linux-doc-3.2 <none> Versions of packages linux-image-3.2.0-4-amd64 is related to: pn firmware-atheros <none> pn firmware-bnx2 <none> pn firmware-bnx2x <none> pn firmware-brcm80211 <none> pn firmware-intelwimax <none> pn firmware-ipw2x00 <none> pn firmware-ivtv <none> pn firmware-iwlwifi <none> pn firmware-libertas <none> pn firmware-linux <none> pn firmware-linux-nonfree <none> pn firmware-myricom <none> pn firmware-netxen <none> pn firmware-qlogic <none> pn firmware-ralink <none> pn firmware-realtek <none> pn xen-hypervisor <none> -- debconf information: linux-image-3.2.0-4-amd64/postinst/depmod-error-initrd-3.2.0-4-amd64: false linux-image-3.2.0-4-amd64/prerm/removing-running-kernel-3.2.0-4-amd64: true linux-image-3.2.0-4-amd64/postinst/ignoring-ramdisk: linux-image-3.2.0-4-amd64/postinst/missing-firmware-3.2.0-4-amd64:
--- End Message ---
--- Begin Message ---
- To: 779469-done@bugs.debian.org
- Subject: Re: Bug#779469: linux-image-3.2.0-4-amd64: Call trace with 3.2.0-4-amd64 3.2.65-1+deb7u2
- From: Ben Hutchings <ben@decadent.org.uk>
- Date: Sun, 01 Mar 2015 02:54:52 +0000
- Message-id: <1425178492.28544.106.camel@decadent.org.uk>
- In-reply-to: <[🔎] 20150301021646.18580.97626.reportbug@tux.exchangewar.info>
- References: <[🔎] 20150301021646.18580.97626.reportbug@tux.exchangewar.info>
On Sat, 2015-02-28 at 23:16 -0300, Daniel Fraga wrote: > Package: src:linux > Version: 3.2.65-1+deb7u2 > Severity: normal > > After I upgraded to the latest kernel (3.2.0-4-amd64 3.2.65-1+deb7u) I'm seeing my > server (KVM VPS) turn unresponsive. The log shows the following: > > Feb 28 22:28:38 tux kernel: [114568.642541] sqlite3 invoked oom-killer: gfp_mask=0x200da, order=0, oom_adj=0, oom_score_adj=0 > Feb 28 22:28:38 tux kernel: [114568.642554] sqlite3 cpuset=/ mems_allowed=0 [...] > Feb 28 22:28:40 tux kernel: [114568.645927] 131040 pages RAM 512 MB RAM... > Feb 28 22:28:40 tux kernel: [114568.645930] 4225 pages reserved > Feb 28 22:28:40 tux kernel: [114568.645931] 2063 pages shared > Feb 28 22:28:40 tux kernel: [114568.645932] 122333 pages non-shared > Feb 28 22:28:40 tux kernel: [114568.645934] [ pid ] uid tgid total_vm rss cpu oom_adj oom_score_adj name [...] > Feb 28 22:28:40 tux kernel: [114568.645998] [24202] 0 24202 19540 15 0 0 0 apache2 > Feb 28 22:28:40 tux kernel: [114568.646001] [ 6778] 33 6778 19546 20 1 0 0 apache2 > Feb 28 22:28:40 tux kernel: [114568.646005] [15149] 33 15149 68357 44 1 0 0 apache2 > Feb 28 22:28:40 tux kernel: [114568.646009] [16368] 33 16368 75180 85 1 0 0 apache2 > Feb 28 22:28:40 tux kernel: [114568.646012] [16541] 33 16541 47444 66 1 0 0 apache2 and multiple Apache processes that use up to 300 MB VM each (total_vm is a number of pages). Sorry, this isn't going to fit. [...] > And the list goes on... It seems it run out of memory? But why does it not happened before? Luck? > This issue started just after upgrading the kernel... https://en.wikipedia.org/wiki/Post_hoc_ergo_propter_hoc Ben. -- Ben Hutchings Absolutum obsoletum. (If it works, it's out of date.) - Stafford BeerAttachment: signature.asc
Description: This is a digitally signed message part
--- End Message ---