You are not logged in.

#1 2013-05-07 06:14:21

Baryon
Member
Registered: 2011-08-12
Posts: 63

[Solved] Login delay

My system boots into runlevel 3, and every time I log in (regardless of which user I log in as), there is a delay of about 15 seconds, after typing the password, before I get anything.
I thought that this might be the wireless network process running in the background and stalling things, but I tried disabling netctl and it didn't fix it.

Not sure what commands are relevant, but here is systemd-analyze.

$ systemd-analyze; systemd-analyze blame
Startup finished in 3.957s (kernel) + 16.942s (userspace) = 20.900s
          7.558s polkit.service
          5.507s netctl-auto@wlan0.service
          4.653s systemd-logind.service
          3.579s systemd-vconsole-setup.service
          3.232s laptop-mode.service
          2.854s systemd-modules-load.service
          2.405s dev-mqueue.mount
          2.357s sys-kernel-debug.mount
          2.322s systemd-binfmt.service
          2.214s systemd-remount-fs.service
          2.201s dev-hugepages.mount
          1.906s systemd-udev-trigger.service
          1.833s proc-sys-fs-binfmt_misc.mount
          1.785s systemd-static-nodes.service
          1.761s tmp.mount
          1.180s rtkit-daemon.service
           590ms systemd-sysctl.service
           576ms systemd-journal-flush.service
           510ms mnt-win.mount
           477ms systemd-random-seed-load.service
           340ms atd.service
           306ms systemd-user-sessions.service
           191ms systemd-udevd.service
           146ms udisks.service
           121ms dev-sda6.swap
            96ms console-kit-log-system-start.service
            73ms systemd-tmpfiles-setup.service
            53ms alsa-restore.service
            31ms ntpd.service
             2ms sys-kernel-config.mount

Last edited by Baryon (2013-05-21 16:35:12)

Offline

#2 2013-05-07 06:55:44

Army
Member
Registered: 2007-12-07
Posts: 1,784

Re: [Solved] Login delay

Do you happen to use zsh as your shell and /etc/hosts to block ads? If so, do you use grml-zsh-config? That would explain the huge delay, because grml's config reads the hosts file.

Offline

#3 2013-05-07 07:08:15

Baryon
Member
Registered: 2011-08-12
Posts: 63

Re: [Solved] Login delay

No, I don't. I use bash. Thanks anyway!

Offline

#4 2013-05-07 08:26:12

mar04
Member
From: Poland
Registered: 2010-02-08
Posts: 117

Re: [Solved] Login delay

I would look in your .profile or .bashrc for potential problems.

Offline

#5 2013-05-07 08:51:19

Baryon
Member
Registered: 2011-08-12
Posts: 63

Re: [Solved] Login delay

Deleted .bashrc temporarily but it didn't change anything. I should point out that the problem only happens the first time I log in, not subsequent times. I'm fairly sure there is some startup process which is stalling the login, but I'm not sure how to identify it or find out what it's doing.

Offline

#6 2013-05-07 11:12:08

skanky
Member
From: WAIS
Registered: 2009-10-23
Posts: 1,845

Re: [Solved] Login delay

I forget what it's called and have to rush off, but there is a systemd analyze option or extra functionality that will graph the start-up and show what units are waiting for each others, etc...
If no one else has suggested the name by the time I return, I'll look it up.


"...one cannot be angry when one looks at a penguin."  - John Ruskin
"Life in general is a bit shit, and so too is the internet. And that's all there is." - scepticisle

Offline

#7 2013-05-07 11:19:09

flipper T
Member
Registered: 2012-09-14
Posts: 419

Re: [Solved] Login delay

systemd-analyze plot >plot.jpg

is what you are looking for. You can change .jpg to .svg if desired.


If I'm curt with you it's because time is a factor. I think fast, I talk fast and I need you guys to act fast if you wanna get out of this. So, pretty please... with sugar on top. Clean the [censored] car. -The Wolf

Offline

#8 2013-05-07 11:45:42

skanky
Member
From: WAIS
Registered: 2009-10-23
Posts: 1,845

Re: [Solved] Login delay

flipper T wrote:
systemd-analyze plot >plot.jpg

is what you are looking for. You can change .jpg to .svg if desired.

Thanks. smile


"...one cannot be angry when one looks at a penguin."  - John Ruskin
"Life in general is a bit shit, and so too is the internet. And that's all there is." - scepticisle

Offline

#9 2013-05-07 13:10:03

Baryon
Member
Registered: 2011-08-12
Posts: 63

Re: [Solved] Login delay

Thanks, OK... I rebooted and made a plot and it seems like sys-kernel-config.mount hangs for about 3 seconds, then sound.target hangs for another 3 seconds although they're both activated within milliseconds. But that doesn't account for the ~15 seconds or more that I have to wait to actually log in. I tried disabling laptop-mode, too, but that didn't work either.

Last edited by Baryon (2013-05-07 13:10:37)

Offline

#10 2013-05-07 13:19:39

skanky
Member
From: WAIS
Registered: 2009-10-23
Posts: 1,845

Re: [Solved] Login delay

Does the delay happen if you wait a while (>15 seconds) before logging on?


"...one cannot be angry when one looks at a penguin."  - John Ruskin
"Life in general is a bit shit, and so too is the internet. And that's all there is." - scepticisle

Offline

#11 2013-05-07 13:29:20

Baryon
Member
Registered: 2011-08-12
Posts: 63

Re: [Solved] Login delay

skanky wrote:

Does the delay happen if you wait a while (>15 seconds) before logging on?

No, you're right. I can wait it out. So it's not connected to actually logging on, it must be a startup process of some kind.

Offline

#12 2013-05-07 13:30:18

65kid
Member
From: Germany
Registered: 2011-01-26
Posts: 663

Re: [Solved] Login delay

This may not cause the problem, but you should uninstall consolekit. Also "journalctl -b" would be nice. wink

Offline

#13 2013-05-07 13:35:13

skanky
Member
From: WAIS
Registered: 2009-10-23
Posts: 1,845

Re: [Solved] Login delay

Baryon wrote:
skanky wrote:

Does the delay happen if you wait a while (>15 seconds) before logging on?

No, you're right. I can wait it out. So it's not connected to actually logging on, it must be a startup process of some kind.

As well as the journal, it might also be worth posting the analyze plot, if you're happy to.


"...one cannot be angry when one looks at a penguin."  - John Ruskin
"Life in general is a bit shit, and so too is the internet. And that's all there is." - scepticisle

Offline

#14 2013-05-07 13:43:41

Baryon
Member
Registered: 2011-08-12
Posts: 63

Re: [Solved] Login delay

Link to the plot.

