RTAPI: ERROR: Unexpected realtime delay on task 1

More
12 Jun 2016 21:41 #75874 by arhi
Hi,

What the message states is IMHO clear, whatever the reason the realtime thread didn't get the slot when it expected to get one .. the reason for this question / post is that I can't figure out "why" it happens and "how" to solve it :( ..

I let the latency tester run for 72 hours and I molest the machine both directly there and trough internet, I surf, search on disk, compres files, move things around, play in gimp .. for few days .. and I never seen the max jitter value touch 4000ns .. I'd say this is a good value ..

machine is dedicated for linuxcnc, some "Intel(R) Core(TM) i7-2600K CPU @ 3.40GHz" (4*2 cores) with 4G ram and "AMD HD 3600" gpu and ssd (KINGSTON SV100S264G) .. using integrated lpt port

I see in dmesg
[    0.000201] ENERGY_PERF_BIAS: Set to 'normal', was 'performance'
[    0.000202] ENERGY_PERF_BIAS: View and update with x86_energy_perf_policy(8)

not sure why linux switched from performance the energy stuff..

anyhow, measured jitter never touches 4000ns .. I tried using 4000 value in configurator
BASE_PERIOD = 4000
SERVO_PERIOD = 40000

then I tried larger and larger numbers, finally tried
BASE_PERIOD = 100000
SERVO_PERIOD = 1000000

and nothing changes, I still have exactly the same problem

Not sure where to go from here ... will check if my HT is turned off, seen somewhere trying to solve the problem that hyper threading is not agreeing with linuxcnc so will kill it ..

is the linuxcnc sensitive to this things also when using mesa cards? I'm thinking of getting a mesa card (pci-e + daugterboard, will open separate thread about how to select best one but need to first get this working somehow without mesa)


config:
[EMC]
MACHINE = mill25d
DEBUG = 0

[DISPLAY]
DISPLAY = axis
EDITOR = gedit
POSITION_OFFSET = RELATIVE
POSITION_FEEDBACK = ACTUAL
ARCDIVISION = 64
GRIDS = 10mm 20mm 50mm 100mm 1in 2in 5in 10in
MAX_FEED_OVERRIDE = 1.2
MIN_SPINDLE_OVERRIDE = 0.5
MAX_SPINDLE_OVERRIDE = 1.2
DEFAULT_LINEAR_VELOCITY = 1.00
MIN_LINEAR_VELOCITY = 0
MAX_LINEAR_VELOCITY = 10.00
INTRO_GRAPHIC = linuxcnc.gif
INTRO_TIME = 5
INCREMENTS = 5mm 1mm .5mm .1mm .05mm .01mm .005mm

[FILTER]
PROGRAM_EXTENSION = .png,.gif,.jpg Greyscale Depth Image
PROGRAM_EXTENSION = .py Python Script
png = image-to-gcode
gif = image-to-gcode
jpg = image-to-gcode
py = python

[TASK]
TASK = milltask
CYCLE_TIME = 0.010

[RS274NGC]
PARAMETER_FILE = linuxcnc.var

[EMCMOT]
EMCMOT = motmod
COMM_TIMEOUT = 1.0
COMM_WAIT = 0.010
BASE_PERIOD = 100000
SERVO_PERIOD = 1000000

[HAL]
HALFILE = mill25d.hal
HALFILE = custom.hal
POSTGUI_HALFILE = custom_postgui.hal

[TRAJ]
AXES = 3
COORDINATES = X Y Z
LINEAR_UNITS = mm
ANGULAR_UNITS = degree
CYCLE_TIME = 0.010
DEFAULT_VELOCITY = 1.00
MAX_VELOCITY = 10.00

[EMCIO]
EMCIO = io
CYCLE_TIME = 0.100
TOOL_TABLE = tool.tbl

[AXIS_0]
TYPE = LINEAR
HOME = 0.0
MAX_VELOCITY = 10.0
MAX_ACCELERATION = 4.0
STEPGEN_MAXACCEL = 5.0
SCALE = 400.0
FERROR = 1
MIN_FERROR = .25
MIN_LIMIT = -0.001
MAX_LIMIT = 125.0
HOME_OFFSET = 0.0

[AXIS_1]
TYPE = LINEAR
HOME = 0.0
MAX_VELOCITY = 10.0
MAX_ACCELERATION = 4.0
STEPGEN_MAXACCEL = 5.0
SCALE = 400.0
FERROR = 1
MIN_FERROR = .25
MIN_LIMIT = -0.001
MAX_LIMIT = 105.0
HOME_OFFSET = 0.0

[AXIS_2]
TYPE = LINEAR
HOME = 0.0
MAX_VELOCITY = 10.0
MAX_ACCELERATION = 1.0
STEPGEN_MAXACCEL = 1.25
SCALE = 400.0
FERROR = 1
MIN_FERROR = .25
MIN_LIMIT = -100.0
MAX_LIMIT = 100.0
HOME_OFFSET = 0.0

dmesg
root@linuxCNC:~# dmesg
[    0.000000] Initializing cgroup subsys cpuset
[    0.000000] Initializing cgroup subsys cpu
[    0.000000] Linux version 3.4-9-rtai-686-pae (Debian 3.4.55-4linuxcnc) () (gcc version 4.6.3 (Debian 4.6.3-14) ) #1 SMP PREEMPT Debian 3.4.55-4linuxcnc
[    0.000000] BIOS-provided physical RAM map:
[    0.000000]  BIOS-e820: 0000000000000000 - 0000000000096400 (usable)
[    0.000000]  BIOS-e820: 000000000009f800 - 00000000000a0000 (reserved)
[    0.000000]  BIOS-e820: 00000000000f0000 - 0000000000100000 (reserved)
[    0.000000]  BIOS-e820: 0000000000100000 - 00000000df790000 (usable)
[    0.000000]  BIOS-e820: 00000000df790000 - 00000000df7b3000 (ACPI NVS)
[    0.000000]  BIOS-e820: 00000000df7b3000 - 00000000df7f0000 (ACPI data)
[    0.000000]  BIOS-e820: 00000000df7f0000 - 00000000df800000 (reserved)
[    0.000000]  BIOS-e820: 00000000f4000000 - 00000000f8000000 (reserved)
[    0.000000]  BIOS-e820: 00000000fec00000 - 0000000100000000 (reserved)
[    0.000000]  BIOS-e820: 0000000100000000 - 000000021f800000 (usable)
[    0.000000] Notice: NX (Execute Disable) protection cannot be enabled: non-PAE kernel!
[    0.000000] SMBIOS 2.4 present.
[    0.000000] DMI: Gigabyte Technology Co., Ltd. P67-DS3-B3/P67-DS3-B3, BIOS F1 05/06/2011
[    0.000000] e820 update range: 0000000000000000 - 0000000000010000 (usable) ==> (reserved)
[    0.000000] e820 remove range: 00000000000a0000 - 0000000000100000 (usable)
[    0.000000] last_pfn = 0xdf790 max_arch_pfn = 0x100000
[    0.000000] MTRR default type: uncachable
[    0.000000] MTRR fixed ranges enabled:
[    0.000000]   00000-9FFFF write-back
[    0.000000]   A0000-BFFFF uncachable
[    0.000000]   C0000-CFFFF write-protect
[    0.000000]   D0000-EFFFF uncachable
[    0.000000]   F0000-FFFFF write-through
[    0.000000] MTRR variable ranges enabled:
[    0.000000]   0 base 000000000 mask F00000000 write-back
[    0.000000]   1 base 0E0000000 mask FE0000000 uncachable
[    0.000000]   2 base 100000000 mask F00000000 write-back
[    0.000000]   3 base 200000000 mask FE0000000 write-back
[    0.000000]   4 disabled
[    0.000000]   5 disabled
[    0.000000]   6 disabled
[    0.000000]   7 disabled
[    0.000000]   8 disabled
[    0.000000]   9 disabled
[    0.000000] x86 PAT enabled: cpu 0, old 0x7040600070406, new 0x7010600070106
[    0.000000] e820 update range: 00000000e0000000 - 0000000100000000 (usable) ==> (reserved)
[    0.000000] found SMP MP-table at [c00f5a80] f5a80
[    0.000000] initial memory mapped : 0 - 01800000
[    0.000000] Base memory trampoline at [c0092000] 92000 size 16384
[    0.000000] init_memory_mapping: 0000000000000000-00000000377fe000
[    0.000000]  0000000000 - 0000400000 page 4k
[    0.000000]  0000400000 - 0037400000 page 2M
[    0.000000]  0037400000 - 00377fe000 page 4k
[    0.000000] kernel direct mapping tables up to 0x377fdfff @ [mem 0x017f8000-0x017fffff]
[    0.000000] RAMDISK: 36524000 - 3728a000
[    0.000000] ACPI: RSDP 000f7600 00014 (v00 GBT   )
[    0.000000] ACPI: RSDT df7b3040 0004C (v01 GBT    GBTUACPI 42302E31 GBTU 01010101)
[    0.000000] ACPI: FACP df7b3100 00074 (v01 GBT    GBTUACPI 42302E31 GBTU 01010101)
[    0.000000] ACPI: DSDT df7b31c0 05000 (v01 GBT    GBTUACPI 00001000 MSFT 04000000)
[    0.000000] ACPI: FACS df790000 00040
[    0.000000] ACPI: HPET df7b8300 00038 (v01 GBT    GBTUACPI 42302E31 GBTU 00000098)
[    0.000000] ACPI: MCFG df7b8380 0003C (v01 GBT    GBTUACPI 42302E31 GBTU 01010101)
[    0.000000] ACPI: ASPT df7b8500 00034 (v07 GBT    PerfTune 312E3042 UTBG 01010101)
[    0.000000] ACPI: SSPT df7b8540 02340 (v01 GBT    SsptHead 312E3042 UTBG 01010101)
[    0.000000] ACPI: EUDS df7ba880 000C0 (v01 GBT             00000000      00000000)
[    0.000000] ACPI: TAMG df7ba940 008FA (v01 GBT    GBT   B0 5455312E BG?? 45240101)
[    0.000000] ACPI: APIC df7b8200 000BC (v01 GBT    GBTUACPI 42302E31 GBTU 01010101)
[    0.000000] ACPI: SSDT df7bb240 0314C (v01  INTEL PPM RCM  80000001 INTL 20061109)
[    0.000000] ACPI: MATS df7be3c0 0A3CC (v01        MATS RCM 80000001 INTL 20061109)
[    0.000000] ACPI: Local APIC address 0xfee00000
[    0.000000] 2687MB HIGHMEM available.
[    0.000000] 887MB LOWMEM available.
[    0.000000]   mapped low ram: 0 - 377fe000
[    0.000000]   low ram: 0 - 377fe000
[    0.000000] Zone PFN ranges:
[    0.000000]   DMA      0x00000010 -> 0x00001000
[    0.000000]   Normal   0x00001000 -> 0x000377fe
[    0.000000]   HighMem  0x000377fe -> 0x000df790
[    0.000000] Movable zone start PFN for each node
[    0.000000] Early memory PFN ranges
[    0.000000]     0: 0x00000010 -> 0x00000096
[    0.000000]     0: 0x00000100 -> 0x000df790
[    0.000000] On node 0 totalpages: 915222
[    0.000000] free_area_init_node: node 0, pgdat c1498a00, node_mem_map f4934200
[    0.000000]   DMA zone: 32 pages used for memmap
[    0.000000]   DMA zone: 0 pages reserved
[    0.000000]   DMA zone: 3942 pages, LIFO batch:0
[    0.000000]   Normal zone: 1744 pages used for memmap
[    0.000000]   Normal zone: 221486 pages, LIFO batch:31
[    0.000000]   HighMem zone: 5376 pages used for memmap
[    0.000000]   HighMem zone: 682642 pages, LIFO batch:31
[    0.000000] Using APIC driver default
[    0.000000] ACPI: PM-Timer IO Port: 0x408
[    0.000000] ACPI: Local APIC address 0xfee00000
[    0.000000] ACPI: LAPIC (acpi_id[0x00] lapic_id[0x00] enabled)
[    0.000000] ACPI: LAPIC (acpi_id[0x01] lapic_id[0x02] enabled)
[    0.000000] ACPI: LAPIC (acpi_id[0x02] lapic_id[0x04] enabled)
[    0.000000] ACPI: LAPIC (acpi_id[0x03] lapic_id[0x06] enabled)
[    0.000000] ACPI: LAPIC (acpi_id[0x04] lapic_id[0x01] enabled)
[    0.000000] ACPI: LAPIC (acpi_id[0x05] lapic_id[0x03] enabled)
[    0.000000] ACPI: LAPIC (acpi_id[0x06] lapic_id[0x05] enabled)
[    0.000000] ACPI: LAPIC (acpi_id[0x07] lapic_id[0x07] enabled)
[    0.000000] ACPI: LAPIC_NMI (acpi_id[0x00] dfl dfl lint[0x1])
[    0.000000] ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1])
[    0.000000] ACPI: LAPIC_NMI (acpi_id[0x02] dfl dfl lint[0x1])
[    0.000000] ACPI: LAPIC_NMI (acpi_id[0x03] dfl dfl lint[0x1])
[    0.000000] ACPI: LAPIC_NMI (acpi_id[0x04] dfl dfl lint[0x1])
[    0.000000] ACPI: LAPIC_NMI (acpi_id[0x05] dfl dfl lint[0x1])
[    0.000000] ACPI: LAPIC_NMI (acpi_id[0x06] dfl dfl lint[0x1])
[    0.000000] ACPI: LAPIC_NMI (acpi_id[0x07] dfl dfl lint[0x1])
[    0.000000] ACPI: IOAPIC (id[0x02] address[0xfec00000] gsi_base[0])
[    0.000000] IOAPIC[0]: apic_id 2, version 32, address 0xfec00000, GSI 0-23
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
[    0.000000] ACPI: IRQ0 used by override.
[    0.000000] ACPI: IRQ2 used by override.
[    0.000000] ACPI: IRQ9 used by override.
[    0.000000] Using ACPI (MADT) for SMP configuration information
[    0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000
[    0.000000] SMP: Allowing 8 CPUs, 0 hotplug CPUs
[    0.000000] nr_irqs_gsi: 40
[    0.000000] PM: Registered nosave memory: 0000000000096000 - 00000000000a0000
[    0.000000] PM: Registered nosave memory: 00000000000a0000 - 00000000000f0000
[    0.000000] PM: Registered nosave memory: 00000000000f0000 - 0000000000100000
[    0.000000] Allocating PCI resources starting at df800000 (gap: df800000:14800000)
[    0.000000] setup_percpu: NR_CPUS:8 nr_cpumask_bits:8 nr_cpu_ids:8 nr_node_ids:1
[    0.000000] PERCPU: Embedded 17 pages/cpu @f7763000 s48384 r0 d21248 u69632
[    0.000000] pcpu-alloc: s48384 r0 d21248 u69632 alloc=17*4096
[    0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 [0] 4 [0] 5 [0] 6 [0] 7
[    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 908070
[    0.000000] Kernel command line: BOOT_IMAGE=/boot/vmlinuz-3.4-9-rtai-686-pae root=UUID=e2fdae5f-0236-459f-b1ad-c67e25b4a192 ro initrd=/install/gtk/initrd.gz lapic quiet rootdelay=5
[    0.000000] PID hash table entries: 4096 (order: 2, 16384 bytes)
[    0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes)
[    0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes)
[    0.000000] Initializing CPU#0
[    0.000000] xsave/xrstor: enabled xstate_bv 0x7, cntxt size 0x340
[    0.000000] allocated 7322624 bytes of page_cgroup
[    0.000000] please try 'cgroup_disable=memory' option if you don't want memory cgroups
[    0.000000] Initializing HighMem for node 0 (000377fe:000df790)
[    0.000000] Memory: 3604128k/3661376k available (3495k kernel code, 56760k reserved, 1237k data, 416k init, 2752072k highmem)
[    0.000000] virtual kernel memory layout:
[    0.000000]     fixmap  : 0xfff17000 - 0xfffff000   ( 928 kB)
[    0.000000]     pkmap   : 0xff800000 - 0xffc00000   (4096 kB)
[    0.000000]     vmalloc : 0xf7ffe000 - 0xff7fe000   ( 120 MB)
[    0.000000]     lowmem  : 0xc0000000 - 0xf77fe000   ( 887 MB)
[    0.000000]       .init : 0xc14a0000 - 0xc1508000   ( 416 kB)
[    0.000000]       .data : 0xc1369d4a - 0xc149f480   (1237 kB)
[    0.000000]       .text : 0xc1000000 - 0xc1369d4a   (3495 kB)
[    0.000000] Checking if this processor honours the WP bit even in supervisor mode...Ok.
[    0.000000] Preemptible hierarchical RCU implementation.
[    0.000000]  RCU dyntick-idle grace-period acceleration is enabled.
[    0.000000] NR_IRQS:2304 nr_irqs:744 16
[    0.000000] CPU 0 irqstacks, hard=f3c06000 soft=f3c08000
[    0.000000] Interrupt pipeline (release #3)
[    0.000000] Console: colour VGA+ 80x25
[    0.000000] console [tty0] enabled
[    0.000000] hpet clockevent registered
[    0.000000] Fast TSC calibration using PIT
[    0.004000] Detected 3392.161 MHz processor.
[    0.000001] Calibrating delay loop (skipped), value calculated using timer frequency.. 6784.32 BogoMIPS (lpj=13568644)
[    0.000004] pid_max: default: 32768 minimum: 301
[    0.000029] Security Framework initialized
[    0.000033] AppArmor: AppArmor disabled by boot time parameter
[    0.000043] Mount-cache hash table entries: 512
[    0.000165] Initializing cgroup subsys cpuacct
[    0.000166] Initializing cgroup subsys memory
[    0.000171] Initializing cgroup subsys devices
[    0.000172] Initializing cgroup subsys freezer
[    0.000174] Initializing cgroup subsys net_cls
[    0.000175] Initializing cgroup subsys blkio
[    0.000178] Initializing cgroup subsys perf_event
[    0.000197] CPU: Physical Processor ID: 0
[    0.000198] CPU: Processor Core ID: 0
[    0.000201] ENERGY_PERF_BIAS: Set to 'normal', was 'performance'
[    0.000202] ENERGY_PERF_BIAS: View and update with x86_energy_perf_policy(8)
[    0.000204] mce: CPU supports 9 MCE banks
[    0.000213] CPU0: Thermal monitoring enabled (TM1)
[    0.000278] ACPI: Core revision 20120320
[    0.004961] Enabling APIC mode:  Flat.  Using 1 I/O APICs
[    0.005357] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
[    0.044926] CPU0: Intel(R) Core(TM) i7-2600K CPU @ 3.40GHz stepping 07
[    0.148806] Performance Events: PEBS fmt1+, 16-deep LBR, SandyBridge events, Intel PMU driver.
[    0.148811] PEBS disabled due to CPU errata.
[    0.148813] ... version:                3
[    0.148814] ... bit width:              48
[    0.148815] ... generic registers:      4
[    0.148816] ... value mask:             0000ffffffffffff
[    0.148818] ... max period:             000000007fffffff
[    0.148819] ... fixed-purpose events:   3
[    0.148820] ... event mask:             000000070000000f
[    0.160813] NMI watchdog: enabled, takes one hw-pmu counter.
[    0.172745] CPU 1 irqstacks, hard=f3d18000 soft=f3d1a000
[    0.172747] Booting Node   0, Processors  #1
[    0.183179] Initializing CPU#1
[    0.185855] NMI watchdog: enabled, takes one hw-pmu counter.
[    0.192691] CPU 2 irqstacks, hard=f3d2a000 soft=f3d2c000
[    0.192693]  #2
[    0.202937] Initializing CPU#2
[    0.205799] NMI watchdog: enabled, takes one hw-pmu counter.
[    0.212632] CPU 3 irqstacks, hard=f3d3e000 soft=f3d40000
[    0.212634]  #3
[    0.222879] Initializing CPU#3
[    0.225740] NMI watchdog: enabled, takes one hw-pmu counter.
[    0.232574] CPU 4 irqstacks, hard=f3d6e000 soft=f3d70000
[    0.232575]  #4
[    0.242906] Initializing CPU#4
[    0.245684] NMI watchdog: enabled, takes one hw-pmu counter.
[    0.252517] CPU 5 irqstacks, hard=f3d80000 soft=f3d82000
[    0.252518]  #5
[    0.262762] Initializing CPU#5
[    0.265624] NMI watchdog: enabled, takes one hw-pmu counter.
[    0.272458] CPU 6 irqstacks, hard=f3d94000 soft=f3d96000
[    0.272459]  #6
[    0.282703] Initializing CPU#6
[    0.285567] NMI watchdog: enabled, takes one hw-pmu counter.
[    0.292399] CPU 7 irqstacks, hard=f3dc4000 soft=f3dc6000
[    0.292401]  #7 Ok.
[    0.302645] Initializing CPU#7
[    0.305507] NMI watchdog: enabled, takes one hw-pmu counter.
[    0.305531] Brought up 8 CPUs
[    0.305533] Total of 8 processors activated (54274.57 BogoMIPS).
[    0.311201] devtmpfs: initialized
[    0.311354] PM: Registering ACPI NVS region [mem 0xdf790000-0xdf7b2fff] (143360 bytes)
[    0.311429] dummy:
[    0.311471] NET: Registered protocol family 16
[    0.311549] ACPI: bus type pci registered
[    0.311591] PCI: MMCONFIG for domain 0000 [bus 00-3f] at [mem 0xf4000000-0xf7ffffff] (base 0xf4000000)
[    0.311593] PCI: MMCONFIG at [mem 0xf4000000-0xf7ffffff] reserved in E820
[    0.311594] PCI: Using MMCONFIG for extended config space
[    0.311596] PCI: Using configuration type 1 for base access
[    0.312074] bio: create slab <bio-0> at 0
[    0.312144] ACPI: Added _OSI(Module Device)
[    0.312146] ACPI: Added _OSI(Processor Device)
[    0.312147] ACPI: Added _OSI(3.0 _SCP Extensions)
[    0.312149] ACPI: Added _OSI(Processor Aggregator Device)
[    0.312902] ACPI: EC: Look up EC in DSDT
[    0.318310] ACPI: Interpreter enabled
[    0.318322] ACPI: (supports S0 S3 S4 S5)
[    0.318337] ACPI: Using IOAPIC for interrupt routing
[    0.321395] ACPI: No dock devices found.
[    0.321400] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
[    0.321439] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-3f])
[    0.321490] pci_root PNP0A03:00: host bridge window [io  0x0000-0x0cf7]
[    0.321492] pci_root PNP0A03:00: host bridge window [io  0x0d00-0xffff]
[    0.321494] pci_root PNP0A03:00: host bridge window [mem 0x000a0000-0x000bffff]
[    0.321495] pci_root PNP0A03:00: host bridge window [mem 0x000c0000-0x000dffff]
[    0.321497] pci_root PNP0A03:00: host bridge window [mem 0xfed40000-0xfed44fff]
[    0.321498] pci_root PNP0A03:00: host bridge window [mem 0xe0000000-0xfebfffff]
[    0.321524] PCI host bridge to bus 0000:00
[    0.321526] pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7]
[    0.321527] pci_bus 0000:00: root bus resource [io  0x0d00-0xffff]
[    0.321529] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff]
[    0.321530] pci_bus 0000:00: root bus resource [mem 0x000c0000-0x000dffff]
[    0.321532] pci_bus 0000:00: root bus resource [mem 0xfed40000-0xfed44fff]
[    0.321533] pci_bus 0000:00: root bus resource [mem 0xe0000000-0xfebfffff]
[    0.321541] pci 0000:00:00.0: [8086:0100] type 00 class 0x060000
[    0.321568] pci 0000:00:01.0: [8086:0101] type 01 class 0x060400
[    0.321589] pci 0000:00:01.0: PME# supported from D0 D3hot D3cold
[    0.321629] pci 0000:00:16.0: [8086:1c3a] type 00 class 0x078000
[    0.321651] pci 0000:00:16.0: reg 10: [mem 0xfbfff000-0xfbfff00f 64bit]
[    0.321710] pci 0000:00:16.0: PME# supported from D0 D3hot D3cold
[    0.321740] pci 0000:00:1a.0: [8086:1c2d] type 00 class 0x0c0320
[    0.321760] pci 0000:00:1a.0: reg 10: [mem 0xfbffe000-0xfbffe3ff]
[    0.321831] pci 0000:00:1a.0: PME# supported from D0 D3hot D3cold
[    0.321855] pci 0000:00:1b.0: [8086:1c20] type 00 class 0x040300
[    0.321869] pci 0000:00:1b.0: reg 10: [mem 0xfbff4000-0xfbff7fff 64bit]
[    0.321921] pci 0000:00:1b.0: PME# supported from D0 D3hot D3cold
[    0.321942] pci 0000:00:1c.0: [8086:1c10] type 01 class 0x060400
[    0.322001] pci 0000:00:1c.0: PME# supported from D0 D3hot D3cold
[    0.322025] pci 0000:00:1c.1: [8086:1c12] type 01 class 0x060400
[    0.322085] pci 0000:00:1c.1: PME# supported from D0 D3hot D3cold
[    0.322108] pci 0000:00:1c.2: [8086:1c14] type 01 class 0x060400
[    0.322167] pci 0000:00:1c.2: PME# supported from D0 D3hot D3cold
[    0.322191] pci 0000:00:1c.3: [8086:244e] type 01 class 0x060401
[    0.322250] pci 0000:00:1c.3: PME# supported from D0 D3hot D3cold
[    0.322281] pci 0000:00:1d.0: [8086:1c26] type 00 class 0x0c0320
[    0.322301] pci 0000:00:1d.0: reg 10: [mem 0xfbffd000-0xfbffd3ff]
[    0.322371] pci 0000:00:1d.0: PME# supported from D0 D3hot D3cold
[    0.322395] pci 0000:00:1f.0: [8086:1c46] type 00 class 0x060100
[    0.322504] pci 0000:00:1f.2: [8086:2822] type 00 class 0x010400
[    0.322521] pci 0000:00:1f.2: reg 10: [io  0xff00-0xff07]
[    0.322528] pci 0000:00:1f.2: reg 14: [io  0xfe00-0xfe03]
[    0.322535] pci 0000:00:1f.2: reg 18: [io  0xfd00-0xfd07]
[    0.322542] pci 0000:00:1f.2: reg 1c: [io  0xfc00-0xfc03]
[    0.322550] pci 0000:00:1f.2: reg 20: [io  0xfb00-0xfb1f]
[    0.322557] pci 0000:00:1f.2: reg 24: [mem 0xfbffc000-0xfbffc7ff]
[    0.322587] pci 0000:00:1f.2: PME# supported from D3hot
[    0.322603] pci 0000:00:1f.3: [8086:1c22] type 00 class 0x0c0500
[    0.322617] pci 0000:00:1f.3: reg 10: [mem 0xfbffb000-0xfbffb0ff 64bit]
[    0.322637] pci 0000:00:1f.3: reg 20: [io  0x0500-0x051f]
[    0.322683] pci 0000:01:00.0: [1002:9598] type 00 class 0x030000
[    0.322694] pci 0000:01:00.0: reg 10: [mem 0xe0000000-0xefffffff 64bit pref]
[    0.322703] pci 0000:01:00.0: reg 18: [mem 0xfbde0000-0xfbdeffff 64bit]
[    0.322710] pci 0000:01:00.0: reg 20: [io  0xce00-0xceff]
[    0.322720] pci 0000:01:00.0: reg 30: [mem 0x00000000-0x0001ffff pref]
[    0.322735] pci 0000:01:00.0: supports D1 D2
[    0.322751] pci 0000:01:00.1: [1002:aa20] type 00 class 0x040300
[    0.322763] pci 0000:01:00.1: reg 10: [mem 0xfbdfc000-0xfbdfffff 64bit]
[    0.322801] pci 0000:01:00.1: supports D1 D2
[    0.328292] pci 0000:00:01.0: PCI bridge to [bus 01-01]
[    0.328295] pci 0000:00:01.0:   bridge window [io  0xc000-0xcfff]
[    0.328297] pci 0000:00:01.0:   bridge window [mem 0xfbd00000-0xfbdfffff]
[    0.328300] pci 0000:00:01.0:   bridge window [mem 0xe0000000-0xefffffff 64bit pref]
[    0.328342] pci 0000:00:1c.0: PCI bridge to [bus 02-02]
[    0.328417] pci 0000:03:00.0: [1c00:3050] type 00 class 0x070005
[    0.328441] pci 0000:03:00.0: reg 10: [io  0xbc00-0xbcff]
[    0.328457] pci 0000:03:00.0: reg 14: [mem 0xfbbf8000-0xfbbfffff pref]
[    0.328474] pci 0000:03:00.0: reg 18: [io  0xbf00-0xbf03]
[    0.328536] pci 0000:03:00.0: reg 30: [mem 0x00000000-0x00007fff pref]
[    0.328584] pci 0000:03:00.0: PME# supported from D0 D3hot D3cold
[    0.336277] pci 0000:00:1c.1: PCI bridge to [bus 03-03]
[    0.336280] pci 0000:00:1c.1:   bridge window [io  0xb000-0xbfff]
[    0.336283] pci 0000:00:1c.1:   bridge window [mem 0xfbc00000-0xfbcfffff]
[    0.336289] pci 0000:00:1c.1:   bridge window [mem 0xfbb00000-0xfbbfffff 64bit pref]
[    0.336353] pci 0000:04:00.0: [10ec:8168] type 00 class 0x020000
[    0.336372] pci 0000:04:00.0: reg 10: [io  0xee00-0xeeff]
[    0.336405] pci 0000:04:00.0: reg 18: [mem 0xfbeff000-0xfbefffff 64bit pref]
[    0.336426] pci 0000:04:00.0: reg 20: [mem 0xfbef8000-0xfbefbfff 64bit pref]
[    0.336485] pci 0000:04:00.0: supports D1 D2
[    0.336486] pci 0000:04:00.0: PME# supported from D0 D1 D2 D3hot D3cold
[    0.344251] pci 0000:00:1c.2: PCI bridge to [bus 04-04]
[    0.344255] pci 0000:00:1c.2:   bridge window [io  0xe000-0xefff]
[    0.344262] pci 0000:00:1c.2:   bridge window [mem 0xfbe00000-0xfbefffff 64bit pref]
[    0.344326] pci 0000:05:00.0: [1283:8892] type 01 class 0x060401
[    0.344454] pci 0000:05:00.0: supports D1 D2
[    0.344455] pci 0000:05:00.0: PME# supported from D0 D1 D2 D3hot D3cold
[    0.344483] pci 0000:00:1c.3: PCI bridge to [bus 05-06] (subtractive decode)
[    0.344486] pci 0000:00:1c.3:   bridge window [io  0xd000-0xdfff]
[    0.344493] pci 0000:00:1c.3:   bridge window [io  0x0000-0x0cf7] (subtractive decode)
[    0.344495] pci 0000:00:1c.3:   bridge window [io  0x0d00-0xffff] (subtractive decode)
[    0.344497] pci 0000:00:1c.3:   bridge window [mem 0x000a0000-0x000bffff] (subtractive decode)
[    0.344499] pci 0000:00:1c.3:   bridge window [mem 0x000c0000-0x000dffff] (subtractive decode)
[    0.344500] pci 0000:00:1c.3:   bridge window [mem 0xfed40000-0xfed44fff] (subtractive decode)
[    0.344502] pci 0000:00:1c.3:   bridge window [mem 0xe0000000-0xfebfffff] (subtractive decode)
[    0.344583] pci 0000:06:00.0: [4651:7073] type 00 class 0x070002
[    0.344612] pci 0000:06:00.0: reg 10: [io  0xdff8-0xdfff]
[    0.344633] pci 0000:06:00.0: reg 14: [io  0xdff0-0xdff7]
[    0.344761] pci 0000:06:01.0: [1c00:2170] type 00 class 0x070101
[    0.344789] pci 0000:06:01.0: reg 10: [io  0xdfe8-0xdfef]
[    0.344810] pci 0000:06:01.0: reg 14: [io  0xdfe0-0xdfe7]
[    0.345001] pci 0000:05:00.0: PCI bridge to [bus 06-06] (subtractive decode)
[    0.345012] pci 0000:05:00.0:   bridge window [io  0xd000-0xdfff]
[    0.345028] pci 0000:05:00.0:   bridge window [io  0xd000-0xdfff] (subtractive decode)
[    0.345030] pci 0000:05:00.0:   bridge window [??? 0x00000000 flags 0x0] (subtractive decode)
[    0.345032] pci 0000:05:00.0:   bridge window [??? 0x00000000 flags 0x0] (subtractive decode)
[    0.345034] pci 0000:05:00.0:   bridge window [??? 0x00000000 flags 0x0] (subtractive decode)
[    0.345035] pci 0000:05:00.0:   bridge window [io  0x0000-0x0cf7] (subtractive decode)
[    0.345037] pci 0000:05:00.0:   bridge window [io  0x0d00-0xffff] (subtractive decode)
[    0.345039] pci 0000:05:00.0:   bridge window [mem 0x000a0000-0x000bffff] (subtractive decode)
[    0.345040] pci 0000:05:00.0:   bridge window [mem 0x000c0000-0x000dffff] (subtractive decode)
[    0.345042] pci 0000:05:00.0:   bridge window [mem 0xfed40000-0xfed44fff] (subtractive decode)
[    0.345044] pci 0000:05:00.0:   bridge window [mem 0xe0000000-0xfebfffff] (subtractive decode)
[    0.345076] pci_bus 0000:00: on NUMA node 0
[    0.345078] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0._PRT]
[    0.345274] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.PEG0._PRT]
[    0.345309] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.PEX0._PRT]
[    0.345342] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.PEX1._PRT]
[    0.345375] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.PEX2._PRT]
[    0.345409] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.PEX3._PRT]
[    0.345466]  pci0000:00: Unable to request _OSC control (_OSC support mask: 0x0f)
[    0.351077] ACPI: PCI Interrupt Link [LNKA] (IRQs 3 4 5 6 7 9 10 11 *12 14 15)
[    0.351113] ACPI: PCI Interrupt Link [LNKB] (IRQs *3 4 5 6 7 9 10 11 12 14 15)
[    0.351149] ACPI: PCI Interrupt Link [LNKC] (IRQs 3 4 5 6 7 9 *10 11 12 14 15)
[    0.351184] ACPI: PCI Interrupt Link [LNKD] (IRQs 3 4 5 6 7 9 10 *11 12 14 15)
[    0.351219] ACPI: PCI Interrupt Link [LNKE] (IRQs 3 4 5 6 7 9 10 11 12 14 15) *0, disabled.
[    0.351254] ACPI: PCI Interrupt Link [LNKF] (IRQs 3 4 5 6 7 9 10 11 12 14 15) *0, disabled.
[    0.351290] ACPI: PCI Interrupt Link [LNK0] (IRQs 3 4 *5 6 7 9 10 11 12 14 15)
[    0.351326] ACPI: PCI Interrupt Link [LNK1] (IRQs 3 4 5 6 7 *9 10 11 12 14 15)
[    0.351380] vgaarb: device added: PCI:0000:01:00.0,decodes=io+mem,owns=io+mem,locks=none
[    0.351384] vgaarb: loaded
[    0.351385] vgaarb: bridge control possible 0000:01:00.0
[    0.351403] PCI: Using ACPI for IRQ routing
[    0.352797] PCI: pci_cache_line_size set to 64 bytes
[    0.352868] reserve RAM buffer: 0000000000096400 - 000000000009ffff
[    0.352869] reserve RAM buffer: 00000000df790000 - 00000000dfffffff
[    0.352954] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0, 0, 0, 0, 0, 0
[    0.352960] hpet0: 8 comparators, 64-bit 14.318180 MHz counter
[    0.354968] Switching to clocksource hpet
[    0.355022] pnp: PnP ACPI init
[    0.355029] ACPI: bus type pnp registered
[    0.355076] pnp 00:00: [bus 00-3f]
[    0.355077] pnp 00:00: [io  0x0cf8-0x0cff]
[    0.355079] pnp 00:00: [io  0x0000-0x0cf7 window]
[    0.355081] pnp 00:00: [io  0x0d00-0xffff window]
[    0.355082] pnp 00:00: [mem 0x000a0000-0x000bffff window]
[    0.355084] pnp 00:00: [mem 0x000c0000-0x000dffff window]
[    0.355085] pnp 00:00: [mem 0xfed40000-0xfed44fff window]
[    0.355087] pnp 00:00: [mem 0xe0000000-0xfebfffff window]
[    0.355112] pnp 00:00: Plug and Play ACPI device, IDs PNP0a03 (active)
[    0.355180] pnp 00:01: [io  0x0010-0x001f]
[    0.355181] pnp 00:01: [io  0x0022-0x003f]
[    0.355183] pnp 00:01: [io  0x0044-0x004d]
[    0.355184] pnp 00:01: [io  0x0050-0x005f]
[    0.355185] pnp 00:01: [io  0x0062-0x0063]
[    0.355187] pnp 00:01: [io  0x0065-0x006f]
[    0.355188] pnp 00:01: [io  0x0074-0x007f]
[    0.355189] pnp 00:01: [io  0x0091-0x0093]
[    0.355190] pnp 00:01: [io  0x00a2-0x00bf]
[    0.355192] pnp 00:01: [io  0x00e0-0x00ef]
[    0.355193] pnp 00:01: [io  0x04d0-0x04d1]
[    0.355194] pnp 00:01: [io  0x0290-0x029f]
[    0.355196] pnp 00:01: [io  0x0800-0x0805]
[    0.355197] pnp 00:01: [io  0x0290-0x0294]
[    0.355198] pnp 00:01: [io  0x0880-0x088f]
[    0.355231] system 00:01: [io  0x04d0-0x04d1] has been reserved
[    0.355233] system 00:01: [io  0x0290-0x029f] has been reserved
[    0.355234] system 00:01: [io  0x0800-0x0805] has been reserved
[    0.355236] system 00:01: [io  0x0290-0x0294] has been reserved
[    0.355238] system 00:01: [io  0x0880-0x088f] has been reserved
[    0.355240] system 00:01: Plug and Play ACPI device, IDs PNP0c02 (active)
[    0.355248] pnp 00:02: [dma 4]
[    0.355250] pnp 00:02: [io  0x0000-0x000f]
[    0.355251] pnp 00:02: [io  0x0080-0x0090]
[    0.355252] pnp 00:02: [io  0x0094-0x009f]
[    0.355254] pnp 00:02: [io  0x00c0-0x00df]
[    0.355270] pnp 00:02: Plug and Play ACPI device, IDs PNP0200 (active)
[    0.355306] pnp 00:03: [irq 0 disabled]
[    0.355311] pnp 00:03: [irq 8]
[    0.355312] pnp 00:03: [mem 0xfed00000-0xfed003ff]
[    0.355329] pnp 00:03: Plug and Play ACPI device, IDs PNP0103 (active)
[    0.355348] pnp 00:04: [io  0x0070-0x0073]
[    0.355366] pnp 00:04: Plug and Play ACPI device, IDs PNP0b00 (active)
[    0.355372] pnp 00:05: [io  0x0061]
[    0.355389] pnp 00:05: Plug and Play ACPI device, IDs PNP0800 (active)
[    0.355395] pnp 00:06: [io  0x00f0-0x00ff]
[    0.355399] pnp 00:06: [irq 13]
[    0.355416] pnp 00:06: Plug and Play ACPI device, IDs PNP0c04 (active)
[    0.355597] pnp 00:07: [io  0x03f8-0x03ff]
[    0.355601] pnp 00:07: [irq 4]
[    0.355641] pnp 00:07: Plug and Play ACPI device, IDs PNP0501 (active)
[    0.355866] pnp 00:08: [io  0x0378-0x037f]
[    0.355868] pnp 00:08: [io  0x0778-0x077b]
[    0.355871] pnp 00:08: [irq 7]
[    0.355873] pnp 00:08: [dma 3]
[    0.355907] pnp 00:08: Plug and Play ACPI device, IDs PNP0401 (active)
[    0.355956] pnp 00:09: [io  0x0400-0x04cf]
[    0.355958] pnp 00:09: [io  0x04d2-0x04ff]
[    0.355986] system 00:09: [io  0x0400-0x04cf] has been reserved
[    0.355988] system 00:09: [io  0x04d2-0x04ff] has been reserved
[    0.355990] system 00:09: Plug and Play ACPI device, IDs PNP0c02 (active)
[    0.355998] pnp 00:0a: [io  0x1000-0x107f]
[    0.355999] pnp 00:0a: [io  0x1080-0x10ff]
[    0.356001] pnp 00:0a: [io  0x1100-0x117f]
[    0.356002] pnp 00:0a: [io  0x1180-0x11ff]
[    0.356031] system 00:0a: [io  0x1000-0x107f] has been reserved
[    0.356032] system 00:0a: [io  0x1080-0x10ff] has been reserved
[    0.356034] system 00:0a: [io  0x1100-0x117f] has been reserved
[    0.356036] system 00:0a: [io  0x1180-0x11ff] has been reserved
[    0.356038] system 00:0a: Plug and Play ACPI device, IDs ICD0001 PNP0c02 (active)
[    0.356184] pnp 00:0b: [io  0x0454-0x0457]
[    0.356225] system 00:0b: [io  0x0454-0x0457] has been reserved
[    0.356227] system 00:0b: Plug and Play ACPI device, IDs INT3f0d PNP0c02 (active)
[    0.356241] pnp 00:0c: [mem 0xf4000000-0xf7ffffff]
[    0.356275] system 00:0c: [mem 0xf4000000-0xf7ffffff] has been reserved
[    0.356277] system 00:0c: Plug and Play ACPI device, IDs PNP0c02 (active)
[    0.356425] pnp 00:0d: [mem 0x000d7600-0x000d7fff]
[    0.356426] pnp 00:0d: [mem 0x000f0000-0x000f7fff]
[    0.356428] pnp 00:0d: [mem 0x000f8000-0x000fbfff]
[    0.356429] pnp 00:0d: [mem 0x000fc000-0x000fffff]
[    0.356430] pnp 00:0d: [mem 0xdf790000-0xdf7effff]
[    0.356432] pnp 00:0d: [mem 0x00000000-0x0009ffff]
[    0.356433] pnp 00:0d: [mem 0x00100000-0xdf78ffff]
[    0.356435] pnp 00:0d: [mem 0xdf7f0000-0xdf7fffff]
[    0.356436] pnp 00:0d: [mem 0xfec00000-0xfec00fff]
[    0.356437] pnp 00:0d: [mem 0xfed10000-0xfed1dfff]
[    0.356439] pnp 00:0d: [mem 0xfed20000-0xfed8ffff]
[    0.356440] pnp 00:0d: [mem 0xfee00000-0xfee00fff]
[    0.356442] pnp 00:0d: [mem 0xffb00000-0xffb7ffff]
[    0.356443] pnp 00:0d: [mem 0xfff00000-0xffffffff]
[    0.356444] pnp 00:0d: [mem 0x000e0000-0x000effff]
[    0.356446] pnp 00:0d: [mem 0x20000000-0x201fffff]
[    0.356447] pnp 00:0d: [mem 0x40000000-0x400fffff]
[    0.356449] pnp 00:0d: [mem 0xdf800000-0xdfffffff]
[    0.356489] system 00:0d: [mem 0x000d7600-0x000d7fff] has been reserved
[    0.356491] system 00:0d: [mem 0x000f0000-0x000f7fff] could not be reserved
[    0.356493] system 00:0d: [mem 0x000f8000-0x000fbfff] could not be reserved
[    0.356495] system 00:0d: [mem 0x000fc000-0x000fffff] could not be reserved
[    0.356497] system 00:0d: [mem 0xdf790000-0xdf7effff] could not be reserved
[    0.356499] system 00:0d: [mem 0x00000000-0x0009ffff] could not be reserved
[    0.356500] system 00:0d: [mem 0x00100000-0xdf78ffff] could not be reserved
[    0.356502] system 00:0d: [mem 0xdf7f0000-0xdf7fffff] has been reserved
[    0.356504] system 00:0d: [mem 0xfec00000-0xfec00fff] could not be reserved
[    0.356506] system 00:0d: [mem 0xfed10000-0xfed1dfff] has been reserved
[    0.356508] system 00:0d: [mem 0xfed20000-0xfed8ffff] could not be reserved
[    0.356510] system 00:0d: [mem 0xfee00000-0xfee00fff] has been reserved
[    0.356511] system 00:0d: [mem 0xffb00000-0xffb7ffff] has been reserved
[    0.356513] system 00:0d: [mem 0xfff00000-0xffffffff] has been reserved
[    0.356515] system 00:0d: [mem 0x000e0000-0x000effff] has been reserved
[    0.356517] system 00:0d: [mem 0x20000000-0x201fffff] could not be reserved
[    0.356519] system 00:0d: [mem 0x40000000-0x400fffff] could not be reserved
[    0.356521] system 00:0d: [mem 0xdf800000-0xdfffffff] could not be reserved
[    0.356523] system 00:0d: Plug and Play ACPI device, IDs PNP0c01 (active)
[    0.356536] pnp 00:0e: [mem 0xffb80000-0xffbfffff]
[    0.356559] pnp 00:0e: Plug and Play ACPI device, IDs INT0800 (active)
[    0.356563] pnp: PnP ACPI: found 15 devices
[    0.356564] ACPI: ACPI bus type pnp unregistered
[    0.356567] PnPBIOS: Disabled by ACPI PNP
[    0.392343] pci 0000:00:1c.0: bridge window [io  0x1000-0x0fff] to [bus 02-02] add_size 1000
[    0.392346] pci 0000:00:1c.0: bridge window [mem 0x00100000-0x000fffff 64bit pref] to [bus 02-02] add_size 200000
[    0.392348] pci 0000:00:1c.0: bridge window [mem 0x00100000-0x000fffff] to [bus 02-02] add_size 200000
[    0.392385] pci 0000:00:1c.0: res[14]=[mem 0x00100000-0x000fffff] get_res_add_size add_size 200000
[    0.392387] pci 0000:00:1c.0: res[15]=[mem 0x00100000-0x000fffff 64bit pref] get_res_add_size add_size 200000
[    0.392389] pci 0000:00:1c.0: res[13]=[io  0x1000-0x0fff] get_res_add_size add_size 1000
[    0.392392] pci 0000:00:1c.0: BAR 14: assigned [mem 0xf0000000-0xf01fffff]
[    0.392395] pci 0000:00:1c.0: BAR 15: assigned [mem 0xf0200000-0xf03fffff 64bit pref]
[    0.392397] pci 0000:00:1c.0: BAR 13: assigned [io  0x2000-0x2fff]
[    0.392400] pci 0000:01:00.0: BAR 6: assigned [mem 0xfbd00000-0xfbd1ffff pref]
[    0.392402] pci 0000:00:01.0: PCI bridge to [bus 01-01]
[    0.392404] pci 0000:00:01.0:   bridge window [io  0xc000-0xcfff]
[    0.392406] pci 0000:00:01.0:   bridge window [mem 0xfbd00000-0xfbdfffff]
[    0.392408] pci 0000:00:01.0:   bridge window [mem 0xe0000000-0xefffffff 64bit pref]
[    0.392411] pci 0000:00:1c.0: PCI bridge to [bus 02-02]
[    0.392414] pci 0000:00:1c.0:   bridge window [io  0x2000-0x2fff]
[    0.392418] pci 0000:00:1c.0:   bridge window [mem 0xf0000000-0xf01fffff]
[    0.392422] pci 0000:00:1c.0:   bridge window [mem 0xf0200000-0xf03fffff 64bit pref]
[    0.392428] pci 0000:03:00.0: BAR 6: assigned [mem 0xfbb00000-0xfbb07fff pref]
[    0.392429] pci 0000:00:1c.1: PCI bridge to [bus 03-03]
[    0.392432] pci 0000:00:1c.1:   bridge window [io  0xb000-0xbfff]
[    0.392436] pci 0000:00:1c.1:   bridge window [mem 0xfbc00000-0xfbcfffff]
[    0.392439] pci 0000:00:1c.1:   bridge window [mem 0xfbb00000-0xfbbfffff 64bit pref]
[    0.392445] pci 0000:00:1c.2: PCI bridge to [bus 04-04]
[    0.392447] pci 0000:00:1c.2:   bridge window [io  0xe000-0xefff]
[    0.392454] pci 0000:00:1c.2:   bridge window [mem 0xfbe00000-0xfbefffff 64bit pref]
[    0.392459] pci 0000:05:00.0: PCI bridge to [bus 06-06]
[    0.392463] pci 0000:05:00.0:   bridge window [io  0xd000-0xdfff]
[    0.392486] pci 0000:00:1c.3: PCI bridge to [bus 05-06]
[    0.392488] pci 0000:00:1c.3:   bridge window [io  0xd000-0xdfff]
[    0.392508] pci 0000:00:1c.0: enabling device (0000 -> 0003)
[    0.392553] pci 0000:05:00.0: setting latency timer to 64
[    0.392558] pci_bus 0000:00: resource 4 [io  0x0000-0x0cf7]
[    0.392559] pci_bus 0000:00: resource 5 [io  0x0d00-0xffff]
[    0.392561] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff]
[    0.392562] pci_bus 0000:00: resource 7 [mem 0x000c0000-0x000dffff]
[    0.392564] pci_bus 0000:00: resource 8 [mem 0xfed40000-0xfed44fff]
[    0.392565] pci_bus 0000:00: resource 9 [mem 0xe0000000-0xfebfffff]
[    0.392567] pci_bus 0000:01: resource 0 [io  0xc000-0xcfff]
[    0.392568] pci_bus 0000:01: resource 1 [mem 0xfbd00000-0xfbdfffff]
[    0.392570] pci_bus 0000:01: resource 2 [mem 0xe0000000-0xefffffff 64bit pref]
[    0.392571] pci_bus 0000:02: resource 0 [io  0x2000-0x2fff]
[    0.392573] pci_bus 0000:02: resource 1 [mem 0xf0000000-0xf01fffff]
[    0.392574] pci_bus 0000:02: resource 2 [mem 0xf0200000-0xf03fffff 64bit pref]
[    0.392576] pci_bus 0000:03: resource 0 [io  0xb000-0xbfff]
[    0.392577] pci_bus 0000:03: resource 1 [mem 0xfbc00000-0xfbcfffff]
[    0.392579] pci_bus 0000:03: resource 2 [mem 0xfbb00000-0xfbbfffff 64bit pref]
[    0.392580] pci_bus 0000:04: resource 0 [io  0xe000-0xefff]
[    0.392582] pci_bus 0000:04: resource 2 [mem 0xfbe00000-0xfbefffff 64bit pref]
[    0.392583] pci_bus 0000:05: resource 0 [io  0xd000-0xdfff]
[    0.392585] pci_bus 0000:05: resource 4 [io  0x0000-0x0cf7]
[    0.392586] pci_bus 0000:05: resource 5 [io  0x0d00-0xffff]
[    0.392588] pci_bus 0000:05: resource 6 [mem 0x000a0000-0x000bffff]
[    0.392589] pci_bus 0000:05: resource 7 [mem 0x000c0000-0x000dffff]
[    0.392590] pci_bus 0000:05: resource 8 [mem 0xfed40000-0xfed44fff]
[    0.392592] pci_bus 0000:05: resource 9 [mem 0xe0000000-0xfebfffff]
[    0.392593] pci_bus 0000:06: resource 0 [io  0xd000-0xdfff]
[    0.392595] pci_bus 0000:06: resource 4 [io  0xd000-0xdfff]
[    0.392596] pci_bus 0000:06: resource 8 [io  0x0000-0x0cf7]
[    0.392598] pci_bus 0000:06: resource 9 [io  0x0d00-0xffff]
[    0.392599] pci_bus 0000:06: resource 10 [mem 0x000a0000-0x000bffff]
[    0.392601] pci_bus 0000:06: resource 11 [mem 0x000c0000-0x000dffff]
[    0.392602] pci_bus 0000:06: resource 12 [mem 0xfed40000-0xfed44fff]
[    0.392604] pci_bus 0000:06: resource 13 [mem 0xe0000000-0xfebfffff]
[    0.392692] NET: Registered protocol family 2
[    0.392736] IP route cache hash table entries: 32768 (order: 5, 131072 bytes)
[    0.392874] TCP established hash table entries: 131072 (order: 8, 1048576 bytes)
[    0.393019] TCP bind hash table entries: 65536 (order: 7, 524288 bytes)
[    0.393082] TCP: Hash tables configured (established 131072 bind 65536)
[    0.393083] TCP: reno registered
[    0.393085] UDP hash table entries: 512 (order: 2, 16384 bytes)
[    0.393088] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes)
[    0.393160] NET: Registered protocol family 1
[    0.422791] pci 0000:01:00.0: Boot video device
[    0.422809] PCI: CLS 4 bytes, default 64
[    0.422834] Unpacking initramfs...
[    0.609144] Freeing initrd memory: 13720k freed
[    0.610925] audit: initializing netlink socket (disabled)
[    0.610937] type=2000 audit(1465656659.504:1): initialized
[    0.619396] highmem bounce pool size: 64 pages
[    0.619400] HugeTLB registered 4 MB page size, pre-allocated 0 pages
[    0.619617] VFS: Disk quotas dquot_6.5.2
[    0.619641] Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[    0.619695] msgmni has been set to 1690
[    0.619809] alg: No test for stdrng (krng)
[    0.619833] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 253)
[    0.619836] io scheduler noop registered
[    0.619837] io scheduler deadline registered
[    0.619863] io scheduler cfq registered (default)
[    0.620070] pci_hotplug: PCI Hot Plug PCI Core version: 0.5
[    0.620081] pciehp: PCI Express Hot Plug Controller Driver version: 0.4
[    0.620082] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
[    0.620348] GHES: HEST is not enabled!
[    0.620355] isapnp: Scanning for PnP cards...
[    0.974593] isapnp: No Plug & Play device found
[    0.974620] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
[    0.994958] serial8250: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A
[    1.015520] 00:07: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A
[    1.015899] 0000:06:00.0: ttyS1 at I/O 0xdff8 (irq = 19) is a XScale
[    1.016058] Linux agpgart interface v0.103
[    1.016225] i8042: PNP: No PS/2 controller found. Probing ports directly.
[    1.048750] i8042: Failed to disable AUX port, but continuing anyway... Is this a SiS?
[    1.048751] i8042: If AUX port is really absent please use the 'i8042.noaux' option
[    1.296363] serio: i8042 KBD port at 0x60,0x64 irq 1
[    1.296430] mousedev: PS/2 mouse device common for all mice
[    1.296476] rtc_cmos 00:04: RTC can wake from S4
[    1.296584] rtc_cmos 00:04: rtc core: registered rtc_cmos as rtc0
[    1.296609] rtc0: alarms up to one month, 242 bytes nvram, hpet irqs
[    1.296698] TCP: cubic registered
[    1.296776] NET: Registered protocol family 10
[    1.296937] Mobile IPv6
[    1.296938] NET: Registered protocol family 17
[    1.296941] Registering the dns_resolver key type
[    1.296972] Using IPI No-Shortcut mode
[    1.297031] PM: Hibernation image not present or could not be loaded.
[    1.297038] registered taskstats version 1
[    1.297398] rtc_cmos 00:04: setting system clock to 2016-06-11 14:51:01 UTC (1465656661)
[    1.297468] Freeing unused kernel memory: 416k freed
[    1.297557] Write protecting the kernel text: 3496k
[    1.297576] Write protecting the kernel read-only data: 984k
[    1.304547] udevd[75]: starting version 175
[    1.312657] usbcore: registered new interface driver usbfs
[    1.312675] usbcore: registered new interface driver hub
[    1.313149] r8169 Gigabit Ethernet driver 2.3LK-NAPI loaded
[    1.313407] r8169 0000:04:00.0: eth0: RTL8168evl/8111evl at 0xf801a000, 50:e5:49:40:48:65, XID 0c900800 IRQ 18
[    1.313410] r8169 0000:04:00.0: eth0: jumbo features [frames: 9200 bytes, tx checksumming: ko]
[    1.314014] usbcore: registered new device driver usb
[    1.314033] SCSI subsystem initialized
[    1.314296] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    1.314337] ehci_hcd 0000:00:1a.0: setting latency timer to 64
[    1.314341] ehci_hcd 0000:00:1a.0: EHCI Host Controller
[    1.314361] ehci_hcd 0000:00:1a.0: new USB bus registered, assigned bus number 1
[    1.314384] ehci_hcd 0000:00:1a.0: debug port 2
[    1.314876] libata version 3.00 loaded.
[    1.318251] ehci_hcd 0000:00:1a.0: cache line size of 4 is not supported
[    1.318273] ehci_hcd 0000:00:1a.0: irq 18, io mem 0xfbffe000
[    1.319140] microcode: CPU0 sig=0x206a7, pf=0x2, revision=0x17
[    1.327772] microcode: CPU0 updated to revision 0x29, date = 2013-06-12
[    1.327783] microcode: CPU1 sig=0x206a7, pf=0x2, revision=0x17
[    1.328312] ehci_hcd 0000:00:1a.0: USB 2.0 started, EHCI 1.00
[    1.328357] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
[    1.328359] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.328361] usb usb1: Product: EHCI Host Controller
[    1.328362] usb usb1: Manufacturer: Linux 3.4-9-rtai-686-pae ehci_hcd
[    1.328363] usb usb1: SerialNumber: 0000:00:1a.0
[    1.328467] hub 1-0:1.0: USB hub found
[    1.328474] hub 1-0:1.0: 2 ports detected
[    1.328560] ehci_hcd 0000:00:1d.0: setting latency timer to 64
[    1.328563] ehci_hcd 0000:00:1d.0: EHCI Host Controller
[    1.328571] ehci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 2
[    1.328591] ehci_hcd 0000:00:1d.0: debug port 2
[    1.329331] microcode: CPU1 updated to revision 0x29, date = 2013-06-12
[    1.329340] microcode: CPU2 sig=0x206a7, pf=0x2, revision=0x17
[    1.330814] microcode: CPU2 updated to revision 0x29, date = 2013-06-12
[    1.330823] microcode: CPU3 sig=0x206a7, pf=0x2, revision=0x17
[    1.332291] microcode: CPU3 updated to revision 0x29, date = 2013-06-12
[    1.332301] microcode: CPU4 sig=0x206a7, pf=0x2, revision=0x17
[    1.332459] ehci_hcd 0000:00:1d.0: cache line size of 4 is not supported
[    1.332477] ehci_hcd 0000:00:1d.0: irq 23, io mem 0xfbffd000
[    1.333806] microcode: CPU4 updated to revision 0x29, date = 2013-06-12
[    1.333815] microcode: CPU5 sig=0x206a7, pf=0x2, revision=0x17
[    1.335267] microcode: CPU5 updated to revision 0x29, date = 2013-06-12
[    1.335275] microcode: CPU6 sig=0x206a7, pf=0x2, revision=0x17
[    1.336813] microcode: CPU6 updated to revision 0x29, date = 2013-06-12
[    1.336823] microcode: CPU7 sig=0x206a7, pf=0x2, revision=0x17
[    1.338288] microcode: CPU7 updated to revision 0x29, date = 2013-06-12
[    1.338315] microcode: Microcode Update Driver: v2.00 <tigran@aivazian.fsnet.co.uk>, Peter Oruba
[    1.344155] ehci_hcd 0000:00:1d.0: USB 2.0 started, EHCI 1.00
[    1.344173] usb usb2: New USB device found, idVendor=1d6b, idProduct=0002
[    1.344175] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.344177] usb usb2: Product: EHCI Host Controller
[    1.344179] usb usb2: Manufacturer: Linux 3.4-9-rtai-686-pae ehci_hcd
[    1.344181] usb usb2: SerialNumber: 0000:00:1d.0
[    1.344261] hub 2-0:1.0: USB hub found
[    1.344265] hub 2-0:1.0: 2 ports detected
[    1.344319] ahci 0000:00:1f.2: version 3.0
[    1.344354] ahci 0000:00:1f.2: controller can't do SNTF, turning off CAP_SNTF
[    1.344377] ahci: SSS flag set, parallel bus scan disabled
[    1.360131] ahci 0000:00:1f.2: AHCI 0001.0300 32 slots 6 ports 6 Gbps 0x3f impl RAID mode
[    1.360133] ahci 0000:00:1f.2: flags: 64bit ncq stag pm led clo pmp pio slum part ems apst
[    1.360137] ahci 0000:00:1f.2: setting latency timer to 64
[    1.400332] scsi0 : ahci
[    1.400421] scsi1 : ahci
[    1.400467] scsi2 : ahci
[    1.400516] scsi3 : ahci
[    1.400562] scsi4 : ahci
[    1.400608] scsi5 : ahci
[    1.400738] ata1: SATA max UDMA/133 abar m2048@0xfbffc000 port 0xfbffc100 irq 19
[    1.400741] ata2: SATA max UDMA/133 abar m2048@0xfbffc000 port 0xfbffc180 irq 19
[    1.400743] ata3: SATA max UDMA/133 abar m2048@0xfbffc000 port 0xfbffc200 irq 19
[    1.400745] ata4: SATA max UDMA/133 abar m2048@0xfbffc000 port 0xfbffc280 irq 19
[    1.400747] ata5: SATA max UDMA/133 abar m2048@0xfbffc000 port 0xfbffc300 irq 19
[    1.400749] ata6: SATA max UDMA/133 abar m2048@0xfbffc000 port 0xfbffc380 irq 19
[    1.607395] Refined TSC clocksource calibration: 3392.293 MHz.
[    1.607399] Switching to clocksource tsc
[    1.643290] usb 1-1: new high-speed USB device number 2 using ehci_hcd
[    1.775256] usb 1-1: New USB device found, idVendor=8087, idProduct=0024
[    1.775257] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[    1.775401] hub 1-1:1.0: USB hub found
[    1.775504] hub 1-1:1.0: 6 ports detected
[    1.886595] usb 2-1: new high-speed USB device number 2 using ehci_hcd
[    1.890587] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[    1.890814] ata1.00: ATA-8: KINGSTON SV100S264G, D110225a, max UDMA/100
[    1.890816] ata1.00: 125045424 sectors, multi 16: LBA48 NCQ (depth 31/32), AA
[    1.891070] ata1.00: configured for UDMA/100
[    1.891148] scsi 0:0:0:0: Direct-Access     ATA      KINGSTON SV100S2 D110 PQ: 0 ANSI: 5
[    2.018558] usb 2-1: New USB device found, idVendor=8087, idProduct=0024
[    2.018560] usb 2-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[    2.018701] hub 2-1:1.0: USB hub found
[    2.018807] hub 2-1:1.0: 8 ports detected
[    2.090104] usb 1-1.2: new low-speed USB device number 3 using ehci_hcd
[    2.185955] usb 1-1.2: New USB device found, idVendor=0458, idProduct=003a
[    2.185956] usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[    2.185958] usb 1-1.2: Product: Optical Mouse
[    2.185959] usb 1-1.2: Manufacturer: Genius
[    2.209674] ata2: SATA link down (SStatus 0 SControl 300)
[    2.257626] usb 1-1.3: new high-speed USB device number 4 using ehci_hcd
[    2.376034] usb 1-1.3: New USB device found, idVendor=0ac8, idProduct=3500
[    2.376036] usb 1-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[    2.376038] usb 1-1.3: Product: A4 TECH HD PC Camera
[    2.376039] usb 1-1.3: Manufacturer: A4 TECH
[    2.376040] usb 1-1.3: SerialNumber: MI1320_SOC
[    2.445089] usb 1-1.4: new low-speed USB device number 5 using ehci_hcd
[    2.528763] ata3: SATA link down (SStatus 0 SControl 300)
[    2.542432] usb 1-1.4: New USB device found, idVendor=046d, idProduct=c517
[    2.542434] usb 1-1.4: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[    2.542436] usb 1-1.4: Product: USB Receiver
[    2.542437] usb 1-1.4: Manufacturer: Logitech
[    2.546001] input: Genius Optical Mouse as /devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.2/1-1.2:1.0/input/input0
[    2.546053] generic-usb 0003:0458:003A.0001: input,hidraw0: USB HID v1.11 Mouse [Genius Optical Mouse] on usb-0000:00:1a.0-1.2/input0
[    2.546109] usbcore: registered new interface driver usbhid
[    2.546110] usbhid: USB HID core driver
[    2.847848] ata4: SATA link down (SStatus 0 SControl 300)
[    3.166936] ata5: SATA link down (SStatus 0 SControl 300)
[    3.486024] ata6: SATA link down (SStatus 0 SControl 300)
[    3.488349] input: Logitech USB Receiver as /devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.4/1-1.4:1.0/input/input1
[    3.488408] logitech 0003:046D:C517.0002: input,hidraw1: USB HID v1.10 Keyboard [Logitech USB Receiver] on usb-0000:00:1a.0-1.4/input0
[    3.488458] sd 0:0:0:0: [sda] 125045424 512-byte logical blocks: (64.0 GB/59.6 GiB)
[    3.488516] sd 0:0:0:0: [sda] Write Protect is off
[    3.488519] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
[    3.488538] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[    3.489494]  sda: sda1 sda2
[    3.489646] sd 0:0:0:0: [sda] Attached SCSI disk
[    3.490540] sd 0:0:0:0: Attached scsi generic sg0 type 0
[    3.493335] logitech 0003:046D:C517.0003: fixing up Logitech keyboard report descriptor
[    3.493566] input: Logitech USB Receiver as /devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.4/1-1.4:1.1/input/input2
[    3.493651] logitech 0003:046D:C517.0003: input,hiddev0,hidraw2: USB HID v1.10 Mouse [Logitech USB Receiver] on usb-0000:00:1a.0-1.4/input1
[    8.583489] PM: Starting manual resume from disk
[    8.583491] PM: Hibernation image partition 8:1 present
[    8.583492] PM: Looking for hibernation image.
[    8.583983] PM: Image not found (code -22)
[    8.583985] PM: Hibernation image not present or could not be loaded.
[    8.592766] EXT4-fs (sda2): mounted filesystem with ordered data mode. Opts: (null)
[    8.814611] udevd[416]: starting version 175
[    8.879009] input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input3
[    8.879014] ACPI: Power Button [PWRB]
[    8.879052] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input4
[    8.879055] ACPI: Power Button [PWRF]
[    8.893574] parport_pc 00:08: reported by Plug and Play ACPI
[    8.893637] parport0: PC-style at 0x378 (0x778), irq 7 [PCSPP,TRISTATE]
[    8.896118] iTCO_vendor_support: vendor-support=0
[    8.900579] iTCO_wdt: Intel TCO WatchDog Timer Driver v1.07
[    8.900623] iTCO_wdt: unable to reset NO_REBOOT flag, device disabled by hardware/BIOS
[    8.910105] [drm] Initialized drm 1.1.0 20060810
[    8.938806] [drm] radeon kernel modesetting enabled.
[    8.938942] [drm] initializing kernel modesetting (RV635 0x1002:0x9598 0x1043:0x01DA).
[    8.938955] [drm] register mmio base: 0xFBDE0000
[    8.938956] [drm] register mmio size: 65536
[    8.938997] ATOM BIOS: 9598.10.78.0.0.AS01
[    8.939008] radeon 0000:01:00.0: VRAM: 512M 0x0000000000000000 - 0x000000001FFFFFFF (512M used)
[    8.939010] radeon 0000:01:00.0: GTT: 512M 0x0000000020000000 - 0x000000003FFFFFFF
[    8.939014] mtrr: type mismatch for e0000000,10000000 old: write-back new: write-combining
[    8.939015] [drm] Detected VRAM RAM=512M, BAR=256M
[    8.939016] [drm] RAM width 128bits DDR
[    8.939045] [TTM] Zone  kernel: Available graphics memory: 433096 kiB
[    8.939047] [TTM] Zone highmem: Available graphics memory: 1809132 kiB
[    8.939048] [TTM] Initializing pool allocator
[    8.939060] [drm] radeon: 512M of VRAM memory ready
[    8.939061] [drm] radeon: 512M of GTT memory ready.
[    8.939068] [drm] GART: num cpu pages 131072, num gpu pages 131072
[    8.939201] [drm] radeon: ib pool ready.
[    8.939244] [drm] Loading RV635 Microcode
[    8.954813] [drm] PCIE GART of 512M enabled (table at 0x0000000000040000).
[    8.954857] radeon 0000:01:00.0: WB enabled
[    8.954860] [drm] fence driver on ring 0 use gpu addr 0x20000c00 and cpu addr 0xff99dc00
[    8.954863] [drm] Supports vblank timestamp caching Rev 1 (10.10.2010).
[    8.954865] [drm] Driver supports precise vblank timestamp query.
[    8.954888] [drm] radeon: irq initialized.
[    8.965727] Linux media interface: v0.10
[    8.967277] Linux video capture interface: v2.00
[    8.972221] uvcvideo: Found UVC 1.00 device A4 TECH HD PC Camera (0ac8:3500)
[    8.973185] input: A4 TECH HD PC Camera as /devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.3/1-1.3:1.0/input/input5
[    8.973229] usbcore: registered new interface driver uvcvideo
[    8.973231] USB Video Class driver (1.1.1)
[    8.985964] [drm] ring test on 0 succeeded in 1 usecs
[    8.986051] [drm] ib test on ring 0 succeeded in 0 usecs
[    8.986182] [drm] Radeon Display Connectors
[    8.986184] [drm] Connector 0:
[    8.986185] [drm]   DVI-I
[    8.986186] [drm]   HPD1
[    8.986187] [drm]   DDC: 0x7e50 0x7e50 0x7e54 0x7e54 0x7e58 0x7e58 0x7e5c 0x7e5c
[    8.986189] [drm]   Encoders:
[    8.986190] [drm]     DFP1: INTERNAL_UNIPHY
[    8.986191] [drm]     CRT2: INTERNAL_KLDSCP_DAC2
[    8.986193] [drm] Connector 1:
[    8.986194] [drm]   DIN
[    8.986195] [drm]   Encoders:
[    8.986196] [drm]     TV1: INTERNAL_KLDSCP_DAC2
[    8.986197] [drm] Connector 2:
[    8.986198] [drm]   DVI-I
[    8.986199] [drm]   HPD2
[    8.986201] [drm]   DDC: 0x7e40 0x7e40 0x7e44 0x7e44 0x7e48 0x7e48 0x7e4c 0x7e4c
[    8.986202] [drm]   Encoders:
[    8.986203] [drm]     CRT1: INTERNAL_KLDSCP_DAC1
[    8.986205] [drm]     DFP2: INTERNAL_KLDSCP_LVTMA
[    8.986230] [drm] Internal thermal controller without fan control
[    8.986253] [drm] radeon: power management initialized
[    8.987088] input: HDA Digital PCBeep as /devices/pci0000:00/0000:00:1b.0/input/input6
[    8.992310] input: HDA Intel PCH Line as /devices/pci0000:00/0000:00:1b.0/sound/card0/input7
[    8.992372] input: HDA Intel PCH Front Mic as /devices/pci0000:00/0000:00:1b.0/sound/card0/input8
[    8.992418] input: HDA Intel PCH Rear Mic as /devices/pci0000:00/0000:00:1b.0/sound/card0/input9
[    8.992461] input: HDA Intel PCH Front Headphone as /devices/pci0000:00/0000:00:1b.0/sound/card0/input10
[    8.992505] input: HDA Intel PCH Line Out as /devices/pci0000:00/0000:00:1b.0/sound/card0/input11
[    9.040394] [drm] fb mappable at 0xE0142000
[    9.040396] [drm] vram apper at 0xE0000000
[    9.040398] [drm] size 5242880
[    9.040399] [drm] fb depth is 24
[    9.040400] [drm]    pitch is 5120
[    9.040440] fbcon: radeondrmfb (fb0) is primary device
[    9.455255] Console: switching to colour frame buffer device 160x64
[    9.457553] fb0: radeondrmfb frame buffer device
[    9.457554] drm: registered panic notifier
[    9.457558] [drm] Initialized radeon 2.16.0 20080528 for 0000:01:00.0 on minor 0
[    9.474673] input: HDA ATI HDMI HDMI/DP,pcm=3 as /devices/pci0000:00/0000:00:01.0/0000:01:00.1/sound/card2/input12
[   18.950883] usbcore: registered new interface driver snd-usb-audio
[   19.144677] Adding 7811068k swap on /dev/sda1.  Priority:-1 extents:1 across:7811068k SS
[   19.152222] EXT4-fs (sda2): re-mounted. Opts: (null)
[   19.192371] EXT4-fs (sda2): re-mounted. Opts: errors=remount-ro
[   19.204959] loop: module loaded
[   19.539444] RPC: Registered named UNIX socket transport module.
[   19.539446] RPC: Registered udp transport module.
[   19.539447] RPC: Registered tcp transport module.
[   19.539448] RPC: Registered tcp NFSv4.1 backchannel transport module.
[   19.544205] FS-Cache: Loaded
[   19.549652] NFS: Registering the id_resolver key type
[   19.549663] FS-Cache: Netfs 'nfs' registered for caching
[   19.553336] Installing knfsd (copyright (C) 1996 okir@monad.swb.de).
[   20.013677] Bluetooth: Core ver 2.16
[   20.013687] NET: Registered protocol family 31
[   20.013689] Bluetooth: HCI device and connection manager initialized
[   20.013690] Bluetooth: HCI socket layer initialized
[   20.013692] Bluetooth: L2CAP socket layer initialized
[   20.013695] Bluetooth: SCO socket layer initialized
[   20.019254] Bluetooth: RFCOMM TTY layer initialized
[   20.019258] Bluetooth: RFCOMM socket layer initialized
[   20.019259] Bluetooth: RFCOMM ver 1.11
[   20.025467] Bluetooth: BNEP (Ethernet Emulation) ver 1.3
[   20.025469] Bluetooth: BNEP filters: protocol multicast
[   20.059767] lp0: using parport0 (interrupt-driven).
[   20.061144] ppdev: user-space parallel port driver
[   20.302892] r8169 0000:04:00.0: eth0: link down
[   20.302945] r8169 0000:04:00.0: eth0: link down
[   20.302974] ADDRCONF(NETDEV_UP): eth0: link is not ready
[   20.799339] input: ACPI Virtual Keyboard Device as /devices/virtual/input/input13
[   22.656152] r8169 0000:04:00.0: eth0: link up
[   22.656206] ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[   33.161152] eth0: no IPv6 routers present
[  200.333547] I-pipe: head domain RTAI registered.
[  200.333551] RTAI[hal]: compiled with gcc version 4.7.2 (Debian 4.7.2-5) .
[  200.333620] RTAI[hal]: mounted (IPIPE-NOTHREADS, IMMEDIATE (INTERNAL IRQs DISPATCHED), ISOL_CPUS_MASK: 0).
[  200.333623] SYSINFO: CPUs 8, LINUX APIC IRQ 2312, TIM_FREQ 6235350, CLK_FREQ 3392161000, CPU_FREQ 3392161000
[  200.333626] RTAI_APIC_TIMER_IPI: RTAI DEFINED 2314, VECTOR 2314; LINUX_APIC_TIMER_IPI: RTAI DEFINED 2312, VECTOR 2312
[  200.333629] TIMER NAME: lapic; VARIOUSLY FOUND APIC FREQs: 6235350, 6235350, 6166750
[  200.345320] RTAI[malloc]: global heap size = 2097152 bytes, <BSD>.
[  200.345342] , <uses LINUX SYSCALLs>, kstacks pool size = 524288 bytes.
[  200.345345] RTAI[sched]: hard timer type/freq = APIC/6235350(Hz); default timing: oneshot; linear timed lists.
[  200.345347] RTAI[sched]: Linux timer freq = 250 (Hz), TimeBase freq = 3392161000 hz.
[  200.345348] RTAI[sched]: timer setup = 999 ns, resched latency = 2943 ns.
[  200.352012] RTAI[math]: loaded.
[74461.150456] RTAI[math]: unloaded.
[74461.151360] SCHED releases registered named ALIEN PEDV$D
[74461.152917] RTAI[malloc]: unloaded.
[74461.251989] RTAI[sched]: unloaded (forced hard/soft/hard transitions: traps 0, syscalls 0).
[74461.252914] I-pipe: head domain RTAI unregistered.
[74461.252994] RTAI[hal]: unmounted.
...
Attachments:

