You are not logged in.

Pages: 1

#1 2012-03-09 21:05:06

Mc_Lenet
Member
Registered: 2008-04-06
Posts: 6

Bug after pacman -Syu

Since a few days, I have been having issues with my 4 year old Arch desktop, including lockups while X is running, hangs in shutdown process with my screen filling with lines I could only get glimpses of, and, lately, a simple "pacman -Syu" would cause my computer to become unresponsive.

Today I decided to try and create a new arch installation with the latest media available (2011.08.19 x86_64). With my fresh system, I can already see that at least one of my issues has not gone away. In a tty as root, pacman -Syu proceeded to upgrade my system, readily downloaded the packages and installed them, but, at the end of the process, here is what got printed on my screen (copied from /var/log/messages.log) :

Mar  9 21:32:32 localhost kernel: [  590.574960] CPU 1 
Mar  9 21:32:32 localhost kernel: [  590.574993] Modules linked in: nvidia(P) dvb_pll cx22702 cx88_dvb videobuf_dvb cx88_vp3054_i2c dvb_core snd_hda_codec_analog rc_winfast cx8802 cx8800 cx88xx ir_lirc_codec lirc_dev tveeprom btcx_risc ir_mce_kbd_decoder videobuf_dma_sg i2c_algo_bit ir_sony_decoder videobuf_core ir_jvc_decoder v4l2_common videodev ir_rc6_decoder ir_rc5_decoder v4l2_compat_ioctl32 media snd_hda_intel snd_hda_codec snd_hwdep snd_pcm ir_nec_decoder rc_core firewire_ohci firewire_core psmouse intel_agp crc_itu_t snd_page_alloc i2c_i801 intel_gtt pcspkr serio_raw i2c_core snd_timer iTCO_wdt snd iTCO_vendor_support soundcore floppy joydev evdev asus_atk0110 processor button sky2 skge ext4 crc16 jbd2 mbcache sr_mod cdrom hid_logitech_dj usbhid hid pata_jmicron sd_mod ata_piix pata_acpi ahci libahci libata uhci_hcd scsi_mod ehci_hcd usbcore usb_common
Mar  9 21:32:32 localhost kernel: [  590.576608] 
Mar  9 21:32:32 localhost kernel: [  590.576608] Pid: 825, comm: ldconfig Tainted: P           O 3.2.9-1-ARCH #1 System manufacturer System Product Name/P5B-Deluxe
Mar  9 21:32:32 localhost kernel: [  590.576608] RIP: 0010:[<ffffffff8117f3a3>]  [<ffffffff8117f3a3>] __d_alloc+0x123/0x180
Mar  9 21:32:32 localhost kernel: [  590.576608] RSP: 0018:ffff88017791dc48  EFLAGS: 00010202
Mar  9 21:32:32 localhost kernel: [  590.576608] RAX: ffff880166d7f8d0 RBX: ffff880166d7f840 RCX: 0000000000000000
Mar  9 21:32:32 localhost kernel: [  590.576608] RDX: 0000000000000002 RSI: ffff8801725f901e RDI: ffff880166d7f840
Mar  9 21:32:32 localhost kernel: [  590.576608] RBP: ffff88017791dc68 R08: 2222222222222222 R09: 2222222222222222
Mar  9 21:32:32 localhost kernel: [  590.576608] R10: 0000000000000000 R11: 0000000000000000 R12: ffff88017791de58
Mar  9 21:32:32 localhost kernel: [  590.576608] R13: ffff880166d7f878 R14: 0000880178beec00 R15: 0000000000000022
Mar  9 21:32:32 localhost kernel: [  590.576608] FS:  00000000016fc880(0063) GS:ffff88017fc80000(0000) knlGS:0000000000000000
Mar  9 21:32:32 localhost kernel: [  590.576608] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
Mar  9 21:32:32 localhost kernel: [  590.576608] CR2: 0000000000410000 CR3: 00000001771bc000 CR4: 00000000000006e0
Mar  9 21:32:32 localhost kernel: [  590.576608] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
Mar  9 21:32:32 localhost kernel: [  590.576608] DR3: 0000000000000000 DR6: 00000000ffff0ff0 DR7: 0000000000000400
Mar  9 21:32:32 localhost kernel: [  590.576608] Process ldconfig (pid: 825, threadinfo ffff88017791c000, task ffff88017745e3c0)
Mar  9 21:32:32 localhost kernel: [  590.576608]  ffff880170197cc0 ffff8801701c6b70 ffff88017791de48 ffff88017791de48
Mar  9 21:32:32 localhost kernel: [  590.576608]  ffff88017791dc98 ffffffff8117f6f5 ffff88017791ddd8 0000000000000000
Mar  9 21:32:32 localhost kernel: [  590.576608]  ffff8801701c6b70 ffff88017791de48 ffff88017791dcd8 ffffffff811729dd
Mar  9 21:32:32 localhost kernel: [  590.576608]  [<ffffffff8117f6f5>] d_alloc+0x25/0x90
Mar  9 21:32:32 localhost kernel: [  590.576608]  [<ffffffff811729dd>] d_alloc_and_lookup+0x2d/0x90
Mar  9 21:32:32 localhost kernel: [  590.576608]  [<ffffffff8117faf5>] ? d_lookup+0x35/0x60
Mar  9 21:32:32 localhost kernel: [  590.576608]  [<ffffffff81173abf>] __lookup_hash.part.20+0xbf/0xe0
Mar  9 21:32:32 localhost kernel: [  590.576608]  [<ffffffff81173dac>] ? inode_permission+0x4c/0x110
Mar  9 21:32:32 localhost kernel: [  590.576608]  [<ffffffff81175be0>] lookup_hash+0x50/0x60
Mar  9 21:32:32 localhost kernel: [  590.576608]  [<ffffffff81176e9f>] do_last+0x46f/0x970
Mar  9 21:32:32 localhost kernel: [  590.576608]  [<ffffffff811774b2>] path_openat+0xd2/0x3c0
Mar  9 21:32:32 localhost kernel: [  590.576608]  [<ffffffff81177823>] ? user_path_at_empty+0x63/0xa0
Mar  9 21:32:32 localhost kernel: [  590.576608]  [<ffffffff811778c2>] do_filp_open+0x42/0xa0
Mar  9 21:32:32 localhost kernel: [  590.576608]  [<ffffffff81183fcc>] ? alloc_fd+0xec/0x140
Mar  9 21:32:32 localhost kernel: [  590.576608]  [<ffffffff81166a97>] do_sys_open+0xf7/0x1d0
Mar  9 21:32:32 localhost kernel: [  590.576608]  [<ffffffff81166b90>] sys_open+0x20/0x30
Mar  9 21:32:32 localhost kernel: [  590.576608]  [<ffffffff8145dd42>] system_call_fastpath+0x16/0x1b
Mar  9 21:32:32 localhost kernel: [  590.576608]  RSP <ffff88017791dc48>
Mar  9 21:32:32 localhost kernel: [  590.583014] ---[ end trace c87e3f6f4ea02d0c ]---