# journalctl -b
-- Logs begin at Mon 2012-11-05 13:28:04 GMT, end at Tue 2013-05-07 14:52:18 BST. --
May 07 14:50:58 cogitate systemd-journal[91]: Allowing runtime journal files to grow to 50.2M.
May 07 14:50:58 cogitate kernel: Initializing cgroup subsys cpuset
May 07 14:50:58 cogitate kernel: Initializing cgroup subsys cpu
May 07 14:50:58 cogitate kernel: Linux version 3.8.11-1-ARCH (tobias@testing) (gcc version 4.8.0 20130425 (prerelease) (GCC) ) #1 SMP PREEMPT Thu May 2 17:52:13 CEST 2013
May 07 14:50:58 cogitate kernel: e820: BIOS-provided physical RAM map:
May 07 14:50:58 cogitate kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009efff] usable
May 07 14:50:58 cogitate kernel: BIOS-e820: [mem 0x000000000009f000-0x000000000009ffff] reserved
May 07 14:50:58 cogitate kernel: BIOS-e820: [mem 0x00000000000d2000-0x00000000000d3fff] reserved
May 07 14:50:58 cogitate kernel: BIOS-e820: [mem 0x00000000000dc000-0x00000000000fffff] reserved
May 07 14:50:58 cogitate kernel: BIOS-e820: [mem 0x0000000000100000-0x000000003ff5ffff] usable
May 07 14:50:58 cogitate kernel: BIOS-e820: [mem 0x000000003ff60000-0x000000003ff76fff] ACPI data
May 07 14:50:58 cogitate kernel: BIOS-e820: [mem 0x000000003ff77000-0x000000003ff78fff] ACPI NVS
May 07 14:50:58 cogitate kernel: BIOS-e820: [mem 0x000000003ff80000-0x000000003fffffff] reserved
May 07 14:50:58 cogitate kernel: BIOS-e820: [mem 0x00000000ff800000-0x00000000ffffffff] reserved
May 07 14:50:58 cogitate kernel: Notice: NX (Execute Disable) protection missing in CPU!
May 07 14:50:58 cogitate kernel: SMBIOS 2.3 present.
May 07 14:50:58 cogitate kernel: DMI: IBM 2373GEG/2373GEG, BIOS 1RETDRWW (3.23 ) 06/18/2007
May 07 14:50:58 cogitate kernel: e820: update [mem 0x00000000-0x0000ffff] usable ==> reserved
May 07 14:50:58 cogitate kernel: e820: remove [mem 0x000a0000-0x000fffff] usable
May 07 14:50:58 cogitate kernel: e820: last_pfn = 0x3ff60 max_arch_pfn = 0x100000
May 07 14:50:58 cogitate kernel: MTRR default type: uncachable
May 07 14:50:58 cogitate kernel: MTRR fixed ranges enabled:
May 07 14:50:58 cogitate kernel:   00000-9FFFF write-back
May 07 14:50:58 cogitate kernel:   A0000-BFFFF uncachable
May 07 14:50:58 cogitate kernel:   C0000-CFFFF write-protect
May 07 14:50:58 cogitate kernel:   D0000-DBFFF uncachable
May 07 14:50:58 cogitate kernel:   DC000-DFFFF write-back
May 07 14:50:58 cogitate kernel:   E0000-FFFFF write-protect
May 07 14:50:58 cogitate kernel: MTRR variable ranges enabled:
May 07 14:50:58 cogitate kernel:   0 base 000000000 mask FC0000000 write-back
May 07 14:50:58 cogitate kernel:   1 base 03FF80000 mask FFFF80000 uncachable
May 07 14:50:58 cogitate kernel:   2 disabled
May 07 14:50:58 cogitate kernel:   3 disabled
May 07 14:50:58 cogitate kernel:   4 disabled
May 07 14:50:58 cogitate kernel:   5 disabled
May 07 14:50:58 cogitate kernel:   6 disabled
May 07 14:50:58 cogitate kernel:   7 disabled
May 07 14:50:58 cogitate kernel: PAT not supported by CPU.
May 07 14:50:58 cogitate kernel: initial memory mapped: [mem 0x00000000-0x00bfffff]
May 07 14:50:58 cogitate kernel: Base memory trampoline at [c009b000] 9b000 size 16384
May 07 14:50:58 cogitate kernel: init_memory_mapping: [mem 0x00000000-0x377fdfff]
May 07 14:50:58 cogitate kernel:  [mem 0x00000000-0x003fffff] page 4k
May 07 14:50:58 cogitate kernel:  [mem 0x00400000-0x373fffff] page 2M
May 07 14:50:58 cogitate kernel:  [mem 0x37400000-0x377fdfff] page 4k
May 07 14:50:58 cogitate kernel: kernel direct mapping tables up to 0x377fdfff @ [mem 0x00bfa000-0x00bfffff]
May 07 14:50:58 cogitate kernel: RAMDISK: [mem 0x3fc86000-0x3ff4ffff]
May 07 14:50:58 cogitate kernel: Allocated new RAMDISK: [mem 0x37534000-0x377fde38]
May 07 14:50:58 cogitate kernel: Move RAMDISK from [mem 0x3fc86000-0x3ff4fe38] to [mem 0x37534000-0x377fde38]
May 07 14:50:58 cogitate kernel: ACPI: RSDP 000f6d70 00024 (v02 IBM   )
May 07 14:50:58 cogitate kernel: ACPI: XSDT 3ff6a672 0004C (v01 IBM    TP-1R    00003230  LTP 00000000)
May 07 14:50:58 cogitate kernel: ACPI: FACP 3ff6a700 000F4 (v03 IBM    TP-1R    00003230 IBM  00000001)
May 07 14:50:58 cogitate kernel: ACPI BIOS Bug: Warning: 32/64X length mismatch in FADT/Gpe1Block: 0/32 (20121018/tbfadt-567)
May 07 14:50:58 cogitate kernel: ACPI BIOS Bug: Warning: Optional FADT field Gpe1Block has zero address or length: 0x000000000000102C/0x0 (20121018/tbfadt-598)
May 07 14:50:58 cogitate kernel: ACPI: DSDT 3ff6a8e7 0C530 (v01 IBM    TP-1R    00003230 MSFT 0100000E)
May 07 14:50:58 cogitate kernel: ACPI: FACS 3ff78000 00040
May 07 14:50:58 cogitate kernel: ACPI: SSDT 3ff6a8b4 00033 (v01 IBM    TP-1R    00003230 MSFT 0100000E)
May 07 14:50:58 cogitate kernel: ACPI: ECDT 3ff76e17 00052 (v01 IBM    TP-1R    00003230 IBM  00000001)
May 07 14:50:58 cogitate kernel: ACPI: TCPA 3ff76e69 00032 (v01 IBM    TP-1R    00003230 PTL  00000001)
May 07 14:50:58 cogitate kernel: ACPI: BOOT 3ff76fd8 00028 (v01 IBM    TP-1R    00003230  LTP 00000001)
May 07 14:50:58 cogitate kernel: 135MB HIGHMEM available.
May 07 14:50:58 cogitate kernel: 887MB LOWMEM available.
May 07 14:50:58 cogitate kernel:   mapped low ram: 0 - 377fe000
May 07 14:50:58 cogitate kernel:   low ram: 0 - 377fe000
May 07 14:50:58 cogitate kernel: Zone ranges:
May 07 14:50:58 cogitate kernel:   DMA      [mem 0x00010000-0x00ffffff]
May 07 14:50:58 cogitate kernel:   Normal   [mem 0x01000000-0x377fdfff]
May 07 14:50:58 cogitate kernel:   HighMem  [mem 0x377fe000-0x3ff5ffff]
May 07 14:50:58 cogitate kernel: Movable zone start for each node
May 07 14:50:58 cogitate kernel: Early memory node ranges
May 07 14:50:58 cogitate kernel:   node   0: [mem 0x00010000-0x0009efff]
May 07 14:50:58 cogitate kernel:   node   0: [mem 0x00100000-0x3ff5ffff]
May 07 14:50:58 cogitate kernel: On node 0 totalpages: 261871
May 07 14:50:58 cogitate kernel: free_area_init_node: node 0, pgdat c0698800, node_mem_map f6d33200
May 07 14:50:58 cogitate kernel:   DMA zone: 32 pages used for memmap
May 07 14:50:58 cogitate kernel:   DMA zone: 0 pages reserved
May 07 14:50:58 cogitate kernel:   DMA zone: 3951 pages, LIFO batch:0
May 07 14:50:58 cogitate kernel:   Normal zone: 1744 pages used for memmap
May 07 14:50:58 cogitate kernel:   Normal zone: 221486 pages, LIFO batch:31
May 07 14:50:58 cogitate kernel:   HighMem zone: 271 pages used for memmap
May 07 14:50:58 cogitate kernel:   HighMem zone: 34387 pages, LIFO batch:7
May 07 14:50:58 cogitate kernel: Using APIC driver default
May 07 14:50:58 cogitate kernel: ACPI: PM-Timer IO Port: 0x1008
May 07 14:50:58 cogitate kernel: smpboot: Allowing 1 CPUs, 0 hotplug CPUs
May 07 14:50:58 cogitate kernel: Local APIC disabled by BIOS -- you can enable it with "lapic"
May 07 14:50:58 cogitate kernel: APIC: disable apic facility
May 07 14:50:58 cogitate kernel: APIC: switched to apic NOOP
May 07 14:50:58 cogitate kernel: nr_irqs_gsi: 16
May 07 14:50:58 cogitate kernel: PM: Registered nosave memory: 000000000009f000 - 00000000000a0000
May 07 14:50:58 cogitate kernel: PM: Registered nosave memory: 00000000000a0000 - 00000000000d2000
May 07 14:50:58 cogitate kernel: PM: Registered nosave memory: 00000000000d2000 - 00000000000d4000
May 07 14:50:58 cogitate kernel: PM: Registered nosave memory: 00000000000d4000 - 00000000000dc000
May 07 14:50:58 cogitate kernel: PM: Registered nosave memory: 00000000000dc000 - 0000000000100000
May 07 14:50:58 cogitate kernel: e820: [mem 0x40000000-0xff7fffff] available for PCI devices
May 07 14:50:58 cogitate kernel: Booting paravirtualized kernel on bare hardware
May 07 14:50:58 cogitate kernel: setup_percpu: NR_CPUS:8 nr_cpumask_bits:8 nr_cpu_ids:1 nr_node_ids:1
May 07 14:50:58 cogitate kernel: PERCPU: Embedded 13 pages/cpu @f6d1f000 s32704 r0 d20544 u53248
May 07 14:50:58 cogitate kernel: pcpu-alloc: s32704 r0 d20544 u53248 alloc=13*4096
May 07 14:50:58 cogitate kernel: pcpu-alloc: [0] 0 
May 07 14:50:58 cogitate kernel: Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 259824
May 07 14:50:58 cogitate kernel: Kernel command line: root=/dev/sda5 ro quiet
May 07 14:50:58 cogitate kernel: PID hash table entries: 4096 (order: 2, 16384 bytes)
May 07 14:50:58 cogitate kernel: Dentry cache hash table entries: 131072 (order: 7, 524288 bytes)
May 07 14:50:58 cogitate kernel: Inode-cache hash table entries: 65536 (order: 6, 262144 bytes)
May 07 14:50:58 cogitate kernel: __ex_table already sorted, skipping sort
May 07 14:50:58 cogitate kernel: Initializing CPU#0
May 07 14:50:58 cogitate kernel: allocated 2095744 bytes of page_cgroup
May 07 14:50:58 cogitate kernel: please try 'cgroup_disable=memory' option if you don't want memory cgroups
May 07 14:50:58 cogitate kernel: Initializing HighMem for node 0 (000377fe:0003ff60)
May 07 14:50:58 cogitate kernel: Memory: 1026200k/1047936k available (4118k kernel code, 21284k reserved, 1670k data, 556k init, 138632k highmem)
May 07 14:50:58 cogitate kernel: [379B blob data]
May 07 14:50:58 cogitate kernel: Checking if this processor honours the WP bit even in supervisor mode...Ok.
May 07 14:50:58 cogitate kernel: SLUB: Genslabs=15, HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
May 07 14:50:58 cogitate kernel: Preemptible hierarchical RCU implementation.
May 07 14:50:58 cogitate kernel:         RCU dyntick-idle grace-period acceleration is enabled.
May 07 14:50:58 cogitate kernel:         Dump stacks of tasks blocking RCU-preempt GP.
May 07 14:50:58 cogitate kernel:         RCU restricting CPUs from NR_CPUS=8 to nr_cpu_ids=1.
May 07 14:50:58 cogitate kernel: NR_IRQS:2304 nr_irqs:256 16
May 07 14:50:58 cogitate kernel: CPU 0 irqstacks, hard=f6406000 soft=f6408000
May 07 14:50:58 cogitate kernel: Extended CMOS year: 2000
May 07 14:50:58 cogitate kernel: Console: colour VGA+ 80x25
May 07 14:50:58 cogitate kernel: console [tty0] enabled
May 07 14:50:58 cogitate kernel: tsc: Fast TSC calibration using PIT
May 07 14:50:58 cogitate kernel: tsc: Detected 1698.684 MHz processor
May 07 14:50:58 cogitate kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 3398.92 BogoMIPS (lpj=5662280)
May 07 14:50:58 cogitate kernel: pid_max: default: 32768 minimum: 301
May 07 14:50:58 cogitate kernel: Security Framework initialized
May 07 14:50:58 cogitate kernel: AppArmor: AppArmor disabled by boot time parameter
May 07 14:50:58 cogitate kernel: Mount-cache hash table entries: 512
May 07 14:50:58 cogitate kernel: Initializing cgroup subsys cpuacct
May 07 14:50:58 cogitate kernel: Initializing cgroup subsys memory
May 07 14:50:58 cogitate kernel: Initializing cgroup subsys devices
May 07 14:50:58 cogitate kernel: Initializing cgroup subsys freezer
May 07 14:50:58 cogitate kernel: Initializing cgroup subsys net_cls
May 07 14:50:58 cogitate kernel: Initializing cgroup subsys blkio
May 07 14:50:58 cogitate kernel: mce: CPU supports 5 MCE banks
May 07 14:50:58 cogitate kernel: [115B blob data]
May 07 14:50:58 cogitate kernel: Freeing SMP alternatives: 16k freed
May 07 14:50:58 cogitate kernel: ACPI: Core revision 20121018
May 07 14:50:58 cogitate kernel: ACPI: setting ELCR to 0200 (from 0800)
May 07 14:50:58 cogitate kernel: ftrace: allocating 18219 entries in 36 pages
May 07 14:50:58 cogitate kernel: smpboot: weird, boot CPU (#0) not listed by the BIOS
May 07 14:50:58 cogitate kernel: smpboot: SMP motherboard not detected
May 07 14:50:58 cogitate kernel: smpboot: Local APIC not detected. Using dummy APIC emulation.
May 07 14:50:58 cogitate kernel: smpboot: SMP disabled
May 07 14:50:58 cogitate kernel: Performance Events: 
May 07 14:50:58 cogitate kernel: no APIC, boot with the "lapic" boot parameter to force-enable it.
May 07 14:50:58 cogitate kernel: no hardware sampling interrupt available.
May 07 14:50:58 cogitate kernel: p6 PMU driver.
May 07 14:50:58 cogitate kernel: ... version:                0
May 07 14:50:58 cogitate kernel: ... bit width:              32
May 07 14:50:58 cogitate kernel: ... generic registers:      2
May 07 14:50:58 cogitate kernel: ... value mask:             00000000ffffffff
May 07 14:50:58 cogitate kernel: ... max period:             000000007fffffff
May 07 14:50:58 cogitate kernel: ... fixed-purpose events:   0
May 07 14:50:58 cogitate kernel: ... event mask:             0000000000000003
May 07 14:50:58 cogitate kernel: Brought up 1 CPUs
May 07 14:50:58 cogitate kernel: smpboot: Total of 1 processors activated (3398.92 BogoMIPS)
May 07 14:50:58 cogitate kernel: NMI watchdog: disabled (cpu0): not supported (no LAPIC?)
May 07 14:50:58 cogitate kernel: devtmpfs: initialized
May 07 14:50:58 cogitate kernel: PM: Registering ACPI NVS region [mem 0x3ff77000-0x3ff78fff] (8192 bytes)
May 07 14:50:58 cogitate kernel: RTC time: 13:50:50, date: 05/07/13
May 07 14:50:58 cogitate kernel: NET: Registered protocol family 16
May 07 14:50:58 cogitate kernel: ACPI: bus type pci registered
May 07 14:50:58 cogitate kernel: PCI: PCI BIOS revision 2.10 entry at 0xfd8d6, last bus=8
May 07 14:50:58 cogitate kernel: PCI: Using configuration type 1 for base access
May 07 14:50:58 cogitate kernel: bio: create slab <bio-0> at 0
May 07 14:50:58 cogitate kernel: ACPI: Added _OSI(Module Device)
May 07 14:50:58 cogitate kernel: ACPI: Added _OSI(Processor Device)
May 07 14:50:58 cogitate kernel: ACPI: Added _OSI(3.0 _SCP Extensions)
May 07 14:50:58 cogitate kernel: ACPI: Added _OSI(Processor Aggregator Device)
May 07 14:50:58 cogitate kernel: ACPI: EC: EC description table is found, configuring boot EC
May 07 14:50:58 cogitate kernel: ACPI: Interpreter enabled
May 07 14:50:58 cogitate kernel: ACPI: (supports S0 S3 S4 S5)
May 07 14:50:58 cogitate kernel: ACPI: Using PIC for interrupt routing
May 07 14:50:58 cogitate kernel: ACPI: Power Resource [PUBS] (on)
May 07 14:50:58 cogitate kernel: ACPI: EC: GPE = 0x1c, I/O: command/status = 0x66, data = 0x62
May 07 14:50:58 cogitate kernel: ACPI: ACPI Dock Station Driver: 3 docks/bays found
May 07 14:50:58 cogitate kernel: PCI: Ignoring host bridge windows from ACPI; if necessary, use "pci=use_crs" and report a bug
May 07 14:50:58 cogitate kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
May 07 14:50:58 cogitate kernel: ACPI: PCI Interrupt Routing Table [\_SB_.PCI0._PRT]
May 07 14:50:58 cogitate kernel: pci_root PNP0A03:00: host bridge window [io  0x0000-0x0cf7] (ignored)
May 07 14:50:58 cogitate kernel: pci_root PNP0A03:00: host bridge window [io  0x0d00-0xffff] (ignored)
May 07 14:50:58 cogitate kernel: pci_root PNP0A03:00: host bridge window [mem 0x000a0000-0x000bffff] (ignored)
May 07 14:50:58 cogitate kernel: pci_root PNP0A03:00: host bridge window [mem 0x000d4000-0x000d7fff] (ignored)
May 07 14:50:58 cogitate kernel: pci_root PNP0A03:00: host bridge window [mem 0x000d8000-0x000dbfff] (ignored)
May 07 14:50:58 cogitate kernel: pci_root PNP0A03:00: host bridge window [mem 0x40000000-0xfebfffff] (ignored)
May 07 14:50:58 cogitate kernel: PCI: root bus 00: using default resources
May 07 14:50:58 cogitate kernel: pci_root PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge.
May 07 14:50:58 cogitate kernel: PCI host bridge to bus 0000:00
May 07 14:50:58 cogitate kernel: pci_bus 0000:00: root bus resource [bus 00-ff]
May 07 14:50:58 cogitate kernel: pci_bus 0000:00: root bus resource [io  0x0000-0xffff]
May 07 14:50:58 cogitate kernel: pci_bus 0000:00: root bus resource [mem 0x00000000-0xffffffff]
May 07 14:50:58 cogitate kernel: pci 0000:00:00.0: [8086:3340] type 00 class 0x060000
May 07 14:50:58 cogitate kernel: pci 0000:00:00.0: reg 10: [mem 0xd0000000-0xdfffffff pref]
May 07 14:50:58 cogitate kernel: pci 0000:00:01.0: [8086:3341] type 01 class 0x060400
May 07 14:50:58 cogitate kernel: pci 0000:00:1d.0: [8086:24c2] type 00 class 0x0c0300
May 07 14:50:58 cogitate kernel: pci 0000:00:1d.0: reg 20: [io  0x1800-0x181f]
May 07 14:50:58 cogitate kernel: pci 0000:00:1d.1: [8086:24c4] type 00 class 0x0c0300
May 07 14:50:58 cogitate kernel: pci 0000:00:1d.1: reg 20: [io  0x1820-0x183f]
May 07 14:50:58 cogitate kernel: pci 0000:00:1d.2: [8086:24c7] type 00 class 0x0c0300
May 07 14:50:58 cogitate kernel: pci 0000:00:1d.2: reg 20: [io  0x1840-0x185f]
May 07 14:50:58 cogitate kernel: pci 0000:00:1d.7: [8086:24cd] type 00 class 0x0c0320
May 07 14:50:58 cogitate kernel: pci 0000:00:1d.7: reg 10: [mem 0xc0000000-0xc00003ff]
May 07 14:50:58 cogitate kernel: pci 0000:00:1d.7: PME# supported from D0 D3hot D3cold
May 07 14:50:58 cogitate kernel: pci 0000:00:1e.0: [8086:2448] type 01 class 0x060400
May 07 14:50:58 cogitate kernel: pci 0000:00:1f.0: [8086:24cc] type 00 class 0x060100
May 07 14:50:58 cogitate kernel: pci 0000:00:1f.0: quirk: [io  0x1000-0x107f] claimed by ICH4 ACPI/GPIO/TCO
May 07 14:50:58 cogitate kernel: pci 0000:00:1f.0: quirk: [io  0x1180-0x11bf] claimed by ICH4 GPIO
May 07 14:50:58 cogitate kernel: pci 0000:00:1f.1: [8086:24ca] type 00 class 0x01018a
May 07 14:50:58 cogitate kernel: pci 0000:00:1f.1: reg 10: [io  0x0000-0x0007]
May 07 14:50:58 cogitate kernel: pci 0000:00:1f.1: reg 14: [io  0x0000-0x0003]
May 07 14:50:58 cogitate kernel: pci 0000:00:1f.1: reg 18: [io  0x0000-0x0007]
May 07 14:50:58 cogitate kernel: pci 0000:00:1f.1: reg 1c: [io  0x0000-0x0003]
May 07 14:50:58 cogitate kernel: pci 0000:00:1f.1: reg 20: [io  0x1860-0x186f]
May 07 14:50:58 cogitate kernel: pci 0000:00:1f.1: reg 24: [mem 0x00000000-0x000003ff]
May 07 14:50:58 cogitate kernel: pci 0000:00:1f.3: [8086:24c3] type 00 class 0x0c0500
May 07 14:50:58 cogitate kernel: pci 0000:00:1f.3: reg 20: [io  0x1880-0x189f]
May 07 14:50:58 cogitate kernel: pci 0000:00:1f.5: [8086:24c5] type 00 class 0x040100
May 07 14:50:58 cogitate kernel: pci 0000:00:1f.5: reg 10: [io  0x1c00-0x1cff]
May 07 14:50:58 cogitate kernel: pci 0000:00:1f.5: reg 14: [io  0x18c0-0x18ff]
May 07 14:50:58 cogitate kernel: pci 0000:00:1f.5: reg 18: [mem 0xc0000c00-0xc0000dff]
May 07 14:50:58 cogitate kernel: pci 0000:00:1f.5: reg 1c: [mem 0xc0000800-0xc00008ff]
May 07 14:50:58 cogitate kernel: pci 0000:00:1f.5: PME# supported from D0 D3hot D3cold
May 07 14:50:58 cogitate kernel: pci 0000:00:1f.6: [8086:24c6] type 00 class 0x070300
May 07 14:50:58 cogitate kernel: pci 0000:00:1f.6: reg 10: [io  0x2400-0x24ff]
May 07 14:50:58 cogitate kernel: pci 0000:00:1f.6: reg 14: [io  0x2000-0x207f]
May 07 14:50:58 cogitate kernel: pci 0000:00:1f.6: PME# supported from D0 D3hot D3cold
May 07 14:50:58 cogitate kernel: pci 0000:01:00.0: [1002:4e54] type 00 class 0x030000
May 07 14:50:58 cogitate kernel: pci 0000:01:00.0: reg 10: [mem 0xe0000000-0xe7ffffff pref]
May 07 14:50:58 cogitate kernel: pci 0000:01:00.0: reg 14: [io  0x3000-0x30ff]
May 07 14:50:58 cogitate kernel: pci 0000:01:00.0: reg 18: [mem 0xc0100000-0xc010ffff]
May 07 14:50:58 cogitate kernel: pci 0000:01:00.0: reg 30: [mem 0x00000000-0x0001ffff pref]
May 07 14:50:58 cogitate kernel: pci 0000:01:00.0: supports D1 D2
May 07 14:50:58 cogitate kernel: pci 0000:00:01.0: PCI bridge to [bus 01]
May 07 14:50:58 cogitate kernel: pci 0000:00:01.0:   bridge window [io  0x3000-0x3fff]
May 07 14:50:58 cogitate kernel: pci 0000:00:01.0:   bridge window [mem 0xc0100000-0xc01fffff]
May 07 14:50:58 cogitate kernel: pci 0000:00:01.0:   bridge window [mem 0xe0000000-0xe7ffffff pref]
May 07 14:50:58 cogitate kernel: pci 0000:02:00.0: [104c:ac46] type 02 class 0x060700
May 07 14:50:58 cogitate kernel: pci 0000:02:00.0: reg 10: [mem 0xb0000000-0xb0000fff]
May 07 14:50:58 cogitate kernel: pci 0000:02:00.0: supports D1 D2
May 07 14:50:58 cogitate kernel: pci 0000:02:00.0: PME# supported from D0 D1 D2 D3hot D3cold
May 07 14:50:58 cogitate kernel: pci 0000:02:00.1: [104c:ac46] type 02 class 0x060700
May 07 14:50:58 cogitate kernel: pci 0000:02:00.1: reg 10: [mem 0xb1000000-0xb1000fff]
May 07 14:50:58 cogitate kernel: pci 0000:02:00.1: supports D1 D2
May 07 14:50:58 cogitate kernel: pci 0000:02:00.1: PME# supported from D0 D1 D2 D3hot D3cold
May 07 14:50:58 cogitate kernel: pci 0000:02:01.0: [8086:101e] type 00 class 0x020000
May 07 14:50:58 cogitate kernel: pci 0000:02:01.0: reg 10: [mem 0xc0240000-0xc025ffff]
May 07 14:50:58 cogitate kernel: pci 0000:02:01.0: reg 14: [mem 0xc0200000-0xc020ffff]
May 07 14:50:58 cogitate kernel: pci 0000:02:01.0: reg 18: [io  0x8000-0x803f]
May 07 14:50:58 cogitate kernel: pci 0000:02:01.0: reg 30: [mem 0x00000000-0x0000ffff pref]
May 07 14:50:58 cogitate kernel: pci 0000:02:01.0: PME# supported from D0 D3hot D3cold
May 07 14:50:58 cogitate kernel: pci 0000:02:02.0: [168c:1014] type 00 class 0x020000
May 07 14:50:58 cogitate kernel: pci 0000:02:02.0: reg 10: [mem 0xc0210000-0xc021ffff]
May 07 14:50:58 cogitate kernel: pci 0000:00:1e.0: PCI bridge to [bus 02-08] (subtractive decode)
May 07 14:50:58 cogitate kernel: pci 0000:00:1e.0:   bridge window [io  0x4000-0x8fff]
May 07 14:50:58 cogitate kernel: pci 0000:00:1e.0:   bridge window [mem 0xc0200000-0xcfffffff]
May 07 14:50:58 cogitate kernel: pci 0000:00:1e.0:   bridge window [mem 0xe8000000-0xefffffff pref]
May 07 14:50:58 cogitate kernel: pci 0000:00:1e.0:   bridge window [io  0x0000-0xffff] (subtractive decode)
May 07 14:50:58 cogitate kernel: pci 0000:00:1e.0:   bridge window [mem 0x00000000-0xffffffff] (subtractive decode)
May 07 14:50:58 cogitate kernel: pci_bus 0000:03: busn_res: can not insert [bus 03-ff] under [bus 02-08] (conflicts with (null) [bus 02-08])
May 07 14:50:58 cogitate kernel: pci_bus 0000:03: busn_res: [bus 03-ff] end is updated to 06
May 07 14:50:58 cogitate kernel: pci_bus 0000:07: busn_res: can not insert [bus 07-ff] under [bus 02-08] (conflicts with (null) [bus 02-08])
May 07 14:50:58 cogitate kernel: pci_bus 0000:07: busn_res: [bus 07-ff] end is updated to 07
May 07 14:50:58 cogitate kernel: pci_bus 0000:00: on NUMA node 0
May 07 14:50:58 cogitate kernel: ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.AGP_._PRT]
May 07 14:50:58 cogitate kernel: ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.PCI1._PRT]
May 07 14:50:58 cogitate kernel:  pci0000:00: ACPI _OSC support notification failed, disabling PCIe ASPM
May 07 14:50:58 cogitate kernel:  pci0000:00: Unable to request _OSC control (_OSC support mask: 0x08)
May 07 14:50:58 cogitate kernel: ACPI: PCI Interrupt Link [LNKA] (IRQs 3 4 5 6 7 9 10 *11)
May 07 14:50:58 cogitate kernel: ACPI: PCI Interrupt Link [LNKB] (IRQs 3 4 5 6 7 9 10 *11)
May 07 14:50:58 cogitate kernel: ACPI: PCI Interrupt Link [LNKC] (IRQs 3 4 5 6 7 9 10 *11)
May 07 14:50:58 cogitate kernel: ACPI: PCI Interrupt Link [LNKD] (IRQs 3 4 5 6 7 9 10 *11)
May 07 14:50:58 cogitate kernel: ACPI: PCI Interrupt Link [LNKE] (IRQs 3 4 5 6 7 9 10 11) *0, disabled.
May 07 14:50:58 cogitate kernel: ACPI: PCI Interrupt Link [LNKF] (IRQs 3 4 5 6 7 9 10 11) *0, disabled.
May 07 14:50:58 cogitate kernel: ACPI: PCI Interrupt Link [LNKG] (IRQs 3 4 5 6 7 9 10 11) *0, disabled.
May 07 14:50:58 cogitate kernel: ACPI: PCI Interrupt Link [LNKH] (IRQs 3 4 5 6 7 9 10 *11)
May 07 14:50:58 cogitate kernel: vgaarb: device added: PCI:0000:01:00.0,decodes=io+mem,owns=io+mem,locks=none
May 07 14:50:58 cogitate kernel: vgaarb: loaded
May 07 14:50:58 cogitate kernel: vgaarb: bridge control possible 0000:01:00.0
May 07 14:50:58 cogitate kernel: PCI: Using ACPI for IRQ routing
May 07 14:50:58 cogitate kernel: PCI: pci_cache_line_size set to 64 bytes
May 07 14:50:58 cogitate kernel: e820: reserve RAM buffer [mem 0x0009f000-0x0009ffff]
May 07 14:50:58 cogitate kernel: e820: reserve RAM buffer [mem 0x3ff60000-0x3fffffff]
May 07 14:50:58 cogitate kernel: NetLabel: Initializing
May 07 14:50:58 cogitate kernel: NetLabel:  domain hash size = 128
May 07 14:50:58 cogitate kernel: NetLabel:  protocols = UNLABELED CIPSOv4
May 07 14:50:58 cogitate kernel: NetLabel:  unlabeled traffic allowed by default
May 07 14:50:58 cogitate kernel: Switching to clocksource pit
May 07 14:50:58 cogitate kernel: pnp: PnP ACPI init
May 07 14:50:58 cogitate kernel: ACPI: bus type pnp registered
May 07 14:50:58 cogitate kernel: system 00:00: [mem 0x00000000-0x0009ffff] could not be reserved
May 07 14:50:58 cogitate kernel: system 00:00: [mem 0x000c0000-0x000c3fff] could not be reserved
May 07 14:50:58 cogitate kernel: system 00:00: [mem 0x000c4000-0x000c7fff] could not be reserved
May 07 14:50:58 cogitate kernel: system 00:00: [mem 0x000c8000-0x000cbfff] could not be reserved
May 07 14:50:58 cogitate kernel: system 00:00: [mem 0x000cc000-0x000cffff] could not be reserved
May 07 14:50:58 cogitate kernel: system 00:00: [mem 0x000d0000-0x000d3fff] could not be reserved
May 07 14:50:58 cogitate kernel: system 00:00: [mem 0x000dc000-0x000dffff] could not be reserved
May 07 14:50:58 cogitate kernel: system 00:00: [mem 0x000e0000-0x000e3fff] could not be reserved
May 07 14:50:58 cogitate kernel: system 00:00: [mem 0x000e4000-0x000e7fff] could not be reserved
May 07 14:50:58 cogitate kernel: system 00:00: [mem 0x000e8000-0x000ebfff] could not be reserved
May 07 14:50:58 cogitate kernel: system 00:00: [mem 0x000ec000-0x000effff] could not be reserved
May 07 14:50:58 cogitate kernel: system 00:00: [mem 0x000f0000-0x000fffff] could not be reserved
May 07 14:50:58 cogitate kernel: system 00:00: [mem 0x00100000-0x3fffffff] could not be reserved
May 07 14:50:58 cogitate kernel: system 00:00: [mem 0xfec00000-0xffffffff] could not be reserved
May 07 14:50:58 cogitate kernel: system 00:00: Plug and Play ACPI device, IDs PNP0c01 (active)
May 07 14:50:58 cogitate kernel: system 00:01: [io  0x1000-0x107f] has been reserved
May 07 14:50:58 cogitate kernel: system 00:01: [io  0x1180-0x11bf] has been reserved
May 07 14:50:58 cogitate kernel: system 00:01: [io  0x15e0-0x15ef] has been reserved
May 07 14:50:58 cogitate kernel: system 00:01: [io  0x1600-0x162f] has been reserved
May 07 14:50:58 cogitate kernel: system 00:01: [io  0x1632-0x167f] has been reserved
May 07 14:50:58 cogitate kernel: system 00:01: [io  0x1630-0x1631] has been reserved
May 07 14:50:58 cogitate kernel: system 00:01: Plug and Play ACPI device, IDs PNP0c02 (active)
May 07 14:50:58 cogitate kernel: pnp 00:02: [dma 4]
May 07 14:50:58 cogitate kernel: pnp 00:02: Plug and Play ACPI device, IDs PNP0200 (active)
May 07 14:50:58 cogitate kernel: pnp 00:03: Plug and Play ACPI device, IDs PNP0800 (active)
May 07 14:50:58 cogitate kernel: pnp 00:04: Plug and Play ACPI device, IDs PNP0c04 (active)
May 07 14:50:58 cogitate kernel: pnp 00:05: Plug and Play ACPI device, IDs PNP0b00 (active)
May 07 14:50:58 cogitate kernel: pnp 00:06: Plug and Play ACPI device, IDs PNP0303 (active)
May 07 14:50:58 cogitate kernel: pnp 00:07: Plug and Play ACPI device, IDs IBM0057 PNP0f13 (active)
May 07 14:50:58 cogitate kernel: pnp 00:08: [dma 2]
May 07 14:50:58 cogitate kernel: pnp 00:08: Plug and Play ACPI device, IDs PNP0700 (active)
May 07 14:50:58 cogitate kernel: pnp 00:09: Plug and Play ACPI device, IDs PNP0501 (disabled)
May 07 14:50:58 cogitate kernel: pnp 00:0a: Plug and Play ACPI device, IDs PNP0400 (active)
May 07 14:50:58 cogitate kernel: pnp 00:0b: Plug and Play ACPI device, IDs IBM0071 PNP0511 (disabled)
May 07 14:50:58 cogitate kernel: pnp: PnP ACPI: found 12 devices
May 07 14:50:58 cogitate kernel: ACPI: ACPI bus type pnp unregistered
May 07 14:50:58 cogitate kernel: Switching to clocksource acpi_pm
May 07 14:50:58 cogitate kernel: pci 0000:00:1f.1: BAR 5: assigned [mem 0x40000000-0x400003ff]
May 07 14:50:58 cogitate kernel: pci 0000:01:00.0: BAR 6: assigned [mem 0xc0120000-0xc013ffff pref]
May 07 14:50:58 cogitate kernel: pci 0000:00:01.0: PCI bridge to [bus 01]
May 07 14:50:58 cogitate kernel: pci 0000:00:01.0:   bridge window [io  0x3000-0x3fff]
May 07 14:50:58 cogitate kernel: pci 0000:00:01.0:   bridge window [mem 0xc0100000-0xc01fffff]
May 07 14:50:58 cogitate kernel: pci 0000:00:01.0:   bridge window [mem 0xe0000000-0xe7ffffff pref]
May 07 14:50:58 cogitate kernel: pci 0000:02:00.0: res[15]=[mem 0x04000000-0x03ffffff pref] get_res_add_size add_size 4000000
May 07 14:50:58 cogitate kernel: pci 0000:02:00.0: res[16]=[mem 0x04000000-0x03ffffff] get_res_add_size add_size 4000000
May 07 14:50:58 cogitate kernel: pci 0000:02:00.1: res[15]=[mem 0x04000000-0x03ffffff pref] get_res_add_size add_size 4000000
May 07 14:50:58 cogitate kernel: pci 0000:02:00.1: res[16]=[mem 0x04000000-0x03ffffff] get_res_add_size add_size 4000000
May 07 14:50:58 cogitate kernel: pci 0000:02:00.0: res[13]=[io  0x0100-0x00ff] get_res_add_size add_size 100
May 07 14:50:58 cogitate kernel: pci 0000:02:00.0: res[14]=[io  0x0100-0x00ff] get_res_add_size add_size 100
May 07 14:50:58 cogitate kernel: pci 0000:02:00.1: res[13]=[io  0x0100-0x00ff] get_res_add_size add_size 100
May 07 14:50:58 cogitate kernel: pci 0000:02:00.1: res[14]=[io  0x0100-0x00ff] get_res_add_size add_size 100
May 07 14:50:58 cogitate kernel: pci 0000:02:00.0: BAR 15: assigned [mem 0xe8000000-0xebffffff pref]
May 07 14:50:58 cogitate kernel: pci 0000:02:00.0: BAR 16: assigned [mem 0xc4000000-0xc7ffffff]
May 07 14:50:58 cogitate kernel: pci 0000:02:00.1: BAR 15: assigned [mem 0xec000000-0xefffffff pref]
May 07 14:50:58 cogitate kernel: pci 0000:02:00.1: BAR 16: assigned [mem 0xc8000000-0xcbffffff]
May 07 14:50:58 cogitate kernel: pci 0000:02:01.0: BAR 6: assigned [mem 0xc0220000-0xc022ffff pref]
May 07 14:50:58 cogitate kernel: pci 0000:02:00.0: BAR 13: assigned [io  0x4000-0x40ff]
May 07 14:50:58 cogitate kernel: pci 0000:02:00.0: BAR 14: assigned [io  0x4400-0x44ff]
May 07 14:50:58 cogitate kernel: pci 0000:02:00.1: BAR 13: assigned [io  0x4800-0x48ff]
May 07 14:50:58 cogitate kernel: pci 0000:02:00.1: BAR 14: assigned [io  0x4c00-0x4cff]
May 07 14:50:58 cogitate kernel: pci 0000:02:00.0: CardBus bridge to [bus 03-06]
May 07 14:50:58 cogitate kernel: pci 0000:02:00.0:   bridge window [io  0x4000-0x40ff]
May 07 14:50:58 cogitate kernel: pci 0000:02:00.0:   bridge window [io  0x4400-0x44ff]
May 07 14:50:58 cogitate kernel: pci 0000:02:00.0:   bridge window [mem 0xe8000000-0xebffffff pref]
May 07 14:50:58 cogitate kernel: pci 0000:02:00.0:   bridge window [mem 0xc4000000-0xc7ffffff]
May 07 14:50:58 cogitate kernel: pci 0000:02:00.1: CardBus bridge to [bus 07]
May 07 14:50:58 cogitate kernel: pci 0000:02:00.1:   bridge window [io  0x4800-0x48ff]
May 07 14:50:58 cogitate kernel: pci 0000:02:00.1:   bridge window [io  0x4c00-0x4cff]
May 07 14:50:58 cogitate kernel: pci 0000:02:00.1:   bridge window [mem 0xec000000-0xefffffff pref]
May 07 14:50:58 cogitate kernel: pci 0000:02:00.1:   bridge window [mem 0xc8000000-0xcbffffff]
May 07 14:50:58 cogitate kernel: pci 0000:00:1e.0: PCI bridge to [bus 02-08]
May 07 14:50:58 cogitate kernel: pci 0000:00:1e.0:   bridge window [io  0x4000-0x8fff]
May 07 14:50:58 cogitate kernel: pci 0000:00:1e.0:   bridge window [mem 0xc0200000-0xcfffffff]
May 07 14:50:58 cogitate kernel: pci 0000:00:1e.0:   bridge window [mem 0xe8000000-0xefffffff pref]
May 07 14:50:58 cogitate kernel: pci 0000:00:1e.0: setting latency timer to 64
May 07 14:50:58 cogitate kernel: ACPI: PCI Interrupt Link [LNKA] enabled at IRQ 11
May 07 14:50:58 cogitate kernel: PCI: setting IRQ 11 as level-triggered
May 07 14:50:58 cogitate kernel: ACPI: PCI Interrupt Link [LNKB] enabled at IRQ 11
May 07 14:50:58 cogitate kernel: pci_bus 0000:00: resource 4 [io  0x0000-0xffff]
May 07 14:50:58 cogitate kernel: pci_bus 0000:00: resource 5 [mem 0x00000000-0xffffffff]
May 07 14:50:58 cogitate kernel: pci_bus 0000:01: resource 0 [io  0x3000-0x3fff]
May 07 14:50:58 cogitate kernel: pci_bus 0000:01: resource 1 [mem 0xc0100000-0xc01fffff]
May 07 14:50:58 cogitate kernel: pci_bus 0000:01: resource 2 [mem 0xe0000000-0xe7ffffff pref]
May 07 14:50:58 cogitate kernel: pci_bus 0000:02: resource 0 [io  0x4000-0x8fff]
May 07 14:50:58 cogitate kernel: pci_bus 0000:02: resource 1 [mem 0xc0200000-0xcfffffff]
May 07 14:50:58 cogitate kernel: pci_bus 0000:02: resource 2 [mem 0xe8000000-0xefffffff pref]
May 07 14:50:58 cogitate kernel: pci_bus 0000:02: resource 4 [io  0x0000-0xffff]
May 07 14:50:58 cogitate kernel: pci_bus 0000:02: resource 5 [mem 0x00000000-0xffffffff]
May 07 14:50:58 cogitate kernel: pci_bus 0000:03: resource 0 [io  0x4000-0x40ff]
May 07 14:50:58 cogitate kernel: pci_bus 0000:03: resource 1 [io  0x4400-0x44ff]
May 07 14:50:58 cogitate kernel: pci_bus 0000:03: resource 2 [mem 0xe8000000-0xebffffff pref]
May 07 14:50:58 cogitate kernel: pci_bus 0000:03: resource 3 [mem 0xc4000000-0xc7ffffff]
May 07 14:50:58 cogitate kernel: pci_bus 0000:07: resource 0 [io  0x4800-0x48ff]
May 07 14:50:58 cogitate kernel: pci_bus 0000:07: resource 1 [io  0x4c00-0x4cff]
May 07 14:50:58 cogitate kernel: pci_bus 0000:07: resource 2 [mem 0xec000000-0xefffffff pref]
May 07 14:50:58 cogitate kernel: pci_bus 0000:07: resource 3 [mem 0xc8000000-0xcbffffff]
May 07 14:50:58 cogitate kernel: NET: Registered protocol family 2
May 07 14:50:58 cogitate kernel: TCP established hash table entries: 8192 (order: 4, 65536 bytes)
May 07 14:50:58 cogitate kernel: TCP bind hash table entries: 8192 (order: 4, 65536 bytes)
May 07 14:50:58 cogitate kernel: TCP: Hash tables configured (established 8192 bind 8192)
May 07 14:50:58 cogitate kernel: TCP: reno registered
May 07 14:50:58 cogitate kernel: UDP hash table entries: 512 (order: 2, 16384 bytes)
May 07 14:50:58 cogitate kernel: UDP-Lite hash table entries: 512 (order: 2, 16384 bytes)
May 07 14:50:58 cogitate kernel: NET: Registered protocol family 1
May 07 14:50:58 cogitate kernel: pci 0000:00:1d.0: power state changed by ACPI to D0
May 07 14:50:58 cogitate kernel: pci 0000:00:1d.1: power state changed by ACPI to D0
May 07 14:50:58 cogitate kernel: ACPI: PCI Interrupt Link [LNKD] enabled at IRQ 11
May 07 14:50:58 cogitate kernel: ACPI: PCI Interrupt Link [LNKC] enabled at IRQ 11
May 07 14:50:58 cogitate kernel: ACPI: PCI Interrupt Link [LNKH] enabled at IRQ 11
May 07 14:50:58 cogitate kernel: pci 0000:01:00.0: Boot video device
May 07 14:50:58 cogitate kernel: PCI: CLS 32 bytes, default 64
May 07 14:50:58 cogitate kernel: Unpacking initramfs...
May 07 14:50:58 cogitate kernel: Freeing initrd memory: 2856k freed
May 07 14:50:58 cogitate kernel: Simple Boot Flag at 0x35 set to 0x1
May 07 14:50:58 cogitate kernel: IBM machine detected. Enabling interrupts during APM calls.
May 07 14:50:58 cogitate kernel: apm: BIOS version 1.2 Flags 0x03 (Driver version 1.16ac)
May 07 14:50:58 cogitate kernel: apm: overridden by ACPI.
May 07 14:50:58 cogitate kernel: audit: initializing netlink socket (disabled)
May 07 14:50:58 cogitate kernel: type=2000 audit(1367934650.236:1): initialized
May 07 14:50:58 cogitate kernel: bounce pool size: 64 pages
May 07 14:50:58 cogitate kernel: HugeTLB registered 4 MB page size, pre-allocated 0 pages
May 07 14:50:58 cogitate kernel: VFS: Disk quotas dquot_6.5.2
May 07 14:50:58 cogitate kernel: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
May 07 14:50:58 cogitate kernel: msgmni has been set to 1739
May 07 14:50:58 cogitate kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 252)
May 07 14:50:58 cogitate kernel: io scheduler noop registered
May 07 14:50:58 cogitate kernel: io scheduler deadline registered
May 07 14:50:58 cogitate kernel: io scheduler cfq registered (default)
May 07 14:50:58 cogitate kernel: intel_idle: does not run on family 6 model 9
May 07 14:50:58 cogitate kernel: GHES: HEST is not enabled!
May 07 14:50:58 cogitate kernel: isapnp: Scanning for PnP cards...
May 07 14:50:58 cogitate kernel: isapnp: No Plug & Play device found
May 07 14:50:58 cogitate kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing disabled
May 07 14:50:58 cogitate kernel: serial 00:09: [io  0x03f8-0x03ff]
May 07 14:50:58 cogitate kernel: serial 00:09: [irq 4]
May 07 14:50:58 cogitate kernel: serial 00:09: activated
May 07 14:50:58 cogitate kernel: 00:09: ttyS0 at I/O 0x3f8 (irq = 4) is a NS16550A
May 07 14:50:58 cogitate kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12
May 07 14:50:58 cogitate kernel: serio: i8042 KBD port at 0x60,0x64 irq 1
May 07 14:50:58 cogitate kernel: serio: i8042 AUX port at 0x60,0x64 irq 12
May 07 14:50:58 cogitate kernel: mousedev: PS/2 mouse device common for all mice
May 07 14:50:58 cogitate kernel: rtc_cmos 00:05: RTC can wake from S4
May 07 14:50:58 cogitate kernel: rtc_cmos 00:05: rtc core: registered rtc_cmos as rtc0
May 07 14:50:58 cogitate kernel: rtc0: alarms up to one month, y3k, 114 bytes nvram
May 07 14:50:58 cogitate kernel: cpuidle: using governor ladder
May 07 14:50:58 cogitate kernel: cpuidle: using governor menu
May 07 14:50:58 cogitate kernel: EFI Variables Facility v0.08 2004-May-17
May 07 14:50:58 cogitate kernel: drop_monitor: Initializing network drop monitor service
May 07 14:50:58 cogitate kernel: TCP: cubic registered
May 07 14:50:58 cogitate kernel: NET: Registered protocol family 10
May 07 14:50:58 cogitate kernel: NET: Registered protocol family 17
May 07 14:50:58 cogitate kernel: Key type dns_resolver registered
May 07 14:50:58 cogitate kernel: Using IPI No-Shortcut mode
May 07 14:50:58 cogitate kernel: PM: Hibernation image not present or could not be loaded.
May 07 14:50:58 cogitate kernel: registered taskstats version 1
May 07 14:50:58 cogitate kernel:   Magic number: 13:683:836
May 07 14:50:58 cogitate kernel: rtc_cmos 00:05: setting system clock to 2013-05-07 13:50:51 UTC (1367934651)
May 07 14:50:58 cogitate kernel: Freeing unused kernel memory: 556k freed
May 07 14:50:58 cogitate kernel: Write protecting the kernel text: 4120k
May 07 14:50:58 cogitate kernel: Write protecting the kernel read-only data: 1280k
May 07 14:50:58 cogitate kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0
May 07 14:50:58 cogitate systemd-udevd[39]: starting version 202
May 07 14:50:58 cogitate kernel: ACPI: bus type usb registered
May 07 14:50:58 cogitate kernel: usbcore: registered new interface driver usbfs
May 07 14:50:58 cogitate kernel: usbcore: registered new interface driver hub
May 07 14:50:58 cogitate kernel: usbcore: registered new device driver usb
May 07 14:50:58 cogitate kernel: SCSI subsystem initialized
May 07 14:50:58 cogitate kernel: ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
May 07 14:50:58 cogitate kernel: ehci-pci: EHCI PCI platform driver
May 07 14:50:58 cogitate kernel: ehci-pci 0000:00:1d.7: setting latency timer to 64
May 07 14:50:58 cogitate kernel: ehci-pci 0000:00:1d.7: EHCI Host Controller
May 07 14:50:58 cogitate kernel: ehci-pci 0000:00:1d.7: new USB bus registered, assigned bus number 1
May 07 14:50:58 cogitate kernel: ehci-pci 0000:00:1d.7: debug port 1
May 07 14:50:58 cogitate kernel: uhci_hcd: USB Universal Host Controller Interface driver
May 07 14:50:58 cogitate kernel: ehci-pci 0000:00:1d.7: cache line size of 32 is not supported
May 07 14:50:58 cogitate kernel: ehci-pci 0000:00:1d.7: irq 11, io mem 0xc0000000
May 07 14:50:58 cogitate kernel: ACPI: bus type scsi registered
May 07 14:50:58 cogitate kernel: ehci-pci 0000:00:1d.7: USB 2.0 started, EHCI 1.00
May 07 14:50:58 cogitate kernel: hub 1-0:1.0: USB hub found
May 07 14:50:58 cogitate kernel: hub 1-0:1.0: 6 ports detected
May 07 14:50:58 cogitate kernel: uhci_hcd 0000:00:1d.0: setting latency timer to 64
May 07 14:50:58 cogitate kernel: uhci_hcd 0000:00:1d.0: UHCI Host Controller
May 07 14:50:58 cogitate kernel: uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 2
May 07 14:50:58 cogitate kernel: uhci_hcd 0000:00:1d.0: irq 11, io base 0x00001800
May 07 14:50:58 cogitate kernel: hub 2-0:1.0: USB hub found
May 07 14:50:58 cogitate kernel: hub 2-0:1.0: 2 ports detected
May 07 14:50:58 cogitate kernel: uhci_hcd 0000:00:1d.1: setting latency timer to 64
May 07 14:50:58 cogitate kernel: uhci_hcd 0000:00:1d.1: UHCI Host Controller
May 07 14:50:58 cogitate kernel: uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 3
May 07 14:50:58 cogitate kernel: uhci_hcd 0000:00:1d.1: irq 11, io base 0x00001820
May 07 14:50:58 cogitate kernel: hub 3-0:1.0: USB hub found
May 07 14:50:58 cogitate kernel: hub 3-0:1.0: 2 ports detected
May 07 14:50:58 cogitate kernel: uhci_hcd 0000:00:1d.2: setting latency timer to 64
May 07 14:50:58 cogitate kernel: uhci_hcd 0000:00:1d.2: UHCI Host Controller
May 07 14:50:58 cogitate kernel: uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 4
May 07 14:50:58 cogitate kernel: uhci_hcd 0000:00:1d.2: irq 11, io base 0x00001840
May 07 14:50:58 cogitate kernel: hub 4-0:1.0: USB hub found
May 07 14:50:58 cogitate kernel: hub 4-0:1.0: 2 ports detected
May 07 14:50:58 cogitate kernel: libata version 3.00 loaded.
May 07 14:50:58 cogitate kernel: ata_piix 0000:00:1f.1: version 2.13
May 07 14:50:58 cogitate kernel: ata_piix 0000:00:1f.1: enabling device (0005 -> 0007)
May 07 14:50:58 cogitate kernel: ata_piix 0000:00:1f.1: setting latency timer to 64
May 07 14:50:58 cogitate kernel: scsi0 : ata_piix
May 07 14:50:58 cogitate kernel: scsi1 : ata_piix
May 07 14:50:58 cogitate kernel: ata1: PATA max UDMA/100 cmd 0x1f0 ctl 0x3f6 bmdma 0x1860 irq 14
May 07 14:50:58 cogitate kernel: ata2: PATA max UDMA/100 cmd 0x170 ctl 0x376 bmdma 0x1868 irq 15
May 07 14:50:58 cogitate kernel: ata1.00: ATA-6: ST9402113A, 3.01, max UDMA/100
May 07 14:50:58 cogitate kernel: ata1.00: 78140160 sectors, multi 16: LBA48 
May 07 14:50:58 cogitate kernel: ata2.00: ATAPI: HL-DT-STDVD-ROM GDR8083N, 0K04, max UDMA/33
May 07 14:50:58 cogitate kernel: ata1.00: configured for UDMA/100
May 07 14:50:58 cogitate kernel: scsi 0:0:0:0: Direct-Access     ATA      ST9402113A       3.01 PQ: 0 ANSI: 5
May 07 14:50:58 cogitate kernel: ata2.00: configured for UDMA/33
May 07 14:50:58 cogitate kernel: scsi 1:0:0:0: CD-ROM            HL-DT-ST DVD-ROM GDR8083N 0K04 PQ: 0 ANSI: 5
May 07 14:50:58 cogitate kernel: sd 0:0:0:0: [sda] 78140160 512-byte logical blocks: (40.0 GB/37.2 GiB)
May 07 14:50:58 cogitate kernel: sd 0:0:0:0: [sda] Write Protect is off
May 07 14:50:58 cogitate kernel: sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
May 07 14:50:58 cogitate kernel: sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
May 07 14:50:58 cogitate kernel: sr0: scsi3-mmc drive: 10x/24x cd/rw xa/form2 cdda tray
May 07 14:50:58 cogitate kernel: cdrom: Uniform CD-ROM driver Revision: 3.20
May 07 14:50:58 cogitate kernel: sr 1:0:0:0: Attached scsi CD-ROM sr0
May 07 14:50:58 cogitate kernel:  sda: sda1 sda2 < sda5 sda6 >
May 07 14:50:58 cogitate kernel: sd 0:0:0:0: [sda] Attached SCSI disk
May 07 14:50:58 cogitate kernel: tsc: Refined TSC clocksource calibration: 1698.557 MHz
May 07 14:50:58 cogitate kernel: Switching to clocksource tsc
May 07 14:50:58 cogitate kernel: EXT4-fs (sda5): mounted filesystem with ordered data mode. Opts: (null)
May 07 14:50:58 cogitate systemd[1]: systemd 202 running in system mode. (+PAM -LIBWRAP -AUDIT -SELINUX -IMA -SYSVINIT +LIBCRYPTSETUP +GCRYPT +ACL +XZ)
May 07 14:50:58 cogitate systemd[1]: Set hostname to <cogitate>.
May 07 14:50:58 cogitate systemd[1]: Cannot add dependency job for unit netcfg.service, ignoring: Unit netcfg.service failed to load: No such file or directory. See system logs and 'systemctl status netcfg.service' for details.
May 07 14:50:58 cogitate systemd[1]: Cannot add dependency job for unit display-manager.service, ignoring: Unit display-manager.service failed to load: No such file or directory. See system logs and 'systemctl status display-manager.service' for details.
May 07 14:50:58 cogitate systemd[1]: Starting Forward Password Requests to Wall Directory Watch.
May 07 14:50:58 cogitate systemd[1]: Started Forward Password Requests to Wall Directory Watch.
May 07 14:50:58 cogitate systemd[1]: Expecting device sys-subsystem-net-devices-eth0.device...
May 07 14:50:58 cogitate systemd[1]: Expecting device sys-subsystem-net-devices-wlan0.device...
May 07 14:50:58 cogitate systemd[1]: Starting Remote File Systems.
May 07 14:50:58 cogitate systemd[1]: Reached target Remote File Systems.
May 07 14:50:58 cogitate systemd[1]: Starting /dev/initctl Compatibility Named Pipe.
May 07 14:50:58 cogitate systemd[1]: Listening on /dev/initctl Compatibility Named Pipe.
May 07 14:50:58 cogitate systemd[1]: Starting Device-mapper event daemon FIFOs.
May 07 14:50:58 cogitate systemd[1]: Listening on Device-mapper event daemon FIFOs.
May 07 14:50:58 cogitate systemd[1]: Starting LVM2 metadata daemon socket.
May 07 14:50:58 cogitate systemd[1]: Listening on LVM2 metadata daemon socket.
May 07 14:50:58 cogitate systemd[1]: Starting Delayed Shutdown Socket.
May 07 14:50:58 cogitate systemd[1]: Listening on Delayed Shutdown Socket.
May 07 14:50:58 cogitate systemd[1]: Starting Arbitrary Executable File Formats File System Automount Point.
May 07 14:50:58 cogitate systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point.
May 07 14:50:58 cogitate systemd[1]: Starting Encrypted Volumes.
May 07 14:50:58 cogitate systemd[1]: Reached target Encrypted Volumes.
May 07 14:50:58 cogitate systemd[1]: Starting Dispatch Password Requests to Console Directory Watch.
May 07 14:50:58 cogitate systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
May 07 14:50:58 cogitate systemd[1]: Starting Paths.
May 07 14:50:58 cogitate systemd[1]: Reached target Paths.
May 07 14:50:58 cogitate systemd[1]: Starting Journal Socket.
May 07 14:50:58 cogitate systemd[1]: Listening on Journal Socket.
May 07 14:50:58 cogitate systemd[1]: Starting Setup Virtual Console...
May 07 14:50:58 cogitate systemd[1]: Starting Load Kernel Modules...
May 07 14:50:58 cogitate systemd[1]: Starting Apply Kernel Variables...
May 07 14:50:58 cogitate systemd[1]: Mounting Debug File System...
May 07 14:50:58 cogitate systemd[1]: Mounting POSIX Message Queue File System...
May 07 14:50:58 cogitate systemd[1]: Starting Set Up Additional Binary Formats...
May 07 14:50:58 cogitate systemd[1]: Starting Create static device nodes in /dev...
May 07 14:50:58 cogitate systemd[1]: Starting Journal Service...
May 07 14:50:58 cogitate systemd[1]: Started Journal Service.
May 07 14:50:58 cogitate systemd[1]: Mounting Huge Pages File System...
May 07 14:50:58 cogitate systemd[1]: Starting udev Kernel Socket.
May 07 14:50:58 cogitate systemd[1]: Listening on udev Kernel Socket.
May 07 14:50:58 cogitate systemd[1]: Starting udev Control Socket.
May 07 14:50:58 cogitate systemd[1]: Listening on udev Control Socket.
May 07 14:50:58 cogitate systemd[1]: Starting udev Coldplug all Devices...
May 07 14:50:58 cogitate systemd[1]: Expecting device dev-sda6.device...
May 07 14:50:58 cogitate systemd[1]: Started File System Check on Root Device.
May 07 14:50:58 cogitate systemd[1]: Starting Remount Root and Kernel File Systems...
May 07 14:50:58 cogitate systemd[1]: Mounting Temporary Directory...
May 07 14:50:58 cogitate systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway.
May 07 14:50:58 cogitate systemd[1]: Expecting device dev-disk-by\x2duuid-BE7CC0527CC006DD.device...
May 07 14:50:58 cogitate systemd[1]: Started Apply Kernel Variables.
May 07 14:50:58 cogitate kernel: e1000: Intel(R) PRO/1000 Network Driver - version 7.3.21-k8-NAPI
May 07 14:50:58 cogitate kernel: e1000: Copyright (c) 1999-2006 Intel Corporation.
May 07 14:50:58 cogitate kernel: e1000 0000:02:01.0 eth0: (PCI:33MHz:32-bit) 00:11:25:11:e2:27
May 07 14:50:58 cogitate kernel: e1000 0000:02:01.0 eth0: Intel(R) PRO/1000 Network Connection
May 07 14:50:58 cogitate systemd-journal[91]: Journal started
May 07 14:50:58 cogitate systemd-sysctl[85]: Duplicate assignment of kernel/sysrq in file '/usr/lib/sysctl.d/50-default.conf', ignoring.
May 07 14:50:58 cogitate systemd[1]: Mounting Arbitrary Executable File Formats File System...
May 07 14:50:58 cogitate systemd[1]: Started Create static device nodes in /dev.
May 07 14:50:58 cogitate systemd[1]: Starting udev Kernel Device Manager...
May 07 14:50:58 cogitate systemd-modules-load[84]: Inserted module 'e1000'
May 07 14:50:58 cogitate systemd[1]: Started udev Kernel Device Manager.
May 07 14:50:58 cogitate systemd-udevd[105]: starting version 202
May 07 14:50:58 cogitate systemd[1]: Started udev Coldplug all Devices.
May 07 14:50:58 cogitate kernel: Non-volatile memory driver v1.3
May 07 14:50:58 cogitate systemd[1]: Mounted Temporary Directory.
May 07 14:50:58 cogitate systemd[1]: Mounted Arbitrary Executable File Formats File System.
May 07 14:50:58 cogitate systemd[1]: Mounted Huge Pages File System.
May 07 14:50:58 cogitate systemd[1]: Mounted Debug File System.
May 07 14:50:58 cogitate systemd[1]: Mounted POSIX Message Queue File System.
May 07 14:50:58 cogitate kernel: EXT4-fs (sda5): re-mounted. Opts: (null)
May 07 14:50:58 cogitate systemd[1]: Started Remount Root and Kernel File Systems.
May 07 14:50:58 cogitate systemd[1]: Starting Local File Systems (Pre).
May 07 14:50:58 cogitate systemd[1]: Reached target Local File Systems (Pre).
May 07 14:50:58 cogitate systemd[1]: Starting Load Random Seed...
May 07 14:50:59 cogitate systemd[1]: Started Load Random Seed.
May 07 14:50:59 cogitate systemd[1]: Started Set Up Additional Binary Formats.
May 07 14:50:59 cogitate kernel: thinkpad_acpi: ThinkPad ACPI Extras v0.24
May 07 14:50:59 cogitate kernel: thinkpad_acpi: http://ibm-acpi.sf.net/
May 07 14:50:59 cogitate kernel: thinkpad_acpi: ThinkPad BIOS 1RETDRWW (3.23 ), EC 1RHT71WW-3.04
May 07 14:50:59 cogitate kernel: thinkpad_acpi: IBM ThinkPad T41p, model 2373GEG
May 07 14:50:59 cogitate kernel: thinkpad_acpi: detected a 8-level brightness capable ThinkPad
May 07 14:50:59 cogitate kernel: thinkpad_acpi: rfkill switch tpacpi_bluetooth_sw: radio is blocked
May 07 14:50:59 cogitate systemd-modules-load[84]: Inserted module 'thinkpad_acpi'
May 07 14:50:59 cogitate kernel: thinkpad_acpi: Console audio control enabled, mode: monitor (read only)
May 07 14:50:59 cogitate kernel: input: ThinkPad Extra Buttons as /devices/platform/thinkpad_acpi/input/input1
May 07 14:50:59 cogitate systemd-modules-load[84]: Inserted module 'hfsplus'
May 07 14:50:59 cogitate systemd[1]: Started Load Kernel Modules.
May 07 14:50:59 cogitate systemd[1]: Mounted FUSE Control File System.
May 07 14:50:59 cogitate systemd[1]: Mounting Configuration File System...
May 07 14:50:59 cogitate systemd[1]: Mounted Configuration File System.
May 07 14:51:00 cogitate systemd[1]: Started Setup Virtual Console.
May 07 14:51:00 cogitate systemd-sysctl[138]: Duplicate assignment of kernel/sysrq in file '/usr/lib/sysctl.d/50-default.conf', ignoring.
May 07 14:51:00 cogitate systemd[1]: Found device Thinkpad.
May 07 14:51:00 cogitate kernel: input: Lid Switch as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0D:00/input/input2
May 07 14:51:00 cogitate kernel: ACPI: Lid Switch [LID]
May 07 14:51:00 cogitate kernel: input: Sleep Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0E:00/input/input3
May 07 14:51:00 cogitate kernel: ACPI: Sleep Button [SLPB]
May 07 14:51:00 cogitate kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input4
May 07 14:51:00 cogitate kernel: ACPI: Power Button [PWRF]
May 07 14:51:00 cogitate kernel: ACPI: AC Adapter [AC] (on-line)
May 07 14:51:00 cogitate kernel: intel_rng: FWH not detected
May 07 14:51:00 cogitate kernel: pci_hotplug: PCI Hot Plug PCI Core version: 0.5
May 07 14:51:00 cogitate kernel: lpc_ich 0000:00:1f.0: I/O space for GPIO uninitialized
May 07 14:51:00 cogitate kernel: ACPI: Video Device [VID] (multi-head: yes  rom: no  post: no)
May 07 14:51:00 cogitate kernel: input: Video Bus as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A03:00/device:02/LNXVIDEO:00/input/input5
May 07 14:51:00 cogitate kernel: parport_pc 00:0a: reported by Plug and Play ACPI
May 07 14:51:00 cogitate kernel: parport0: PC-style at 0x3bc, irq 7 [PCSPP,TRISTATE]
May 07 14:51:00 cogitate kernel: ACPI: Battery Slot [BAT0] (battery present)
May 07 14:51:00 cogitate kernel: NET: Registered protocol family 23
May 07 14:51:00 cogitate kernel: ACPI: Requesting acpi_cpufreq
May 07 14:51:00 cogitate kernel: Linux agpgart interface v0.103
May 07 14:51:00 cogitate kernel: nsc-ircc 00:0b: [io  0x02f8-0x02ff]
May 07 14:51:00 cogitate kernel: nsc-ircc 00:0b: [irq 3]
May 07 14:51:00 cogitate kernel: nsc-ircc 00:0b: [dma 1]
May 07 14:51:00 cogitate kernel: nsc-ircc 00:0b: activated
May 07 14:51:00 cogitate kernel: nsc-ircc, chip->init
May 07 14:51:00 cogitate kernel: nsc-ircc, Found chip at base=0x02e
May 07 14:51:00 cogitate kernel: nsc-ircc, driver loaded (Dag Brattli)
May 07 14:51:00 cogitate kernel: IrDA: Registered device irda0
May 07 14:51:00 cogitate kernel: nsc-ircc, Using dongle: IBM31T1100 or Temic TFDS6000/TFDS6500
May 07 14:51:00 cogitate kernel: tsc: Marking TSC unstable due to TSC halts in idle
May 07 14:51:00 cogitate kernel: ACPI: acpi_idle registered with cpuidle
May 07 14:51:00 cogitate kernel: Switching to clocksource acpi_pm
May 07 14:51:00 cogitate kernel: thermal LNXTHERM:00: registered as thermal_zone0
May 07 14:51:00 cogitate kernel: ACPI: Thermal Zone [THM0] (48 C)
May 07 14:51:01 cogitate kernel: shpchp: Standard Hot Plug PCI Controller Driver version: 0.4
May 07 14:51:01 cogitate kernel: agpgart-intel 0000:00:00.0: Intel 855PM Chipset
May 07 14:51:01 cogitate systemd-sysctl[161]: Duplicate assignment of kernel/sysrq in file '/usr/lib/sysctl.d/50-default.conf', ignoring.
May 07 14:51:01 cogitate kernel: agpgart-intel 0000:00:00.0: AGP aperture is 256M @ 0xd0000000
May 07 14:51:01 cogitate kernel: input: PC Speaker as /devices/platform/pcspkr/input/input6
May 07 14:51:01 cogitate kernel: snd_intel8x0m 0000:00:1f.6: setting latency timer to 64
May 07 14:51:01 cogitate kernel: microcode: CPU0 sig=0x695, pf=0x20, revision=0x7
May 07 14:51:02 cogitate kernel: snd_intel8x0 0000:00:1f.5: setting latency timer to 64
May 07 14:51:02 cogitate kernel: cfg80211: Calling CRDA to update world regulatory domain
May 07 14:51:02 cogitate systemd[1]: Starting Sound Card.
May 07 14:51:02 cogitate systemd[1]: Reached target Sound Card.
May 07 14:51:02 cogitate kernel: iTCO_vendor_support: vendor-support=0
May 07 14:51:02 cogitate kernel: iTCO_wdt: Intel TCO WatchDog Timer Driver v1.10
May 07 14:51:02 cogitate kernel: iTCO_wdt: Found a ICH4-M TCO device (Version=1, TCOBASE=0x1060)
May 07 14:51:02 cogitate kernel: iTCO_wdt: initialized. heartbeat=30 sec (nowayout=0)
May 07 14:51:02 cogitate kernel: ppdev: user-space parallel port driver
May 07 14:51:02 cogitate systemd-sysctl[189]: Duplicate assignment of kernel/sysrq in file '/usr/lib/sysctl.d/50-default.conf', ignoring.
May 07 14:51:02 cogitate kernel: yenta_cardbus 0000:02:00.0: CardBus bridge found [1014:0552]
May 07 14:51:02 cogitate kernel: yenta_cardbus 0000:02:00.0: Using INTVAL to route CSC interrupts to PCI
May 07 14:51:02 cogitate kernel: yenta_cardbus 0000:02:00.0: Routing CardBus interrupts to PCI
May 07 14:51:02 cogitate kernel: yenta_cardbus 0000:02:00.0: TI: mfunc 0x01d21b22, devctl 0x64
May 07 14:51:02 cogitate kernel: [drm] Initialized drm 1.1.0 20060810
May 07 14:51:02 cogitate kernel: yenta_cardbus 0000:02:00.0: ISA IRQ mask 0x0470, PCI irq 11
May 07 14:51:02 cogitate kernel: yenta_cardbus 0000:02:00.0: Socket status: 30000086
May 07 14:51:02 cogitate kernel: yenta_cardbus 0000:02:00.0: pcmcia: parent PCI bridge window: [io  0x4000-0x8fff]
May 07 14:51:02 cogitate kernel: pcmcia_socket pcmcia_socket0: cs: IO port probe 0x4000-0x8fff:
May 07 14:51:02 cogitate kernel:  excluding 0x4000-0x40ff 0x4400-0x44ff 0x4800-0x48ff 0x4c00-0x4cff 0x8000-0x803f
May 07 14:51:02 cogitate kernel: yenta_cardbus 0000:02:00.0: pcmcia: parent PCI bridge window: [mem 0xc0200000-0xcfffffff]
May 07 14:51:02 cogitate kernel: pcmcia_socket pcmcia_socket0: cs: memory probe 0xc0200000-0xcfffffff:
May 07 14:51:02 cogitate kernel:  excluding 0xc0200000-0xc09fffff 0xc3a00000-0xcc1fffff 0xcfa00000-0xd01fffff
May 07 14:51:02 cogitate kernel: yenta_cardbus 0000:02:00.0: pcmcia: parent PCI bridge window: [mem 0xe8000000-0xefffffff pref]
May 07 14:51:02 cogitate kernel: pcmcia_socket pcmcia_socket0: cs: memory probe 0xe8000000-0xefffffff:
May 07 14:51:02 cogitate kernel:  excluding 0xe8000000-0xefffffff
May 07 14:51:02 cogitate kernel: yenta_cardbus 0000:02:00.1: CardBus bridge found [1014:0552]
May 07 14:51:02 cogitate kernel: yenta_cardbus 0000:02:00.1: Using INTVAL to route CSC interrupts to PCI
May 07 14:51:02 cogitate kernel: yenta_cardbus 0000:02:00.1: Routing CardBus interrupts to PCI
May 07 14:51:02 cogitate kernel: yenta_cardbus 0000:02:00.1: TI: mfunc 0x01d21b22, devctl 0x64
May 07 14:51:02 cogitate kernel: microcode: Microcode Update Driver: v2.00 <tigran@aivazian.fsnet.co.uk>, Peter Oruba
May 07 14:51:02 cogitate kernel: yenta_cardbus 0000:02:00.1: ISA IRQ mask 0x0470, PCI irq 11
May 07 14:51:02 cogitate kernel: yenta_cardbus 0000:02:00.1: Socket status: 30000086
May 07 14:51:02 cogitate kernel: yenta_cardbus 0000:02:00.1: pcmcia: parent PCI bridge window: [io  0x4000-0x8fff]
May 07 14:51:02 cogitate kernel: pcmcia_socket pcmcia_socket1: cs: IO port probe 0x4000-0x8fff:
May 07 14:51:02 cogitate kernel:  excluding 0x4000-0x40ff 0x4400-0x44ff 0x4800-0x48ff 0x4c00-0x4cff 0x8000-0x803f
May 07 14:51:02 cogitate kernel: yenta_cardbus 0000:02:00.1: pcmcia: parent PCI bridge window: [mem 0xc0200000-0xcfffffff]
May 07 14:51:02 cogitate kernel: pcmcia_socket pcmcia_socket1: cs: memory probe 0xc0200000-0xcfffffff:
May 07 14:51:02 cogitate kernel:  excluding 0xc0200000-0xc09fffff 0xc3a00000-0xcc1fffff 0xcfa00000-0xd01fffff
May 07 14:51:02 cogitate kernel: yenta_cardbus 0000:02:00.1: pcmcia: parent PCI bridge window: [mem 0xe8000000-0xefffffff pref]
May 07 14:51:02 cogitate kernel: pcmcia_socket pcmcia_socket1: cs: memory probe 0xe8000000-0xefffffff:
May 07 14:51:02 cogitate kernel:  excluding 0xe8000000-0xefffffff
May 07 14:51:03 cogitate kernel: intel8x0_measure_ac97_clock: measured 52501 usecs (2530 samples)
May 07 14:51:03 cogitate kernel: intel8x0: clocking to 48000
May 07 14:51:03 cogitate kernel: psmouse serio1: synaptics: Touchpad model: 1, fw: 5.9, id: 0x2c6ab1, caps: 0x884793/0x0/0x0, board id: 71, fw id: 4797594
May 07 14:51:03 cogitate kernel: psmouse serio1: synaptics: serio: Synaptics pass-through port at isa0060/serio1/input0
May 07 14:51:03 cogitate kernel: [drm] radeon defaulting to kernel modesetting.
May 07 14:51:03 cogitate kernel: [drm] radeon kernel modesetting enabled.
May 07 14:51:03 cogitate kernel: [drm] initializing kernel modesetting (RV350 0x1002:0x4E54 0x1014:0x054F).
May 07 14:51:03 cogitate kernel: [drm] register mmio base: 0xC0100000
May 07 14:51:03 cogitate kernel: [drm] register mmio size: 65536
May 07 14:51:03 cogitate kernel: agpgart-intel 0000:00:00.0: AGP 2.0 bridge
May 07 14:51:03 cogitate kernel: agpgart-intel 0000:00:00.0: putting AGP V2 device into 2x mode
May 07 14:51:03 cogitate kernel: radeon 0000:01:00.0: putting AGP V2 device into 2x mode
May 07 14:51:03 cogitate kernel: radeon 0000:01:00.0: GTT: 256M 0xD0000000 - 0xDFFFFFFF
May 07 14:51:03 cogitate kernel: [drm] Generation 2 PCI interface, using max accessible memory
May 07 14:51:03 cogitate kernel: radeon 0000:01:00.0: VRAM: 128M 0x00000000E0000000 - 0x00000000E7FFFFFF (128M used)
May 07 14:51:03 cogitate kernel: [drm] Supports vblank timestamp caching Rev 1 (10.10.2010).
May 07 14:51:03 cogitate kernel: [drm] Driver supports precise vblank timestamp query.
May 07 14:51:03 cogitate kernel: [drm] radeon: irq initialized.
May 07 14:51:03 cogitate kernel: [drm] Detected VRAM RAM=128M, BAR=128M
May 07 14:51:03 cogitate kernel: input: SynPS/2 Synaptics TouchPad as /devices/platform/i8042/serio1/input/input7
May 07 14:51:03 cogitate kernel: [drm] RAM width 128bits DDR
May 07 14:51:03 cogitate kernel: [TTM] Zone  kernel: Available graphics memory: 445498 kiB
May 07 14:51:03 cogitate kernel: [TTM] Zone highmem: Available graphics memory: 514814 kiB
May 07 14:51:03 cogitate kernel: [TTM] Initializing pool allocator
May 07 14:51:03 cogitate kernel: [drm] radeon: 128M of VRAM memory ready
May 07 14:51:03 cogitate kernel: [drm] radeon: 256M of GTT memory ready.
May 07 14:51:03 cogitate kernel: [drm] radeon: 1 quad pipes, 1 Z pipes initialized.
May 07 14:51:03 cogitate kernel: radeon 0000:01:00.0: WB disabled
May 07 14:51:03 cogitate kernel: radeon 0000:01:00.0: fence driver on ring 0 use gpu addr 0x00000000d0000000 and cpu addr 0xf800e000
May 07 14:51:03 cogitate kernel: [drm] Loading R300 Microcode
May 07 14:51:03 cogitate kernel: [drm] radeon: ring at 0x00000000D0001000
May 07 14:51:03 cogitate kernel: [drm] ring test succeeded in 1 usecs
May 07 14:51:03 cogitate kernel: [drm] ib test succeeded in 0 usecs
May 07 14:51:03 cogitate kernel: [drm] Panel ID String: SXGA+ Single (85MHz)    
May 07 14:51:03 cogitate kernel: [drm] Panel Size 1400x1050
May 07 14:51:03 cogitate kernel: [drm] No TV DAC info found in BIOS
May 07 14:51:03 cogitate kernel: [drm] radeon legacy LVDS backlight initialized
May 07 14:51:03 cogitate kernel: [drm] Radeon Display Connectors
May 07 14:51:03 cogitate kernel: [drm] Connector 0:
May 07 14:51:03 cogitate kernel: [drm]   VGA-1
May 07 14:51:03 cogitate kernel: [drm]   DDC: 0x60 0x60 0x60 0x60 0x60 0x60 0x60 0x60
May 07 14:51:03 cogitate kernel: [drm]   Encoders:
May 07 14:51:03 cogitate kernel: [drm]     CRT1: INTERNAL_DAC1
May 07 14:51:03 cogitate kernel: [drm] Connector 1:
May 07 14:51:03 cogitate kernel: [drm]   DVI-D-1
May 07 14:51:03 cogitate kernel: [drm]   HPD1
May 07 14:51:03 cogitate kernel: [drm]   DDC: 0x64 0x64 0x64 0x64 0x64 0x64 0x64 0x64
May 07 14:51:03 cogitate kernel: [drm]   Encoders:
May 07 14:51:03 cogitate kernel: [drm]     DFP1: INTERNAL_TMDS1
May 07 14:51:03 cogitate kernel: [drm] Connector 2:
May 07 14:51:03 cogitate kernel: [drm]   LVDS-1
May 07 14:51:03 cogitate kernel: [drm]   Encoders:
May 07 14:51:03 cogitate kernel: [drm]     LCD1: INTERNAL_LVDS
May 07 14:51:03 cogitate kernel: [drm] Connector 3:
May 07 14:51:03 cogitate kernel: [drm]   SVIDEO-1
May 07 14:51:03 cogitate kernel: [drm]   Encoders:
May 07 14:51:03 cogitate kernel: [drm]     TV1: INTERNAL_DAC2
May 07 14:51:03 cogitate kernel: [drm] radeon: power management initialized
May 07 14:51:03 cogitate kernel: pcmcia_socket pcmcia_socket0: cs: IO port probe 0x100-0x3af:
May 07 14:51:03 cogitate kernel: pcmcia_socket pcmcia_socket1: cs: IO port probe 0x100-0x3af:
May 07 14:51:03 cogitate kernel:  excluding 0x170-0x177 0x1f0-0x1f7
May 07 14:51:03 cogitate kernel:  excluding
May 07 14:51:03 cogitate kernel:  0x170-0x177 0x1f0-0x1f7 0x2f8-0x2ff 0x370-0x377
May 07 14:51:03 cogitate kernel: pcmcia_socket pcmcia_socket0: cs: IO port probe 0x3e0-0x4ff:
May 07 14:51:03 cogitate kernel:  excluding 0x3f0-0x3ff 0x4d0-0x4d7
May 07 14:51:03 cogitate kernel: pcmcia_socket pcmcia_socket0: cs: IO port probe 0x820-0x8ff:
May 07 14:51:03 cogitate kernel:  clean.
May 07 14:51:03 cogitate kernel: pcmcia_socket pcmcia_socket0: cs: IO port probe 0xc00-0xcf7:
May 07 14:51:03 cogitate kernel:  0x2f8-0x2ff 0x370-0x377
May 07 14:51:03 cogitate kernel: pcmcia_socket pcmcia_socket1: cs: IO port probe 0x3e0-0x4ff:
May 07 14:51:03 cogitate kernel:  excluding 0x3f0-0x3ff 0x4d0-0x4d7
May 07 14:51:03 cogitate kernel: pcmcia_socket pcmcia_socket1: cs: IO port probe 0x820-0x8ff:
May 07 14:51:03 cogitate kernel:  clean.
May 07 14:51:03 cogitate kernel: pcmcia_socket pcmcia_socket1: cs: IO port probe 0xc00-0xcf7:
May 07 14:51:03 cogitate kernel:  excluding 0xcd0-0xcd7
May 07 14:51:03 cogitate kernel: pcmcia_socket pcmcia_socket1: cs: memory probe 0x0c0000-0x0fffff:
May 07 14:51:03 cogitate kernel:  excluding 0xc0000-0xd3fff 0xdc000-0xfffff
May 07 14:51:03 cogitate kernel: pcmcia_socket pcmcia_socket1: cs: memory probe 0xa0000000-0xa0ffffff:
May 07 14:51:03 cogitate kernel:  clean.
May 07 14:51:03 cogitate kernel: pcmcia_socket pcmcia_socket1: cs: memory probe 0x60000000-0x60ffffff:
May 07 14:51:03 cogitate kernel:  clean.
May 07 14:51:03 cogitate kernel: pcmcia_socket pcmcia_socket1: cs: IO port probe 0xa00-0xaff:
May 07 14:51:03 cogitate kernel:  clean.
May 07 14:51:03 cogitate kernel:  clean.
May 07 14:51:03 cogitate kernel: pcmcia_socket pcmcia_socket0: cs: memory probe 0x0c0000-0x0fffff:
May 07 14:51:03 cogitate kernel:  excluding 0xc0000-0xd3fff 0xdc000-0xfffff
May 07 14:51:03 cogitate kernel: pcmcia_socket pcmcia_socket0: cs: memory probe 0xa0000000-0xa0ffffff:
May 07 14:51:03 cogitate kernel:  clean.
May 07 14:51:03 cogitate kernel: pcmcia_socket pcmcia_socket0: cs: memory probe 0x60000000-0x60ffffff:
May 07 14:51:03 cogitate kernel:  clean.
May 07 14:51:03 cogitate kernel: pcmcia_socket pcmcia_socket0: cs: IO port probe 0xa00-0xaff:
May 07 14:51:03 cogitate kernel:  clean.
May 07 14:51:03 cogitate kernel: [drm] fb mappable at 0xE0040000
May 07 14:51:03 cogitate kernel: [drm] vram apper at 0xE0000000
May 07 14:51:03 cogitate kernel: [drm] size 5914624
May 07 14:51:03 cogitate kernel: [drm] fb depth is 24
May 07 14:51:03 cogitate kernel: [drm]    pitch is 5632
May 07 14:51:03 cogitate kernel: fbcon: radeondrmfb (fb0) is primary device
May 07 14:51:03 cogitate kernel: ath5k 0000:02:02.0: registered as 'phy0'
May 07 14:51:03 cogitate kernel: Console: switching to colour frame buffer device 175x65
May 07 14:51:03 cogitate kernel: radeon 0000:01:00.0: fb0: radeondrmfb frame buffer device
May 07 14:51:03 cogitate kernel: radeon 0000:01:00.0: registered panic notifier
May 07 14:51:03 cogitate kernel: [drm] Initialized radeon 2.29.0 20080528 for 0000:01:00.0 on minor 0
May 07 14:51:03 cogitate kernel: ath: EEPROM regdomain: 0x61
May 07 14:51:03 cogitate kernel: ath: EEPROM indicates we should expect a direct regpair map
May 07 14:51:03 cogitate kernel: ath: Country alpha2 being used: 00
May 07 14:51:03 cogitate kernel: ath: Regpair used: 0x61
May 07 14:51:03 cogitate kernel: ieee80211 phy0: Selected rate control algorithm 'minstrel_ht'
May 07 14:51:03 cogitate kernel: ath5k: phy0: Atheros AR5212 chip found (MAC: 0x56, PHY: 0x41)
May 07 14:51:03 cogitate kernel: ath5k: phy0: RF5111 5GHz radio found (0x17)
May 07 14:51:03 cogitate kernel: ath5k: phy0: RF2111 2GHz radio found (0x23)
May 07 14:51:03 cogitate systemd-sysctl[212]: Duplicate assignment of kernel/sysrq in file '/usr/lib/sysctl.d/50-default.conf', ignoring.
May 07 14:51:04 cogitate systemd[1]: Found device AR5212 802.11abg NIC.
May 07 14:51:05 cogitate systemd[1]: Found device ST9402113A.
May 07 14:51:05 cogitate systemd[1]: Activating swap /dev/sda6...
May 07 14:51:05 cogitate kernel: psmouse serio2: hgpk: ID: 10 00 64
May 07 14:51:05 cogitate systemd[1]: Found device ST9402113A.
May 07 14:51:05 cogitate systemd[1]: Mounting /mnt/win...
May 07 14:51:05 cogitate kernel: Adding 851408k swap on /dev/sda6.  Priority:-1 extents:1 across:851408k 
May 07 14:51:05 cogitate systemd[1]: Activated swap /dev/sda6.
May 07 14:51:05 cogitate systemd[1]: Starting Swap.
May 07 14:51:05 cogitate systemd[1]: Reached target Swap.
May 07 14:51:05 cogitate systemd[1]: Mounted FUSE Control File System.
May 07 14:51:05 cogitate kernel: fuse init (API version 7.20)
May 07 14:51:06 cogitate systemd[1]: Mounted /mnt/win.
May 07 14:51:06 cogitate systemd[1]: Starting Local File Systems.
May 07 14:51:06 cogitate systemd[1]: Reached target Local File Systems.
May 07 14:51:06 cogitate systemd[1]: Starting Trigger Flushing of Journal to Persistent Storage...
May 07 14:51:06 cogitate systemd[1]: Starting Recreate Volatile Files and Directories...
May 07 14:51:06 cogitate ntfs-3g[400]: Version 2013.1.13 external FUSE 29
May 07 14:51:06 cogitate ntfs-3g[400]: Mounted /dev/sda1 (Read-Write, label "", NTFS 3.1)
May 07 14:51:06 cogitate ntfs-3g[400]: Cmdline options: rw,gid=100,fmask=113,dmask=002
May 07 14:51:06 cogitate ntfs-3g[400]: Mount options: rw,allow_other,nonempty,relatime,default_permissions,fsname=/dev/sda1,blkdev,blksize=4096
May 07 14:51:06 cogitate ntfs-3g[400]: Global ownership and permissions enforced, configuration type 7
May 07 14:51:06 cogitate ntfs-3g[400]: Warning : using problematic uid==0 and gid!=0
May 07 14:51:06 cogitate systemd[1]: Started Recreate Volatile Files and Directories.
May 07 14:51:06 cogitate systemd[1]: Starting System Initialization.
May 07 14:51:06 cogitate systemd[1]: Reached target System Initialization.
May 07 14:51:06 cogitate systemd[1]: Starting Daily Cleanup of Temporary Directories.
May 07 14:51:06 cogitate systemd[1]: Started Daily Cleanup of Temporary Directories.
May 07 14:51:06 cogitate systemd[1]: Starting Timers.
May 07 14:51:06 cogitate systemd[1]: Reached target Timers.
May 07 14:51:06 cogitate systemd[1]: Started Manage Sound Card State (restore and store).
May 07 14:51:06 cogitate systemd[1]: Starting Restore Sound Card State...
May 07 14:51:06 cogitate systemd[1]: Starting ACPID Listen Socket.
May 07 14:51:06 cogitate systemd[1]: Listening on ACPID Listen Socket.
May 07 14:51:06 cogitate systemd[1]: Starting D-Bus System Message Bus Socket.
May 07 14:51:06 cogitate systemd[1]: Started Restore Sound Card State.
May 07 14:51:06 cogitate systemd[1]: Started ATD daemon.
May 07 14:51:07 cogitate systemd-journal[91]: Allowing system journal files to grow to 1.7G.
May 07 14:51:06 cogitate acpid[407]: starting up with netlink and the input layer
May 07 14:51:06 cogitate crond[408]: (CRON) INFO (Syslog will be used instead of sendmail.)
May 07 14:51:06 cogitate crond[408]: (CRON) INFO (running with inotify support)
May 07 14:51:06 cogitate acpid[407]: 4 rules loaded
May 07 14:51:06 cogitate acpid[407]: waiting for events: event logging is off
May 07 14:51:07 cogitate laptop-mode[447]: enabled, not active
May 07 14:51:10 cogitate laptop-mode[941]: Failed.
May 07 14:51:10 cogitate wpa_actiond[946]: Starting wpa_actiond session for interface 'wlan0'
May 07 14:51:10 cogitate ntpd[947]: ntpd 4.2.6p5@1.2349-o Mon May  6 10:21:04 UTC 2013 (1)
May 07 14:51:10 cogitate ntpd[948]: proto: precision = 1.677 usec
May 07 14:51:11 cogitate ntpd[948]: ntp_io: estimated max descriptors: 1024, initial socket boundary: 16
May 07 14:51:39 cogitate wpa_actiond[946]: Interface 'wlan0' connected to network 'SpeedOfSound'
May 07 14:51:39 cogitate login[442]: pam_unix(login:session): session opened for user anarch by LOGIN(uid=0)
May 07 14:51:40 cogitate ntpd_intres[949]: host name not found: 0.pool.ntp.org
May 07 14:51:40 cogitate login[442]: LOGIN ON tty1 BY anarch
May 07 14:51:40 cogitate ntpd_intres[949]: host name not found: 1.pool.ntp.org
May 07 14:51:40 cogitate ntpd_intres[949]: host name not found: 2.pool.ntp.org
May 07 14:51:40 cogitate dhcpcd[960]: version 5.6.8 starting
May 07 14:51:41 cogitate dhcpcd[960]: wlan0: sending IPv6 Router Solicitation
May 07 14:51:41 cogitate dhcpcd[960]: wlan0: broadcasting for a lease
May 07 14:51:42 cogitate ntpd_intres[949]: host name not found: 0.pool.ntp.org
May 07 14:51:42 cogitate ntpd_intres[949]: host name not found: 1.pool.ntp.org
May 07 14:51:42 cogitate ntpd_intres[949]: host name not found: 2.pool.ntp.org
May 07 14:51:06 cogitate systemd[1]: Started Trigger Flushing of Journal to Persistent Storage.
May 07 14:51:06 cogitate systemd[1]: Starting Permit User Sessions...
May 07 14:51:06 cogitate systemd[1]: Started Permit User Sessions.
May 07 14:51:06 cogitate systemd[1]: Starting Getty on tty1...
May 07 14:51:06 cogitate systemd[1]: Started Getty on tty1.
May 07 14:51:06 cogitate systemd[1]: Starting Login Prompts.
May 07 14:51:06 cogitate systemd[1]: Reached target Login Prompts.
May 07 14:51:08 cogitate systemd[1]: Started Laptop Mode Tools.
May 07 14:51:09 cogitate systemd-logind[411]: New seat seat0.
May 07 14:51:09 cogitate systemd[1]: Started Login Service.
May 07 14:51:09 cogitate systemd-logind[411]: Watching system buttons on /dev/input/event4 (Power Button)
May 07 14:51:42 cogitate kernel: IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
May 07 14:51:42 cogitate dhcpcd[960]: wlan0: offered 192.168.2.5 from 192.168.2.1
May 07 14:51:43 cogitate netctl-auto[405]: Included profile 'homewifi'
May 07 14:51:43 cogitate netctl-auto[405]: Included profile 'wlan0-AppeaseTheCheese'
May 07 14:51:43 cogitate kernel: uhci_hcd 0000:00:1d.0: power state changed by ACPI to D2
May 07 14:51:43 cogitate ifplugd[406]: ifplugd 0.28 initializing.
May 07 14:51:43 cogitate ifplugd[406]: Using interface eth0/00:11:25:11:E2:27 with driver <e1000> (version: 7.3.21-k8-NAPI)
May 07 14:51:43 cogitate ifplugd[406]: Using detection mode: SIOCETHTOOL
May 07 14:51:43 cogitate ifplugd[406]: Initialization complete, link beat not detected.
May 07 14:51:43 cogitate kernel: uhci_hcd 0000:00:1d.1: power state changed by ACPI to D2
May 07 14:51:43 cogitate kernel: ehci-pci 0000:00:1d.7: power state changed by ACPI to D3cold
May 07 14:51:43 cogitate kernel: psmouse serio2: trackpoint: IBM TrackPoint firmware: 0x0e, buttons: 3/3
May 07 14:51:43 cogitate kernel: ehci-pci 0000:00:1d.7: power state changed by ACPI to D0
May 07 14:51:43 cogitate kernel: ehci-pci 0000:00:1d.7: setting latency timer to 64
May 07 14:51:43 cogitate kernel: uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0
May 07 14:51:43 cogitate kernel: uhci_hcd 0000:00:1d.0: setting latency timer to 64
May 07 14:51:43 cogitate kernel: uhci_hcd 0000:00:1d.1: power state changed by ACPI to D0
May 07 14:51:43 cogitate kernel: uhci_hcd 0000:00:1d.1: setting latency timer to 64
May 07 14:51:43 cogitate kernel: input: TPPS/2 IBM TrackPoint as /devices/platform/i8042/serio1/serio2/input/input8
May 07 14:51:43 cogitate systemd-logind[411]: Watching system buttons on /dev/input/event5 (Video Bus)
May 07 14:51:43 cogitate systemd-logind[411]: Watching system buttons on /dev/input/event2 (Lid Switch)
May 07 14:51:43 cogitate systemd-logind[411]: Watching system buttons on /dev/input/event3 (Sleep Button)
May 07 14:51:43 cogitate systemd-logind[411]: Watching system buttons on /dev/input/event1 (ThinkPad Extra Buttons)
May 07 14:51:43 cogitate kernel: IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
May 07 14:51:43 cogitate kernel: wlan0: authenticate with 94:44:52:1f:1e:96
May 07 14:51:43 cogitate kernel: wlan0: send auth to 94:44:52:1f:1e:96 (try 1/3)
May 07 14:51:43 cogitate kernel: wlan0: authenticated
May 07 14:51:43 cogitate kernel: wlan0: associate with 94:44:52:1f:1e:96 (try 1/3)
May 07 14:51:43 cogitate kernel: wlan0: RX AssocResp from 94:44:52:1f:1e:96 (capab=0x11 status=0 aid=2)
May 07 14:51:43 cogitate kernel: IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
May 07 14:51:43 cogitate kernel: wlan0: associated
May 07 14:51:44 cogitate dhcpcd[960]: wlan0: acknowledged 192.168.2.5 from 192.168.2.1
May 07 14:51:44 cogitate dhcpcd[960]: wlan0: checking for 192.168.2.5
May 07 14:51:45 cogitate dhcpcd[960]: wlan0: sending IPv6 Router Solicitation
May 07 14:51:49 cogitate dhcpcd[960]: wlan0: leased 192.168.2.5 for infinity
May 07 14:51:49 cogitate dhcpcd[960]: forked to background, child pid 1040
May 07 14:51:49 cogitate dhcpcd[1040]: wlan0: sending IPv6 Router Solicitation
May 07 14:51:50 cogitate dbus-daemon[412]: dbus[412]: [system] Activating via systemd: service name='org.freedesktop.UDisks' unit='udisks.service'
May 07 14:51:50 cogitate dbus[412]: [system] Activating via systemd: service name='org.freedesktop.UDisks' unit='udisks.service'
May 07 14:51:50 cogitate ntpd[948]: Listen normally on 5 wlan0 192.168.2.5 UDP 123
May 07 14:51:50 cogitate ntpd[948]: peers refreshed
May 07 14:51:50 cogitate ntpd[948]: new interface(s) found: waking up resolver
May 07 14:51:50 cogitate systemd[1]: Starting UDisks...
May 07 14:51:51 cogitate udisks-daemon[1042]: (process:1042): GLib-WARNING **: goption.c:2199: ignoring no-arg, optional-arg or filename flags (16) on option of arg-type 1 in entry (null):helper-dir
May 07 14:51:51 cogitate dbus-daemon[412]: dbus[412]: [system] Successfully activated service 'org.freedesktop.UDisks'
May 07 14:51:51 cogitate dbus[412]: [system] Successfully activated service 'org.freedesktop.UDisks'
May 07 14:51:51 cogitate systemd[1]: Started UDisks.
May 07 14:51:51 cogitate dbus-daemon[412]: dbus[412]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service'
May 07 14:51:51 cogitate dbus[412]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service'
May 07 14:51:51 cogitate systemd[1]: Starting Authorization Manager...
May 07 14:51:51 cogitate polkitd[1045]: Started polkitd version 0.110
May 07 14:51:52 cogitate polkitd[1045]: Loading rules from directory /etc/polkit-1/rules.d
May 07 14:51:52 cogitate polkitd[1045]: Loading rules from directory /usr/share/polkit-1/rules.d
May 07 14:51:52 cogitate polkitd[1045]: Finished loading, compiling and executing 2 rules
May 07 14:51:52 cogitate dbus-daemon[412]: dbus[412]: [system] Successfully activated service 'org.freedesktop.PolicyKit1'
May 07 14:51:52 cogitate dbus[412]: [system] Successfully activated service 'org.freedesktop.PolicyKit1'
May 07 14:51:52 cogitate systemd[1]: Started Authorization Manager.
May 07 14:51:52 cogitate polkitd[1045]: Acquired the name org.freedesktop.PolicyKit1 on the system bus
May 07 14:51:52 cogitate udisks-daemon[1042]: (udisks-daemon:1042): udisks-daemon-WARNING **: No /proc/mdstat file: No such file or directory
May 07 14:51:52 cogitate udisks-daemon[1042]: **** pci ADDING /sys/devices/pci0000:00/0000:00:00.0
May 07 14:51:52 cogitate udisks-daemon[1042]: **** pci IGNORING ADD /sys/devices/pci0000:00/0000:00:00.0
May 07 14:51:52 cogitate udisks-daemon[1042]: **** pci ADDING /sys/devices/pci0000:00/0000:00:01.0
May 07 14:51:52 cogitate udisks-daemon[1042]: **** pci IGNORING ADD /sys/devices/pci0000:00/0000:00:01.0
May 07 14:51:52 cogitate udisks-daemon[1042]: **** pci ADDING /sys/devices/pci0000:00/0000:00:01.0/0000:01:00.0
May 07 14:51:52 cogitate udisks-daemon[1042]: **** pci IGNORING ADD /sys/devices/pci0000:00/0000:00:01.0/0000:01:00.0
May 07 14:51:52 cogitate udisks-daemon[1042]: **** pci ADDING /sys/devices/pci0000:00/0000:00:1d.0
May 07 14:51:52 cogitate udisks-daemon[1042]: **** pci IGNORING ADD /sys/devices/pci0000:00/0000:00:1d.0
May 07 14:51:52 cogitate udisks-daemon[1042]: **** pci ADDING /sys/devices/pci0000:00/0000:00:1d.1
May 07 14:51:52 cogitate udisks-daemon[1042]: **** pci IGNORING ADD /sys/devices/pci0000:00/0000:00:1d.1
May 07 14:51:52 cogitate udisks-daemon[1042]: **** pci ADDING /sys/devices/pci0000:00/0000:00:1d.2
May 07 14:51:52 cogitate udisks-daemon[1042]: **** pci IGNORING ADD /sys/devices/pci0000:00/0000:00:1d.2
May 07 14:51:52 cogitate udisks-daemon[1042]: **** pci ADDING /sys/devices/pci0000:00/0000:00:1d.7
May 07 14:51:52 cogitate udisks-daemon[1042]: **** pci IGNORING ADD /sys/devices/pci0000:00/0000:00:1d.7
May 07 14:51:52 cogitate udisks-daemon[1042]: **** pci ADDING /sys/devices/pci0000:00/0000:00:1e.0
May 07 14:51:52 cogitate udisks-daemon[1042]: **** pci IGNORING ADD /sys/devices/pci0000:00/0000:00:1e.0
May 07 14:51:52 cogitate udisks-daemon[1042]: **** pci ADDING /sys/devices/pci0000:00/0000:00:1e.0/0000:02:00.0
May 07 14:51:52 cogitate udisks-daemon[1042]: **** pci IGNORING ADD /sys/devices/pci0000:00/0000:00:1e.0/0000:02:00.0
May 07 14:51:52 cogitate udisks-daemon[1042]: **** pci ADDING /sys/devices/pci0000:00/0000:00:1e.0/0000:02:00.1
May 07 14:51:52 cogitate udisks-daemon[1042]: **** pci IGNORING ADD /sys/devices/pci0000:00/0000:00:1e.0/0000:02:00.1
May 07 14:51:52 cogitate udisks-daemon[1042]: **** pci ADDING /sys/devices/pci0000:00/0000:00:1e.0/0000:02:01.0
May 07 14:51:52 cogitate udisks-daemon[1042]: **** pci IGNORING ADD /sys/devices/pci0000:00/0000:00:1e.0/0000:02:01.0
May 07 14:51:52 cogitate udisks-daemon[1042]: **** pci ADDING /sys/devices/pci0000:00/0000:00:1e.0/0000:02:02.0
May 07 14:51:52 cogitate udisks-daemon[1042]: **** pci IGNORING ADD /sys/devices/pci0000:00/0000:00:1e.0/0000:02:02.0
May 07 14:51:52 cogitate udisks-daemon[1042]: **** pci ADDING /sys/devices/pci0000:00/0000:00:1f.0
May 07 14:51:52 cogitate udisks-daemon[1042]: **** pci IGNORING ADD /sys/devices/pci0000:00/0000:00:1f.0
May 07 14:51:52 cogitate udisks-daemon[1042]: **** pci ADDING /sys/devices/pci0000:00/0000:00:1f.1
May 07 14:51:52 cogitate udisks-daemon[1042]: **** UPDATING /sys/devices/pci0000:00/0000:00:1f.1
May 07 14:51:52 cogitate udisks-daemon[1042]: **** pci IGNORING ADD /sys/devices/pci0000:00/0000:00:1f.1
May 07 14:51:52 cogitate udisks-daemon[1042]: **** pci ADDING /sys/devices/pci0000:00/0000:00:1f.3
May 07 14:51:52 cogitate udisks-daemon[1042]: **** pci IGNORING ADD /sys/devices/pci0000:00/0000:00:1f.3
May 07 14:51:52 cogitate udisks-daemon[1042]: **** pci ADDING /sys/devices/pci0000:00/0000:00:1f.5
May 07 14:51:52 cogitate udisks-daemon[1042]: **** pci IGNORING ADD /sys/devices/pci0000:00/0000:00:1f.5
May 07 14:51:52 cogitate udisks-daemon[1042]: **** pci ADDING /sys/devices/pci0000:00/0000:00:1f.6
May 07 14:51:52 cogitate udisks-daemon[1042]: **** pci IGNORING ADD /sys/devices/pci0000:00/0000:00:1f.6
May 07 14:51:52 cogitate udisks-daemon[1042]: **** scsi_host ADDING /sys/devices/pci0000:00/0000:00:1f.1/ata1/host0/scsi_host/host0
May 07 14:51:52 cogitate udisks-daemon[1042]: **** scsi_host IGNORING ADD /sys/devices/pci0000:00/0000:00:1f.1/ata1/host0/scsi_host/host0
May 07 14:51:52 cogitate udisks-daemon[1042]: **** scsi_host ADDING /sys/devices/pci0000:00/0000:00:1f.1/ata2/host1/scsi_host/host1
May 07 14:51:52 cogitate udisks-daemon[1042]: **** scsi_host IGNORING ADD /sys/devices/pci0000:00/0000:00:1f.1/ata2/host1/scsi_host/host1
May 07 14:51:52 cogitate udisks-daemon[1042]: **** ADDING /sys/devices/pci0000:00/0000:00:1f.1/ata1/host0/target0:0:0/0:0:0:0/block/sda
May 07 14:51:52 cogitate udisks-daemon[1042]: **** UPDATING /sys/devices/pci0000:00/0000:00:1f.1/ata1/host0/target0:0:0/0:0:0:0/block/sda
May 07 14:51:52 cogitate udisks-daemon[1042]: helper(pid  1051): launched job udisks-helper-ata-smart-collect on /dev/sda
May 07 14:51:52 cogitate udisks-daemon[1042]: **** ADDED /sys/devices/pci0000:00/0000:00:1f.1/ata1/host0/target0:0:0/0:0:0:0/block/sda
May 07 14:51:52 cogitate udisks-daemon[1042]: **** ADDING /sys/devices/pci0000:00/0000:00:1f.1/ata1/host0/target0:0:0/0:0:0:0/block/sda/sda1
May 07 14:51:52 cogitate udisks-daemon[1042]: **** UPDATING /sys/devices/pci0000:00/0000:00:1f.1/ata1/host0/target0:0:0/0:0:0:0/block/sda/sda1
May 07 14:51:52 cogitate udisks-daemon[1042]: **** ADDED /sys/devices/pci0000:00/0000:00:1f.1/ata1/host0/target0:0:0/0:0:0:0/block/sda/sda1
May 07 14:51:52 cogitate udisks-daemon[1042]: **** ADDING /sys/devices/pci0000:00/0000:00:1f.1/ata1/host0/target0:0:0/0:0:0:0/block/sda/sda2
May 07 14:51:52 cogitate udisks-daemon[1042]: **** UPDATING /sys/devices/pci0000:00/0000:00:1f.1/ata1/host0/target0:0:0/0:0:0:0/block/sda/sda2
May 07 14:51:52 cogitate udisks-daemon[1042]: **** ADDED /sys/devices/pci0000:00/0000:00:1f.1/ata1/host0/target0:0:0/0:0:0:0/block/sda/sda2
May 07 14:51:52 cogitate udisks-daemon[1042]: **** ADDING /sys/devices/pci0000:00/0000:00:1f.1/ata1/host0/target0:0:0/0:0:0:0/block/sda/sda5
May 07 14:51:52 cogitate udisks-daemon[1042]: **** UPDATING /sys/devices/pci0000:00/0000:00:1f.1/ata1/host0/target0:0:0/0:0:0:0/block/sda/sda5
May 07 14:51:52 cogitate udisks-daemon[1042]: **** ADDED /sys/devices/pci0000:00/0000:00:1f.1/ata1/host0/target0:0:0/0:0:0:0/block/sda/sda5
May 07 14:51:52 cogitate udisks-daemon[1042]: **** ADDING /sys/devices/pci0000:00/0000:00:1f.1/ata1/host0/target0:0:0/0:0:0:0/block/sda/sda6
May 07 14:51:52 cogitate udisks-daemon[1042]: **** UPDATING /sys/devices/pci0000:00/0000:00:1f.1/ata1/host0/target0:0:0/0:0:0:0/block/sda/sda6
May 07 14:51:52 cogitate udisks-daemon[1042]: **** ADDED /sys/devices/pci0000:00/0000:00:1f.1/ata1/host0/target0:0:0/0:0:0:0/block/sda/sda6
May 07 14:51:52 cogitate udisks-daemon[1042]: **** ADDING /sys/devices/pci0000:00/0000:00:1f.1/ata2/host1/target1:0:0/1:0:0:0/block/sr0
May 07 14:51:52 cogitate udisks-daemon[1042]: **** UPDATING /sys/devices/pci0000:00/0000:00:1f.1/ata2/host1/target1:0:0/1:0:0:0/block/sr0
May 07 14:51:52 cogitate udisks-daemon[1042]: **** ADDED /sys/devices/pci0000:00/0000:00:1f.1/ata2/host1/target1:0:0/1:0:0:0/block/sr0
May 07 14:51:52 cogitate udisks-daemon[1042]: **** CHANGING /sys/devices/pci0000:00/0000:00:1f.1/ata1/host0/target0:0:0/0:0:0:0/block/sda/sda5
May 07 14:51:52 cogitate udisks-daemon[1042]: **** UPDATING /sys/devices/pci0000:00/0000:00:1f.1/ata1/host0/target0:0:0/0:0:0:0/block/sda/sda5
May 07 14:51:52 cogitate udisks-daemon[1042]: **** CHANGED /sys/devices/pci0000:00/0000:00:1f.1/ata1/host0/target0:0:0/0:0:0:0/block/sda/sda5
May 07 14:51:52 cogitate udisks-daemon[1042]: **** CHANGING /sys/devices/pci0000:00/0000:00:1f.1/ata1/host0/target0:0:0/0:0:0:0/block/sda/sda6
May 07 14:51:52 cogitate udisks-daemon[1042]: **** UPDATING /sys/devices/pci0000:00/0000:00:1f.1/ata1/host0/target0:0:0/0:0:0:0/block/sda/sda6
May 07 14:51:52 cogitate udisks-daemon[1042]: **** CHANGED /sys/devices/pci0000:00/0000:00:1f.1/ata1/host0/target0:0:0/0:0:0:0/block/sda/sda6
May 07 14:51:52 cogitate udisks-daemon[1042]: **** CHANGING /sys/devices/pci0000:00/0000:00:1f.1/ata1/host0/target0:0:0/0:0:0:0/block/sda/sda1
May 07 14:51:52 cogitate udisks-daemon[1042]: **** UPDATING /sys/devices/pci0000:00/0000:00:1f.1/ata1/host0/target0:0:0/0:0:0:0/block/sda/sda1
May 07 14:51:52 cogitate udisks-daemon[1042]: **** CHANGED /sys/devices/pci0000:00/0000:00:1f.1/ata1/host0/target0:0:0/0:0:0:0/block/sda/sda1
May 07 14:51:52 cogitate udisks-daemon[1042]: **** CHANGING /sys/devices/pci0000:00/0000:00:1f.1/ata1/host0/target0:0:0/0:0:0:0/block/sda/sda2
May 07 14:51:52 cogitate udisks-daemon[1042]: **** UPDATING /sys/devices/pci0000:00/0000:00:1f.1/ata1/host0/target0:0:0/0:0:0:0/block/sda/sda2
May 07 14:51:52 cogitate udisks-daemon[1042]: **** CHANGED /sys/devices/pci0000:00/0000:00:1f.1/ata1/host0/target0:0:0/0:0:0:0/block/sda/sda2
May 07 14:51:52 cogitate udisks-daemon[1042]: **** CHANGING /sys/devices/pci0000:00/0000:00:1f.1/ata1/host0/target0:0:0/0:0:0:0/block/sda
May 07 14:51:52 cogitate udisks-daemon[1042]: **** UPDATING /sys/devices/pci0000:00/0000:00:1f.1/ata1/host0/target0:0:0/0:0:0:0/block/sda
May 07 14:51:52 cogitate udisks-daemon[1042]: **** CHANGED /sys/devices/pci0000:00/0000:00:1f.1/ata1/host0/target0:0:0/0:0:0:0/block/sda
May 07 14:51:52 cogitate udisks-daemon[1042]: **** CHANGING /sys/devices/pci0000:00/0000:00:1f.1/ata2/host1/target1:0:0/1:0:0:0/block/sr0
May 07 14:51:52 cogitate udisks-daemon[1042]: **** UPDATING /sys/devices/pci0000:00/0000:00:1f.1/ata2/host1/target1:0:0/1:0:0:0/block/sr0
May 07 14:51:52 cogitate udisks-daemon[1042]: **** CHANGED /sys/devices/pci0000:00/0000:00:1f.1/ata2/host1/target1:0:0/1:0:0:0/block/sr0
May 07 14:51:52 cogitate udisks-daemon[1042]: helper(pid  1051): completed with exit code 0
May 07 14:51:52 cogitate udisks-daemon[1042]: **** EMITTING CHANGED for /sys/devices/pci0000:00/0000:00:1f.1/ata1/host0/target0:0:0/0:0:0:0/block/sda
May 07 14:51:53 cogitate dhcpcd[1040]: wlan0: sending IPv6 Router Solicitation
May 07 14:51:53 cogitate dhcpcd[1040]: wlan0: no IPv6 Routers available
May 07 14:51:54 cogitate ntpd_intres[949]: DNS 0.pool.ntp.org -> 193.55.167.1
May 07 14:51:54 cogitate ntpd_intres[949]: DNS 1.pool.ntp.org -> 94.125.129.7
May 07 14:51:54 cogitate ntpd_intres[949]: DNS 2.pool.ntp.org -> 89.111.0.73
May 07 14:52:00 cogitate dbus-daemon[412]: dbus[412]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service'
May 07 14:52:00 cogitate dbus[412]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service'
May 07 14:52:00 cogitate systemd[1]: Starting RealtimeKit Scheduling Policy Service...
May 07 14:52:00 cogitate systemd[1]: Started RealtimeKit Scheduling Policy Service.
May 07 14:52:00 cogitate dbus[412]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1'
May 07 14:52:00 cogitate rtkit-daemon[1086]: Successfully called chroot.
May 07 14:52:00 cogitate rtkit-daemon[1086]: Successfully dropped privileges.
May 07 14:52:00 cogitate rtkit-daemon[1086]: Successfully limited resources.
May 07 14:52:00 cogitate rtkit-daemon[1086]: Running.
May 07 14:52:00 cogitate rtkit-daemon[1086]: Watchdog thread running.
May 07 14:52:00 cogitate rtkit-daemon[1086]: Canary thread running.
May 07 14:52:00 cogitate dbus-daemon[412]: dbus[412]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1'
May 07 14:52:01 cogitate rtkit-daemon[1086]: Successfully made thread 1085 of process 1085 (/usr/bin/pulseaudio) owned by '1000' high priority at nice level -11.
May 07 14:52:01 cogitate rtkit-daemon[1086]: Supervising 1 threads of 1 processes of 1 users.
May 07 14:52:01 cogitate dbus-daemon[412]: dbus[412]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service'
May 07 14:52:01 cogitate dbus[412]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service'
May 07 14:52:01 cogitate dbus-daemon[412]: dbus[412]: [system] Activation via systemd failed for unit 'dbus-org.bluez.service': Unit dbus-org.bluez.service failed to load: No such file or directory. See system logs and 'systemctl status dbus-org.bluez.service' for details.
May 07 14:52:01 cogitate dbus[412]: [system] Activation via systemd failed for unit 'dbus-org.bluez.service': Unit dbus-org.bluez.service failed to load: No such file or directory. See system logs and 'systemctl status dbus-org.bluez.service' for details.
May 07 14:52:01 cogitate pulseaudio[1085]: [pulseaudio] bluetooth-util.c: org.bluez.Manager.GetProperties() failed: org.freedesktop.systemd1.LoadFailed: Unit dbus-org.bluez.service failed to load: No such file or directory. See system logs and 'systemctl status dbus-org.bluez.service' for details.
May 07 14:52:02 cogitate pulseaudio[1085]: [pulseaudio] module-gconf.c: Unable to read or parse data from client.
May 07 14:52:02 cogitate pulseaudio[1085]: [pulseaudio] module.c: Failed to load module "module-gconf" (argument: ""): initialization failed.
May 07 14:52:02 cogitate pulseaudio[1085]: [pulseaudio] main.c: Module load failed.
May 07 14:52:15 cogitate su[1291]: pam_unix(su:auth): authentication failure; logname= uid=1000 euid=0 tty=pts/0 ruser=anarch rhost=  user=root
May 07 14:52:18 cogitate su[1326]: (to anarch) anarch on none
May 07 14:52:18 cogitate su[1326]: pam_unix(su:session): session opened for user root by (uid=1000)