Please Log in or Create an account to join the conversation.

More
12 Jun 2016 22:00 #75875 by PCW
I would try with the built-in intel graphics and see if the real time delay goes away

The latency test only tests dispatch latency and does no PCI/PCIE/LPC/whatever access so any hardware blockage (say by a video board) is missed

Please Log in or Create an account to join the conversation.

More
12 Jun 2016 22:09 #75876 by arhi

I would try with the built-in intel graphics and see if the real time delay goes away

The latency test only tests dispatch latency and does no PCI/PCIE/LPC/whatever access so any hardware blockage (say by a video board) is missed


I'll try .. maybe it will help, thanks for the hint... but I dislike that solution as I wanted to use that box to do the slicing too as it's kinda dedicated for those few printers and mills in that part of the office ..

btw, wouldn't glxgear running in background show if video board is blocking anything?

are there any "logs" I can look at? I don't see linuxcnc producing any logs, can I turn it on somewhere?
Attachments:

Please Log in or Create an account to join the conversation.

More
12 Jun 2016 22:11 #75877 by arhi
for e.g. is there a way I can see the "amount of unexpected realtime delay" in some log, was the delay longer by a 1ns, 100ns, a second?

Please Log in or Create an account to join the conversation.

More
12 Jun 2016 22:15 #75878 by PCW
The latency test wont show if the video blocks PCI/PCIE/LPC access since the
latency test doesn't access any of them (it only accesses memory)

