LinHES Forums
http://forums.linhes.org/

R8.1 Intermittent Lockups (and a kernel oops)
http://forums.linhes.org/viewtopic.php?f=21&t=24697
Page 1 of 1

Author:  knappster [ Tue Nov 28, 2017 12:47 pm ]
Post subject:  R8.1 Intermittent Lockups (and a kernel oops)

I've had R8.1 installed for about 4 years now and first had some lockup issues in July. I noticed that the video card temperature had creeped up some and so I replaced the passively cooled card with a fan cooled card and had no lockup issues until Friday November 24 at about 3:30 pm. The system was idle at the time and below are the contents of the messages.log file for about an hour prior to the lockup:
Code:
2017-11-24T14:30:01.773504-06:00 linhes cron: FILE /var/spool/cron/root USER root PID 22806 /usr/sbin/run-cron /etc/cron.tenminutes
2017-11-24T14:40:01.867565-06:00 linhes cron: FILE /var/spool/cron/root USER root PID 23179 /usr/sbin/run-cron /etc/cron.tenminutes
2017-11-24T14:50:01.961538-06:00 linhes cron: FILE /var/spool/cron/root USER root PID 23575 /usr/sbin/run-cron /etc/cron.tenminutes
2017-11-24T15:00:01.062318-06:00 linhes cron: FILE /var/spool/cron/root USER root PID 23958 /usr/sbin/run-cron /etc/cron.tenminutes
2017-11-24T15:06:55.345227-06:00 linhes acpid: input device has been disconnected, fd 15
2017-11-24T15:06:55.347801-06:00 linhes acpid: input device has been disconnected, fd 9
2017-11-24T15:06:55.403368-06:00 linhes kernel: [31804.437919] usb 3-2: USB disconnect, device number 3
2017-11-24T15:06:55.403394-06:00 linhes kernel: [31804.438827] streamzap 3-2:1.0: urb terminated, status: -108
2017-11-24T15:06:55.897595-06:00 linhes kernel: [31804.995591] usb 3-2: new low-speed USB device number 4 using ohci_hcd
2017-11-24T15:06:56.067554-06:00 linhes kernel: [31805.167839] Registered IR keymap rc-streamzap
2017-11-24T15:06:56.067587-06:00 linhes kernel: [31805.168152] input: Streamzap PC Remote Infrared Receiver (0e9c:0000) as /devices/pci0000:00/0000:00:12.0/usb3/3-2/3-2:1.0/rc/rc1/input13
2017-11-24T15:06:56.067604-06:00 linhes kernel: [31805.168401] rc1: Streamzap PC Remote Infrared Receiver (0e9c:0000) as /devices/pci0000:00/0000:00:12.0/usb3/3-2/3-2:1.0/rc/rc1
2017-11-24T15:06:56.070946-06:00 linhes kernel: [31805.168692] input: MCE IR Keyboard/Mouse (streamzap) as /devices/virtual/input/input14
2017-11-24T15:06:56.070978-06:00 linhes kernel: [31805.169013] rc rc1: lirc_dev: driver ir-lirc-codec (streamzap) registered at minor = 0
2017-11-24T15:06:56.070985-06:00 linhes kernel: [31805.169029] streamzap 3-2:1.0: Registered Streamzap, Inc. Streamzap Remote Control on usb3:4
2017-11-24T15:10:01.132349-06:00 linhes cron: FILE /var/spool/cron/root USER root PID 24354 /usr/sbin/run-cron /etc/cron.tenminutes
2017-11-24T15:16:01.237761-06:00 linhes cron: FILE /var/spool/cron/root USER root PID 24549 job sys-hourly
2017-11-24T15:16:01.297531-06:00 linhes su: (to mythtv) root on none
2017-11-24T15:20:01.768860-06:00 linhes cron: FILE /var/spool/cron/root USER root PID 24744 /usr/sbin/run-cron /etc/cron.tenminutes
2017-11-24T15:30:01.851154-06:00 linhes cron: FILE /var/spool/cron/root USER root PID 25121 /usr/sbin/run-cron /etc/cron.tenminutes


It then happened again yesterday, November 27, while watching live tv at about 6:23 pm. This time it resulted in a kernel oops.