Thanks again. I uninstalled consolekit. The pulseaudio error seems to be something separate. I was tantalised for a moment, because I found that I'd forgotten to merge the pacnew file for pulse's daemon.conf, and the necessary change was to the setting called "exit-idle-time"... but this still didn't fix the issue.

Last edited by Baryon (2013-05-07 13:56:02)

Offline

#15 2013-05-07 14:17:19

skanky
Member
From: WAIS
Registered: 2009-10-23
Posts: 1,845

Re: [Solved] Login delay

I'm not sure here, but there seems to be a delay getting the dhcp lease, but then that may be blocked by the IPv6 eth0 side of things:

May 07 14:51:42 cogitate kernel: IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready

A lot of the networking side of things happens after that line, that has a 32 second gap in front of it. If you can remove that delay, it may not fix your problem, but it may well improve the boot time, generally?


"...one cannot be angry when one looks at a penguin."  - John Ruskin
"Life in general is a bit shit, and so too is the internet. And that's all there is." - scepticisle

Offline

#16 2013-05-08 10:23:18

Baryon
Member
Registered: 2011-08-12
Posts: 63

Re: [Solved] Login delay

Hmm, I just tried disabling ifplugd as well, but that still didn't help.

Offline

#17 2013-05-08 12:31:52

skanky
Member
From: WAIS
Registered: 2009-10-23
Posts: 1,845

