from dmesg log, it seems that XFS loading takes much time. but I do not know where is the problem and how to fix it.
[ 1.595606] usb 1-12: New USB device found, idVendor=03f0, idProduct=334a
[ 1.595607] usb 1-12: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[ 1.595608] usb 1-12: Product: HP Business Slim Keyboard
[ 1.595609] usb 1-12: Manufacturer: Chicony
[ 1.639808] nvidia 0000:65:00.0: enabling device (0140 -> 0143)
[ 1.639870] nvidia 0000:65:00.0: vgaarb: changed VGA decodes: olddecodes=io+mem,decodes=none:owns=none
[ 1.739597] NVRM: loading NVIDIA UNIX x86_64 Kernel Module 410.79 Thu Nov 15 10:41:04 CST 2018 (using threaded interrupts)
[ 1.760998] hidraw: raw HID events driver (C) Jiri Kosina
[ 1.761668] nvidia-modeset: Loading NVIDIA Kernel Mode Setting Driver for UNIX platforms 410.79 Thu Nov 15 10:39:32 CST 2018
[ 1.762317] [drm] [nvidia-drm] [GPU ID 0x00001700] Loading driver
[ 1.762319] [drm] Initialized nvidia-drm 0.0.0 20160202 for 0000:17:00.0 on minor 0
[ 1.762396] [drm] [nvidia-drm] [GPU ID 0x00006500] Loading driver
[ 1.762397] [drm] Initialized nvidia-drm 0.0.0 20160202 for 0000:65:00.0 on minor 1
[ 1.772657] scsi host0: ahci
[ 1.772738] scsi host1: ahci
[ 1.772806] scsi host2: ahci
[ 1.772869] scsi host3: ahci
[ 1.773630] scsi host4: ahci
[ 1.773707] scsi host5: ahci
[ 1.773771] scsi host6: ahci
[ 1.773830] scsi host7: ahci
[ 1.773849] ata1: DUMMY
[ 1.773849] ata2: DUMMY
[ 1.773852] ata3: SATA max UDMA/133 abar m2048@0x9e226000 port 0x9e226200 irq 39
[ 1.773856] ata4: SATA max UDMA/133 abar m2048@0x9e226000 port 0x9e226280 irq 39
[ 1.773861] ata5: SATA max UDMA/133 abar m2048@0x9e226000 port 0x9e226300 irq 39
[ 1.773865] ata6: SATA max UDMA/133 abar m2048@0x9e226000 port 0x9e226380 irq 39
[ 1.773866] ata7: SATA max UDMA/133 abar m2048@0x9e226000 port 0x9e226400 irq 39
[ 1.773868] ata8: SATA max UDMA/133 abar m2048@0x9e226000 port 0x9e226480 irq 39
[ 1.774063] i801_smbus 0000:00:1f.4: SPD Write Disable is set
[ 1.774106] i801_smbus 0000:00:1f.4: SMBus using PCI interrupt
[ 1.776114] usbcore: registered new interface driver usbhid
[ 1.776114] usbhid: USB HID core driver
[ 1.777066] e1000e 0000:00:1f.6: Interrupt Throttling Rate (ints/sec) set to dynamic conservative mode
[ 1.778169] logitech-djreceiver 0003:046D:C52B.0003: hiddev0,hidraw0: USB HID v1.11 Device [Logitech USB Receiver] on usb-0000:00:14.0-5/input2
[ 1.778249] input: Chicony HP Business Slim Keyboard as /devices/pci0000:00/0000:00:14.0/usb1/1-12/1-12:1.0/0003:03F0:334A.0004/input/input3
[ 1.788126] tsc: Refined TSC clocksource calibration: 3599.999 MHz
[ 1.788133] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x33e4525366a, max_idle_ns: 440795257657 ns
[ 1.836302] hid-generic 0003:03F0:334A.0004: input,hidraw1: USB HID v1.10 Keyboard [Chicony HP Business Slim Keyboard] on usb-0000:00:14.0-12/input0
[ 1.836592] input: Chicony HP Business Slim Keyboard as /devices/pci0000:00/0000:00:14.0/usb1/1-12/1-12:1.1/0003:03F0:334A.0005/input/input4
[ 1.852367] e1000e 0000:00:1f.6 0000:00:1f.6 (uninitialized): registered PHC clock
[ 1.896249] hid-generic 0003:03F0:334A.0005: input,hiddev1,hidraw2: USB HID v1.10 Device [Chicony HP Business Slim Keyboard] on usb-0000:00:14.0-12/input1
[ 1.917322] e1000e 0000:00:1f.6 eth0: (PCI Express:2.5GT/s:Width x1) 0c:c4:7a:e7:cd:b7
[ 1.917324] e1000e 0000:00:1f.6 eth0: Intel(R) PRO/1000 Network Connection
[ 1.917425] e1000e 0000:00:1f.6 eth0: MAC: 12, PHY: 12, PBA No: 0100FF-0FF
[ 1.918020] e1000e 0000:00:1f.6 rename3: renamed from eth0
[ 2.086668] ata5: SATA link down (SStatus 0 SControl 300)
[ 2.086783] ata3: SATA link up 6.0 Gbps (SStatus 133 SControl 300)
[ 2.087535] ata3.00: supports DRM functions and may not be fully accessible
[ 2.087559] ata3.00: ATA-10: CT500MX500SSD1, M3CR010, max UDMA/133
[ 2.087560] ata3.00: 976773168 sectors, multi 1: LBA48 NCQ (depth 31/32), AA
[ 2.087885] ata4: SATA link up 6.0 Gbps (SStatus 133 SControl 300)
[ 2.087963] ata6: SATA link down (SStatus 0 SControl 300)
[ 2.088156] ata3.00: supports DRM functions and may not be fully accessible
[ 2.088665] ata3.00: configured for UDMA/133
[ 2.088892] scsi 2:0:0:0: Direct-Access ATA CT500MX500SSD1 010 PQ: 0 ANSI: 5
[ 2.089103] sd 2:0:0:0: Attached scsi generic sg0 type 0
[ 2.089119] sd 2:0:0:0: [sda] 976773168 512-byte logical blocks: (500 GB/466 GiB)
[ 2.089120] sd 2:0:0:0: [sda] 4096-byte physical blocks
[ 2.089124] sd 2:0:0:0: [sda] Write Protect is off
[ 2.089125] sd 2:0:0:0: [sda] Mode Sense: 00 3a 00 00
[ 2.089131] sd 2:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[ 2.089366] sda: sda1
[ 2.089746] sd 2:0:0:0: [sda] supports TCG Opal
[ 2.089747] sd 2:0:0:0: [sda] Attached SCSI disk
[ 2.090808] ata8: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
[ 2.091866] ata7: SATA link down (SStatus 0 SControl 300)
[ 2.094081] ata8.00: ATAPI: ATAPI iHAS324 F, CL8N, max UDMA/133
[ 2.094977] ata8.00: configured for UDMA/133
[ 2.231563] ata4.00: ATA-8: TOSHIBA MG04ACA400E, FP3B, max UDMA/100
[ 2.231564] ata4.00: 7814037168 sectors, multi 16: LBA48 NCQ (depth 31/32), AA
[ 2.232837] ata4.00: configured for UDMA/100
[ 2.233159] scsi 3:0:0:0: Direct-Access ATA TOSHIBA MG04ACA4 FP3B PQ: 0 ANSI: 5
[ 2.233368] sd 3:0:0:0: [sdb] 7814037168 512-byte logical blocks: (4.00 TB/3.64 TiB)
[ 2.233369] sd 3:0:0:0: [sdb] 4096-byte physical blocks
[ 2.233375] sd 3:0:0:0: [sdb] Write Protect is off
[ 2.233376] sd 3:0:0:0: [sdb] Mode Sense: 00 3a 00 00
[ 2.233384] sd 3:0:0:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[ 2.233389] sd 3:0:0:0: Attached scsi generic sg1 type 0
[ 2.238240] scsi 7:0:0:0: CD-ROM ATAPI iHAS324 F CL8N PQ: 0 ANSI: 5
[ 2.309568] sr 7:0:0:0: [sr0] scsi3-mmc drive: 48x/48x writer cd/rw xa/form2 cdda tray
[ 2.309569] cdrom: Uniform CD-ROM driver Revision: 3.20
[ 2.309699] sr 7:0:0:0: Attached scsi CD-ROM sr0
[ 2.309769] sr 7:0:0:0: Attached scsi generic sg2 type 5
[ 2.476687] sdb: sdb1
[ 2.476946] sd 3:0:0:0: [sdb] Attached SCSI disk
[ 2.614163] random: fast init done
[ 2.812310] clocksource: Switched to clocksource tsc
[ 2.909946] input: Logitech Wireless Mouse as /devices/pci0000:00/0000:00:14.0/usb1/1-5/1-5:1.2/0003:046D:C52B.0003/0003:046D:4055.0006/input/input5
[ 2.910022] random: systemd-udevd: uninitialized urandom read (16 bytes read)
[ 2.910047] random: systemd-udevd: uninitialized urandom read (16 bytes read)
[ 2.910061] random: systemd-udevd: uninitialized urandom read (16 bytes read)
[ 2.910094] logitech-hidpp-device 0003:046D:4055.0006: input,hidraw3: USB HID v1.11 Mouse [Logitech Wireless Mouse] on usb-0000:00:14.0-5:1
[ 92.285063] SGI XFS with ACLs, security attributes, realtime, no debug enabled
[ 92.286703] XFS (sda1): Mounting V5 Filesystem
[ 92.299585] XFS (sda1): Ending clean mount
[ 92.385512] ip_tables: (C) 2000-2006 Netfilter Core Team
[ 92.392141] systemd[1]: systemd 237 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD -IDN2 +IDN -PCRE2 default-hierarchy=hybrid)
[ 92.412734] systemd[1]: Detected architecture x86-64.
[ 92.415357] systemd[1]: Set hostname to.
[ 92.483938] systemd[1]: Created slice System Slice.
[ 92.484019] systemd[1]: Listening on udev Control Socket.
[ 92.484114] systemd[1]: Listening on Journal Audit Socket.
[ 92.484153] systemd[1]: Listening on Journal Socket (/dev/log).
[ 92.484288] systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point.
[ 92.484314] systemd[1]: Listening on /dev/initctl Compatibility Named Pipe.
[ 92.484353] systemd[1]: Listening on Journal Socket.
[ 92.498239] lp: driver loaded but no devices found
[ 92.510111] ppdev: user-space parallel port driver
[ 92.540172] systemd-journald[311]: Received request to flush runtime journal from PID 1
[ 92.634326] Adding 2097148k swap on /swapfile. Priority:-2 extents:1 across:2097148k SSFS
[ 92.640386] nvidia-uvm: Loaded the UVM driver in 8 mode, major device number 236
[ 92.640445] dca service started, version 1.12.1
[ 92.643402] ioatdma: Intel(R) QuickData Technology Driver 4.00
[ 92.684016] intel-lpss 0000:00:15.0: enabling device (0000 -> 0002)
[ 92.717857] idma64 idma64.0: Found Intel integrated DMA 64-bit
[ 92.740020] intel-lpss 0000:00:15.1: enabling device (0000 -> 0002)
[ 92.740306] idma64 idma64.1: Found Intel integrated DMA 64-bit
[ 92.744333] mei_me 0000:00:16.0: enabling device (0000 -> 0002)
[ 92.842132] RAPL PMU: API unit is 2^-32 Joules, 3 fixed counters, 655360 ms ovfl timer
[ 92.842133] RAPL PMU: hw unit of domain pp0-core 2^-14 Joules
[ 92.842134] RAPL PMU: hw unit of domain package 2^-14 Joules
[ 92.842134] RAPL PMU: hw unit of domain dram 2^-16 Joules
[ 92.844806] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4
[ 92.855172] snd_hda_intel 0000:17:00.1: Disabling MSI
[ 92.855192] snd_hda_intel 0000:17:00.1: Handle vga_switcheroo audio client
[ 92.865199] snd_hda_intel 0000:65:00.1: Disabling MSI
[ 92.865234] snd_hda_intel 0000:65:00.1: Handle vga_switcheroo audio client
[ 92.878666] snd_hda_codec_realtek hdaudioC0D0: autoconfig for ALC1220: line_outs=3 (0x14/0x15/0x16/0x0/0x0) type:line
[ 92.878668] snd_hda_codec_realtek hdaudioC0D0: speaker_outs=0 (0x0/0x0/0x0/0x0/0x0)
[ 92.878669] snd_hda_codec_realtek hdaudioC0D0: hp_outs=1 (0x1b/0x0/0x0/0x0/0x0)
[ 92.878670] snd_hda_codec_realtek hdaudioC0D0: mono: mono_out=0x0
[ 92.878671] snd_hda_codec_realtek hdaudioC0D0: dig-out=0x1e/0x0
[ 92.878671] snd_hda_codec_realtek hdaudioC0D0: inputs:
[ 92.878672] snd_hda_codec_realtek hdaudioC0D0: Front Mic=0x19
[ 92.878673] snd_hda_codec_realtek hdaudioC0D0: Rear Mic=0x18
[ 92.878674] snd_hda_codec_realtek hdaudioC0D0: Line=0x1a
[ 92.889995] input: HDA Intel PCH Front Mic as /devices/pci0000:00/0000:00:1f.3/sound/card0/input6
[ 92.890037] input: HDA Intel PCH Rear Mic as /devices/pci0000:00/0000:00:1f.3/sound/card0/input7
[ 92.890074] input: HDA Intel PCH Line as /devices/pci0000:00/0000:00:1f.3/sound/card0/input8
[ 92.890110] input: HDA Intel PCH Line Out Front as /devices/pci0000:00/0000:00:1f.3/sound/card0/input9
[ 92.890145] input: HDA Intel PCH Line Out Surround as /devices/pci0000:00/0000:00:1f.3/sound/card0/input10
[ 92.890181] input: HDA Intel PCH Line Out CLFE as /devices/pci0000:00/0000:00:1f.3/sound/card0/input11
[ 92.890217] input: HDA Intel PCH Front Headphone as /devices/pci0000:00/0000:00:1f.3/sound/card0/input12
[ 92.937118] random: crng init done
[ 92.937119] random: 7 urandom warning(s) missed due to ratelimiting
[ 93.023399] AVX2 version of gcm_enc/dec engaged.
[ 93.023400] AES CTR mode by8 optimization enabled
[ 93.155686] resource sanity check: requesting [mem 0x000c0000-0x000fffff], which spans more than PCI Bus 0000:00 [mem 0x000c4000-0x000c7fff window]
[ 93.155820] caller os_map_kernel_space.part.7+0xda/0x120 [nvidia] mapping multiple BARs
[ 93.156719] EDAC MC0: Giving out device to module skx_edac controller Skylake Socket#0 IMC#0: DEV 0000:64:0a.0 (INTERRUPT)
[ 93.157204] EDAC MC1: Giving out device to module skx_edac controller Skylake Socket#0 IMC#1: DEV 0000:64:0c.0 (INTERRUPT)
[ 93.158757] intel_rapl: Found RAPL domain package
[ 93.158760] intel_rapl: Found RAPL domain dram
[ 93.158761] intel_rapl: DRAM domain energy unit 15300pj
[ 93.339909] XFS (sdb1): Mounting V5 Filesystem
[ 93.459949] XFS (sdb1): Ending clean mount
Postscript 1
I found the 90s delay seems to be happening at random step. following is another dmesg log after reboot.
[ 2.129405] ata3.00: ATA-10: CT500MX500SSD1, M3CR010, max UDMA/133
[ 2.129408] ata3.00: 976773168 sectors, multi 1: LBA48 NCQ (depth 31/32), AA
[ 2.130038] ata3.00: supports DRM functions and may not be fully accessible
[ 2.130560] ata3.00: configured for UDMA/133
[ 2.130873] scsi 2:0:0:0: Direct-Access ATA CT500MX500SSD1 010 PQ: 0 ANSI: 5
[ 2.131069] sd 2:0:0:0: Attached scsi generic sg0 type 0
[ 2.131097] sd 2:0:0:0: [sda] 976773168 512-byte logical blocks: (500 GB/466 GiB)
[ 2.131098] sd 2:0:0:0: [sda] 4096-byte physical blocks
[ 2.131102] sd 2:0:0:0: [sda] Write Protect is off
[ 2.131103] sd 2:0:0:0: [sda] Mode Sense: 00 3a 00 00
[ 2.131109] sd 2:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[ 2.131355] sda: sda1
[ 2.131703] sd 2:0:0:0: [sda] supports TCG Opal
[ 2.131704] sd 2:0:0:0: [sda] Attached SCSI disk
[ 2.131879] ata8.00: ATAPI: ATAPI iHAS324 F, CL8N, max UDMA/133
[ 2.132767] ata8.00: configured for UDMA/133
[ 2.270514] ata4.00: ATA-8: TOSHIBA MG04ACA400E, FP3B, max UDMA/100
[ 2.270517] ata4.00: 7814037168 sectors, multi 16: LBA48 NCQ (depth 31/32), AA
[ 2.271786] ata4.00: configured for UDMA/100
[ 2.272080] scsi 3:0:0:0: Direct-Access ATA TOSHIBA MG04ACA4 FP3B PQ: 0 ANSI: 5
[ 2.272292] sd 3:0:0:0: [sdb] 7814037168 512-byte logical blocks: (4.00 TB/3.64 TiB)
[ 2.272293] sd 3:0:0:0: [sdb] 4096-byte physical blocks
[ 2.272298] sd 3:0:0:0: [sdb] Write Protect is off
[ 2.272299] sd 3:0:0:0: [sdb] Mode Sense: 00 3a 00 00
[ 2.272306] sd 3:0:0:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[ 2.272307] sd 3:0:0:0: Attached scsi generic sg1 type 0
[ 2.276699] scsi 7:0:0:0: CD-ROM ATAPI iHAS324 F CL8N PQ: 0 ANSI: 5
[ 2.341578] sr 7:0:0:0: [sr0] scsi3-mmc drive: 48x/48x writer cd/rw xa/form2 cdda tray
[ 2.341580] cdrom: Uniform CD-ROM driver Revision: 3.20
[ 2.341813] sr 7:0:0:0: Attached scsi CD-ROM sr0
[ 2.341882] sr 7:0:0:0: Attached scsi generic sg2 type 5
[ 2.515745] sdb: sdb1
[ 2.516041] sd 3:0:0:0: [sdb] Attached SCSI disk
[ 2.622700] random: fast init done
[ 2.812315] clocksource: Switched to clocksource tsc
[ 92.283820] random: systemd-udevd: uninitialized urandom read (16 bytes read)
[ 92.283827] random: systemd-udevd: uninitialized urandom read (16 bytes read)
[ 92.284013] random: systemd-udevd: uninitialized urandom read (16 bytes read)
[ 92.312310] SGI XFS with ACLs, security attributes, realtime, no debug enabled
[ 92.313724] XFS (sda1): Mounting V5 Filesystem
[ 92.327122] XFS (sda1): Ending clean mount
[ 92.412761] ip_tables: (C) 2000-2006 Netfilter Core Team
[ 92.419405] systemd[1]: systemd 237 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD -IDN2 +IDN -PCRE2 default-hierarchy=hybrid)
[ 92.436552] systemd[1]: Detected architecture x86-64.
[ 92.439092] systemd[1]: Set hostname to.
[ 92.525609] systemd[1]: Reached target User and Group Name Lookups.
[ 92.525669] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
[ 92.525771] systemd[1]: Created slice System Slice.
[ 92.525851] systemd[1]: Listening on Journal Audit Socket.
[ 92.525856] systemd[1]: Reached target Remote File Systems.
[ 92.525883] systemd[1]: Listening on /dev/initctl Compatibility Named Pipe.
[ 92.525926] systemd[1]: Listening on Journal Socket (/dev/log).
[ 92.543157] lp: driver loaded but no devices found
[ 92.545006] ppdev: user-space parallel port driver
[ 92.607847] systemd-journald[318]: Received request to flush runtime journal from PID 1
[ 92.641108] nvidia-uvm: Loaded the UVM driver in 8 mode, major device number 236
[ 92.673815] dca service started, version 1.12.1
[ 92.674554] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4
[ 92.677204] ioatdma: Intel(R) QuickData Technology Driver 4.00
[ 92.679547] Adding 2097148k swap on /swapfile. Priority:-2 extents:1 across:2097148k SSFS
[ 92.696021] intel-lpss 0000:00:15.0: enabling device (0000 -> 0002)
[ 92.869945] idma64 idma64.0: Found Intel integrated DMA 64-bit
[ 92.896030] intel-lpss 0000:00:15.1: enabling device (0000 -> 0002)
[ 92.896295] idma64 idma64.1: Found Intel integrated DMA 64-bit
[ 92.918134] mei_me 0000:00:16.0: enabling device (0000 -> 0002)
[ 92.928247] snd_hda_intel 0000:17:00.1: Disabling MSI
[ 92.928268] snd_hda_intel 0000:17:00.1: Handle vga_switcheroo audio client
[ 92.928379] snd_hda_intel 0000:65:00.1: Disabling MSI
[ 92.928393] snd_hda_intel 0000:65:00.1: Handle vga_switcheroo audio client
No comments:
Post a Comment