Code:
2017-11-27T17:30:01.763937-06:00 linhes cron: FILE /var/spool/cron/root USER root PID 29482 job sys-daily
2017-11-27T17:30:01.784423-06:00 linhes cron: FILE /var/spool/cron/root USER root PID 29483 /usr/sbin/run-cron /etc/cron.tenminutes
2017-11-27T17:30:01.784454-06:00 linhes cron: FILE /var/spool/cron/mythtv USER mythtv PID 29484 /home/mythtv/getstockhistory.sh
2017-11-27T17:30:08.991771-06:00 linhes su: (to mythtv) root on none
2017-11-27T17:30:11.001259-06:00 linhes php: PHP Notice:  Undefined index: value in /usr/bin/myth_trailers_grabber on line 163
2017-11-27T17:30:11.002642-06:00 linhes php: PHP Notice:  Undefined index: value in /usr/bin/myth_trailers_grabber on line 163
2017-11-27T17:30:11.004355-06:00 linhes php: PHP Notice:  Undefined index: value in /usr/bin/myth_trailers_grabber on line 163
2017-11-27T17:30:11.005734-06:00 linhes php: PHP Notice:  Undefined index: value in /usr/bin/myth_trailers_grabber on line 163
2017-11-27T17:31:19.285382-06:00 linhes frontend: GetModeLine - scrn: 0 clock: 148500
2017-11-27T17:31:19.285457-06:00 linhes frontend: GetModeLine - hdsp: 1920 hbeg: 2008 hend: 2052 httl: 2200
2017-11-27T17:31:19.286133-06:00 linhes frontend:               vdsp: 1080 vbeg: 1084 vend: 1089 vttl: 1125 flags: 5
2017-11-27T17:31:19.384002-06:00 linhes frontend: GetModeLine - scrn: 0 clock: 148500
2017-11-27T17:31:19.384019-06:00 linhes frontend: GetModeLine - hdsp: 1920 hbeg: 2008 hend: 2052 httl: 2200
2017-11-27T17:31:19.384021-06:00 linhes frontend:               vdsp: 1080 vbeg: 1084 vend: 1089 vttl: 1125 flags: 5
2017-11-27T17:31:19.385902-06:00 linhes frontend: GetModeLine - scrn: 0 clock: 148500
2017-11-27T17:31:19.386076-06:00 linhes frontend: GetModeLine - hdsp: 1920 hbeg: 2008 hend: 2052 httl: 2200
2017-11-27T17:31:19.386113-06:00 linhes frontend:               vdsp: 1080 vbeg: 1084 vend: 1089 vttl: 1125 flags: 5
2017-11-27T17:31:23.000029-06:00 linhes frontend: GetModeLine - scrn: 0 clock: 148500
2017-11-27T17:31:23.000310-06:00 linhes frontend: GetModeLine - hdsp: 1920 hbeg: 2008 hend: 2052 httl: 2200
2017-11-27T17:31:23.000331-06:00 linhes frontend:               vdsp: 1080 vbeg: 1084 vend: 1089 vttl: 1125 flags: 5
2017-11-27T17:31:23.149427-06:00 linhes frontend: GetModeLine - scrn: 0 clock: 148500
2017-11-27T17:31:23.149466-06:00 linhes frontend: GetModeLine - hdsp: 1920 hbeg: 2008 hend: 2052 httl: 2200
2017-11-27T17:31:23.149474-06:00 linhes frontend:               vdsp: 1080 vbeg: 1084 vend: 1089 vttl: 1125 flags: 5
2017-11-27T17:31:31.272588-06:00 linhes frontend: GetModeLine - scrn: 0 clock: 148500
2017-11-27T17:31:31.272628-06:00 linhes frontend: GetModeLine - hdsp: 1920 hbeg: 2008 hend: 2052 httl: 2200
2017-11-27T17:31:31.272642-06:00 linhes frontend:               vdsp: 1080 vbeg: 1084 vend: 1089 vttl: 1125 flags: 5
2017-11-27T17:31:31.389765-06:00 linhes frontend: GetModeLine - scrn: 0 clock: 148500
2017-11-27T17:31:31.389908-06:00 linhes frontend: GetModeLine - hdsp: 1920 hbeg: 2008 hend: 2052 httl: 2200
2017-11-27T17:31:31.390009-06:00 linhes frontend:               vdsp: 1080 vbeg: 1084 vend: 1089 vttl: 1125 flags: 5
2017-11-27T17:40:01.303925-06:00 linhes cron: FILE /var/spool/cron/root USER root PID 29961 /usr/sbin/run-cron /etc/cron.tenminutes
2017-11-27T17:50:01.521337-06:00 linhes cron: FILE /var/spool/cron/root USER root PID 30331 /usr/sbin/run-cron /etc/cron.tenminutes
2017-11-27T18:00:01.679941-06:00 linhes cron: FILE /var/spool/cron/root USER root PID 30701 /usr/sbin/run-cron /etc/cron.tenminutes
2017-11-27T18:10:01.840996-06:00 linhes cron: FILE /var/spool/cron/root USER root PID 31093 /usr/sbin/run-cron /etc/cron.tenminutes
2017-11-27T18:16:01.927429-06:00 linhes cron: FILE /var/spool/cron/root USER root PID 31288 job sys-hourly
2017-11-27T18:16:02.109191-06:00 linhes su: (to mythtv) root on none
2017-11-27T18:20:01.167582-06:00 linhes cron: FILE /var/spool/cron/root USER root PID 31484 /usr/sbin/run-cron /etc/cron.tenminutes
2017-11-27T18:22:50.950894-06:00 linhes frontend: GetModeLine - scrn: 0 clock: 148500
2017-11-27T18:22:50.951048-06:00 linhes frontend: GetModeLine - hdsp: 1920 hbeg: 2008 hend: 2052 httl: 2200
2017-11-27T18:22:50.951065-06:00 linhes frontend:               vdsp: 1080 vbeg: 1084 vend: 1089 vttl: 1125 flags: 5
2017-11-27T18:22:51.156015-06:00 linhes frontend: GetModeLine - scrn: 0 clock: 148500
2017-11-27T18:22:51.156032-06:00 linhes frontend: GetModeLine - hdsp: 1920 hbeg: 2008 hend: 2052 httl: 2200
2017-11-27T18:22:51.156034-06:00 linhes frontend:               vdsp: 1080 vbeg: 1084 vend: 1089 vttl: 1125 flags: 5
2017-11-27T18:22:51.158042-06:00 linhes frontend: GetModeLine - scrn: 0 clock: 148500
2017-11-27T18:22:51.158219-06:00 linhes frontend: GetModeLine - hdsp: 1920 hbeg: 2008 hend: 2052 httl: 2200
2017-11-27T18:22:51.158257-06:00 linhes frontend:               vdsp: 1080 vbeg: 1084 vend: 1089 vttl: 1125 flags: 5
2017-11-27T18:22:54.152818-06:00 linhes frontend: GetModeLine - scrn: 0 clock: 148500
2017-11-27T18:22:54.152858-06:00 linhes frontend: GetModeLine - hdsp: 1920 hbeg: 2008 hend: 2052 httl: 2200
2017-11-27T18:22:54.152889-06:00 linhes frontend:               vdsp: 1080 vbeg: 1084 vend: 1089 vttl: 1125 flags: 5
2017-11-27T18:22:54.216124-06:00 linhes frontend: GetModeLine - scrn: 0 clock: 148500
2017-11-27T18:22:54.216281-06:00 linhes frontend: GetModeLine - hdsp: 1920 hbeg: 2008 hend: 2052 httl: 2200
2017-11-27T18:22:54.216300-06:00 linhes frontend:               vdsp: 1080 vbeg: 1084 vend: 1089 vttl: 1125 flags: 5
2017-11-27T18:22:55.783149-06:00 linhes acpid: input device has been disconnected, fd 15
2017-11-27T18:22:55.786176-06:00 linhes acpid: input device has been disconnected, fd 14
2017-11-27T18:22:55.808094-06:00 linhes kernel: [43532.646703] hub 3-0:1.0: port 2 disabled by hub (EMI?), re-enabling...
2017-11-27T18:22:55.808120-06:00 linhes kernel: [43532.646718] usb 3-2: USB disconnect, device number 3
2017-11-27T18:22:55.808125-06:00 linhes kernel: [43532.647638] streamzap 3-2:1.0: urb terminated, status: -108
2017-11-27T18:22:56.001768-06:00 linhes kernel: [43532.904754] usb 3-2: new low-speed USB device number 4 using ohci_hcd
2017-11-27T18:22:56.171781-06:00 linhes kernel: [43533.075505] Registered IR keymap rc-streamzap
2017-11-27T18:22:56.171805-06:00 linhes kernel: [43533.075704] input: Streamzap PC Remote Infrared Receiver (0e9c:0000) as /devices/pci0000:00/0000:00:12.0/usb3/3-2/3-2:1.0/rc/rc1/input13
2017-11-27T18:22:56.171812-06:00 linhes kernel: [43533.075948] rc1: Streamzap PC Remote Infrared Receiver (0e9c:0000) as /devices/pci0000:00/0000:00:12.0/usb3/3-2/3-2:1.0/rc/rc1
2017-11-27T18:22:56.171818-06:00 linhes kernel: [43533.076179] input: MCE IR Keyboard/Mouse (streamzap) as /devices/virtual/input/input14
2017-11-27T18:22:56.171824-06:00 linhes kernel: [43533.076811] rc rc1: lirc_dev: driver ir-lirc-codec (streamzap) registered at minor = 1
2017-11-27T18:22:56.171829-06:00 linhes kernel: [43533.076832] streamzap 3-2:1.0: Registered Streamzap, Inc. Streamzap Remote Control on usb3:4
2017-11-27T18:23:15.798498-06:00 linhes kernel: [43552.648204] BUG: unable to handle kernel NULL pointer dereference at 0000000000000020
2017-11-27T18:23:15.798536-06:00 linhes kernel: [43552.648217] IP: [<ffffffffa0154efd>] xfs_log_commit_cil+0x26d/0x4c0 [xfs]
2017-11-27T18:23:15.798541-06:00 linhes kernel: [43552.648264] PGD 22a7fb067 PUD 22b3de067 PMD 0
2017-11-27T18:23:15.798546-06:00 linhes kernel: [43552.648273] Oops: 0000 [#1] PREEMPT SMP
2017-11-27T18:23:15.798581-06:00 linhes kernel: [43552.648281] Modules linked in: rpcsec_gss_krb5 nfsd auth_rpcgss nfs_acl tveeprom btcx_risc videobuf_dma_sg i2c_algo_bit videobuf_core dvb_core v4l2_common(O) videodev(O) media snd_hda_codec_hdmi ir_lirc_codec lirc_dev ir_mce_kbd_decoder ir_sanyo_decoder ir_sony_decoder nvidia(PO) ir_jvc_decoder ir_rc6_decoder ir_rc5_decoder ir_rc5_sz_decoder ir_nec_decoder snd_seq_dummy snd_seq_oss rc_streamzap snd_seq_midi_event snd_seq snd_seq_device snd_pcm_oss snd_mixer_oss snd_hda_codec_realtek snd_hda_intel snd_hda_codec alx(O) mdio compat(O) microcode i2c_piix4 powernow_k8 edac_mce_amd k10temp mperf edac_core snd_hwdep snd_pcm snd_page_alloc snd_timer snd streamzap i2c_core sp5100_tco soundcore rc_core kvm serio_raw evdev processor wmi psmouse asus_atk0110 button uvesafb hid_generic usbhid hid sr_mod cdrom sd_mod ata_generic pata_acpi ohci_hcd pata_atiixp ahci libahci ehci_hcd libata scsi_mod usbcore usb_common ext4 crc16 jbd2 mbcache jfs xfs reiserfs nfs lockd sunrpc fscache
2017-11-27T18:23:15.798587-06:00 linhes kernel: [43552.648399] CPU 0
2017-11-27T18:23:15.798594-06:00 linhes kernel: [43552.648409] Pid: 31686, comm: mythbackend Tainted: P           O 3.6.7-1-ARCH #1 System manufacturer System Product Name/M5A78L-M LX3
2017-11-27T18:23:15.798602-06:00 linhes kernel: [43552.648415] RIP: 0010:[<ffffffffa0154efd>]  [<ffffffffa0154efd>] xfs_log_commit_cil+0x26d/0x4c0 [xfs]
2017-11-27T18:23:15.798629-06:00 linhes kernel: [43552.648451] RSP: 0018:ffff880114d0dab8  EFLAGS: 00010246
2017-11-27T18:23:15.798635-06:00 linhes kernel: [43552.648455] RAX: 0000000000000000 RBX: 0000000000000000 RCX: ffff8801fefa3630
2017-11-27T18:23:15.798640-06:00 linhes kernel: [43552.648459] RDX: ffff88022235f9c8 RSI: ffff88022235f9c8 RDI: ffff88022235f9c8
2017-11-27T18:23:15.798645-06:00 linhes kernel: [43552.648463] RBP: ffff880114d0db48 R08: 00000000802a001b R09: ffff880114d0dfd8
2017-11-27T18:23:15.798650-06:00 linhes kernel: [43552.648466] R10: 0000000000000000 R11: ffffffffa0111d35 R12: ffff88022c3a7210
2017-11-27T18:23:15.798655-06:00 linhes kernel: [43552.648470] R13: ffff88022aacc300 R14: ffff88022235f9d8 R15: ffff88022235f9c0
2017-11-27T18:23:15.798665-06:00 linhes kernel: [43552.648475] FS:  00007fd6327f4700(0000) GS:ffff880237c00000(0000) knlGS:0000000000000000
2017-11-27T18:23:15.798670-06:00 linhes kernel: [43552.648480] CS:  0010 DS: 0000 ES: 0000 CR0: 000000008005003b
2017-11-27T18:23:15.798680-06:00 linhes kernel: [43552.648483] CR2: 0000000000000020 CR3: 0000000221b05000 CR4: 00000000000007f0
2017-11-27T18:23:15.798685-06:00 linhes kernel: [43552.648487] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
2017-11-27T18:23:15.798691-06:00 linhes kernel: [43552.648491] DR3: 0000000000000000 DR6: 00000000ffff0ff0 DR7: 0000000000000400
2017-11-27T18:23:15.798699-06:00 linhes kernel: [43552.648496] Process mythbackend (pid: 31686, threadinfo ffff880114d0c000, task ffff8801139ce8d0)
2017-11-27T18:23:15.798704-06:00 linhes kernel: [43552.648499] Stack:
2017-11-27T18:23:15.798711-06:00 linhes kernel: [43552.648502]  ffff880114d0db28 ffff88022235f9c0 000000002c3a7200 ffff8802253ba800
2017-11-27T18:23:15.798716-06:00 linhes kernel: [43552.648510]  ffff8801feec7960 ffff8801feec7960 ffff880114d0db60 ffff88022c3a7200
2017-11-27T18:23:15.798725-06:00 linhes kernel: [43552.648518]  ffff8801f756c690 ffff8801f747e5c0 ffff8801f756c690 0000000000000000
2017-11-27T18:23:15.798729-06:00 linhes kernel: [43552.648525] Call Trace:
2017-11-27T18:23:15.798735-06:00 linhes kernel: [43552.648563]  [<ffffffffa014ffd4>] xfs_trans_commit+0x134/0x270 [xfs]
2017-11-27T18:23:15.798740-06:00 linhes kernel: [43552.648597]  [<ffffffffa01070f2>] xfs_vn_update_time+0xf2/0x1c0 [xfs]
2017-11-27T18:23:15.798746-06:00 linhes kernel: [43552.648606]  [<ffffffff81199c35>] update_time+0x25/0xc0
2017-11-27T18:23:15.798751-06:00 linhes kernel: [43552.648613]  [<ffffffff81199d68>] file_update_time+0x98/0xf0
2017-11-27T18:23:15.798756-06:00 linhes kernel: [43552.648622]  [<ffffffff8149067e>] ? __mutex_lock_slowpath+0x24e/0x340
2017-11-27T18:23:15.798762-06:00 linhes kernel: [43552.648653]  [<ffffffffa00ff89b>] xfs_file_aio_write_checks+0xdb/0xf0 [xfs]
2017-11-27T18:23:15.798772-06:00 linhes kernel: [43552.648683]  [<ffffffffa00ff929>] xfs_file_buffered_aio_write+0x79/0x1f0 [xfs]
2017-11-27T18:23:15.798779-06:00 linhes kernel: [43552.648692]  [<ffffffff8111cce7>] ? unlock_page+0x27/0x30
2017-11-27T18:23:15.798787-06:00 linhes kernel: [43552.648722]  [<ffffffffa00ffb99>] xfs_file_aio_write+0xf9/0x160 [xfs]
2017-11-27T18:23:15.798820-06:00 linhes kernel: [43552.648730]  [<ffffffff8117ed37>] do_sync_write+0xa7/0xe0
2017-11-27T18:23:15.798825-06:00 linhes kernel: [43552.648738]  [<ffffffff8117f5d8>] vfs_write+0xa8/0x180
2017-11-27T18:23:15.798830-06:00 linhes kernel: [43552.648745]  [<ffffffff8117f91a>] sys_write+0x4a/0xa0
2017-11-27T18:23:15.798836-06:00 linhes kernel: [43552.648752]  [<ffffffff81499fed>] system_call_fastpath+0x1a/0x1f
2017-11-27T18:23:15.814322-06:00 linhes kernel: [43552.648756] Code: 56 08 48 89 32 49 8b 57 10 49 89 4f 10 48 89 78 50 48 89 50 58 48 89 0a 48 8b 1b 48 85 db 75 ce 8b 45 cc 41 01 45 28 49 8b 45 20 <8b> 70 20 85 f6 75 14 8b 50 24 89 50 20 48 8b 55 b8 49 8b 45 20
2017-11-27T18:23:15.814381-06:00 linhes kernel: [43552.648821] RIP  [<ffffffffa0154efd>] xfs_log_commit_cil+0x26d/0x4c0 [xfs]
2017-11-27T18:23:15.814410-06:00 linhes kernel: [43552.648853]  RSP <ffff880114d0dab8>
2017-11-27T18:23:15.814414-06:00 linhes kernel: [43552.648856] CR2: 0000000000000020
2017-11-27T18:23:15.814419-06:00 linhes kernel: [43552.648879] ---[ end trace 6e58f24ef50f9812 ]---
2017-11-27T18:23:15.814425-06:00 linhes kernel: [43552.648887] note: mythbackend[31686] exited with preempt_count 1
2017-11-27T18:23:18.691826-06:00 linhes kernel: [43555.532457] ------------[ cut here ]------------
2017-11-27T18:23:18.691858-06:00 linhes kernel: [43555.532469] kernel BUG at fs/buffer.c:1457!
2017-11-27T18:23:18.691863-06:00 linhes kernel: [43555.532474] invalid opcode: 0000 [#2] PREEMPT SMP
2017-11-27T18:23:18.691898-06:00 linhes kernel: [43555.532482] Modules linked in: rpcsec_gss_krb5 nfsd auth_rpcgss nfs_acl tveeprom btcx_risc videobuf_dma_sg i2c_algo_bit videobuf_core dvb_core v4l2_common(O) videodev(O) media snd_hda_codec_hdmi ir_lirc_codec lirc_dev ir_mce_kbd_decoder ir_sanyo_decoder ir_sony_decoder nvidia(PO) ir_jvc_decoder ir_rc6_decoder ir_rc5_decoder ir_rc5_sz_decoder ir_nec_decoder snd_seq_dummy snd_seq_oss rc_streamzap snd_seq_midi_event snd_seq snd_seq_device snd_pcm_oss snd_mixer_oss snd_hda_codec_realtek snd_hda_intel snd_hda_codec alx(O) mdio compat(O) microcode i2c_piix4 powernow_k8 edac_mce_amd k10temp mperf edac_core snd_hwdep snd_pcm snd_page_alloc snd_timer snd streamzap i2c_core sp5100_tco soundcore rc_core kvm serio_raw evdev processor wmi psmouse asus_atk0110 button uvesafb hid_generic usbhid hid sr_mod cdrom sd_mod ata_generic pata_acpi ohci_hcd pata_atiixp ahci libahci ehci_hcd libata scsi_mod usbcore usb_common ext4 crc16 jbd2 mbcache jfs xfs reiserfs nfs lockd sunrpc fscache
2017-11-27T18:23:18.691941-06:00 linhes kernel: [43555.532606] CPU 0
2017-11-27T18:23:18.691960-06:00 linhes kernel: [43555.532616] Pid: 344, comm: flush-8:0 Tainted: P      D    O 3.6.7-1-ARCH #1 System manufacturer System Product Name/M5A78L-M LX3
2017-11-27T18:23:18.691969-06:00 linhes kernel: [43555.532621] RIP: 0010:[<ffffffff811b1d00>]  [<ffffffff811b1d00>] block_invalidatepage+0x100/0x110
2017-11-27T18:23:18.691974-06:00 linhes kernel: [43555.532635] RSP: 0018:ffff880222393840  EFLAGS: 00010246
2017-11-27T18:23:18.691980-06:00 linhes kernel: [43555.532640] RAX: 010000000000282c RBX: ffff880173859f38 RCX: 000000000013fe00
2017-11-27T18:23:18.691985-06:00 linhes kernel: [43555.532644] RDX: ffffea0002692740 RSI: 0000000000000000 RDI: ffffea0002692740
2017-11-27T18:23:18.691990-06:00 linhes kernel: [43555.532648] RBP: ffff880222393880 R08: 0000000000019a90 R09: ffff880237c19a90
2017-11-27T18:23:18.691995-06:00 linhes kernel: [43555.532651] R10: ffffffff8111f207 R11: 0000000000000000 R12: ffffea0002692740
2017-11-27T18:23:18.692000-06:00 linhes kernel: [43555.532655] R13: 0000000000000000 R14: 0000000000000000 R15: ffff88009cd46618
2017-11-27T18:23:18.692005-06:00 linhes kernel: [43555.532660] FS:  00007fd62cb24700(0000) GS:ffff880237c00000(0000) knlGS:0000000000000000
2017-11-27T18:23:18.692009-06:00 linhes kernel: [43555.532664] CS:  0010 DS: 0000 ES: 0000 CR0: 000000008005003b
2017-11-27T18:23:18.692014-06:00 linhes kernel: [43555.532668] CR2: 00007fd66d4d9000 CR3: 0000000221b05000 CR4: 00000000000007f0
2017-11-27T18:23:18.692019-06:00 linhes kernel: [43555.532672] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
2017-11-27T18:23:18.692024-06:00 linhes kernel: [43555.532677] DR3: 0000000000000000 DR6: 00000000ffff0ff0 DR7: 0000000000000400
2017-11-27T18:23:18.692030-06:00 linhes kernel: [43555.532681] Process flush-8:0 (pid: 344, threadinfo ffff880222392000, task ffff88022b232040)
2017-11-27T18:23:18.692033-06:00 linhes kernel: [43555.532684] Stack:
2017-11-27T18:23:18.692038-06:00 linhes kernel: [43555.532687]  ffff880222393870 ffffffff8116abc0 ffff880222393870 ffff880173859f38
2017-11-27T18:23:18.692043-06:00 linhes kernel: [43555.532695]  ffffea0002692740 0000000000000000 ffff880173859f38 ffff88009cd46618
2017-11-27T18:23:18.692048-06:00 linhes kernel: [43555.532702]  ffff8802223938b0 ffffffffa00f74fa ffff880173859f38 ffffea0002692740
2017-11-27T18:23:18.692052-06:00 linhes kernel: [43555.532709] Call Trace:
2017-11-27T18:23:18.692057-06:00 linhes kernel: [43555.532719]  [<ffffffff8116abc0>] ? kmem_cache_free+0x20/0x120
2017-11-27T18:23:18.692063-06:00 linhes kernel: [43555.532752]  [<ffffffffa00f74fa>] xfs_vm_invalidatepage+0x3a/0xb0 [xfs]
2017-11-27T18:23:18.692069-06:00 linhes kernel: [43555.532783]  [<ffffffffa00f7727>] xfs_aops_discard_page+0x57/0x160 [xfs]
2017-11-27T18:23:18.692075-06:00 linhes kernel: [43555.532813]  [<ffffffffa00f921e>] xfs_vm_writepage+0x54e/0x600 [xfs]
2017-11-27T18:23:18.692080-06:00 linhes kernel: [43555.532834]  [<ffffffffa0470833>] ? ata_qc_issue+0x173/0x380 [libata]
2017-11-27T18:23:18.692085-06:00 linhes kernel: [43555.532843]  [<ffffffff811268ba>] __writepage+0x1a/0x50
2017-11-27T18:23:18.692091-06:00 linhes kernel: [43555.532850]  [<ffffffff81126d82>] write_cache_pages+0x1f2/0x4e0
2017-11-27T18:23:18.692096-06:00 linhes kernel: [43555.532857]  [<ffffffff811268a0>] ? global_dirtyable_memory+0x40/0x40
2017-11-27T18:23:18.692101-06:00 linhes kernel: [43555.532865]  [<ffffffff811270bd>] generic_writepages+0x4d/0x70
2017-11-27T18:23:18.692107-06:00 linhes kernel: [43555.532894]  [<ffffffffa00f797a>] xfs_vm_writepages+0x4a/0x60 [xfs]
2017-11-27T18:23:18.692112-06:00 linhes kernel: [43555.532901]  [<ffffffff811288d1>] do_writepages+0x21/0x50
2017-11-27T18:23:18.692118-06:00 linhes kernel: [43555.532909]  [<ffffffff811a8ecb>] __writeback_single_inode.isra.31+0x3b/0x190
2017-11-27T18:23:18.692123-06:00 linhes kernel: [43555.532919]  [<ffffffff811a93ba>] writeback_sb_inodes+0x2ba/0x4d0
2017-11-27T18:23:18.692129-06:00 linhes kernel: [43555.532927]  [<ffffffff811a966f>] __writeback_inodes_wb+0x9f/0xd0
2017-11-27T18:23:18.692134-06:00 linhes kernel: [43555.532934]  [<ffffffff811a99b3>] wb_writeback+0x313/0x340
2017-11-27T18:23:18.692139-06:00 linhes kernel: [43555.532941]  [<ffffffff811aa7e8>] wb_do_writeback+0x268/0x270
2017-11-27T18:23:18.692144-06:00 linhes kernel: [43555.532949]  [<ffffffff811aa883>] bdi_writeback_thread+0x93/0x2d0
2017-11-27T18:23:18.692149-06:00 linhes kernel: [43555.532956]  [<ffffffff811aa7f0>] ? wb_do_writeback+0x270/0x270
2017-11-27T18:23:18.692154-06:00 linhes kernel: [43555.532964]  [<ffffffff81079a03>] kthread+0x93/0xa0
2017-11-27T18:23:18.692191-06:00 linhes kernel: [43555.532972]  [<ffffffff8149b204>] kernel_thread_helper+0x4/0x10
2017-11-27T18:23:18.692197-06:00 linhes kernel: [43555.532980]  [<ffffffff81079970>] ? kthread_freezable_should_stop+0x70/0x70
2017-11-27T18:23:18.692203-06:00 linhes kernel: [43555.532988]  [<ffffffff8149b200>] ? gs_change+0x13/0x13
2017-11-27T18:23:18.692232-06:00 linhes kernel: [43555.532990] Code: c3 66 2e 0f 1f 84 00 00 00 00 00 31 f6 48 89 d7 e8 56 b4 f6 ff eb d4 48 89 df 48 89 55 c8 e8 28 e9 ff ff 48 8b 55 c8 eb 80 0f 0b <0f> 0b 66 66 66 66 66 2e 0f 1f 84 00 00 00 00 00 55 48 89 e5 66
2017-11-27T18:23:18.692239-06:00 linhes kernel: [43555.533057] RIP  [<ffffffff811b1d00>] block_invalidatepage+0x100/0x110
2017-11-27T18:23:18.692243-06:00 linhes kernel: [43555.533064]  RSP <ffff880222393840>
2017-11-27T18:23:18.692247-06:00 linhes kernel: [43555.533070] ---[ end trace 6e58f24ef50f9813 ]---
2017-11-27T18:23:20.178475-06:00 linhes kernel: [43557.017105] kernel tried to execute NX-protected page - exploit attempt? (uid: 89)
2017-11-27T18:23:20.178513-06:00 linhes kernel: [43557.017113] BUG: unable to handle kernel paging request at ffff88007d21c890
2017-11-27T18:23:20.178520-06:00 linhes kernel: [43557.017120] IP: [<ffff88007d21c890>] 0xffff88007d21c88f
2017-11-27T18:23:20.178526-06:00 linhes kernel: [43557.017135] PGD 180d063 PUD 7d05c063 PMD 7d3ff063 PTE 800000007d21c163
2017-11-27T18:23:20.178530-06:00 linhes kernel: [43557.017144] Oops: 0011 [#3] PREEMPT SMP
2017-11-27T18:23:20.178565-06:00 linhes kernel: [43557.017152] Modules linked in: rpcsec_gss_krb5 nfsd auth_rpcgss nfs_acl tveeprom btcx_risc videobuf_dma_sg i2c_algo_bit videobuf_core dvb_core v4l2_common(O) videodev(O) media snd_hda_codec_hdmi ir_lirc_codec lirc_dev ir_mce_kbd_decoder ir_sanyo_decoder ir_sony_decoder nvidia(PO) ir_jvc_decoder ir_rc6_decoder ir_rc5_decoder ir_rc5_sz_decoder ir_nec_decoder snd_seq_dummy snd_seq_oss rc_streamzap snd_seq_midi_event snd_seq snd_seq_device snd_pcm_oss snd_mixer_oss snd_hda_codec_realtek snd_hda_intel snd_hda_codec alx(O) mdio compat(O) microcode i2c_piix4 powernow_k8 edac_mce_amd k10temp mperf edac_core snd_hwdep snd_pcm snd_page_alloc snd_timer snd streamzap i2c_core sp5100_tco soundcore rc_core kvm serio_raw evdev processor wmi psmouse asus_atk0110 button uvesafb hid_generic usbhid hid sr_mod cdrom sd_mod ata_generic pata_acpi ohci_hcd pata_atiixp ahci libahci ehci_hcd libata scsi_mod usbcore usb_common ext4 crc16 jbd2 mbcache jfs xfs reiserfs nfs lockd sunrpc fscache
2017-11-27T18:23:20.178569-06:00 linhes kernel: [43557.017269] CPU 1
2017-11-27T18:23:20.178576-06:00 linhes kernel: [43557.017279] Pid: 2214, comm: mysqld Tainted: P      D    O 3.6.7-1-ARCH #1 System manufacturer System Product Name/M5A78L-M LX3
2017-11-27T18:23:20.178583-06:00 linhes kernel: [43557.017284] RIP: 0010:[<ffff88007d21c890>]  [<ffff88007d21c890>] 0xffff88007d21c88f
2017-11-27T18:23:20.178588-06:00 linhes kernel: [43557.017294] RSP: 0018:ffff8801f770db70  EFLAGS: 00010286
2017-11-27T18:23:20.178593-06:00 linhes kernel: [43557.017298] RAX: ffff88007d21c890 RBX: ffff88020979abd0 RCX: 000000000001b16c
2017-11-27T18:23:20.178598-06:00 linhes kernel: [43557.017302] RDX: 0000000000000002 RSI: 0000000000000001 RDI: ffff88020979abd0
2017-11-27T18:23:20.178603-06:00 linhes kernel: [43557.017306] RBP: ffff8801f770dba8 R08: 000000005a1cac78 R09: 0000000000000001
2017-11-27T18:23:20.178608-06:00 linhes kernel: [43557.017309] R10: 0000000000000019 R11: 0000000000000000 R12: 0000000000000001
2017-11-27T18:23:20.178612-06:00 linhes kernel: [43557.017313] R13: 0000000000000006 R14: ffff8801f770de00 R15: ffff88020979ad20
2017-11-27T18:23:20.178617-06:00 linhes kernel: [43557.017318] FS:  00007f6d58937700(0000) GS:ffff880237c40000(0000) knlGS:0000000000000000
2017-11-27T18:23:20.178622-06:00 linhes kernel: [43557.017322] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
2017-11-27T18:23:20.178627-06:00 linhes kernel: [43557.017326] CR2: ffff88007d21c890 CR3: 00000002219d5000 CR4: 00000000000007e0
2017-11-27T18:23:20.178632-06:00 linhes kernel: [43557.017330] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
2017-11-27T18:23:20.178637-06:00 linhes kernel: [43557.017334] DR3: 0000000000000000 DR6: 00000000ffff0ff0 DR7: 0000000000000400
2017-11-27T18:23:20.178642-06:00 linhes kernel: [43557.017339] Process mysqld (pid: 2214, threadinfo ffff8801f770c000, task ffff880224aa70e0)
2017-11-27T18:23:20.178645-06:00 linhes kernel: [43557.017341] Stack:
2017-11-27T18:23:20.178650-06:00 linhes kernel: [43557.017344]  ffffffff811a78e3 ffff8801fec2a4f0 ffff88020979abd0 ffff88020adbe800
2017-11-27T18:23:20.178655-06:00 linhes kernel: [43557.017352]  0000000000000006 ffff8801f770de00 ffff88020979ad20 ffff8801f770dbd8
2017-11-27T18:23:20.178660-06:00 linhes kernel: [43557.017359]  ffffffff81199c91 ffff8801f770dbe8 ffff88020979abd0 ffff88020979abd0
2017-11-27T18:23:20.178664-06:00 linhes kernel: [43557.017366] Call Trace:
2017-11-27T18:23:20.178669-06:00 linhes kernel: [43557.017378]  [<ffffffff811a78e3>] ? __mark_inode_dirty+0x43/0x240
2017-11-27T18:23:20.178674-06:00 linhes kernel: [43557.017386]  [<ffffffff81199c91>] update_time+0x81/0xc0
2017-11-27T18:23:20.178679-06:00 linhes kernel: [43557.017393]  [<ffffffff81199d68>] file_update_time+0x98/0xf0
2017-11-27T18:23:20.178684-06:00 linhes kernel: [43557.017402]  [<ffffffff813d8bac>] ? tcp_recvmsg+0x42c/0xd90
2017-11-27T18:23:20.178690-06:00 linhes kernel: [43557.017411]  [<ffffffff8111e05c>] __generic_file_aio_write+0x17c/0x3b0
2017-11-27T18:23:20.178695-06:00 linhes kernel: [43557.017419]  [<ffffffff8111e30f>] generic_file_aio_write+0x7f/0x100
2017-11-27T18:23:20.178701-06:00 linhes kernel: [43557.017444]  [<ffffffffa022f8c9>] ext4_file_write+0xa9/0x490 [ext4]
2017-11-27T18:23:20.178706-06:00 linhes kernel: [43557.017452]  [<ffffffff8117ed37>] do_sync_write+0xa7/0xe0
2017-11-27T18:23:20.178711-06:00 linhes kernel: [43557.017461]  [<ffffffff8117f5d8>] vfs_write+0xa8/0x180
2017-11-27T18:23:20.178716-06:00 linhes kernel: [43557.017467]  [<ffffffff8117fa92>] sys_pwrite64+0x72/0xb0
2017-11-27T18:23:20.178721-06:00 linhes kernel: [43557.017475]  [<ffffffff81499fed>] system_call_fastpath+0x1a/0x1f
2017-11-27T18:23:20.178730-06:00 linhes kernel: [43557.017478] Code: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 <01> 00 00 00 00 00 00 00 00 c0 e9 e8 01 88 ff ff 02 00 00 00 40
2017-11-27T18:23:20.178736-06:00 linhes kernel: [43557.017544] RIP  [<ffff88007d21c890>] 0xffff88007d21c88f
2017-11-27T18:23:20.178739-06:00 linhes kernel: [43557.017553]  RSP <ffff8801f770db70>
2017-11-27T18:23:20.178743-06:00 linhes kernel: [43557.017555] CR2: ffff88007d21c890
2017-11-27T18:23:20.178747-06:00 linhes kernel: [43557.017561] ---[ end trace 6e58f24ef50f9814 ]---


The common thing I noticed with both logs is that there was an acpid message regarding a USB device being disconnected shortly before the lockup, when there was nothing unplugged at that time. I use a streamzap USB IR receiver which appears to then reconnect afterward. Does anybody have a suggestion on where to start troubleshooting this? When I have had lockups like this in the past I have never really had any useful logs, so this is a first for me.

Author:  knappster [ Sun Dec 31, 2017 2:47 pm ]
Post subject:  Re: R8.1 Intermittent Lockups (and a kernel oops)

I moved my USB cables to different ports and haven't experience this since, but it's only been a week or two. I presume it is a hardware issue, and hopefully only affecting certain USB ports, but time will tell...

edit: I have also upgraded to a new HDD and R8.5.1, not that I expect either of those items to impact this particular issue.

Author:  mattbatt [ Wed Jan 03, 2018 10:44 pm ]
Post subject:  Re: R8.1 Intermittent Lockups (and a kernel oops)

I'm glad to hear you found the problem.

Page 1 of 1 All times are UTC - 6 hours
Powered by phpBB® Forum Software © phpBB Group
http://www.phpbb.com/