Re: [Solved] Login delay

Actually that NETDEV_UP message may be a red herring.
It looks like there's may be a delay in getting the dhcp lease (which I've seen before). Have you tried a static ip address?


"...one cannot be angry when one looks at a penguin."  - John Ruskin
"Life in general is a bit shit, and so too is the internet. And that's all there is." - scepticisle

Offline

#18 2013-05-11 09:27:26

ondoho
Member
Registered: 2013-04-30
Posts: 319

Re: [Solved] Login delay

hello, i'm just searching for a solution to a very similar, if not exactly same, problem:
10+ seconds delay just before login process. hard drive noise. i can wait it out, then X comes on fast enough after login, just like baryon stated earlier.

comparing baryons and my journalctl output, i found similar time gaps arount ntpd (internet time protocol daemon) events; i tried disabling the service, but there's still the same 10+ seconds gap just before and after logging in.
it just might have something to do with ntpd changing the system time. though it seems weird, times jumping backwards and forwards.

comparing them again, i see (baryon):

(...)
May 07 14:51:09 cogitate systemd-logind[411]: Watching system buttons on /dev/input/event4 (Power Button)
May 07 14:51:42 cogitate kernel: IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
(...)

(mine):

(...)
May 11 13:04:48 xxx systemd[1]: Starting D-Bus System Message Bus Socket.
May 11 13:05:07 xxx systemd-logind[202]: Watching system buttons on /dev/input/event6 (Power Button)
May 11 13:04:50 xxx login[205]: pam_unix(login:session): session opened for user xxx by LOGIN(uid=0)
May 11 13:04:50 xxx login[205]: LOGIN ON tty1 BY xxx
May 11 13:05:08 xxx systemd-logind[202]: Watching system buttons on /dev/input/event14 (Video Bus)
(and there it ends)