This time my computer did not become unresponsive, but the output presented above does look alike what I could catch before I reinstalled my system (althoug maybe I have seen RIP instead of RSP in the line before the last one). I have no clue what is going. I am not enclined to think this is hardware related as I have a Chakra install and a Windows7 install that work fine.

I did look at the bug reports and searched the forums but could not see anything that seems remotely related to what I am facing. Apologies in advance if I missed something.

I would be happy to have anyone's insight about my issue.

Cheers smile

Offline

#2 2012-03-10 18:08:22

litemotiv
Forum Fellow
Registered: 2008-08-01
Posts: 5,026

Re: Bug after pacman -Syu

If you are certain that your physical memory is not broken (you should run a memtest to be sure), your best option is probably to file a bug at https://bugzilla.kernel.org/.

ᶘ ᵒᴥᵒᶅ

Offline

#3 2012-03-10 18:49:47

falconindy
Developer
From: New York, USA
Registered: 2009-10-22
Posts: 4,111
Website

Re: Bug after pacman -Syu

That call stack points to a fault in the filesystem (not the driver, the on disk FS itself).  I think you're missing a few lines above from where you started copying to see what the actual warning was.

blog | ±github

Offline

#4 2012-03-21 19:38:28

Mc_Lenet
Member
Registered: 2008-04-06
Posts: 6