Please Log in or Create an account to join the conversation.

More
12 Jun 2016 22:17 - 12 Jun 2016 22:22 #75879 by PCW
I would expect a message with a complaint about clock tics between thread invocations in the kernel log

Edit:
You can also look at servo-thread.tmax and base-thread.tmax hal parameters
(these are in CPU clocks _NOT_ nS )
Last edit: 12 Jun 2016 22:22 by PCW.

Please Log in or Create an account to join the conversation.

More
12 Jun 2016 22:22 #75880 by arhi

The latency test wont show if the video blocks PCI/PCIE/LPC access since the
latency test doesn't access any of them (it only accesses memory)


sorry if I'm dumb here :( but why would servo or base threads in linuxcnc access video card ?! .. yes the axis gui does but I doubt video display is running from realtime thread. .. and if realtime thread is affected by linuxcnc axesing video should the latency test be identically affected by glxgear?

anyhow you gave me 2 things to test so now I'm off testing :D no need to theorize any more :D will get back soon with results :D thanks again

Please Log in or Create an account to join the conversation.

More
12 Jun 2016 22:23 #75881 by PCW
They do not access the video card, but a block transfer to/from the video card can block (in hardware)
access to other hardware on the PC (say the parallel port)

Please Log in or Create an account to join the conversation.

More
12 Jun 2016 22:29 #75883 by arhi
yes, makes sense .. since latency test don't send anything to lpt .. hm .. might be worth adapting that binary :D .. another interesting idea :D

Please Log in or Create an account to join the conversation.

More
12 Jun 2016 22:37 #75884 by PCW
There are also caching effects, LinuxCNCs threads use much more memory than the real time portion of the latency test
so its more likely some part of the real time portion of linuxcnc will get evicted by other tasks adding cache reload latency to the mix.

All this to say:

The latency test gives a good general idea of a CPUs suitability to real time, but its not a very thorough test

If you want an interesting test, plot servo-thread.time in halscope and move the mouse...

Please Log in or Create an account to join the conversation.

Time to create page: 0.170 seconds
Powered by Kunena Forum