so, the system buttons catch my eye but i couldn't say if the problem is there or in the next line or somewhere else entirely...

my setup: new install (~2 weeks) with some customizing done already. no display manager, startx after login (i had autologin enabled but disabled it now to better search for the cause of the delay), 1 kernel upgrade from 3.8.0 to 3.9.0 iirc.
internet connections via netctl (the news came just after installing archlinux, so i migrated from netcfg), wlan and 3g/mobile broadband. they do not connect automatically, and i hope my system is not doing it without my knowledge during bootup.
what else, 64bit, ~5year old laptop.

edit: systemd-analyze blame does not show the time gap in my case.
edit2: just reading up on this related thread.
edit3: i hope i'm not hijacking this thread; i really think the problem is related.

cheers.

o.

Last edited by ondoho (2013-05-11 11:08:16)

Offline

#19 2013-05-12 08:02:49

Baryon
Member
Registered: 2011-08-12
Posts: 63

Re: [Solved] Login delay

Thanks ondoho, it looks like that's exactly the same problem. Are you using a Thinkpad by any chance?

Edit: I tried disabling ntpd myself (systemctl disable ntpd) and it actually fixed the problem! Could it be because wifi takes too long to connect and ntpd waits for a connection before it continues? That's what it looks like to me. If that's the case, I wonder if there is a way to force the ntpd service to start only after there's an internet connection.