Re: Bug after pacman -Syu

Thank you both for your answers (that I did not see until today).

To litemotiv : a 7-hour memcheck did not raise any error. I am not sure how to report such a bug, though.

To falconindy : here are the lines above the one I gave previously. I do not see anything useful but I would be pleased to be enlightened smile

Mar  9 21:22:48 localhost kernel: [    5.122692] cx88[0]/2: cx2388x based DVB/ATSC card
Mar  9 21:22:48 localhost kernel: [    5.122694] cx8802_alloc_frontends() allocating 1 frontend(s)
Mar  9 21:22:48 localhost kernel: [    5.198183] DVB: registering new adapter (cx88[0])
Mar  9 21:22:48 localhost kernel: [    5.198185] DVB: registering adapter 0 frontend 0 (Conexant CX22702 DVB-T)...
Mar  9 21:22:48 localhost kernel: [    5.249916] nvidia 0000:04:00.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16
Mar  9 21:22:48 localhost kernel: [    5.249929] vgaarb: device changed decodes: PCI:0000:04:00.0,olddecodes=io+mem,decodes=none:owns=io+mem
Mar  9 21:22:48 localhost kernel: [    5.250025] NVRM: loading NVIDIA UNIX x86_64 Kernel Module  295.20  Mon Feb  6 21:07:30 PST 2012
Mar  9 21:22:48 localhost kernel: [    5.760798] EXT4-fs (sdc5): re-mounted. Opts: (null)
Mar  9 21:22:48 localhost kernel: [    5.762311] EXT4-fs (sda3): mounting ext2 file system using the ext4 subsystem
Mar  9 21:22:48 localhost kernel: [    5.769319] EXT4-fs (sda3): mounted filesystem without journal. Opts: (null)
Mar  9 21:22:48 localhost kernel: [    5.872575] EXT4-fs (sdb1): mounted filesystem with ordered data mode. Opts: (null)
Mar  9 21:22:48 localhost kernel: [    5.890238] Adding 1052220k swap on /dev/sdc1.  Priority:-1 extents:1 across:1052220k 
Mar  9 21:22:48 localhost kernel: [    6.620511] skge 0000:05:04.0: eth0: enabling interface
Mar  9 21:22:48 localhost dhcpcd[429]: eth0: sending IPv6 Router Solicitation
Mar  9 21:22:48 localhost dhcpcd[429]: eth0: broadcasting for a lease
Mar  9 21:22:52 localhost dhcpcd[429]: eth0: sending IPv6 Router Solicitation
Mar  9 21:22:56 localhost dhcpcd[429]: eth0: sending IPv6 Router Solicitation
Mar  9 21:23:00 localhost dhcpcd[429]: eth0: sending IPv6 Router Solicitation
Mar  9 21:23:00 localhost dhcpcd[429]: eth0: no IPv6 Routers available
Mar  9 21:24:12 localhost dhcpcd[527]: version 5.5.4 starting
Mar  9 21:24:12 localhost kernel: [   90.916689] sky2 0000:02:00.0: eth1: enabling interface
Mar  9 21:24:12 localhost kernel: [   90.917040] ADDRCONF(NETDEV_UP): eth1: link is not ready
Mar  9 21:24:12 localhost dhcpcd[527]: eth1: waiting for carrier
Mar  9 21:24:14 localhost dhcpcd[527]: eth1: carrier acquired
Mar  9 21:24:14 localhost kernel: [   92.783714] sky2 0000:02:00.0: eth1: Link is up at 100 Mbps, full duplex, flow control rx
Mar  9 21:24:14 localhost kernel: [   92.784028] ADDRCONF(NETDEV_CHANGE): eth1: link becomes ready
Mar  9 21:24:14 localhost dhcpcd[527]: eth1: sending IPv6 Router Solicitation
Mar  9 21:24:14 localhost dhcpcd[527]: eth1: rebinding lease of 192.168.0.2
Mar  9 21:24:14 localhost dhcpcd[527]: eth1: acknowledged 192.168.0.2 from 192.168.0.254
Mar  9 21:24:14 localhost dhcpcd[527]: eth1: checking for 192.168.0.2
Mar  9 21:24:18 localhost dhcpcd[527]: eth1: sending IPv6 Router Solicitation
Mar  9 21:24:19 localhost dhcpcd[527]: eth1: leased 192.168.0.2 for 864000 seconds
Mar  9 21:24:19 localhost dhcpcd[527]: forked to background, child pid 559
Mar  9 21:24:22 localhost dhcpcd[559]: eth1: sending IPv6 Router Solicitation
Mar  9 21:24:26 localhost dhcpcd[559]: eth1: sending IPv6 Router Solicitation
Mar  9 21:24:26 localhost dhcpcd[559]: eth1: no IPv6 Routers available
Mar  9 21:30:16 localhost dbus[494]: [system] Reloaded configuration
Mar  9 21:30:16 localhost dbus[494]: [system] Reloaded configuration
Mar  9 21:32:32 localhost kernel: [  590.574960] CPU 1 
Mar  9 21:32:32 localhost kernel: [  590.574993] Modules linked in: nvidia(P) dvb_pll cx22702 cx88_dvb videobuf_dvb cx88_vp3054_i2c dvb_core snd_hda_codec_analog rc_winfast cx8802 cx8800 cx88xx ir_lirc_codec lirc_dev tveeprom btcx_risc ir_mce_kbd_decoder videobuf_dma_sg i2c_algo_bit ir_sony_decoder videobuf_core ir_jvc_decoder v4l2_common videodev ir_rc6_decoder ir_rc5_decoder v4l2_compat_ioctl32 media snd_hda_intel snd_hda_codec snd_hwdep snd_pcm ir_nec_decoder rc_core firewire_ohci firewire_core psmouse intel_agp crc_itu_t snd_page_alloc i2c_i801 intel_gtt pcspkr serio_raw i2c_core snd_timer iTCO_wdt snd iTCO_vendor_support soundcore floppy joydev evdev asus_atk0110 processor button sky2 skge ext4 crc16 jbd2 mbcache sr_mod cdrom hid_logitech_dj usbhid hid pata_jmicron sd_mod ata_piix pata_acpi ahci libahci libata uhci_hcd scsi_mod ehci_hcd usbcore usb_common
Mar  9 21:32:32 localhost kernel: [  590.576608] 
Mar  9 21:32:32 localhost kernel: [  590.576608] Pid: 825, comm: ldconfig Tainted: P           O 3.2.9-1-ARCH #1 System manufacturer System Product Name/P5B-Deluxe
Mar  9 21:32:32 localhost kernel: [  590.576608] RIP: 0010:[<ffffffff8117f3a3>]  [<ffffffff8117f3a3>] __d_alloc+0x123/0x180
Mar  9 21:32:32 localhost kernel: [  590.576608] RSP: 0018:ffff88017791dc48  EFLAGS: 00010202
Mar  9 21:32:32 localhost kernel: [  590.576608] RAX: ffff880166d7f8d0 RBX: ffff880166d7f840 RCX: 0000000000000000
Mar  9 21:32:32 localhost kernel: [  590.576608] RDX: 0000000000000002 RSI: ffff8801725f901e RDI: ffff880166d7f840
Mar  9 21:32:32 localhost kernel: [  590.576608] RBP: ffff88017791dc68 R08: 2222222222222222 R09: 2222222222222222
Mar  9 21:32:32 localhost kernel: [  590.576608] R10: 0000000000000000 R11: 0000000000000000 R12: ffff88017791de58
Mar  9 21:32:32 localhost kernel: [  590.576608] R13: ffff880166d7f878 R14: 0000880178beec00 R15: 0000000000000022
Mar  9 21:32:32 localhost kernel: [  590.576608] FS:  00000000016fc880(0063) GS:ffff88017fc80000(0000) knlGS:0000000000000000
Mar  9 21:32:32 localhost kernel: [  590.576608] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
Mar  9 21:32:32 localhost kernel: [  590.576608] CR2: 0000000000410000 CR3: 00000001771bc000 CR4: 00000000000006e0
Mar  9 21:32:32 localhost kernel: [  590.576608] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
Mar  9 21:32:32 localhost kernel: [  590.576608] DR3: 0000000000000000 DR6: 00000000ffff0ff0 DR7: 0000000000000400
Mar  9 21:32:32 localhost kernel: [  590.576608] Process ldconfig (pid: 825, threadinfo ffff88017791c000, task ffff88017745e3c0)
Mar  9 21:32:32 localhost kernel: [  590.576608]  ffff880170197cc0 ffff8801701c6b70 ffff88017791de48 ffff88017791de48
Mar  9 21:32:32 localhost kernel: [  590.576608]  ffff88017791dc98 ffffffff8117f6f5 ffff88017791ddd8 0000000000000000
Mar  9 21:32:32 localhost kernel: [  590.576608]  ffff8801701c6b70 ffff88017791de48 ffff88017791dcd8 ffffffff811729dd
Mar  9 21:32:32 localhost kernel: [  590.576608]  [<ffffffff8117f6f5>] d_alloc+0x25/0x90
Mar  9 21:32:32 localhost kernel: [  590.576608]  [<ffffffff811729dd>] d_alloc_and_lookup+0x2d/0x90
Mar  9 21:32:32 localhost kernel: [  590.576608]  [<ffffffff8117faf5>] ? d_lookup+0x35/0x60
Mar  9 21:32:32 localhost kernel: [  590.576608]  [<ffffffff81173abf>] __lookup_hash.part.20+0xbf/0xe0
Mar  9 21:32:32 localhost kernel: [  590.576608]  [<ffffffff81173dac>] ? inode_permission+0x4c/0x110
Mar  9 21:32:32 localhost kernel: [  590.576608]  [<ffffffff81175be0>] lookup_hash+0x50/0x60
Mar  9 21:32:32 localhost kernel: [  590.576608]  [<ffffffff81176e9f>] do_last+0x46f/0x970
Mar  9 21:32:32 localhost kernel: [  590.576608]  [<ffffffff811774b2>] path_openat+0xd2/0x3c0
Mar  9 21:32:32 localhost kernel: [  590.576608]  [<ffffffff81177823>] ? user_path_at_empty+0x63/0xa0
Mar  9 21:32:32 localhost kernel: [  590.576608]  [<ffffffff811778c2>] do_filp_open+0x42/0xa0
Mar  9 21:32:32 localhost kernel: [  590.576608]  [<ffffffff81183fcc>] ? alloc_fd+0xec/0x140
Mar  9 21:32:32 localhost kernel: [  590.576608]  [<ffffffff81166a97>] do_sys_open+0xf7/0x1d0
Mar  9 21:32:32 localhost kernel: [  590.576608]  [<ffffffff81166b90>] sys_open+0x20/0x30
Mar  9 21:32:32 localhost kernel: [  590.576608]  [<ffffffff8145dd42>] system_call_fastpath+0x16/0x1b
Mar  9 21:32:32 localhost kernel: [  590.576608]  RSP <ffff88017791dc48>
Mar  9 21:32:32 localhost kernel: [  590.583014] ---[ end trace c87e3f6f4ea02d0c ]---
Mar  9 22:00:08 localhost -- MARK --

My / is on /dev/sdc5, ext3 filesystem. I formated this partition when I reinstalled my system on March 8th.
Should I try and run a fsck.ext3 on this partition to see if anything is wrong ? What about the "Mar  9 21:22:48 localhost kernel: [    5.760798] EXT4-fs (sdc5): re-mounted. Opts: (null)" line ? Is it normal t is mentionned EXT4-fs while the filesystem on this partition is ext3 ?

Thanks

Last edited by Mc_Lenet (2012-03-21 19:39:28)

Offline

Pages: 1

Board footer

Powered by FluxBB