Error: command ['sh', '-c', 'dmesg | comm -13 /var/log/dmesg -'] failed with exit code 1: 0.611531] io scheduler cfq registered comm: file 1 is not in sorted order [ 0.611548] pci 0000:00:02.0: Boot video device [ 0.612056] alloc irq_desc for 24 on node -1 [ 0.612060] alloc kstat_irqs on node -1 [ 0.612085] pcieport-driver 0000:00:1c.0: irq 24 for MSI/MSI-X [ 0.612115] pcieport-driver 0000:00:1c.0: setting latency timer to 64 [ 0.612492] alloc irq_desc for 25 on node -1 [ 0.612495] alloc kstat_irqs on node -1 [ 0.612516] pcieport-driver 0000:00:1c.1: irq 25 for MSI/MSI-X [ 0.612544] pcieport-driver 0000:00:1c.1: setting latency timer to 64 [ 0.612919] alloc irq_desc for 26 on node -1 [ 0.612922] alloc kstat_irqs on node -1 [ 0.612943] pcieport-driver 0000:00:1c.2: irq 26 for MSI/MSI-X [ 0.612971] pcieport-driver 0000:00:1c.2: setting latency timer to 64 [ 0.613229] pci_hotplug: PCI Hot Plug PCI Core version: 0.5 [ 0.613817] pciehp 0000:00:1c.0:pcie04: HPC vendor_id 8086 device_id 283f ss_vid 0 ss_did 0 [ 0.613912] pciehp 0000:00:1c.0:pcie04: service driver pciehp loaded [ 0.613963] pciehp 0000:00:1c.1:pcie04: HPC vendor_id 8086 device_id 2841 ss_vid 0 ss_did 0 [ 0.614048] pciehp 0000:00:1c.1:pcie04: service driver pciehp loaded [ 0.614098] pciehp 0000:00:1c.2:pcie04: HPC vendor_id 8086 device_id 2843 ss_vid 0 ss_did 0 [ 0.614183] pciehp 0000:00:1c.2:pcie04: service driver pciehp loaded [ 0.614196] pciehp: PCI Express Hot Plug Controller Driver version: 0.4 [ 0.614839] ACPI: AC Adapter [AC] (on-line) [ 0.614946] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 0.614952] ACPI: Power Button [PWRF] [ 0.615038] input: Lid Switch as /devices/LNXSYSTM:00/device:00/PNP0C0D:00/input/input1 [ 0.615611] ACPI: Lid Switch [LID] [ 0.615683] input: Sleep Button as /devices/LNXSYSTM:00/device:00/PNP0C0E:00/input/input2 [ 0.615694] ACPI: Sleep Button [SLPB] [ 0.616679] ACPI: SSDT 7d6e1b32 00240 (v01 PmRef Cpu0Ist 00000100 INTL 20050513) [ 0.617783] ACPI: SSDT 7d6e1df7 0085E (v01 PmRef Cpu0Cst 00000100 INTL 20050513) [ 0.622495] Monitor-Mwait will be used to enter C-1 state [ 0.622545] Monitor-Mwait will be used to enter C-2 state [ 0.622596] Monitor-Mwait will be used to enter C-3 state [ 0.622643] ACPI: CPU0 (power states: C1[C1] C2[C2] C3[C3]) [ 0.622683] processor LNXCPU:00: registered as cooling_device0 [ 0.622689] ACPI: Processor [CPU0] (supports 8 throttling states) [ 0.623219] ACPI: SSDT 7d6e1a6a 000C8 (v01 PmRef Cpu1Ist 00000100 INTL 20050513) [ 0.623935] ACPI: SSDT 7d6e1d72 00085 (v01 PmRef Cpu1Cst 00000100 INTL 20050513) [ 0.626346] ACPI: CPU1 (power states: C1[C1] C2[C2] C3[C3]) [ 0.626378] processor LNXCPU:01: registered as cooling_device1 [ 0.626384] ACPI: Processor [CPU1] (supports 8 throttling states) [ 0.634125] thermal LNXTHERM:01: registered as thermal_zone0 [ 0.634139] ACPI: Thermal Zone [THM0] (53 C) [ 0.636154] thermal LNXTHERM:02: registered as thermal_zone1 [ 0.636168] ACPI: Thermal Zone [THM1] (52 C) [ 0.636254] isapnp: Scanning for PnP cards... [ 0.770044] ACPI: Battery Slot [BAT0] (battery present) [ 0.994616] isapnp: No Plug & Play device found [ 0.996332] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 0.998424] brd: module loaded [ 0.999155] loop: module loaded [ 0.999260] input: Macintosh mouse button emulation as /devices/virtual/input/input3 [ 0.999374] ahci 0000:00:1f.2: version 3.0 [ 0.999397] alloc irq_desc for 16 on node -1 [ 0.999400] alloc kstat_irqs on node -1 [ 0.999409] ahci 0000:00:1f.2: PCI INT B -> GSI 16 (level, low) -> IRQ 16 [ 0.999479] alloc irq_desc for 27 on node -1 [ 0.999482] alloc kstat_irqs on node -1 [ 0.999504] ahci 0000:00:1f.2: irq 27 for MSI/MSI-X [ 0.999601] ahci 0000:00:1f.2: AHCI 0001.0100 32 slots 3 ports 1.5 Gbps 0x5 impl SATA mode [ 0.999607] ahci 0000:00:1f.2: flags: 64bit ncq sntf pm led clo pio slum part [ 0.999619] ahci 0000:00:1f.2: setting latency timer to 64 [ 0.999861] scsi0 : ahci [ 0.999996] scsi1 : ahci [ 1.000087] scsi2 : ahci [ 1.000196] ata1: SATA max UDMA/133 abar m2048@0xfa426000 port 0xfa426100 irq 27 [ 1.000200] ata2: DUMMY [ 1.000207] ata3: SATA max UDMA/133 abar m2048@0xfa426000 port 0xfa426200 irq 27 [ 1.000277] ata_piix 0000:00:1f.1: version 2.13 [ 1.000291] ata_piix 0000:00:1f.1: PCI INT C -> GSI 16 (level, low) -> IRQ 16 [ 1.000351] ata_piix 0000:00:1f.1: setting latency timer to 64 [ 1.000463] scsi3 : ata_piix [ 1.000571] scsi4 : ata_piix [ 1.001581] ata4: PATA max UDMA/100 cmd 0x1f0 ctl 0x3f6 bmdma 0x1c00 irq 14 [ 1.001586] ata5: PATA max UDMA/100 cmd 0x170 ctl 0x376 bmdma 0x1c08 irq 15 [ 1.002965] Fixed MDIO Bus: probed [ 1.003019] PPP generic driver version 2.4.2 [ 1.003142] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver [ 1.003618] ata5: port disabled. ignoring. [ 1.003625] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [ 1.003639] ehci_hcd 0000:00:1a.7: PCI INT C -> GSI 22 (level, low) -> IRQ 22 [ 1.003658] ehci_hcd 0000:00:1a.7: setting latency timer to 64 [ 1.003665] ehci_hcd 0000:00:1a.7: EHCI Host Controller [ 1.003740] ehci_hcd 0000:00:1a.7: new USB bus registered, assigned bus number 1 [ 1.007684] ehci_hcd 0000:00:1a.7: cache line size of 32 is not supported [ 1.007705] ehci_hcd 0000:00:1a.7: irq 22, io mem 0xfa426c00 [ 1.021023] ehci_hcd 0000:00:1a.7: USB 2.0 started, EHCI 1.00 [ 1.021137] usb usb1: configuration #1 chosen from 1 choice [ 1.021182] hub 1-0:1.0: USB hub found [ 1.021193] hub 1-0:1.0: 4 ports detected [ 1.021907] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [ 1.021922] alloc irq_desc for 19 on node -1 [ 1.021925] alloc kstat_irqs on node -1 [ 1.021933] ehci_hcd 0000:00:1d.7: PCI INT D -> GSI 19 (level, low) -> IRQ 19 [ 1.021950] ehci_hcd 0000:00:1d.7: setting latency timer to 64 [ 1.021958] ehci_hcd 0000:00:1d.7: EHCI Host Controller [ 1.022006] ehci_hcd 0000:00:1d.7: new USB bus registered, assigned bus number 2 [ 1.025928] ehci_hcd 0000:00:1d.7: debug port 1 [ 1.025944] ehci_hcd 0000:00:1d.7: cache line size of 32 is not supported [ 1.025965] ehci_hcd 0000:00:1d.7: irq 19, io mem 0xfa427000 [ 1.041027] ehci_hcd 0000:00:1d.7: USB 2.0 started, EHCI 1.00 [ 1.041120] usb usb2: configuration #1 chosen from 1 choice [ 1.041163] hub 2-0:1.0: USB hub found [ 1.041173] hub 2-0:1.0: 6 ports detected [ 1.041281] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver [ 1.041308] uhci_hcd: USB Universal Host Controller Interface driver [ 1.041344] uhci_hcd 0000:00:1a.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20 [ 1.041357] uhci_hcd 0000:00:1a.0: setting latency timer to 64 [ 1.041365] uhci_hcd 0000:00:1a.0: UHCI Host Controller [ 1.041410] uhci_hcd 0000:00:1a.0: new USB bus registered, assigned bus number 3 [ 1.041468] uhci_hcd 0000:00:1a.0: irq 20, io base 0x00001860 [ 1.041588] usb usb3: configuration #1 chosen from 1 choice [ 1.041635] hub 3-0:1.0: USB hub found [ 1.041645] hub 3-0:1.0: 2 ports detected [ 1.042206] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [ 1.042217] uhci_hcd 0000:00:1a.1: PCI INT B -> GSI 21 (level, low) -> IRQ 21 [ 1.042231] uhci_hcd 0000:00:1a.1: setting latency timer to 64 [ 1.042238] uhci_hcd 0000:00:1a.1: UHCI Host Controller [ 1.042291] uhci_hcd 0000:00:1a.1: new USB bus registered, assigned bus number 4 [ 1.042346] uhci_hcd 0000:00:1a.1: irq 21, io base 0x00001880 [ 1.042475] usb usb4: configuration #1 chosen from 1 choice [ 1.042519] hub 4-0:1.0: USB hub found [ 1.042529] hub 4-0:1.0: 2 ports detected [ 1.043033] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [ 1.043043] uhci_hcd 0000:00:1d.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [ 1.043057] uhci_hcd 0000:00:1d.0: setting latency timer to 64 [ 1.043064] uhci_hcd 0000:00:1d.0: UHCI Host Controller [ 1.043119] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 5 [ 1.043175] uhci_hcd 0000:00:1d.0: irq 16, io base 0x000018a0 [ 1.043299] usb usb5: configuration #1 chosen from 1 choice [ 1.043342] hub 5-0:1.0: USB hub found [ 1.043352] hub 5-0:1.0: 2 ports detected [ 1.043430] alloc irq_desc for 17 on node -1 [ 1.043433] alloc kstat_irqs on node -1 [ 1.043441] uhci_hcd 0000:00:1d.1: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [ 1.043454] uhci_hcd 0000:00:1d.1: setting latency timer to 64 [ 1.043461] uhci_hcd 0000:00:1d.1: UHCI Host Controller [ 1.043507] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 6 [ 1.043561] uhci_hcd 0000:00:1d.1: irq 17, io base 0x000018c0 [ 1.043683] usb usb6: configuration #1 chosen from 1 choice [ 1.043725] hub 6-0:1.0: USB hub found [ 1.043734] hub 6-0:1.0: 2 ports detected [ 1.044456] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [ 1.044466] alloc irq_desc for 18 on node -1 [ 1.044469] alloc kstat_irqs on node -1 [ 1.044476] uhci_hcd 0000:00:1d.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [ 1.044490] uhci_hcd 0000:00:1d.2: setting latency timer to 64 [ 1.044497] uhci_hcd 0000:00:1d.2: UHCI Host Controller [ 1.044546] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 7 [ 1.044598] uhci_hcd 0000:00:1d.2: irq 18, io base 0x000018e0 [ 1.044727] usb usb7: configuration #1 chosen from 1 choice [ 1.044769] hub 7-0:1.0: USB hub found [ 1.044779] hub 7-0:1.0: 2 ports detected [ 1.044934] PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 1.054128] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 1.054136] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 1.054216] mice: PS/2 mouse device common for all mice [ 1.054385] rtc_cmos 00:07: RTC can wake from S4 [ 1.054434] rtc_cmos 00:07: rtc core: registered rtc_cmos as rtc0 [ 1.054478] rtc0: alarms up to one month, y3k, 114 bytes nvram, hpet irqs [ 1.054632] device-mapper: uevent: version 1.0.3 [ 1.054772] device-mapper: ioctl: 4.15.0-ioctl (2009-04-01) initialised: dm-devel@redhat.com [ 1.054927] device-mapper: multipath: version 1.1.0 loaded [ 1.054931] device-mapper: multipath round-robin: version 1.0.0 loaded [ 1.055118] EISA: Probing bus 0 at eisa.0 [ 1.055128] Cannot allocate resource for EISA slot 1 [ 1.055131] Cannot allocate resource for EISA slot 2 [ 1.055135] Cannot allocate resource for EISA slot 3 [ 1.055138] Cannot allocate resource for EISA slot 4 [ 1.055170] EISA: Detected 0 cards. [ 1.055454] cpuidle: using governor ladder [ 1.055647] cpuidle: using governor menu [ 1.056411] TCP cubic registered [ 1.056654] NET: Registered protocol family 10 [ 1.057365] lo: Disabled Privacy Extensions [ 1.057892] NET: Registered protocol family 17 [ 1.057917] Bluetooth: L2CAP ver 2.13 [ 1.057920] Bluetooth: L2CAP socket layer initialized [ 1.057925] Bluetooth: SCO (Voice Link) ver 0.6 [ 1.057927] Bluetooth: SCO socket layer initialized [ 1.057987] Bluetooth: RFCOMM TTY layer initialized [ 1.057991] Bluetooth: RFCOMM socket layer initialized [ 1.057994] Bluetooth: RFCOMM ver 1.11 [ 1.058956] Using IPI No-Shortcut mode [ 1.059034] PM: Resume from disk failed. [ 1.059049] registered taskstats version 1 [ 1.059184] Magic number: 13:137:927 [ 1.059296] rtc_cmos 00:07: setting system clock to 2009-10-05 08:55:30 UTC (1254732930) [ 1.059301] BIOS EDD facility v0.16 2004-Jun-25, 0 devices found [ 1.059304] EDD information not available. [ 1.062109] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input4 [ 1.164790] ata4.00: ATAPI: MATSHITADVD-RAM UJ-844, RC02, max UDMA/33 [ 1.180523] ata4.00: configured for UDMA/33 [ 1.320122] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 300) [ 1.320148] ata3: SATA link down (SStatus 0 SControl 300) [ 1.321184] ata1.00: ACPI cmd ef/02:00:00:00:00:a0 succeeded [ 1.321190] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 filtered out [ 1.321449] ata1.00: ATA-7: SAMSUNG MCCOE64G8MPP-0VA, PS105L16, max UDMA/100 [ 1.321454] ata1.00: 125045424 sectors, multi 16: LBA48 [ 1.322103] ata1.00: ACPI cmd ef/02:00:00:00:00:a0 succeeded [ 1.322109] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 filtered out [ 1.322114] ata1.00: ACPI cmd ef/10:03:00:00:00:a0 filtered out [ 1.322405] ata1.00: configured for UDMA/100 [ 1.336790] ata1.00: configured for UDMA/100 [ 1.336795] ata1: EH complete [ 1.336961] scsi 0:0:0:0: Direct-Access ATA SAMSUNG MCCOE64G PS10 PQ: 0 ANSI: 5 [ 1.337140] sd 0:0:0:0: Attached scsi generic sg0 type 0 [ 1.337206] sd 0:0:0:0: [sda] 125045424 512-byte logical blocks: (64.0 GB/59.6 GiB) [ 1.337276] sd 0:0:0:0: [sda] Write Protect is off [ 1.337280] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00 [ 1.337317] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA [ 1.337480] sda: sda1 [ 1.337966] sd 0:0:0:0: [sda] Attached SCSI disk [ 1.339545] scsi 3:0:0:0: CD-ROM MATSHITA DVD-RAM UJ-844 RC02 PQ: 0 ANSI: 5 [ 1.344225] sr0: scsi3-mmc drive: 12x/24x writer dvd-ram cd/rw xa/form2 cdda tray [ 1.344230] Uniform CD-ROM driver Revision: 3.20 [ 1.344333] sr 3:0:0:0: Attached scsi CD-ROM sr0 [ 1.344382] sr 3:0:0:0: Attached scsi generic sg1 type 5 [ 1.344495] Freeing unused kernel memory: 540k freed [ 1.344951] Write protecting the kernel text: 4544k [ 1.345047] Write protecting the kernel read-only data: 1832k [ 1.500053] Clocksource tsc unstable (delta = -256451263 ns) [ 1.561067] usb 2-5: new high speed USB device using ehci_hcd and address 2 [ 1.615830] e1000e: Intel(R) PRO/1000 Network Driver - 1.0.2-k2 [ 1.615835] e1000e: Copyright (c) 1999-2008 Intel Corporation. [ 1.615912] e1000e 0000:00:19.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20 [ 1.615922] e1000e 0000:00:19.0: pci_enable_pcie_error_reporting failed 0xfffffffb [ 1.615934] e1000e 0000:00:19.0: setting latency timer to 64 [ 1.616124] alloc irq_desc for 28 on node -1 [ 1.616127] alloc kstat_irqs on node -1 [ 1.616154] e1000e 0000:00:19.0: irq 28 for MSI/MSI-X [ 1.659376] xor: automatically using best checksumming function: pIII_sse [ 1.676010] pIII_sse : 4434.000 MB/sec [ 1.676014] xor: using function: pIII_sse (4434.000 MB/sec) [ 1.716632] usb 2-5: configuration #1 chosen from 1 choice [ 1.729807] device-mapper: dm-raid45: initialized v0.2594b [ 1.907946] 0000:00:19.0: eth0: (PCI Express:2.5GB/s:Width x1) 00:1f:e2:18:d2:5f [ 1.907951] 0000:00:19.0: eth0: Intel(R) PRO/1000 Network Connection [ 1.907990] 0000:00:19.0: eth0: MAC: 6, PHY: 6, PBA No: 1008ff-0ff [ 1.935224] EXT3-fs: INFO: recovery required on readonly filesystem. [ 1.935229] EXT3-fs: write access will be enabled during recovery. [ 1.973096] usb 3-2: new full speed USB device using uhci_hcd and address 2 [ 2.145663] usb 3-2: configuration #1 chosen from 1 choice [ 2.385107] usb 4-1: new full speed USB device using uhci_hcd and address 2 [ 2.558587] usb 4-1: configuration #1 chosen from 1 choice [ 2.571051] usbcore: registered new interface driver hiddev [ 2.574772] input: Logitech USB Receiver as /devices/pci0000:00/0000:00:1a.1/usb4/4-1/4-1:1.0/input/input5 [ 2.574890] generic-usb 0003:046D:C526.0001: input,hidraw0: USB HID v1.11 Mouse [Logitech USB Receiver] on usb-0000:00:1a.1-1/input0 [ 2.579564] input: Logitech USB Receiver as /devices/pci0000:00/0000:00:1a.1/usb4/4-1/4-1:1.1/input/input6 [ 2.579701] generic-usb 0003:046D:C526.0002: input,hiddev96,hidraw1: USB HID v1.11 Device [Logitech USB Receiver] on usb-0000:00:1a.1-1/input1 [ 2.579724] usbcore: registered new interface driver usbhid [ 2.579729] usbhid: v2.6:USB HID core driver [ 4.906883] kjournald starting. Commit interval 5 seconds [ 4.906925] EXT3-fs: recovery complete. [ 4.914610] EXT3-fs: mounted filesystem with writeback data mode. [ 5.023816] type=1505 audit(1254732934.460:2): operation="profile_load" pid=432 name=/usr/share/gdm/guest-session/Xsession [ 5.028142] type=1505 audit(1254732934.468:3): operation="profile_load" pid=433 name=/sbin/dhclient3 [ 5.029200] type=1505 audit(1254732934.468:4): operation="profile_load" pid=433 name=/usr/lib/NetworkManager/nm-dhcp-client.action [ 5.029783] type=1505 audit(1254732934.468:5): operation="profile_load" pid=433 name=/usr/lib/connman/scripts/dhclient-script [ 5.042768] type=1505 audit(1254732934.480:6): operation="profile_load" pid=434 name=/usr/bin/evince [ 5.057192] type=1505 audit(1254732934.496:7): operation="profile_load" pid=434 name=/usr/bin/evince-previewer [ 5.065751] type=1505 audit(1254732934.504:8): operation="profile_load" pid=434 name=/usr/bin/evince-thumbnailer [ 5.080407] type=1505 audit(1254732934.520:9): operation="profile_load" pid=436 name=/usr/lib/cups/backend/cups-pdf [ 5.206097] __ratelimit: 3 callbacks suppressed [ 5.206102] type=1505 audit(1254732934.645:11): operation="profile_load" pid=437 name=/usr/sbin/ntpd [ 5.209507] type=1505 audit(1254732934.649:12): operation="profile_load" pid=438 name=/usr/sbin/tcpdump [ 6.317999] udev: starting version 147 [ 6.450031] Non-volatile memory driver v1.3 [ 6.466264] Linux agpgart interface v0.103 [ 6.641637] thinkpad_acpi: ThinkPad ACPI Extras v0.23 [ 6.641641] thinkpad_acpi: http://ibm-acpi.sf.net/ [ 6.641645] thinkpad_acpi: ThinkPad BIOS 7TET34WW (1.08 ), EC 7THT16WW-1.01 [ 6.641649] thinkpad_acpi: Lenovo ThinkPad X300, model 6477W9D [ 6.642570] thinkpad_acpi: radio switch found; radios are enabled [ 6.642846] thinkpad_acpi: This ThinkPad has standard ACPI backlight brightness control, supported by the ACPI video driver [ 6.642850] thinkpad_acpi: Disabling thinkpad-acpi brightness events by default... [ 6.660849] Registered led device: tpacpi::thinklight [ 6.660896] Registered led device: tpacpi::power [ 6.660935] Registered led device: tpacpi::standby [ 6.660961] Registered led device: tpacpi::thinkvantage [ 6.676839] thinkpad_acpi: Standard ACPI backlight interface available, not loading native one. [ 6.678373] input: ThinkPad Extra Buttons as /devices/virtual/input/input7 [ 6.705321] [drm] Initialized drm 1.1.0 20060810 [ 6.747455] agpgart-intel 0000:00:00.0: Intel 965GM Chipset [ 6.749160] agpgart-intel 0000:00:00.0: detected 7676K stolen memory [ 6.753801] agpgart-intel 0000:00:00.0: AGP aperture is 256M @ 0xe0000000 [ 6.814368] cfg80211: Calling CRDA to update world regulatory domain [ 6.879894] i915 0000:00:02.0: power state changed by ACPI to D0 [ 6.879909] i915 0000:00:02.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [ 6.879916] i915 0000:00:02.0: setting latency timer to 64 [ 6.885486] alloc irq_desc for 29 on node -1 [ 6.885492] alloc kstat_irqs on node -1 [ 6.885504] i915 0000:00:02.0: irq 29 for MSI/MSI-X [ 6.887808] ip_tables: (C) 2000-2006 Netfilter Core Team [ 6.893815] Linux video capture interface: v2.00 [ 6.899042] cfg80211: World regulatory domain updated: [ 6.899046] (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp) [ 6.899051] (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm) [ 6.899056] (2457000 KHz - 2482000 KHz @ 20000 KHz), (300 mBi, 2000 mBm) [ 6.899060] (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm) [ 6.899064] (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm) [ 6.899068] (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm) [ 6.947339] uvcvideo: Found UVC 1.00 device (17ef:4807) [ 6.969239] input: UVC Camera (17ef:4807) as /devices/pci0000:00/0000:00:1d.7/usb2/2-5/2-5:1.0/input/input8 [ 6.969317] usbcore: registered new interface driver uvcvideo [ 6.969322] USB Video Class driver (v0.1.0) [ 7.040368] iwlagn: Intel(R) Wireless WiFi Link AGN driver for Linux, 1.3.27k [ 7.040373] iwlagn: Copyright(c) 2003-2009 Intel Corporation [ 7.040486] iwlagn 0000:03:00.0: PCI INT A -> GSI 17 (level, low) -> IRQ 17 [ 7.040526] iwlagn 0000:03:00.0: setting latency timer to 64 [ 7.040603] iwlagn 0000:03:00.0: Detected Intel Wireless WiFi Link 4965AGN REV=0x4 [ 7.099265] iwlagn 0000:03:00.0: Tunable channels: 13 802.11bg, 19 802.11a channels [ 7.099371] alloc irq_desc for 30 on node -1 [ 7.099375] alloc kstat_irqs on node -1 [ 7.099404] iwlagn 0000:03:00.0: irq 30 for MSI/MSI-X [ 7.123536] [drm] fb0: inteldrmfb frame buffer device [ 7.131674] acpi device:03: registered as cooling_device2 [ 7.131954] input: Video Bus as /devices/LNXSYSTM:00/device:00/PNP0A08:00/device:02/input/input9 [ 7.132010] ACPI: Video Device [VID] (multi-head: yes rom: no post: no) [ 7.132075] [drm] Initialized i915 1.6.0 20080730 for 0000:00:02.0 on minor 0 [ 7.182695] HDA Intel 0000:00:1b.0: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [ 7.182703] hda_intel: probe_mask set to 0x1 for device 17aa:20ac [ 7.182739] HDA Intel 0000:00:1b.0: setting latency timer to 64 [ 7.317431] phy0: Selected rate control algorithm 'iwl-agn-rs' [ 7.529256] [drm] LVDS-8: set mode 1440x900 c [ 7.638208] input: DualPoint Stick as /devices/platform/i8042/serio1/input/input10 [ 7.670105] input: AlpsPS/2 ALPS DualPoint TouchPad as /devices/platform/i8042/serio1/input/input11 [ 7.711811] Console: switching to colour frame buffer device 180x56 [ 7.722506] EXT3 FS on sda1, internal journal [ 7.769527] input: HDA Digital PCBeep as /devices/pci0000:00/0000:00:1b.0/input/input12 [ 8.231687] type=1505 audit(1254732937.668:13): operation="profile_replace" pid=1077 name=/usr/share/gdm/guest-session/Xsession [ 8.234497] type=1505 audit(1254732937.672:14): operation="profile_replace" pid=1078 name=/sbin/dhclient3 [ 8.235680] type=1505 audit(1254732937.672:15): operation="profile_replace" pid=1078 name=/usr/lib/NetworkManager/nm-dhcp-client.action [ 8.236382] type=1505 audit(1254732937.676:16): operation="profile_replace" pid=1078 name=/usr/lib/connman/scripts/dhclient-script [ 8.241885] type=1505 audit(1254732937.680:17): operation="profile_replace" pid=1079 name=/usr/bin/evince [ 8.259003] type=1505 audit(1254732937.696:18): operation="profile_replace" pid=1079 name=/usr/bin/evince-previewer [ 8.270015] type=1505 audit(1254732937.708:19): operation="profile_replace" pid=1079 name=/usr/bin/evince-thumbnailer [ 8.285414] type=1505 audit(1254732937.724:20): operation="profile_replace" pid=1100 name=/usr/lib/cups/backend/cups-pdf [ 8.300417] e1000e 0000:00:19.0: irq 28 for MSI/MSI-X [ 8.356154] e1000e 0000:00:19.0: irq 28 for MSI/MSI-X [ 8.356585] ADDRCONF(NETDEV_UP): eth0: link is not ready [ 8.359198] iwlagn 0000:03:00.0: firmware: requesting iwlwifi-4965-2.ucode [ 8.425620] iwlagn 0000:03:00.0: loaded firmware version 228.61.2.24 [ 8.643074] Registered led device: iwl-phy0::radio [ 8.643105] Registered led device: iwl-phy0::assoc [ 8.643134] Registered led device: iwl-phy0::RX [ 8.643161] Registered led device: iwl-phy0::TX [ 8.813446] ADDRCONF(NETDEV_UP): wlan0: link is not ready [ 9.349063] CE: hpet increasing min_delta_ns to 15000 nsec comm: file 2 is not in sorted order [ 22.494225] input: Virtual ThinkFinger Keyboard as /devices/virtual/input/input13 [ 31.923776] CE: hpet increasing min_delta_ns to 22500 nsec [ 101.950160] input: Virtual ThinkFinger Keyboard as /devices/virtual/input/input14 [ 198.056265] input: Virtual ThinkFinger Keyboard as /devices/virtual/input/input15 [ 226.116721] wlan0: authenticate with AP 00:1d:7e:0c:fc:18 [ 226.118575] wlan0: authenticated [ 226.118579] wlan0: associate with AP 00:1d:7e:0c:fc:18 [ 226.121021] wlan0: RX AssocResp from 00:1d:7e:0c:fc:18 (capab=0x411 status=0 aid=2) [ 226.121025] wlan0: associated [ 226.153287] ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready [ 226.598087] padlock: VIA PadLock not detected. [ 236.312017] wlan0: no IPv6 routers present [ 434.800417] input: Virtual ThinkFinger Keyboard as /devices/virtual/input/input16 [ 439.801829] input: Virtual ThinkFinger Keyboard as /devices/virtual/input/input17 [ 522.186978] input: Virtual ThinkFinger Keyboard as /devices/virtual/input/input18 [ 563.932955] input: Virtual ThinkFinger Keyboard as /devices/virtual/input/input19 [ 936.694993] tun: Universal TUN/TAP device driver, 1.6 [ 936.695000] tun: (C) 1999-2004 Max Krasnyansky [ 940.492217] tun0: Disabled Privacy Extensions [ 1839.567963] input: Virtual ThinkFinger Keyboard as /devices/virtual/input/input20 [ 1849.509987] process `sysctl' is using deprecated sysctl (syscall) net.ipv6.neigh.default.retrans_time; Use net.ipv6.neigh.default.retrans_time_ms instead. [ 3007.444930] input: Virtual ThinkFinger Keyboard as /devices/virtual/input/input21 [ 3612.097326] input: Virtual ThinkFinger Keyboard as /devices/virtual/input/input22 [ 5521.290959] __ratelimit: 9 callbacks suppressed [ 5521.290965] type=1503 audit(1254738450.729:24): operation="open" pid=5524 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/mime.cache" [ 5521.291008] type=1503 audit(1254738450.729:25): operation="open" pid=5524 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/globs2" [ 5521.291037] type=1503 audit(1254738450.729:26): operation="open" pid=5524 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/magic" [ 5521.291067] type=1503 audit(1254738450.729:27): operation="open" pid=5524 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/aliases" [ 5521.291090] type=1503 audit(1254738450.729:28): operation="open" pid=5524 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/subclasses" [ 5521.291114] type=1503 audit(1254738450.729:29): operation="open" pid=5524 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/icons" [ 5521.291136] type=1503 audit(1254738450.729:30): operation="open" pid=5524 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/generic-icons" [ 5521.439492] type=1503 audit(1254738450.876:31): operation="open" pid=5525 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/texmf/ls-R" [ 6598.117396] type=1503 audit(1254739527.556:32): operation="open" pid=5524 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/mime.cache" [ 6598.117425] type=1503 audit(1254739527.556:33): operation="open" pid=5524 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/globs2" [ 6598.117452] type=1503 audit(1254739527.556:34): operation="open" pid=5524 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/magic" [ 6598.117475] type=1503 audit(1254739527.556:35): operation="open" pid=5524 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/aliases" [ 6598.117497] type=1503 audit(1254739527.556:36): operation="open" pid=5524 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/subclasses" [ 6598.117519] type=1503 audit(1254739527.556:37): operation="open" pid=5524 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/icons" [ 6598.117541] type=1503 audit(1254739527.556:38): operation="open" pid=5524 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/generic-icons" [ 6609.265476] type=1503 audit(1254739538.704:39): operation="open" pid=5554 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/mime.cache" [ 6609.265505] type=1503 audit(1254739538.704:40): operation="open" pid=5554 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/globs2" [ 6609.265529] type=1503 audit(1254739538.704:41): operation="open" pid=5554 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/magic" [ 6609.265548] type=1503 audit(1254739538.704:42): operation="open" pid=5554 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/aliases" [ 6609.265567] type=1503 audit(1254739538.704:43): operation="open" pid=5554 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/subclasses" [ 6609.265586] type=1503 audit(1254739538.704:44): operation="open" pid=5554 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/icons" [ 6609.265605] type=1503 audit(1254739538.704:45): operation="open" pid=5554 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/generic-icons" [ 6609.378002] type=1503 audit(1254739538.817:46): operation="open" pid=5555 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/texmf/ls-R" [ 6626.148490] type=1503 audit(1254739555.588:47): operation="open" pid=5554 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/mime.cache" [ 6626.148519] type=1503 audit(1254739555.588:48): operation="open" pid=5554 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/globs2" [ 6626.148546] type=1503 audit(1254739555.588:49): operation="open" pid=5554 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/magic" [ 6626.148570] type=1503 audit(1254739555.588:50): operation="open" pid=5554 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/aliases" [ 6626.148593] type=1503 audit(1254739555.588:51): operation="open" pid=5554 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/subclasses" [ 6626.148615] type=1503 audit(1254739555.588:52): operation="open" pid=5554 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/icons" [ 6626.148637] type=1503 audit(1254739555.588:53): operation="open" pid=5554 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/generic-icons" [ 6628.271816] type=1503 audit(1254739557.708:54): operation="open" pid=5573 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/mime.cache" [ 6628.271849] type=1503 audit(1254739557.708:55): operation="open" pid=5573 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/globs2" [ 6628.271877] type=1503 audit(1254739557.708:56): operation="open" pid=5573 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/magic" [ 6703.962934] __ratelimit: 12 callbacks suppressed [ 6703.962942] type=1503 audit(1254739633.400:61): operation="open" pid=5573 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/mime.cache" [ 6703.962983] type=1503 audit(1254739633.400:62): operation="open" pid=5573 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/globs2" [ 6703.963023] type=1503 audit(1254739633.400:63): operation="open" pid=5573 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/magic" [ 6703.963057] type=1503 audit(1254739633.400:64): operation="open" pid=5573 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/aliases" [ 6703.963091] type=1503 audit(1254739633.400:65): operation="open" pid=5573 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/subclasses" [ 6703.963123] type=1503 audit(1254739633.400:66): operation="open" pid=5573 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/icons" [ 6703.963157] type=1503 audit(1254739633.400:67): operation="open" pid=5573 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/generic-icons" [12135.834335] input: Virtual ThinkFinger Keyboard as /devices/virtual/input/input23 [14492.691682] wlan0: disassociating by local choice (reason=3) [14503.928313] wlan0: authenticate with AP 00:1d:7e:0c:fc:18 [14503.930207] wlan0: authenticated [14503.930212] wlan0: associate with AP 00:1d:7e:0c:fc:18 [14503.932619] wlan0: RX AssocResp from 00:1d:7e:0c:fc:18 (capab=0x411 status=0 aid=1) [14503.932627] wlan0: associated [14974.483434] tun0: Disabled Privacy Extensions [15736.619277] input: Virtual ThinkFinger Keyboard as /devices/virtual/input/input24 [15963.009824] input: Virtual ThinkFinger Keyboard as /devices/virtual/input/input25 [16438.861841] input: Virtual ThinkFinger Keyboard as /devices/virtual/input/input26 [16613.348945] input: Virtual ThinkFinger Keyboard as /devices/virtual/input/input27 [16895.018690] input: Virtual ThinkFinger Keyboard as /devices/virtual/input/input28 [28092.410795] type=1503 audit(1254761021.848:68): operation="open" pid=5574 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/mime.cache" [28092.410837] type=1503 audit(1254761021.848:69): operation="open" pid=5574 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/globs2" [28092.410873] type=1503 audit(1254761021.848:70): operation="open" pid=5574 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/magic" [28092.410902] type=1503 audit(1254761021.848:71): operation="open" pid=5574 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/aliases" [28092.410931] type=1503 audit(1254761021.848:72): operation="open" pid=5574 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/subclasses" [28092.410961] type=1503 audit(1254761021.848:73): operation="open" pid=5574 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/icons" [28092.410988] type=1503 audit(1254761021.848:74): operation="open" pid=5574 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/generic-icons" [30670.937064] type=1503 audit(1254763600.377:75): operation="open" pid=5574 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/mime.cache" [30670.937094] type=1503 audit(1254763600.377:76): operation="open" pid=5574 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/globs2" [30670.937122] type=1503 audit(1254763600.377:77): operation="open" pid=5574 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/magic" [30670.937144] type=1503 audit(1254763600.377:78): operation="open" pid=5574 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/aliases" [30670.937167] type=1503 audit(1254763600.377:79): operation="open" pid=5574 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/subclasses" [30670.937193] type=1503 audit(1254763600.377:80): operation="open" pid=5574 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/icons" [30670.937212] type=1503 audit(1254763600.377:81): operation="open" pid=5574 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/generic-icons" [30683.708916] type=1503 audit(1254763613.145:82): operation="open" pid=5574 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/mime.cache" [30683.708946] type=1503 audit(1254763613.145:83): operation="open" pid=5574 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/globs2" [30683.708974] type=1503 audit(1254763613.145:84): operation="open" pid=5574 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/magic" [30683.708998] type=1503 audit(1254763613.145:85): operation="open" pid=5574 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/aliases" [30683.709053] type=1503 audit(1254763613.149:86): operation="open" pid=5574 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/subclasses" [30683.709078] type=1503 audit(1254763613.149:87): operation="open" pid=5574 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/icons" [30683.709101] type=1503 audit(1254763613.149:88): operation="open" pid=5574 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/generic-icons" [32711.357558] type=1503 audit(1254765640.797:89): operation="open" pid=5574 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/mime.cache" [32711.357583] type=1503 audit(1254765640.797:90): operation="open" pid=5574 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/globs2" [32711.357607] type=1503 audit(1254765640.797:91): operation="open" pid=5574 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/magic" [32711.357627] type=1503 audit(1254765640.797:92): operation="open" pid=5574 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/aliases" [32711.357647] type=1503 audit(1254765640.797:93): operation="open" pid=5574 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/subclasses" [32711.357666] type=1503 audit(1254765640.797:94): operation="open" pid=5574 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/icons" [32711.357685] type=1503 audit(1254765640.797:95): operation="open" pid=5574 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/generic-icons" [32723.417970] tun0: Disabled Privacy Extensions [32724.962844] type=1503 audit(1254765654.401:96): operation="open" pid=5574 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/mime.cache" [32724.962873] type=1503 audit(1254765654.401:97): operation="open" pid=5574 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/globs2" [32724.962902] type=1503 audit(1254765654.401:98): operation="open" pid=5574 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/magic" [32724.962924] type=1503 audit(1254765654.401:99): operation="open" pid=5574 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/aliases" [32724.962949] type=1503 audit(1254765654.401:100): operation="open" pid=5574 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/subclasses" [32724.962972] type=1503 audit(1254765654.401:101): operation="open" pid=5574 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/icons" [32724.962995] type=1503 audit(1254765654.401:102): operation="open" pid=5574 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/generic-icons" [32812.663628] type=1503 audit(1254765742.101:103): operation="open" pid=5574 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/mime.cache" [32812.663658] type=1503 audit(1254765742.101:104): operation="open" pid=5574 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/globs2" [32812.663686] type=1503 audit(1254765742.101:105): operation="open" pid=5574 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/magic" [32812.663709] type=1503 audit(1254765742.101:106): operation="open" pid=5574 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/aliases" [32812.663738] type=1503 audit(1254765742.101:107): operation="open" pid=5574 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/subclasses" [32812.663757] type=1503 audit(1254765742.101:108): operation="open" pid=5574 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/icons" [32812.663777] type=1503 audit(1254765742.101:109): operation="open" pid=5574 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/generic-icons" [32825.933906] type=1503 audit(1254765755.373:110): operation="open" pid=5574 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/mime.cache" [32825.933936] type=1503 audit(1254765755.373:111): operation="open" pid=5574 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/globs2" [32825.933965] type=1503 audit(1254765755.373:112): operation="open" pid=5574 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/magic" [32825.933987] type=1503 audit(1254765755.373:113): operation="open" pid=5574 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/aliases" [32825.934010] type=1503 audit(1254765755.373:114): operation="open" pid=5574 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/subclasses" [32825.934033] type=1503 audit(1254765755.373:115): operation="open" pid=5574 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/icons" [32825.934057] type=1503 audit(1254765755.373:116): operation="open" pid=5574 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/generic-icons" [39671.288155] usb 2-1: new high speed USB device using ehci_hcd and address 3 [39671.421094] usb 2-1: configuration #1 chosen from 1 choice [39671.421374] hub 2-1:1.0: USB hub found [39671.421524] hub 2-1:1.0: 4 ports detected [41438.255832] type=1503 audit(1254774367.693:117): operation="open" pid=5574 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/mime.cache" [41438.255862] type=1503 audit(1254774367.693:118): operation="open" pid=5574 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/globs2" [41438.255891] type=1503 audit(1254774367.693:119): operation="open" pid=5574 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/magic" [41438.255914] type=1503 audit(1254774367.693:120): operation="open" pid=5574 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/aliases" [41438.255937] type=1503 audit(1254774367.693:121): operation="open" pid=5574 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/subclasses" [41438.255959] type=1503 audit(1254774367.693:122): operation="open" pid=5574 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/icons" [41438.255982] type=1503 audit(1254774367.693:123): operation="open" pid=5574 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/generic-icons" [41450.992526] type=1503 audit(1254774380.429:124): operation="open" pid=5574 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/mime.cache" [41450.992556] type=1503 audit(1254774380.429:125): operation="open" pid=5574 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/globs2" [41450.992585] type=1503 audit(1254774380.429:126): operation="open" pid=5574 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/magic" [41450.992608] type=1503 audit(1254774380.429:127): operation="open" pid=5574 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/aliases" [41450.992632] type=1503 audit(1254774380.429:128): operation="open" pid=5574 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/subclasses" [41450.992656] type=1503 audit(1254774380.429:129): operation="open" pid=5574 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/icons" [41450.992680] type=1503 audit(1254774380.429:130): operation="open" pid=5574 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/generic-icons" [42075.456035] input: Virtual ThinkFinger Keyboard as /devices/virtual/input/input29 [42080.027509] input: Virtual ThinkFinger Keyboard as /devices/virtual/input/input30 [42257.456110] wlan0: deauthenticating by local choice (reason=3) [42277.505669] Registered led device: iwl-phy0::radio [42277.505716] Registered led device: iwl-phy0::assoc [42277.505758] Registered led device: iwl-phy0::RX [42277.505799] Registered led device: iwl-phy0::TX [42277.539481] ADDRCONF(NETDEV_UP): wlan0: link is not ready [42282.593548] wlan0: authenticate with AP 00:1d:7e:0c:fc:18 [42282.595450] wlan0: authenticated [42282.595455] wlan0: associate with AP 00:1d:7e:0c:fc:18 [42282.597851] wlan0: RX AssocResp from 00:1d:7e:0c:fc:18 (capab=0x411 status=0 aid=1) [42282.597856] wlan0: associated [42282.620741] ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready [42293.344087] wlan0: no IPv6 routers present [42591.628185] e1000e 0000:00:19.0: pci_disable_pcie_error_reporting failed 0xfffffffb [42591.628201] e1000e 0000:00:19.0: PCI INT A disabled [44245.872395] type=1503 audit(1254777175.309:131): operation="open" pid=5574 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/mime.cache" [44245.872425] type=1503 audit(1254777175.309:132): operation="open" pid=5574 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/globs2" [44245.872455] type=1503 audit(1254777175.309:133): operation="open" pid=5574 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/magic" [44245.872478] type=1503 audit(1254777175.309:134): operation="open" pid=5574 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/aliases" [44245.872502] type=1503 audit(1254777175.309:135): operation="open" pid=5574 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/subclasses" [44245.872525] type=1503 audit(1254777175.309:136): operation="open" pid=5574 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/icons" [44245.872548] type=1503 audit(1254777175.309:137): operation="open" pid=5574 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/generic-icons" [44257.274304] type=1503 audit(1254777186.713:138): operation="open" pid=5574 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/mime.cache" [44257.274335] type=1503 audit(1254777186.713:139): operation="open" pid=5574 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/globs2" [44257.274363] type=1503 audit(1254777186.713:140): operation="open" pid=5574 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/magic" [44257.274387] type=1503 audit(1254777186.713:141): operation="open" pid=5574 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/aliases" [44257.274411] type=1503 audit(1254777186.713:142): operation="open" pid=5574 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/subclasses" [44257.274434] type=1503 audit(1254777186.713:143): operation="open" pid=5574 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/icons" [44257.274458] type=1503 audit(1254777186.713:144): operation="open" pid=5574 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/generic-icons" [44410.142369] tun0: Disabled Privacy Extensions [44709.148270] usb 2-1.3: new high speed USB device using ehci_hcd and address 4 [44709.273438] usb 2-1.3: configuration #1 chosen from 2 choices [44709.305951] Initializing USB Mass Storage driver... [44709.306232] scsi5 : SCSI emulation for USB Mass Storage devices [44709.306438] usbcore: registered new interface driver usb-storage [44709.306446] USB Mass Storage support registered. [44709.306627] usb-storage: device found at 4 [44709.306631] usb-storage: waiting for device to settle before scanning [44714.316090] usb-storage: device scan complete [44714.316702] scsi 5:0:0:0: Direct-Access iRex DR1000 0322 PQ: 0 ANSI: 2 [44714.317244] sd 5:0:0:0: Attached scsi generic sg2 type 0 [44714.328932] sd 5:0:0:0: [sdb] Attached SCSI removable disk [44718.565489] sd 5:0:0:0: [sdb] 1987335 512-byte logical blocks: (1.01 GB/970 MiB) [44718.675908] sd 5:0:0:0: [sdb] Assuming drive cache: write through [44718.782362] sd 5:0:0:0: [sdb] Assuming drive cache: write through [44718.782373] sdb: [45106.462761] usb 2-1: USB disconnect, address 3 [45106.462769] usb 2-1.3: USB disconnect, address 4 [45176.285083] wlan0: deauthenticating by local choice (reason=3) [45176.375250] wlan0: disassociating by local choice (reason=3) [45176.738159] PM: Syncing filesystems ... done. [45176.748689] PM: Preparing system for mem sleep [45176.748694] Freezing user space processes ... (elapsed 0.00 seconds) done. [45176.749917] Freezing remaining freezable tasks ... (elapsed 0.00 seconds) done. [45176.749987] PM: Entering mem sleep [45176.750003] Suspending console(s) (use no_console_suspend to debug) [45176.832107] sd 0:0:0:0: [sda] Synchronizing SCSI cache [45176.843267] sd 0:0:0:0: [sda] Stopping disk [45178.032299] ata5: port disabled. ignoring. [45178.032451] ata_piix 0000:00:1f.1: PCI INT C disabled [45178.032471] ehci_hcd 0000:00:1d.7: PCI INT D disabled [45178.032488] uhci_hcd 0000:00:1d.2: PCI INT C disabled [45178.032505] uhci_hcd 0000:00:1d.1: PCI INT B disabled [45178.032521] uhci_hcd 0000:00:1d.0: PCI INT A disabled [45178.032527] pciehp 0000:00:1c.2:pcie04: pciehp_suspend ENTRY [45178.032533] pciehp 0000:00:1c.1:pcie04: pciehp_suspend ENTRY [45178.032537] pciehp 0000:00:1c.0:pcie04: pciehp_suspend ENTRY [45178.032556] HDA Intel 0000:00:1b.0: PCI INT B disabled [45178.048108] ehci_hcd 0000:00:1a.7: PCI INT C disabled [45178.048125] uhci_hcd 0000:00:1a.1: PCI INT B disabled [45178.048141] uhci_hcd 0000:00:1a.0: PCI INT A disabled [45178.137158] i915 0000:00:02.0: power state changed by ACPI to D3 [45178.137221] PM: suspend devices took 1.388 seconds [45178.137645] ehci_hcd 0000:00:1d.7: PME# disabled [45178.256085] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D3 [45178.360098] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D3 [45178.456097] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D3 [45178.456621] ehci_hcd 0000:00:1a.7: PME# disabled [45178.568097] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D3 [45178.672097] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D3 [45178.840092] ACPI: Preparing to enter system sleep state S3 [45179.200509] Disabling non-boot CPUs ... [45179.304055] CPU 1 is now offline [45179.304059] SMP alternatives: switching to UP code [45179.313994] CPU0 attaching NULL sched-domain. [45179.313998] CPU1 attaching NULL sched-domain. [45179.314007] CPU0 attaching NULL sched-domain. [45179.314291] CPU1 is down [45179.314349] Extended CMOS year: 2000 [45179.314349] Back to C! [45179.314349] Extended CMOS year: 2000 [45179.314349] Enabling non-boot CPUs ... [45179.314349] SMP alternatives: switching to SMP code [45179.322708] Booting processor 1 APIC 0x1 ip 0x6000 [45179.313805] Initializing CPU#1 [45179.313805] Calibrating delay using timer specific routine.. 2394.12 BogoMIPS (lpj=4788258) [45179.313805] CPU: L1 I cache: 32K, L1 D cache: 32K [45179.313805] CPU: L2 cache: 4096K [45179.313805] CPU: Physical Processor ID: 0 [45179.313805] CPU: Processor Core ID: 1 [45179.413029] CPU1: Intel(R) Core(TM)2 Duo CPU L7100 @ 1.20GHz stepping 0b [45179.413127] CPU0 attaching NULL sched-domain. [45179.416036] Switched to high resolution mode on CPU 1 [45179.428031] CPU0 attaching sched-domain: [45179.428036] domain 0: span 0-1 level MC [45179.428040] groups: 0 1 [45179.428046] CPU1 attaching sched-domain: [45179.428050] domain 0: span 0-1 level MC [45179.428053] groups: 1 0 [45179.429034] CPU1 is up [45179.429038] ACPI: Waking up from system sleep state S3 [45180.212205] i915 0000:00:02.0: restoring config space at offset 0x1 (was 0x900007, writing 0x900407) [45180.212248] pci 0000:00:02.1: restoring config space at offset 0x1 (was 0x900000, writing 0x900007) [45180.212345] pci 0000:00:19.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100103) [45180.212446] uhci_hcd 0000:00:1a.0: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [45180.380086] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [45180.380156] uhci_hcd 0000:00:1a.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [45180.380255] ehci_hcd 0000:00:1a.7: restoring config space at offset 0x1 (was 0x2900106, writing 0x2900102) [45180.380293] ehci_hcd 0000:00:1a.7: PME# disabled [45180.380399] HDA Intel 0000:00:1b.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100102) [45180.380517] pcieport-driver 0000:00:1c.0: restoring config space at offset 0x7 (was 0x20002020, writing 0x2020) [45180.380549] pcieport-driver 0000:00:1c.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [45180.380732] pcieport-driver 0000:00:1c.1: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [45180.380894] pcieport-driver 0000:00:1c.2: restoring config space at offset 0x7 (was 0x20004040, writing 0x4040) [45180.380927] pcieport-driver 0000:00:1c.2: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [45180.420099] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [45180.420169] uhci_hcd 0000:00:1d.0: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [45180.420251] uhci_hcd 0000:00:1d.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [45180.452096] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [45180.452165] uhci_hcd 0000:00:1d.2: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [45180.452270] ehci_hcd 0000:00:1d.7: restoring config space at offset 0x1 (was 0x2900106, writing 0x2900102) [45180.452315] ehci_hcd 0000:00:1d.7: PME# disabled [45180.452581] ata_piix 0000:00:1f.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2880005) [45180.452695] ahci 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00007, writing 0x2b00407) [45180.453090] iwlagn 0000:03:00.0: restoring config space at offset 0x1 (was 0x100106, writing 0x40100106) [45180.529987] i915 0000:00:02.0: setting latency timer to 64 [45180.591249] [drm] LVDS-8: set mode 1440x900 c [45180.611321] pci 0000:00:02.1: PME# disabled [45180.611328] pci 0000:00:19.0: wake-up capability disabled by ACPI [45180.611338] pci 0000:00:19.0: PME# disabled [45180.611352] uhci_hcd 0000:00:1a.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20 [45180.611368] uhci_hcd 0000:00:1a.0: setting latency timer to 64 [45180.611409] usb usb3: root hub lost power or was reset [45180.611435] uhci_hcd 0000:00:1a.1: PCI INT B -> GSI 21 (level, low) -> IRQ 21 [45180.611446] uhci_hcd 0000:00:1a.1: setting latency timer to 64 [45180.611474] usb usb4: root hub lost power or was reset [45180.611505] ehci_hcd 0000:00:1a.7: PME# disabled [45180.611512] ehci_hcd 0000:00:1a.7: PCI INT C -> GSI 22 (level, low) -> IRQ 22 [45180.611521] ehci_hcd 0000:00:1a.7: setting latency timer to 64 [45180.611535] HDA Intel 0000:00:1b.0: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [45180.611548] HDA Intel 0000:00:1b.0: setting latency timer to 64 [45180.611572] pciehp 0000:00:1c.0:pcie04: pciehp_resume ENTRY [45180.611578] pciehp 0000:00:1c.1:pcie04: pciehp_resume ENTRY [45180.611583] pciehp 0000:00:1c.2:pcie04: pciehp_resume ENTRY [45180.611594] uhci_hcd 0000:00:1d.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [45180.611609] uhci_hcd 0000:00:1d.0: setting latency timer to 64 [45180.611646] usb usb5: root hub lost power or was reset [45180.611674] uhci_hcd 0000:00:1d.1: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [45180.611690] uhci_hcd 0000:00:1d.1: setting latency timer to 64 [45180.611729] usb usb6: root hub lost power or was reset [45180.611756] uhci_hcd 0000:00:1d.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [45180.611772] uhci_hcd 0000:00:1d.2: setting latency timer to 64 [45180.611812] usb usb7: root hub lost power or was reset [45180.611843] ehci_hcd 0000:00:1d.7: PME# disabled [45180.611851] ehci_hcd 0000:00:1d.7: PCI INT D -> GSI 19 (level, low) -> IRQ 19 [45180.611866] ehci_hcd 0000:00:1d.7: setting latency timer to 64 [45180.611890] pci 0000:00:1e.0: setting latency timer to 64 [45180.611904] ata_piix 0000:00:1f.1: PCI INT C -> GSI 16 (level, low) -> IRQ 16 [45180.611911] ata_piix 0000:00:1f.1: setting latency timer to 64 [45180.611942] ahci 0000:00:1f.2: setting latency timer to 64 [45180.612481] ata5: port disabled. ignoring. [45180.777280] ata4.00: ACPI cmd ef/03:42:00:00:00:a0 filtered out [45180.777285] ata4.00: ACPI cmd ef/03:0c:00:00:00:a0 filtered out [45180.778719] ata4.00: ACPI cmd e3/00:10:00:00:00:a0 succeeded [45180.780333] ata4.00: ACPI cmd e3/00:03:00:00:00:a0 succeeded [45180.805007] ata4.00: configured for UDMA/33 [45180.828084] thinkpad_acpi: fan watchdog: enabling fan [45180.928117] ata3: SATA link down (SStatus 0 SControl 300) [45181.488102] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 300) [45181.488601] ata1.00: ACPI cmd ef/02:00:00:00:00:a0 succeeded [45181.488606] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 filtered out [45181.488611] ata1.00: ACPI cmd ef/10:03:00:00:00:a0 filtered out [45181.489357] ata1.00: ACPI cmd ef/02:00:00:00:00:a0 succeeded [45181.489362] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 filtered out [45181.489366] ata1.00: ACPI cmd ef/10:03:00:00:00:a0 filtered out [45181.489616] ata1.00: configured for UDMA/100 [45181.490306] sd 0:0:0:0: [sda] Starting disk [45181.505141] ata1: exception Emask 0x10 SAct 0x0 SErr 0x0 action 0x9 t4 [45181.505145] ata1: irq_stat 0x00400040, connection status changed [45181.505757] ata1.00: configured for UDMA/100 [45181.505761] ata1: EH complete [45181.616102] usb 2-5: reset high speed USB device using ehci_hcd and address 2 [45181.864102] usb 3-2: reset full speed USB device using uhci_hcd and address 2 [45182.124104] usb 4-1: reset full speed USB device using uhci_hcd and address 2 [45182.277285] PM: resume devices took 1.824 seconds [45182.277321] PM: Finishing wakeup. [45182.277323] Restarting tasks ... done. [45182.866550] input: Virtual ThinkFinger Keyboard as /devices/virtual/input/input31 [45183.164327] Registered led device: iwl-phy0::radio [45183.164360] Registered led device: iwl-phy0::assoc [45183.164389] Registered led device: iwl-phy0::RX [45183.164418] Registered led device: iwl-phy0::TX [45183.219585] ADDRCONF(NETDEV_UP): wlan0: link is not ready [45183.841027] input: DualPoint Stick as /devices/platform/i8042/serio1/input/input32 [45183.871252] input: AlpsPS/2 ALPS DualPoint TouchPad as /devices/platform/i8042/serio1/input/input33 [45188.294040] wlan0: authenticate with AP 00:1d:7e:0c:fc:18 [45188.299033] wlan0: authenticated [45188.299037] wlan0: associate with AP 00:1d:7e:0c:fc:18 [45188.301459] wlan0: RX AssocResp from 00:1d:7e:0c:fc:18 (capab=0x411 status=0 aid=1) [45188.301465] wlan0: associated [45188.321388] ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready [45198.408079] wlan0: no IPv6 routers present [45200.929199] tun0: Disabled Privacy Extensions [47374.881242] CE: hpet increasing min_delta_ns to 33750 nsec [49180.628246] wlan0: disassociating by local choice (reason=3) [49180.669096] wlan0: deauthenticating by local choice (reason=3) [49181.007263] PM: Syncing filesystems ... done. [49181.058399] PM: Preparing system for mem sleep [49181.058406] Freezing user space processes ... (elapsed 0.00 seconds) done. [49181.061496] Freezing remaining freezable tasks ... (elapsed 0.00 seconds) done. [49181.061552] PM: Entering mem sleep [49181.061565] Suspending console(s) (use no_console_suspend to debug) [49181.148105] sd 0:0:0:0: [sda] Synchronizing SCSI cache [49181.157922] sd 0:0:0:0: [sda] Stopping disk [49182.348199] ata5: port disabled. ignoring. [49182.348313] ata_piix 0000:00:1f.1: PCI INT C disabled [49182.348331] ehci_hcd 0000:00:1d.7: PCI INT D disabled [49182.348347] uhci_hcd 0000:00:1d.2: PCI INT C disabled [49182.348363] uhci_hcd 0000:00:1d.1: PCI INT B disabled [49182.348378] uhci_hcd 0000:00:1d.0: PCI INT A disabled [49182.348384] pciehp 0000:00:1c.2:pcie04: pciehp_suspend ENTRY [49182.348388] pciehp 0000:00:1c.1:pcie04: pciehp_suspend ENTRY [49182.348393] pciehp 0000:00:1c.0:pcie04: pciehp_suspend ENTRY [49182.452392] HDA Intel 0000:00:1b.0: PCI INT B disabled [49182.468107] ehci_hcd 0000:00:1a.7: PCI INT C disabled [49182.468124] uhci_hcd 0000:00:1a.1: PCI INT B disabled [49182.468140] uhci_hcd 0000:00:1a.0: PCI INT A disabled [49182.592147] i915 0000:00:02.0: power state changed by ACPI to D3 [49182.592216] PM: suspend devices took 1.532 seconds [49182.592654] ehci_hcd 0000:00:1d.7: PME# disabled [49182.640105] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D3 [49182.672099] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D3 [49182.704100] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D3 [49182.704632] ehci_hcd 0000:00:1a.7: PME# disabled [49182.752095] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D3 [49182.856088] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D3 [49183.000098] ACPI: Preparing to enter system sleep state S3 [49183.376281] Disabling non-boot CPUs ... [49183.480028] CPU 1 is now offline [49183.480032] SMP alternatives: switching to UP code [49183.489765] CPU0 attaching NULL sched-domain. [49183.489769] CPU1 attaching NULL sched-domain. [49183.489778] CPU0 attaching NULL sched-domain. [49183.490025] CPU1 is down [49183.490082] Extended CMOS year: 2000 [49183.490082] Back to C! [49183.490082] Extended CMOS year: 2000 [49183.490082] Enabling non-boot CPUs ... [49183.490082] SMP alternatives: switching to SMP code [49183.498434] Booting processor 1 APIC 0x1 ip 0x6000 [49183.489607] Initializing CPU#1 [49183.489607] Calibrating delay using timer specific routine.. 2394.25 BogoMIPS (lpj=4788507) [49183.489607] CPU: L1 I cache: 32K, L1 D cache: 32K [49183.489607] CPU: L2 cache: 4096K [49183.489607] CPU: Physical Processor ID: 0 [49183.489607] CPU: Processor Core ID: 1 [49183.589055] CPU1: Intel(R) Core(TM)2 Duo CPU L7100 @ 1.20GHz stepping 0b [49183.589135] CPU0 attaching NULL sched-domain. [49183.593030] Switched to high resolution mode on CPU 1 [49183.604028] CPU0 attaching sched-domain: [49183.604033] domain 0: span 0-1 level MC [49183.604037] groups: 0 1 [49183.604044] CPU1 attaching sched-domain: [49183.604047] domain 0: span 0-1 level MC [49183.604050] groups: 1 0 [49183.605031] CPU1 is up [49183.605035] ACPI: Waking up from system sleep state S3 [49184.396206] i915 0000:00:02.0: restoring config space at offset 0x1 (was 0x900007, writing 0x900407) [49184.396248] pci 0000:00:02.1: restoring config space at offset 0x1 (was 0x900000, writing 0x900007) [49184.396349] pci 0000:00:19.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100103) [49184.396451] uhci_hcd 0000:00:1a.0: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [49184.564088] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [49184.564159] uhci_hcd 0000:00:1a.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [49184.564261] ehci_hcd 0000:00:1a.7: restoring config space at offset 0x1 (was 0x2900106, writing 0x2900102) [49184.564298] ehci_hcd 0000:00:1a.7: PME# disabled [49184.564404] HDA Intel 0000:00:1b.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100102) [49184.564549] pcieport-driver 0000:00:1c.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [49184.564736] pcieport-driver 0000:00:1c.1: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [49184.564926] pcieport-driver 0000:00:1c.2: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [49184.596099] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [49184.596169] uhci_hcd 0000:00:1d.0: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [49184.596250] uhci_hcd 0000:00:1d.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [49184.628100] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [49184.628170] uhci_hcd 0000:00:1d.2: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [49184.628280] ehci_hcd 0000:00:1d.7: restoring config space at offset 0x1 (was 0x2900106, writing 0x2900102) [49184.628325] ehci_hcd 0000:00:1d.7: PME# disabled [49184.628594] ata_piix 0000:00:1f.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2880005) [49184.628710] ahci 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00007, writing 0x2b00407) [49184.629086] iwlagn 0000:03:00.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100506) [49184.705685] i915 0000:00:02.0: setting latency timer to 64 [49184.766697] [drm] LVDS-8: set mode 1440x900 c [49184.766890] Monitor-Mwait will be used to enter C-3 state [49184.786822] pci 0000:00:02.1: PME# disabled [49184.786829] pci 0000:00:19.0: wake-up capability disabled by ACPI [49184.786840] pci 0000:00:19.0: PME# disabled [49184.786855] uhci_hcd 0000:00:1a.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20 [49184.786871] uhci_hcd 0000:00:1a.0: setting latency timer to 64 [49184.786912] usb usb3: root hub lost power or was reset [49184.786940] uhci_hcd 0000:00:1a.1: PCI INT B -> GSI 21 (level, low) -> IRQ 21 [49184.786953] uhci_hcd 0000:00:1a.1: setting latency timer to 64 [49184.786984] usb usb4: root hub lost power or was reset [49184.787014] ehci_hcd 0000:00:1a.7: PME# disabled [49184.787022] ehci_hcd 0000:00:1a.7: PCI INT C -> GSI 22 (level, low) -> IRQ 22 [49184.787031] ehci_hcd 0000:00:1a.7: setting latency timer to 64 [49184.787046] HDA Intel 0000:00:1b.0: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [49184.787059] HDA Intel 0000:00:1b.0: setting latency timer to 64 [49184.787082] pciehp 0000:00:1c.0:pcie04: pciehp_resume ENTRY [49184.787089] pciehp 0000:00:1c.1:pcie04: pciehp_resume ENTRY [49184.787094] pciehp 0000:00:1c.2:pcie04: pciehp_resume ENTRY [49184.787105] uhci_hcd 0000:00:1d.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [49184.787120] uhci_hcd 0000:00:1d.0: setting latency timer to 64 [49184.787160] usb usb5: root hub lost power or was reset [49184.787187] uhci_hcd 0000:00:1d.1: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [49184.787203] uhci_hcd 0000:00:1d.1: setting latency timer to 64 [49184.787247] usb usb6: root hub lost power or was reset [49184.787274] uhci_hcd 0000:00:1d.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [49184.787290] uhci_hcd 0000:00:1d.2: setting latency timer to 64 [49184.787335] usb usb7: root hub lost power or was reset [49184.787366] ehci_hcd 0000:00:1d.7: PME# disabled [49184.787374] ehci_hcd 0000:00:1d.7: PCI INT D -> GSI 19 (level, low) -> IRQ 19 [49184.787389] ehci_hcd 0000:00:1d.7: setting latency timer to 64 [49184.787415] pci 0000:00:1e.0: setting latency timer to 64 [49184.787430] ata_piix 0000:00:1f.1: PCI INT C -> GSI 16 (level, low) -> IRQ 16 [49184.787437] ata_piix 0000:00:1f.1: setting latency timer to 64 [49184.787468] ahci 0000:00:1f.2: setting latency timer to 64 [49184.787981] ata5: port disabled. ignoring. [49184.953254] ata4.00: ACPI cmd ef/03:42:00:00:00:a0 filtered out [49184.953260] ata4.00: ACPI cmd ef/03:0c:00:00:00:a0 filtered out [49184.954707] ata4.00: ACPI cmd e3/00:10:00:00:00:a0 succeeded [49184.956345] ata4.00: ACPI cmd e3/00:03:00:00:00:a0 succeeded [49184.980925] ata4.00: configured for UDMA/33 [49185.104115] ata3: SATA link down (SStatus 0 SControl 300) [49185.472085] thinkpad_acpi: fan watchdog: enabling fan [49185.667604] sd 0:0:0:0: [sda] Starting disk [49185.945156] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 300) [49185.945629] ata1.00: ACPI cmd ef/02:00:00:00:00:a0 succeeded [49185.945635] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 filtered out [49185.945639] ata1.00: ACPI cmd ef/10:03:00:00:00:a0 filtered out [49185.946426] ata1.00: ACPI cmd ef/02:00:00:00:00:a0 succeeded [49185.946431] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 filtered out [49185.946436] ata1.00: ACPI cmd ef/10:03:00:00:00:a0 filtered out [49185.946688] ata1.00: configured for UDMA/100 [49185.960086] ata1: exception Emask 0x10 SAct 0x0 SErr 0x0 action 0x9 t4 [49185.960089] ata1: irq_stat 0x00400040, connection status changed [49185.960709] ata1.00: configured for UDMA/100 [49185.960714] ata1: EH complete [49186.072105] usb 2-5: reset high speed USB device using ehci_hcd and address 2 [49186.320098] usb 3-2: reset full speed USB device using uhci_hcd and address 2 [49186.580097] usb 4-1: reset full speed USB device using uhci_hcd and address 2 [49186.733207] PM: resume devices took 2.104 seconds [49186.733242] PM: Finishing wakeup. [49186.733244] Restarting tasks ... done. [49187.479062] input: Virtual ThinkFinger Keyboard as /devices/virtual/input/input34 [49187.790165] Registered led device: iwl-phy0::radio [49187.790197] Registered led device: iwl-phy0::assoc [49187.790228] Registered led device: iwl-phy0::RX [49187.790257] Registered led device: iwl-phy0::TX [49187.843597] ADDRCONF(NETDEV_UP): wlan0: link is not ready [49188.407623] input: DualPoint Stick as /devices/platform/i8042/serio1/input/input35 [49188.438049] input: AlpsPS/2 ALPS DualPoint TouchPad as /devices/platform/i8042/serio1/input/input36 [49192.906169] wlan0: authenticate with AP 00:1d:7e:0c:fa:88 [49192.913574] wlan0: authenticated [49192.913582] wlan0: associate with AP 00:1d:7e:0c:fa:88 [49192.916482] wlan0: RX AssocResp from 00:1d:7e:0c:fa:88 (capab=0x411 status=0 aid=1) [49192.916487] wlan0: associated [49192.937844] ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready [49203.380053] wlan0: no IPv6 routers present [49307.384432] wlan0: disassociating by local choice (reason=3) [49307.433074] wlan0: deauthenticating by local choice (reason=3) [49307.661597] PM: Syncing filesystems ... done. [49307.678795] PM: Preparing system for mem sleep [49307.678799] Freezing user space processes ... (elapsed 0.00 seconds) done. [49307.680216] Freezing remaining freezable tasks ... (elapsed 0.00 seconds) done. [49307.680279] PM: Entering mem sleep [49307.680295] Suspending console(s) (use no_console_suspend to debug) [49307.764115] sd 0:0:0:0: [sda] Synchronizing SCSI cache [49307.769887] sd 0:0:0:0: [sda] Stopping disk [49308.960210] ata5: port disabled. ignoring. [49308.960326] ata_piix 0000:00:1f.1: PCI INT C disabled [49308.960345] ehci_hcd 0000:00:1d.7: PCI INT D disabled [49308.960361] uhci_hcd 0000:00:1d.2: PCI INT C disabled [49308.960377] uhci_hcd 0000:00:1d.1: PCI INT B disabled [49308.960393] uhci_hcd 0000:00:1d.0: PCI INT A disabled [49308.960398] pciehp 0000:00:1c.2:pcie04: pciehp_suspend ENTRY [49308.960403] pciehp 0000:00:1c.1:pcie04: pciehp_suspend ENTRY [49308.960407] pciehp 0000:00:1c.0:pcie04: pciehp_suspend ENTRY [49309.064421] HDA Intel 0000:00:1b.0: PCI INT B disabled [49309.080112] ehci_hcd 0000:00:1a.7: PCI INT C disabled [49309.080128] uhci_hcd 0000:00:1a.1: PCI INT B disabled [49309.080144] uhci_hcd 0000:00:1a.0: PCI INT A disabled [49309.132163] i915 0000:00:02.0: power state changed by ACPI to D3 [49309.132225] PM: suspend devices took 1.452 seconds [49309.132661] ehci_hcd 0000:00:1d.7: PME# disabled [49309.180113] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D3 [49309.212101] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D3 [49309.244102] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D3 [49309.244641] ehci_hcd 0000:00:1a.7: PME# disabled [49309.300102] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D3 [49309.404091] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D3 [49309.548094] ACPI: Preparing to enter system sleep state S3 [49309.916283] Disabling non-boot CPUs ... [49310.020024] CPU 1 is now offline [49310.020028] SMP alternatives: switching to UP code [49310.029734] CPU0 attaching NULL sched-domain. [49310.029738] CPU1 attaching NULL sched-domain. [49310.029746] CPU0 attaching NULL sched-domain. [49310.029987] CPU1 is down [49310.030043] Extended CMOS year: 2000 [49310.030043] Back to C! [49310.030043] Extended CMOS year: 2000 [49310.030043] Enabling non-boot CPUs ... [49310.030043] SMP alternatives: switching to SMP code [49310.038401] Booting processor 1 APIC 0x1 ip 0x6000 [49310.029570] Initializing CPU#1 [49310.029570] Calibrating delay using timer specific routine.. 2392.42 BogoMIPS (lpj=4784847) [49310.029570] CPU: L1 I cache: 32K, L1 D cache: 32K [49310.029570] CPU: L2 cache: 4096K [49310.029570] CPU: Physical Processor ID: 0 [49310.029570] CPU: Processor Core ID: 1 [49310.129038] CPU1: Intel(R) Core(TM)2 Duo CPU L7100 @ 1.20GHz stepping 0b [49310.129117] CPU0 attaching NULL sched-domain. [49310.133032] Switched to high resolution mode on CPU 1 [49310.144033] CPU0 attaching sched-domain: [49310.144038] domain 0: span 0-1 level MC [49310.144041] groups: 0 1 [49310.144048] CPU1 attaching sched-domain: [49310.144051] domain 0: span 0-1 level MC [49310.144055] groups: 1 0 [49310.145036] CPU1 is up [49310.145040] ACPI: Waking up from system sleep state S3 [49310.696037] thinkpad_acpi: fan watchdog: enabling fan [49310.952215] i915 0000:00:02.0: restoring config space at offset 0x1 (was 0x900007, writing 0x900407) [49310.952257] pci 0000:00:02.1: restoring config space at offset 0x1 (was 0x900000, writing 0x900007) [49310.952356] pci 0000:00:19.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100103) [49310.952458] uhci_hcd 0000:00:1a.0: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [49311.120092] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [49311.120157] uhci_hcd 0000:00:1a.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [49311.120257] ehci_hcd 0000:00:1a.7: restoring config space at offset 0x1 (was 0x2900106, writing 0x2900102) [49311.120293] ehci_hcd 0000:00:1a.7: PME# disabled [49311.120399] HDA Intel 0000:00:1b.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100102) [49311.120539] pcieport-driver 0000:00:1c.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [49311.120726] pcieport-driver 0000:00:1c.1: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [49311.120911] pcieport-driver 0000:00:1c.2: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [49311.152103] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [49311.152172] uhci_hcd 0000:00:1d.0: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [49311.152254] uhci_hcd 0000:00:1d.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [49311.184099] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [49311.184168] uhci_hcd 0000:00:1d.2: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [49311.184276] ehci_hcd 0000:00:1d.7: restoring config space at offset 0x1 (was 0x2900106, writing 0x2900102) [49311.184321] ehci_hcd 0000:00:1d.7: PME# disabled [49311.184588] ata_piix 0000:00:1f.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2880005) [49311.184699] ahci 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00007, writing 0x2b00407) [49311.185075] iwlagn 0000:03:00.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100506) [49311.263839] i915 0000:00:02.0: setting latency timer to 64 [49311.325037] [drm] LVDS-8: set mode 1440x900 c [49311.345129] pci 0000:00:02.1: PME# disabled [49311.345137] pci 0000:00:19.0: wake-up capability disabled by ACPI [49311.345146] pci 0000:00:19.0: PME# disabled [49311.345160] uhci_hcd 0000:00:1a.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20 [49311.345176] uhci_hcd 0000:00:1a.0: setting latency timer to 64 [49311.345218] usb usb3: root hub lost power or was reset [49311.345263] uhci_hcd 0000:00:1a.1: PCI INT B -> GSI 21 (level, low) -> IRQ 21 [49311.345271] uhci_hcd 0000:00:1a.1: setting latency timer to 64 [49311.345305] usb usb4: root hub lost power or was reset [49311.345348] ehci_hcd 0000:00:1a.7: PME# disabled [49311.345354] ehci_hcd 0000:00:1a.7: PCI INT C -> GSI 22 (level, low) -> IRQ 22 [49311.345363] ehci_hcd 0000:00:1a.7: setting latency timer to 64 [49311.345398] HDA Intel 0000:00:1b.0: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [49311.345407] HDA Intel 0000:00:1b.0: setting latency timer to 64 [49311.345434] pciehp 0000:00:1c.0:pcie04: pciehp_resume ENTRY [49311.345440] pciehp 0000:00:1c.1:pcie04: pciehp_resume ENTRY [49311.345446] pciehp 0000:00:1c.2:pcie04: pciehp_resume ENTRY [49311.345457] uhci_hcd 0000:00:1d.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [49311.345472] uhci_hcd 0000:00:1d.0: setting latency timer to 64 [49311.345512] usb usb5: root hub lost power or was reset [49311.345541] uhci_hcd 0000:00:1d.1: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [49311.345556] uhci_hcd 0000:00:1d.1: setting latency timer to 64 [49311.345601] usb usb6: root hub lost power or was reset [49311.345629] uhci_hcd 0000:00:1d.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [49311.345644] uhci_hcd 0000:00:1d.2: setting latency timer to 64 [49311.345684] usb usb7: root hub lost power or was reset [49311.345715] ehci_hcd 0000:00:1d.7: PME# disabled [49311.345724] ehci_hcd 0000:00:1d.7: PCI INT D -> GSI 19 (level, low) -> IRQ 19 [49311.345739] ehci_hcd 0000:00:1d.7: setting latency timer to 64 [49311.345774] pci 0000:00:1e.0: setting latency timer to 64 [49311.345787] ata_piix 0000:00:1f.1: PCI INT C -> GSI 16 (level, low) -> IRQ 16 [49311.345798] ata_piix 0000:00:1f.1: setting latency timer to 64 [49311.345885] ata5: port disabled. ignoring. [49311.345912] ahci 0000:00:1f.2: setting latency timer to 64 [49311.513247] ata4.00: ACPI cmd ef/03:42:00:00:00:a0 filtered out [49311.513253] ata4.00: ACPI cmd ef/03:0c:00:00:00:a0 filtered out [49311.514758] ata4.00: ACPI cmd e3/00:10:00:00:00:a0 succeeded [49311.516332] ata4.00: ACPI cmd e3/00:03:00:00:00:a0 succeeded [49311.540956] ata4.00: configured for UDMA/33 [49311.664110] ata3: SATA link down (SStatus 0 SControl 300) [49311.944141] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 300) [49311.944611] ata1.00: ACPI cmd ef/02:00:00:00:00:a0 succeeded [49311.944615] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 filtered out [49311.944620] ata1.00: ACPI cmd ef/10:03:00:00:00:a0 filtered out [49311.945389] ata1.00: ACPI cmd ef/02:00:00:00:00:a0 succeeded [49311.945393] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 filtered out [49311.945397] ata1.00: ACPI cmd ef/10:03:00:00:00:a0 filtered out [49311.945648] ata1.00: configured for UDMA/100 [49311.960089] ata1: exception Emask 0x10 SAct 0x0 SErr 0x0 action 0x9 t4 [49311.960093] ata1: irq_stat 0x00400040, connection status changed [49311.960705] ata1.00: configured for UDMA/100 [49311.960710] ata1: EH complete [49312.226383] sd 0:0:0:0: [sda] Starting disk [49312.336127] usb 2-5: reset high speed USB device using ehci_hcd and address 2 [49312.584130] usb 3-2: reset full speed USB device using uhci_hcd and address 2 [49312.844104] usb 4-1: reset full speed USB device using uhci_hcd and address 2 [49312.996454] PM: resume devices took 1.812 seconds [49312.996498] PM: Finishing wakeup. [49312.996500] Restarting tasks ... done. [49313.740101] Registered led device: iwl-phy0::radio [49313.740134] Registered led device: iwl-phy0::assoc [49313.740162] Registered led device: iwl-phy0::RX [49313.740189] Registered led device: iwl-phy0::TX [49313.829010] ADDRCONF(NETDEV_UP): wlan0: link is not ready [49314.068341] input: Virtual ThinkFinger Keyboard as /devices/virtual/input/input37 [49314.567041] input: DualPoint Stick as /devices/platform/i8042/serio1/input/input38 [49314.600031] input: AlpsPS/2 ALPS DualPoint TouchPad as /devices/platform/i8042/serio1/input/input39 [49318.890208] wlan0: authenticate with AP 00:1d:7e:0c:fa:88 [49318.892120] wlan0: authenticated [49318.892125] wlan0: associate with AP 00:1d:7e:0c:fa:88 [49318.897757] wlan0: RX AssocResp from 00:1d:7e:0c:fa:88 (capab=0x411 status=0 aid=1) [49318.897763] wlan0: associated [49318.930960] ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready [49329.600048] wlan0: no IPv6 routers present [49437.597443] type=1503 audit(1254822566.800:145): operation="open" pid=13797 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/mime.cache" [49437.597483] type=1503 audit(1254822566.800:146): operation="open" pid=13797 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/globs2" [49437.597511] type=1503 audit(1254822566.800:147): operation="open" pid=13797 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/magic" [49437.597533] type=1503 audit(1254822566.800:148): operation="open" pid=13797 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/aliases" [49437.597555] type=1503 audit(1254822566.800:149): operation="open" pid=13797 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/subclasses" [49437.597578] type=1503 audit(1254822566.800:150): operation="open" pid=13797 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/icons" [49437.597599] type=1503 audit(1254822566.800:151): operation="open" pid=13797 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/generic-icons" [49442.835946] tun0: Disabled Privacy Extensions [49446.707377] type=1503 audit(1254822575.908:152): operation="open" pid=13797 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/mime.cache" [49446.707420] type=1503 audit(1254822575.908:153): operation="open" pid=13797 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/globs2" [49446.707461] type=1503 audit(1254822575.908:154): operation="open" pid=13797 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/magic" [49446.707495] type=1503 audit(1254822575.908:155): operation="open" pid=13797 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/aliases" [49446.707529] type=1503 audit(1254822575.908:156): operation="open" pid=13797 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/subclasses" [49446.707562] type=1503 audit(1254822575.908:157): operation="open" pid=13797 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/icons" [49446.707595] type=1503 audit(1254822575.908:158): operation="open" pid=13797 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/generic-icons" [49452.523935] type=1503 audit(1254822581.726:159): operation="open" pid=13797 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/mime.cache" [49452.523978] type=1503 audit(1254822581.726:160): operation="open" pid=13797 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/globs2" [49452.524064] type=1503 audit(1254822581.728:161): operation="open" pid=13797 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/magic" [49452.524100] type=1503 audit(1254822581.728:162): operation="open" pid=13797 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/aliases" [49452.524134] type=1503 audit(1254822581.728:163): operation="open" pid=13797 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/subclasses" [49452.524167] type=1503 audit(1254822581.728:164): operation="open" pid=13797 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/icons" [49452.524200] type=1503 audit(1254822581.728:165): operation="open" pid=13797 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/generic-icons" [49583.044988] type=1503 audit(1254822712.245:166): operation="open" pid=13797 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/mime.cache" [49583.045068] type=1503 audit(1254822712.249:167): operation="open" pid=13797 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/globs2" [49583.045111] type=1503 audit(1254822712.249:168): operation="open" pid=13797 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/magic" [49583.045146] type=1503 audit(1254822712.249:169): operation="open" pid=13797 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/aliases" [49583.045181] type=1503 audit(1254822712.249:170): operation="open" pid=13797 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/subclasses" [49583.045215] type=1503 audit(1254822712.249:171): operation="open" pid=13797 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/icons" [49583.045249] type=1503 audit(1254822712.249:172): operation="open" pid=13797 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/generic-icons" [49601.465161] type=1503 audit(1254822730.668:173): operation="open" pid=13852 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/mime.cache" [49601.465211] type=1503 audit(1254822730.668:174): operation="open" pid=13852 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/globs2" [49601.465254] type=1503 audit(1254822730.668:175): operation="open" pid=13852 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/magic" [49601.465288] type=1503 audit(1254822730.668:176): operation="open" pid=13852 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/aliases" [49601.465322] type=1503 audit(1254822730.668:177): operation="open" pid=13852 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/subclasses" [49601.465355] type=1503 audit(1254822730.668:178): operation="open" pid=13852 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/icons" [49601.465388] type=1503 audit(1254822730.668:179): operation="open" pid=13852 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/generic-icons" [49606.145311] type=1503 audit(1254822735.349:180): operation="open" pid=13852 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/mime.cache" [49606.145354] type=1503 audit(1254822735.349:181): operation="open" pid=13852 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/globs2" [49606.145395] type=1503 audit(1254822735.349:182): operation="open" pid=13852 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/magic" [49611.028806] __ratelimit: 12 callbacks suppressed [49611.028814] type=1503 audit(1254822740.232:187): operation="open" pid=13852 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/mime.cache" [49611.028856] type=1503 audit(1254822740.232:188): operation="open" pid=13852 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/globs2" [49611.028897] type=1503 audit(1254822740.232:189): operation="open" pid=13852 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/magic" [49611.028933] type=1503 audit(1254822740.232:190): operation="open" pid=13852 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/aliases" [49611.028967] type=1503 audit(1254822740.232:191): operation="open" pid=13852 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/subclasses" [49611.029000] type=1503 audit(1254822740.232:192): operation="open" pid=13852 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/icons" [49611.029040] type=1503 audit(1254822740.232:193): operation="open" pid=13852 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/generic-icons" [49661.860501] type=1503 audit(1254822791.061:194): operation="open" pid=13867 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/mime.cache" [49661.860545] type=1503 audit(1254822791.061:195): operation="open" pid=13867 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/globs2" [49661.860586] type=1503 audit(1254822791.061:196): operation="open" pid=13867 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/magic" [49661.860621] type=1503 audit(1254822791.061:197): operation="open" pid=13867 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/aliases" [49661.860654] type=1503 audit(1254822791.061:198): operation="open" pid=13867 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/subclasses" [49661.860687] type=1503 audit(1254822791.061:199): operation="open" pid=13867 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/icons" [49661.860721] type=1503 audit(1254822791.061:200): operation="open" pid=13867 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/generic-icons" [49672.852530] type=1503 audit(1254822802.053:201): operation="open" pid=13852 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/mime.cache" [49672.852574] type=1503 audit(1254822802.053:202): operation="open" pid=13852 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/globs2" [49672.852617] type=1503 audit(1254822802.053:203): operation="open" pid=13852 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/magic" [49672.852652] type=1503 audit(1254822802.053:204): operation="open" pid=13852 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/aliases" [49672.852686] type=1503 audit(1254822802.053:205): operation="open" pid=13852 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/subclasses" [49672.852719] type=1503 audit(1254822802.053:206): operation="open" pid=13852 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/icons" [49672.852753] type=1503 audit(1254822802.053:207): operation="open" pid=13852 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/generic-icons" [49730.112828] input: Virtual ThinkFinger Keyboard as /devices/virtual/input/input40 [49851.680418] type=1503 audit(1254822980.884:208): operation="open" pid=13852 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/mime.cache" [49851.680461] type=1503 audit(1254822980.884:209): operation="open" pid=13852 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/globs2" [49851.680503] type=1503 audit(1254822980.884:210): operation="open" pid=13852 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/magic" [49851.680537] type=1503 audit(1254822980.884:211): operation="open" pid=13852 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/aliases" [49851.680572] type=1503 audit(1254822980.884:212): operation="open" pid=13852 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/subclasses" [49851.680605] type=1503 audit(1254822980.884:213): operation="open" pid=13852 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/icons" [49851.680639] type=1503 audit(1254822980.884:214): operation="open" pid=13852 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/generic-icons" [49856.495543] type=1503 audit(1254822985.696:215): operation="open" pid=13852 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/mime.cache" [49856.495587] type=1503 audit(1254822985.696:216): operation="open" pid=13852 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/globs2" [49856.495629] type=1503 audit(1254822985.696:217): operation="open" pid=13852 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/magic" [50156.351368] __ratelimit: 12 callbacks suppressed [50156.351376] type=1503 audit(1254823285.552:222): operation="open" pid=13852 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/mime.cache" [50156.351418] type=1503 audit(1254823285.552:223): operation="open" pid=13852 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/globs2" [50156.351459] type=1503 audit(1254823285.552:224): operation="open" pid=13852 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/magic" [50156.351493] type=1503 audit(1254823285.552:225): operation="open" pid=13852 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/aliases" [50156.351526] type=1503 audit(1254823285.552:226): operation="open" pid=13852 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/subclasses" [50156.351559] type=1503 audit(1254823285.552:227): operation="open" pid=13852 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/icons" [50156.351592] type=1503 audit(1254823285.552:228): operation="open" pid=13852 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/generic-icons" [50166.889452] type=1503 audit(1254823296.093:229): operation="open" pid=13852 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/mime.cache" [50166.889499] type=1503 audit(1254823296.093:230): operation="open" pid=13852 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/globs2" [50166.889541] type=1503 audit(1254823296.093:231): operation="open" pid=13852 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/magic" [50166.889576] type=1503 audit(1254823296.093:232): operation="open" pid=13852 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/aliases" [50166.889611] type=1503 audit(1254823296.093:233): operation="open" pid=13852 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/subclasses" [50166.889645] type=1503 audit(1254823296.093:234): operation="open" pid=13852 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/icons" [50166.889678] type=1503 audit(1254823296.093:235): operation="open" pid=13852 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/generic-icons" [51127.389390] wlan0: disassociating by local choice (reason=3) [51127.444048] wlan0: deauthenticating by local choice (reason=3) [51127.856507] PM: Syncing filesystems ... done. [51127.881605] PM: Preparing system for mem sleep [51127.881613] Freezing user space processes ... (elapsed 0.00 seconds) done. [51127.883440] Freezing remaining freezable tasks ... (elapsed 0.00 seconds) done. [51127.883536] PM: Entering mem sleep [51127.883559] Suspending console(s) (use no_console_suspend to debug) [51127.964138] sd 0:0:0:0: [sda] Synchronizing SCSI cache [51127.973232] sd 0:0:0:0: [sda] Stopping disk [51129.160241] ata5: port disabled. ignoring. [51129.160377] ata_piix 0000:00:1f.1: PCI INT C disabled [51129.160402] ehci_hcd 0000:00:1d.7: PCI INT D disabled [51129.160423] uhci_hcd 0000:00:1d.2: PCI INT C disabled [51129.160443] uhci_hcd 0000:00:1d.1: PCI INT B disabled [51129.160463] uhci_hcd 0000:00:1d.0: PCI INT A disabled [51129.160473] pciehp 0000:00:1c.2:pcie04: pciehp_suspend ENTRY [51129.160481] pciehp 0000:00:1c.1:pcie04: pciehp_suspend ENTRY [51129.160488] pciehp 0000:00:1c.0:pcie04: pciehp_suspend ENTRY [51129.264405] HDA Intel 0000:00:1b.0: PCI INT B disabled [51129.280126] ehci_hcd 0000:00:1a.7: PCI INT C disabled [51129.280146] uhci_hcd 0000:00:1a.1: PCI INT B disabled [51129.280165] uhci_hcd 0000:00:1a.0: PCI INT A disabled [51129.432219] i915 0000:00:02.0: power state changed by ACPI to D3 [51129.432320] PM: suspend devices took 1.552 seconds [51129.432870] ehci_hcd 0000:00:1d.7: PME# disabled [51129.480153] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D3 [51129.512142] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D3 [51129.544140] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D3 [51129.544749] ehci_hcd 0000:00:1a.7: PME# disabled [51129.592141] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D3 [51129.696124] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D3 [51129.840128] ACPI: Preparing to enter system sleep state S3 [51130.200395] Disabling non-boot CPUs ... [51130.304039] CPU 1 is now offline [51130.304045] SMP alternatives: switching to UP code [51130.318513] CPU0 attaching NULL sched-domain. [51130.318520] CPU1 attaching NULL sched-domain. [51130.318532] CPU0 attaching NULL sched-domain. [51130.318882] CPU1 is down [51130.318959] Extended CMOS year: 2000 [51130.318959] Back to C! [51130.318959] Extended CMOS year: 2000 [51130.318959] Enabling non-boot CPUs ... [51130.318959] SMP alternatives: switching to SMP code [51130.327326] Booting processor 1 APIC 0x1 ip 0x6000 [51130.318267] Initializing CPU#1 [51130.318267] Calibrating delay using timer specific routine.. 2393.78 BogoMIPS (lpj=4787579) [51130.318267] CPU: L1 I cache: 32K, L1 D cache: 32K [51130.318267] CPU: L2 cache: 4096K [51130.318267] CPU: Physical Processor ID: 0 [51130.318267] CPU: Processor Core ID: 1 [51130.417056] CPU1: Intel(R) Core(TM)2 Duo CPU L7100 @ 1.20GHz stepping 0b [51130.417136] CPU0 attaching NULL sched-domain. [51130.421030] Switched to high resolution mode on CPU 1 [51130.432031] CPU0 attaching sched-domain: [51130.432036] domain 0: span 0-1 level MC [51130.432040] groups: 0 1 [51130.432046] CPU1 attaching sched-domain: [51130.432050] domain 0: span 0-1 level MC [51130.432053] groups: 1 0 [51130.433053] CPU1 is up [51130.433059] ACPI: Waking up from system sleep state S3 [51131.232304] i915 0000:00:02.0: restoring config space at offset 0x1 (was 0x900007, writing 0x900407) [51131.232370] pci 0000:00:02.1: restoring config space at offset 0x1 (was 0x900000, writing 0x900007) [51131.232487] pci 0000:00:19.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100103) [51131.232606] uhci_hcd 0000:00:1a.0: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [51131.408128] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [51131.408208] uhci_hcd 0000:00:1a.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [51131.408323] ehci_hcd 0000:00:1a.7: restoring config space at offset 0x1 (was 0x2900106, writing 0x2900102) [51131.408366] ehci_hcd 0000:00:1a.7: PME# disabled [51131.408487] HDA Intel 0000:00:1b.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100102) [51131.408651] pcieport-driver 0000:00:1c.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [51131.408865] pcieport-driver 0000:00:1c.1: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [51131.409079] pcieport-driver 0000:00:1c.2: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [51131.448144] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [51131.448224] uhci_hcd 0000:00:1d.0: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [51131.448321] uhci_hcd 0000:00:1d.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [51131.480143] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [51131.480223] uhci_hcd 0000:00:1d.2: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [51131.480346] ehci_hcd 0000:00:1d.7: restoring config space at offset 0x1 (was 0x2900106, writing 0x2900102) [51131.480399] ehci_hcd 0000:00:1d.7: PME# disabled [51131.480715] ata_piix 0000:00:1f.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2880005) [51131.480846] ahci 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00007, writing 0x2b00407) [51131.481279] iwlagn 0000:03:00.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100506) [51131.564198] i915 0000:00:02.0: setting latency timer to 64 [51131.625474] [drm] LVDS-8: set mode 1440x900 c [51131.645607] pci 0000:00:02.1: PME# disabled [51131.645617] pci 0000:00:19.0: wake-up capability disabled by ACPI [51131.645631] pci 0000:00:19.0: PME# disabled [51131.645651] uhci_hcd 0000:00:1a.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20 [51131.645669] uhci_hcd 0000:00:1a.0: setting latency timer to 64 [51131.645720] usb usb3: root hub lost power or was reset [51131.645758] uhci_hcd 0000:00:1a.1: PCI INT B -> GSI 21 (level, low) -> IRQ 21 [51131.645773] uhci_hcd 0000:00:1a.1: setting latency timer to 64 [51131.645815] usb usb4: root hub lost power or was reset [51131.645853] ehci_hcd 0000:00:1a.7: PME# disabled [51131.645865] ehci_hcd 0000:00:1a.7: PCI INT C -> GSI 22 (level, low) -> IRQ 22 [51131.645878] ehci_hcd 0000:00:1a.7: setting latency timer to 64 [51131.645906] HDA Intel 0000:00:1b.0: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [51131.645927] HDA Intel 0000:00:1b.0: setting latency timer to 64 [51131.645964] pciehp 0000:00:1c.0:pcie04: pciehp_resume ENTRY [51131.645972] pciehp 0000:00:1c.1:pcie04: pciehp_resume ENTRY [51131.645980] pciehp 0000:00:1c.2:pcie04: pciehp_resume ENTRY [51131.645995] uhci_hcd 0000:00:1d.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [51131.646013] uhci_hcd 0000:00:1d.0: setting latency timer to 64 [51131.646062] usb usb5: root hub lost power or was reset [51131.646096] uhci_hcd 0000:00:1d.1: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [51131.646115] uhci_hcd 0000:00:1d.1: setting latency timer to 64 [51131.646165] usb usb6: root hub lost power or was reset [51131.646199] uhci_hcd 0000:00:1d.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [51131.646217] uhci_hcd 0000:00:1d.2: setting latency timer to 64 [51131.646265] usb usb7: root hub lost power or was reset [51131.646301] ehci_hcd 0000:00:1d.7: PME# disabled [51131.646312] ehci_hcd 0000:00:1d.7: PCI INT D -> GSI 19 (level, low) -> IRQ 19 [51131.646330] ehci_hcd 0000:00:1d.7: setting latency timer to 64 [51131.646364] pci 0000:00:1e.0: setting latency timer to 64 [51131.646386] ata_piix 0000:00:1f.1: PCI INT C -> GSI 16 (level, low) -> IRQ 16 [51131.646398] ata_piix 0000:00:1f.1: setting latency timer to 64 [51131.646444] ahci 0000:00:1f.2: setting latency timer to 64 [51131.647171] ata5: port disabled. ignoring. [51131.813259] ata4.00: ACPI cmd ef/03:42:00:00:00:a0 filtered out [51131.813265] ata4.00: ACPI cmd ef/03:0c:00:00:00:a0 filtered out [51131.814710] ata4.00: ACPI cmd e3/00:10:00:00:00:a0 succeeded [51131.816346] ata4.00: ACPI cmd e3/00:03:00:00:00:a0 succeeded [51131.840838] ata4.00: configured for UDMA/33 [51131.964111] ata3: SATA link down (SStatus 0 SControl 300) [51132.208091] thinkpad_acpi: fan watchdog: enabling fan [51132.244104] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 300) [51132.244570] ata1.00: ACPI cmd ef/02:00:00:00:00:a0 succeeded [51132.244575] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 filtered out [51132.244579] ata1.00: ACPI cmd ef/10:03:00:00:00:a0 filtered out [51132.245371] ata1.00: ACPI cmd ef/02:00:00:00:00:a0 succeeded [51132.245377] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 filtered out [51132.245381] ata1.00: ACPI cmd ef/10:03:00:00:00:a0 filtered out [51132.245634] ata1.00: configured for UDMA/100 [51132.260095] ata1: exception Emask 0x10 SAct 0x0 SErr 0x0 action 0x9 t4 [51132.260098] ata1: irq_stat 0x00400040, connection status changed [51132.260711] ata1.00: configured for UDMA/100 [51132.260715] ata1: EH complete [51132.526426] sd 0:0:0:0: [sda] Starting disk [51132.636100] usb 2-5: reset high speed USB device using ehci_hcd and address 2 [51132.884097] usb 3-2: reset full speed USB device using uhci_hcd and address 2 [51133.144095] usb 4-1: reset full speed USB device using uhci_hcd and address 2 [51133.297201] PM: resume devices took 1.816 seconds [51133.297236] PM: Finishing wakeup. [51133.297239] Restarting tasks ... done. [51133.866546] type=1503 audit(1254828307.639:236): operation="open" pid=13852 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/mime.cache" [51133.866575] type=1503 audit(1254828307.639:237): operation="open" pid=13852 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/globs2" [51133.866602] type=1503 audit(1254828307.639:238): operation="open" pid=13852 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/magic" [51133.866624] type=1503 audit(1254828307.639:239): operation="open" pid=13852 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/aliases" [51133.866646] type=1503 audit(1254828307.639:240): operation="open" pid=13852 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/subclasses" [51133.866668] type=1503 audit(1254828307.639:241): operation="open" pid=13852 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/icons" [51133.866691] type=1503 audit(1254828307.639:242): operation="open" pid=13852 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/generic-icons" [51134.339283] Registered led device: iwl-phy0::radio [51134.339331] Registered led device: iwl-phy0::assoc [51134.339374] Registered led device: iwl-phy0::RX [51134.339417] Registered led device: iwl-phy0::TX [51134.387634] ADDRCONF(NETDEV_UP): wlan0: link is not ready [51134.900188] input: DualPoint Stick as /devices/platform/i8042/serio1/input/input41 [51134.932412] input: AlpsPS/2 ALPS DualPoint TouchPad as /devices/platform/i8042/serio1/input/input42 [51139.463282] wlan0: authenticate with AP 00:1d:7e:0c:fa:88 [51139.465108] wlan0: authenticated [51139.465114] wlan0: associate with AP 00:1d:7e:0c:fa:88 [51139.467949] wlan0: RX AssocResp from 00:1d:7e:0c:fa:88 (capab=0x411 status=0 aid=2) [51139.467955] wlan0: associated [51139.489133] ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready [51139.616513] type=1503 audit(1254828313.390:243): operation="open" pid=13852 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/mime.cache" [51139.616542] type=1503 audit(1254828313.390:244): operation="open" pid=13852 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/globs2" [51139.616569] type=1503 audit(1254828313.390:245): operation="open" pid=13852 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/magic" [51139.616592] type=1503 audit(1254828313.390:246): operation="open" pid=13852 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/aliases" [51139.616614] type=1503 audit(1254828313.390:247): operation="open" pid=13852 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/subclasses" [51139.616636] type=1503 audit(1254828313.390:248): operation="open" pid=13852 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/icons" [51139.616659] type=1503 audit(1254828313.390:249): operation="open" pid=13852 parent=1 profile="/usr/bin/evince" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/usr/local/share/mime/generic-icons" [51150.412022] wlan0: no IPv6 routers present [51720.346391] wlan0: disassociating by local choice (reason=3) [51720.413840] wlan0: deauthenticating by local choice (reason=3) [51720.774295] PM: Syncing filesystems ... done. [51720.830877] PM: Preparing system for mem sleep [51720.830886] Freezing user space processes ... (elapsed 0.00 seconds) done. [51720.832852] Freezing remaining freezable tasks ... (elapsed 0.00 seconds) done. [51720.832939] PM: Entering mem sleep [51720.832963] Suspending console(s) (use no_console_suspend to debug) [51720.908134] sd 0:0:0:0: [sda] Synchronizing SCSI cache [51720.917390] sd 0:0:0:0: [sda] Stopping disk [51722.104243] ata5: port disabled. ignoring. [51722.104378] ata_piix 0000:00:1f.1: PCI INT C disabled [51722.104402] ehci_hcd 0000:00:1d.7: PCI INT D disabled [51722.104423] uhci_hcd 0000:00:1d.2: PCI INT C disabled [51722.104443] uhci_hcd 0000:00:1d.1: PCI INT B disabled [51722.104463] uhci_hcd 0000:00:1d.0: PCI INT A disabled [51722.104472] pciehp 0000:00:1c.2:pcie04: pciehp_suspend ENTRY [51722.104480] pciehp 0000:00:1c.1:pcie04: pciehp_suspend ENTRY [51722.104487] pciehp 0000:00:1c.0:pcie04: pciehp_suspend ENTRY [51722.208424] HDA Intel 0000:00:1b.0: PCI INT B disabled [51722.224121] ehci_hcd 0000:00:1a.7: PCI INT C disabled [51722.224141] uhci_hcd 0000:00:1a.1: PCI INT B disabled [51722.224161] uhci_hcd 0000:00:1a.0: PCI INT A disabled [51722.348212] i915 0000:00:02.0: power state changed by ACPI to D3 [51722.348311] PM: suspend devices took 1.516 seconds [51722.348854] ehci_hcd 0000:00:1d.7: PME# disabled [51722.396157] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D3 [51722.428145] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D3 [51722.460143] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D3 [51722.460752] ehci_hcd 0000:00:1a.7: PME# disabled [51722.508143] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D3 [51722.612125] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D3 [51722.756134] ACPI: Preparing to enter system sleep state S3 [51723.116394] Disabling non-boot CPUs ... [51723.220044] CPU 1 is now offline [51723.220050] SMP alternatives: switching to UP code [51723.234466] CPU0 attaching NULL sched-domain. [51723.234472] CPU1 attaching NULL sched-domain. [51723.234484] CPU0 attaching NULL sched-domain. [51723.234835] CPU1 is down [51723.234908] Extended CMOS year: 2000 [51723.234908] Back to C! [51723.234908] Extended CMOS year: 2000 [51723.234908] Enabling non-boot CPUs ... [51723.234908] SMP alternatives: switching to SMP code [51723.243276] Booting processor 1 APIC 0x1 ip 0x6000 [51723.234254] Initializing CPU#1 [51723.234254] Calibrating delay using timer specific routine.. 2394.00 BogoMIPS (lpj=4788004) [51723.234254] CPU: L1 I cache: 32K, L1 D cache: 32K [51723.234254] CPU: L2 cache: 4096K [51723.234254] CPU: Physical Processor ID: 0 [51723.234254] CPU: Processor Core ID: 1 [51723.333007] CPU1: Intel(R) Core(TM)2 Duo CPU L7100 @ 1.20GHz stepping 0b [51723.333087] CPU0 attaching NULL sched-domain. [51723.337030] Switched to high resolution mode on CPU 1 [51723.348033] CPU0 attaching sched-domain: [51723.348038] domain 0: span 0-1 level MC [51723.348042] groups: 0 1 [51723.348049] CPU1 attaching sched-domain: [51723.348052] domain 0: span 0-1 level MC [51723.348055] groups: 1 0 [51723.349054] CPU1 is up [51723.349059] ACPI: Waking up from system sleep state S3 [51724.132305] i915 0000:00:02.0: restoring config space at offset 0x1 (was 0x900007, writing 0x900407) [51724.132371] pci 0000:00:02.1: restoring config space at offset 0x1 (was 0x900000, writing 0x900007) [51724.132489] pci 0000:00:19.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100103) [51724.132608] uhci_hcd 0000:00:1a.0: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [51724.300125] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [51724.300206] uhci_hcd 0000:00:1a.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [51724.300322] ehci_hcd 0000:00:1a.7: restoring config space at offset 0x1 (was 0x2900106, writing 0x2900102) [51724.300366] ehci_hcd 0000:00:1a.7: PME# disabled [51724.300487] HDA Intel 0000:00:1b.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100102) [51724.300650] pcieport-driver 0000:00:1c.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [51724.300865] pcieport-driver 0000:00:1c.1: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [51724.301078] pcieport-driver 0000:00:1c.2: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [51724.332144] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [51724.332224] uhci_hcd 0000:00:1d.0: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [51724.332321] uhci_hcd 0000:00:1d.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [51724.364140] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [51724.364220] uhci_hcd 0000:00:1d.2: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [51724.364344] ehci_hcd 0000:00:1d.7: restoring config space at offset 0x1 (was 0x2900106, writing 0x2900102) [51724.364397] ehci_hcd 0000:00:1d.7: PME# disabled [51724.364714] ata_piix 0000:00:1f.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2880005) [51724.364845] ahci 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00007, writing 0x2b00407) [51724.365277] iwlagn 0000:03:00.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100506) [51724.446051] i915 0000:00:02.0: setting latency timer to 64 [51724.507450] [drm] LVDS-8: set mode 1440x900 c [51724.527568] pci 0000:00:02.1: PME# disabled [51724.527578] pci 0000:00:19.0: wake-up capability disabled by ACPI [51724.527592] pci 0000:00:19.0: PME# disabled [51724.527612] uhci_hcd 0000:00:1a.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20 [51724.527631] uhci_hcd 0000:00:1a.0: setting latency timer to 64 [51724.527686] usb usb3: root hub lost power or was reset [51724.527728] uhci_hcd 0000:00:1a.1: PCI INT B -> GSI 21 (level, low) -> IRQ 21 [51724.527742] uhci_hcd 0000:00:1a.1: setting latency timer to 64 [51724.527785] usb usb4: root hub lost power or was reset [51724.527823] ehci_hcd 0000:00:1a.7: PME# disabled [51724.527833] ehci_hcd 0000:00:1a.7: PCI INT C -> GSI 22 (level, low) -> IRQ 22 [51724.527846] ehci_hcd 0000:00:1a.7: setting latency timer to 64 [51724.527874] HDA Intel 0000:00:1b.0: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [51724.527892] HDA Intel 0000:00:1b.0: setting latency timer to 64 [51724.527927] pciehp 0000:00:1c.0:pcie04: pciehp_resume ENTRY [51724.527936] pciehp 0000:00:1c.1:pcie04: pciehp_resume ENTRY [51724.527944] pciehp 0000:00:1c.2:pcie04: pciehp_resume ENTRY [51724.527958] uhci_hcd 0000:00:1d.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [51724.527977] uhci_hcd 0000:00:1d.0: setting latency timer to 64 [51724.528027] usb usb5: root hub lost power or was reset [51724.528062] uhci_hcd 0000:00:1d.1: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [51724.528080] uhci_hcd 0000:00:1d.1: setting latency timer to 64 [51724.528129] usb usb6: root hub lost power or was reset [51724.528162] uhci_hcd 0000:00:1d.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [51724.528181] uhci_hcd 0000:00:1d.2: setting latency timer to 64 [51724.528232] usb usb7: root hub lost power or was reset [51724.528268] ehci_hcd 0000:00:1d.7: PME# disabled [51724.528279] ehci_hcd 0000:00:1d.7: PCI INT D -> GSI 19 (level, low) -> IRQ 19 [51724.528297] ehci_hcd 0000:00:1d.7: setting latency timer to 64 [51724.528331] pci 0000:00:1e.0: setting latency timer to 64 [51724.528353] ata_piix 0000:00:1f.1: PCI INT C -> GSI 16 (level, low) -> IRQ 16 [51724.528363] ata_piix 0000:00:1f.1: setting latency timer to 64 [51724.528408] ahci 0000:00:1f.2: setting latency timer to 64 [51724.529435] ata5: port disabled. ignoring. [51724.693225] ata4.00: ACPI cmd ef/03:42:00:00:00:a0 filtered out [51724.693230] ata4.00: ACPI cmd ef/03:0c:00:00:00:a0 filtered out [51724.694686] ata4.00: ACPI cmd e3/00:10:00:00:00:a0 succeeded [51724.696109] ata4.00: ACPI cmd e3/00:03:00:00:00:a0 succeeded [51724.721138] ata4.00: configured for UDMA/33 [51724.736096] thinkpad_acpi: fan watchdog: enabling fan [51724.848116] ata3: SATA link down (SStatus 0 SControl 300) [51725.016097] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 300) [51725.016604] ata1.00: ACPI cmd ef/02:00:00:00:00:a0 succeeded [51725.016609] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 filtered out [51725.016613] ata1.00: ACPI cmd ef/10:03:00:00:00:a0 filtered out [51725.017412] ata1.00: ACPI cmd ef/02:00:00:00:00:a0 succeeded [51725.017417] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 filtered out [51725.017421] ata1.00: ACPI cmd ef/10:03:00:00:00:a0 filtered out [51725.017673] ata1.00: configured for UDMA/100 [51725.032105] ata1: exception Emask 0x10 SAct 0x0 SErr 0x0 action 0x9 t4 [51725.032108] ata1: irq_stat 0x00400040, connection status changed [51725.032715] ata1.00: configured for UDMA/100 [51725.032719] ata1: EH complete [51725.406689] sd 0:0:0:0: [sda] Starting disk [51725.516102] usb 2-5: reset high speed USB device using ehci_hcd and address 2 [51725.764098] usb 3-2: reset full speed USB device using uhci_hcd and address 2 [51726.024086] usb 4-1: reset full speed USB device using uhci_hcd and address 2 [51726.177238] PM: resume devices took 1.812 seconds [51726.177273] PM: Finishing wakeup. [51726.177275] Restarting tasks ... done. [51726.952499] Registered led device: iwl-phy0::radio [51726.952531] Registered led device: iwl-phy0::assoc [51726.952559] Registered led device: iwl-phy0::RX [51726.952588] Registered led device: iwl-phy0::TX [51727.098180] ADDRCONF(NETDEV_UP): wlan0: link is not ready [51727.239401] input: Virtual ThinkFinger Keyboard as /devices/virtual/input/input43 [51727.831147] input: DualPoint Stick as /devices/platform/i8042/serio1/input/input44 [51727.863301] input: AlpsPS/2 ALPS DualPoint TouchPad as /devices/platform/i8042/serio1/input/input45 [51732.161073] wlan0: authenticate with AP 00:18:84:20:e3:fd [51732.172192] wlan0: authenticated [51732.172198] wlan0: associate with AP 00:18:84:20:e3:fd [51732.372114] wlan0: associate with AP 00:18:84:20:e3:fd [51732.572387] wlan0: associate with AP 00:18:84:20:e3:fd [51732.772071] wlan0: association with AP 00:18:84:20:e3:fd timed out [51735.133304] wlan0: direct probe to AP 00:18:84:20:e3:fd try 1 [51735.336046] wlan0: direct probe to AP 00:18:84:20:e3:fd try 2 [51735.536079] wlan0: direct probe to AP 00:18:84:20:e3:fd try 3 [51735.740035] wlan0: direct probe to AP 00:18:84:20:e3:fd timed out [51749.695368] wlan0: direct probe to AP 00:18:84:20:e3:fd try 1 [51749.892076] wlan0: direct probe to AP 00:18:84:20:e3:fd try 2 [51750.096052] wlan0: direct probe to AP 00:18:84:20:e3:fd try 3 [51750.292074] wlan0: direct probe to AP 00:18:84:20:e3:fd timed out [51781.492435] wlan0: authenticate with AP 00:1d:7e:0c:fc:18 [51781.494329] wlan0: authenticated [51781.494334] wlan0: associate with AP 00:1d:7e:0c:fc:18 [51781.496788] wlan0: RX AssocResp from 00:1d:7e:0c:fc:18 (capab=0x411 status=0 aid=1) [51781.496793] wlan0: associated [51781.516360] ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready [51791.552053] wlan0: no IPv6 routers present