skanky wrote:

Actually that NETDEV_UP message may be a red herring.
It looks like there's may be a delay in getting the dhcp lease (which I've seen before). Have you tried a static ip address?

I tried disabling the netctl auto profiles entirely and it still didn't fix the problem. I'm assuming it shouldn't even try to get a dhcp lease unless the ethernet service is set to run at boot.

Last edited by Baryon (2013-05-12 08:19:35)

Offline

#20 2013-05-12 11:49:41

ondoho
Member
Registered: 2013-04-30
Posts: 319

Re: [Solved] Login delay

you're welcome! glad to be of help.
no, i have a msi shitty old laptop. i wish i had a great old thinkpad.
anyways, i have it solved on my machine. here's what did it for me:

netctl disable {all|your|profiles}
systemctl disable ntpd.service

it may be necessary to stop them first.
it may also be necessary to check if there's other network-related services in systemctl and disable those, too.
the point is to not have any network initialisation during boot; i don't want it. i have 2 ways to access the internet wirelessly and i want to decide & press buttons. i have a little script now that starts ntpd after i connect to the internet.

manually connecting with netctl i must have accidentally used enable & start instead of only start, because enable puts the service back in the boot process. same for systemctl.
or, the wifi-menu command does that maybe?
or maybe disabling nectl services without disabling e.g. ntpd is useless? because ntpd.service just puts the netctl service back into the boot process instead of just giving up?

i verified things with journalctl - almost no messages related to networking anymore. back to 20s instead of 35! mini006.gif
i noticed that systemd-analyze is not only about the boot process; so if i connect to the network manually after booting, it shows up in

systemd-analyze blame

duh. didn't realise that.

another thing that speeded up booting:
i had a look at /etc/systemd/*.conf, some tweaking possible. i have only 3 ttys now. the login process seems to have speeded up another few seconds with that.
there's also options for the lid/buttons there, but i didn't change anything there.

man, interpreting logfiles is an artform...

Last edited by ondoho (2013-05-12 12:32:43)

Offline

#21 2013-05-13 13:33:29

skanky
Member
From: WAIS
Registered: 2009-10-23
Posts: 1,845

Re: [Solved] Login delay

Baryon wrote:

Thanks ondoho, it looks like that's exactly the same problem. Are you using a Thinkpad by any chance?

Edit: I tried disabling ntpd myself (systemctl disable ntpd) and it actually fixed the problem! Could it be because wifi takes too long to connect and ntpd waits for a connection before it continues? That's what it looks like to me. If that's the case, I wonder if there is a way to force the ntpd service to start only after there's an internet connection.

skanky wrote:

Actually that NETDEV_UP message may be a red herring.
It looks like there's may be a delay in getting the dhcp lease (which I've seen before). Have you tried a static ip address?

I tried disabling the netctl auto profiles entirely and it still didn't fix the problem. I'm assuming it shouldn't even try to get a dhcp lease unless the ethernet service is set to run at boot.

Yeah, I'd assume that too, but thought it worth testing.

You might be able to edit the ntpd unit to require the dhcpcd unit to have started - that way the main delay will be over.
There's a page on the systemd site (at freedestop.org) on the meaning of the network target. That might be worth a read as that's the target that ntpd is after.



ondoho wrote:

man, interpreting logfiles is an artform...

Yeah.


"...one cannot be angry when one looks at a penguin."  - John Ruskin
"Life in general is a bit shit, and so too is the internet. And that's all there is." - scepticisle

Offline

#22 2013-05-13 20:19:32

Baryon
Member
Registered: 2011-08-12
Posts: 63

Re: [Solved] Login delay

ondoho wrote:

the point is to not have any network initialisation during boot; i don't want it. i have 2 ways to access the internet wirelessly and i want to decide & press buttons. i have a little script now that starts ntpd after i connect to the internet.

Thanks, but technically this is a workaround. I want my internet connection and ntpd to start at boot, and I still can't get that to happen without this delay occurring. I can start ntpd manually without it freezing the system but I don't want to do that every time I boot.

So I added the following systemd service file, but this still causes the delay. However, the delay (15 second system hang) occurs just a little bit later than it does when using ntpd.service.

[Unit]
Description=Network Time Service (once)
After=network.target nss-lookup.target

[Service]
Type=oneshot
ExecStart=/usr/bin/systemctl start ntpd

[Install]
WantedBy=multi-user.target

So I don't want to mark this as solved just yet.

Last edited by Baryon (2013-05-13 20:20:07)

Offline

#23 2013-05-14 09:05:36

skanky
Member
From: WAIS
Registered: 2009-10-23
Posts: 1,845

Re: [Solved] Login delay

Just realised, I use openntp and the service file for that is a little different.
It also sets the type as "Forking", which should mean that systemd is not blocked by the service start-up

So you could set the Type=forking in ntp, but if that's going to cause an issue (might need to add a & at the end of the ExecStart line), you could try switching to the openntp package instead?


"...one cannot be angry when one looks at a penguin."  - John Ruskin
"Life in general is a bit shit, and so too is the internet. And that's all there is." - scepticisle

Offline

#24 2013-05-15 10:15:42

Baryon
Member
Registered: 2011-08-12
Posts: 63

Re: [Solved] Login delay

I've realised that, at least in my case, this is not due to ntpd after all. It's got to be to do with the wifi connection and getting a dhcp lease, as you suggested, because I'm still getting the delay even when ntpd is disabled. For some reason, it doesn't want to continue trying for a dhcp lease in the background. It insists on blocking for 15 seconds or so. I notice that the netctl-auto@wlan0.service file has the type forking.

Last edited by Baryon (2013-05-15 10:20:42)

Offline

#25 2013-05-15 13:41:49

skanky
Member
From: WAIS
Registered: 2009-10-23
Posts: 1,845

Re: [Solved] Login delay

I can't see the dhcpcd service in the plot above either.


"...one cannot be angry when one looks at a penguin."  - John Ruskin
"Life in general is a bit shit, and so too is the internet. And that's all there is." - scepticisle

Offline

Board footer

Powered by FluxBB