2007/10/14, Till Kamppeter <email address hidden>:
> Looks like a problem caused by the AppArmor protection of CUPS. Can you
> attach your /var/log/syslog and also the output of the "dmesg" command?
> To deactivate the protection you can run "sudo aa-complain cupsd". Are
> you able to print then? To reactivate the protection, run "sudo aa-
> enforce cupsd".

Till, seems that you were right.

Today I updated Gutsy with the latest patches, without changing
anything in the AppArmor settings. Tried to print, but it failed
again, same error. I attach syslog and dmesg outputs as generated
after unsuccesful print attempts.

Then I followed Till's suggestion and set AppArmor to compain mode:
print jobs completed successfully afterwards.

I then reactivated the protection with aa-enforce, tried to print
again expecting the print job to fail, but they worked instead!

Hope you now have enough info to understand what happened and possibly
fix the issue.

-- 
Pietro Piutti
---------------------------------------------------------------------
CCNA - MCP - MCSA
Network Technician, Systems Engineering Group
United Nations International Computing Centre
United Nations Logistics Base, Brindisi, Italy
---------------------------------------------------------------------
http://www.linkedin.com/in/pietropiutti


[    0.000000] Linux version 2.6.22-14-generic (buildd@vernadsky) (gcc version 4.1.3 20070929 (prerelease) (Ubuntu 4.1.2-16ubuntu2)) #1 SMP Wed Oct 10 06:00:47 GMT 2007 (Ubuntu 2.6.22-14.43-generic)
[    0.000000] BIOS-provided physical RAM map:
[    0.000000]  BIOS-e820: 0000000000000000 - 000000000009f800 (usable)
[    0.000000]  BIOS-e820: 000000000009f800 - 00000000000a0000 (reserved)
[    0.000000]  BIOS-e820: 00000000000f0000 - 0000000000100000 (reserved)
[    0.000000]  BIOS-e820: 0000000000100000 - 000000003fff0000 (usable)
[    0.000000]  BIOS-e820: 000000003fff0000 - 000000003fff3000 (ACPI NVS)
[    0.000000]  BIOS-e820: 000000003fff3000 - 0000000040000000 (ACPI data)
[    0.000000]  BIOS-e820: 00000000fec00000 - 0000000100000000 (reserved)
[    0.000000] 127MB HIGHMEM available.
[    0.000000] 896MB LOWMEM available.
[    0.000000] found SMP MP-table at 000f5b70
[    0.000000] Entering add_active_range(0, 0, 262128) 0 entries of 256 used
[    0.000000] Zone PFN ranges:
[    0.000000]   DMA             0 ->     4096
[    0.000000]   Normal       4096 ->   229376
[    0.000000]   HighMem    229376 ->   262128
[    0.000000] early_node_map[1] active PFN ranges
[    0.000000]     0:        0 ->   262128
[    0.000000] On node 0 totalpages: 262128
[    0.000000]   DMA zone: 32 pages used for memmap
[    0.000000]   DMA zone: 0 pages reserved
[    0.000000]   DMA zone: 4064 pages, LIFO batch:0
[    0.000000]   Normal zone: 1760 pages used for memmap
[    0.000000]   Normal zone: 223520 pages, LIFO batch:31
[    0.000000]   HighMem zone: 255 pages used for memmap
[    0.000000]   HighMem zone: 32497 pages, LIFO batch:7
[    0.000000] DMI 2.2 present.
[    0.000000] ACPI: RSDP signature @ 0xC00F7700 checksum 0
[    0.000000] ACPI: RSDP 000F7700, 0014 (r0 IntelR)
[    0.000000] ACPI: RSDT 3FFF3000, 002C (r1 IntelR AWRDACPI 42302E31 AWRD        0)
[    0.000000] ACPI: FACP 3FFF3040, 0074 (r1 IntelR AWRDACPI 42302E31 AWRD        0)
[    0.000000] ACPI: DSDT 3FFF30C0, 41AF (r1 INTELR AWRDACPI     1000 MSFT  100000E)
[    0.000000] ACPI: FACS 3FFF0000, 0040
[    0.000000] ACPI: APIC 3FFF7280, 0068 (r1 IntelR AWRDACPI 42302E31 AWRD        0)
[    0.000000] ACPI: PM-Timer IO Port: 0x408
[    0.000000] ACPI: Local APIC address 0xfee00000
[    0.000000] ACPI: LAPIC (acpi_id[0x00] lapic_id[0x00] enabled)
[    0.000000] Processor #0 15:4 APIC version 20
[    0.000000] ACPI: LAPIC (acpi_id[0x01] lapic_id[0x01] enabled)
[    0.000000] Processor #1 15:4 APIC version 20
[    0.000000] ACPI: LAPIC_NMI (acpi_id[0x00] high edge lint[0x1])
[    0.000000] ACPI: LAPIC_NMI (acpi_id[0x01] high edge lint[0x1])
[    0.000000] ACPI: IOAPIC (id[0x02] address[0xfec00000] gsi_base[0])
[    0.000000] IOAPIC[0]: apic_id 2, version 32, address 0xfec00000, GSI 0-23
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
[    0.000000] ACPI: IRQ0 used by override.
[    0.000000] ACPI: IRQ2 used by override.
[    0.000000] ACPI: IRQ9 used by override.
[    0.000000] Enabling APIC mode:  Flat.  Using 1 I/O APICs
[    0.000000] Using ACPI (MADT) for SMP configuration information
[    0.000000] Allocating PCI resources starting at 50000000 (gap: 40000000:bec00000)
[    0.000000] Built 1 zonelists.  Total pages: 260081
[    0.000000] Kernel command line: root=UUID=eeed906e-0b88-46cd-b3be-62997b42428b ro quiet splash
[    0.000000] mapped APIC to ffffd000 (fee00000)
[    0.000000] mapped IOAPIC to ffffc000 (fec00000)
[    0.000000] Enabling fast FPU save and restore... done.
[    0.000000] Enabling unmasked SIMD FPU exception support... done.
[    0.000000] Initializing CPU#0
[    0.000000] PID hash table entries: 4096 (order: 12, 16384 bytes)
[    0.000000] Detected 3013.161 MHz processor.
[   25.193568] Console: colour VGA+ 80x25
[   25.194019] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes)
[   25.194428] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes)
[   25.217001] Memory: 1027752k/1048512k available (2015k kernel code, 20028k reserved, 916k data, 364k init, 131008k highmem)
[   25.217012] virtual kernel memory layout:
[   25.217013]     fixmap  : 0xfff4d000 - 0xfffff000   ( 712 kB)
[   25.217014]     pkmap   : 0xff800000 - 0xffc00000   (4096 kB)
[   25.217016]     vmalloc : 0xf8800000 - 0xff7fe000   ( 111 MB)
[   25.217017]     lowmem  : 0xc0000000 - 0xf8000000   ( 896 MB)
[   25.217018]       .init : 0xc03e3000 - 0xc043e000   ( 364 kB)
[   25.217019]       .data : 0xc02f7d26 - 0xc03dce84   ( 916 kB)
[   25.217020]       .text : 0xc0100000 - 0xc02f7d26   (2015 kB)
[   25.217023] Checking if this processor honours the WP bit even in supervisor mode... Ok.
[   25.217071] SLUB: Genslabs=22, HWalign=64, Order=0-1, MinObjects=4, CPUs=2, Nodes=1
[   25.296893] Calibrating delay using timer specific routine.. 6030.19 BogoMIPS (lpj=12060398)
[   25.296924] Security Framework v1.0.0 initialized
[   25.296931] SELinux:  Disabled at boot.
[   25.296943] Mount-cache hash table entries: 512
[   25.297092] CPU: After generic identify, caps: bfebfbff 00000000 00000000 00000000 0000441d 00000000 00000000
[   25.297101] monitor/mwait feature present.
[   25.297103] using mwait in idle threads.
[   25.297110] CPU: Trace cache: 12K uops, L1 D cache: 16K
[   25.297113] CPU: L2 cache: 1024K
[   25.297116] CPU: Physical Processor ID: 0
[   25.297118] CPU: After all inits, caps: bfebfbff 00000000 00000000 0000b180 0000441d 00000000 00000000
[   25.297129] Compat vDSO mapped to ffffe000.
[   25.297146] Checking 'hlt' instruction... OK.
[   25.312980] SMP alternatives: switching to UP code
[   25.313519] Early unpacking initramfs... done
[   25.595033] ACPI: Core revision 20070126
[   25.595086] ACPI: Looking for DSDT in initramfs... error, file /DSDT.aml not found.
[   25.610520] CPU0: Intel(R) Pentium(R) 4 CPU 3.00GHz stepping 01
[   25.610538] SMP alternatives: switching to SMP code
[   25.610680] Booting processor 1/1 eip 3000
[   25.620796] Initializing CPU#1
[   25.699862] Calibrating delay using timer specific routine.. 6025.98 BogoMIPS (lpj=12051977)
[   25.699873] CPU: After generic identify, caps: bfebfbff 00000000 00000000 00000000 0000441d 00000000 00000000
[   25.699879] monitor/mwait feature present.
[   25.699886] CPU: Trace cache: 12K uops, L1 D cache: 16K
[   25.699889] CPU: L2 cache: 1024K
[   25.699892] CPU: Physical Processor ID: 0
[   25.699894] CPU: After all inits, caps: bfebfbff 00000000 00000000 0000b180 0000441d 00000000 00000000
[   25.700270] CPU1: Intel(R) Pentium(R) 4 CPU 3.00GHz stepping 01
[   25.700312] Total of 2 processors activated (12056.18 BogoMIPS).
[   25.700441] ENABLING IO-APIC IRQs
[   25.700621] ..TIMER: vector=0x31 apic1=0 pin1=2 apic2=-1 pin2=-1
[   25.847602] checking TSC synchronization [CPU#0 -> CPU#1]: passed.
[   25.867567] Brought up 2 CPUs
[   25.982072] migration_cost=206
[   25.982253] Booting paravirtualized kernel on bare hardware
[   25.982323] Time: 15:22:01  Date: 09/15/107
[   25.982349] NET: Registered protocol family 16
[   25.982446] EISA bus registered
[   25.982452] ACPI: bus type pci registered
[   26.009395] PCI: PCI BIOS revision 2.10 entry at 0xfba40, last bus=2
[   26.009398] PCI: Using configuration type 1
[   26.009400] Setting up standard PCI resources
[   26.011466] ACPI: EC: Look up EC in DSDT
[   26.015169] ACPI: Interpreter enabled
[   26.015172] ACPI: (supports S0 S1 S4 S5)
[   26.015196] ACPI: Using IOAPIC for interrupt routing
[   26.020331] ACPI: PCI Root Bridge [PCI0] (0000:00)
[   26.020339] PCI: Probing PCI hardware (bus 00)
[   26.020800] PCI quirk: region 0400-047f claimed by ICH4 ACPI/GPIO/TCO
[   26.020804] PCI quirk: region 0480-04bf claimed by ICH4 GPIO
[   26.021178] PCI: Transparent bridge - 0000:00:1e.0
[   26.021206] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0._PRT]
[   26.021333] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.HUB0._PRT]
[   26.027289] ACPI: PCI Interrupt Link [LNKA] (IRQs 3 4 5 7 9 *10 11 12 14 15)
[   26.027397] ACPI: PCI Interrupt Link [LNKB] (IRQs 3 4 *5 7 9 10 11 12 14 15)
[   26.027502] ACPI: PCI Interrupt Link [LNKC] (IRQs 3 4 5 7 9 10 *11 12 14 15)
[   26.027608] ACPI: PCI Interrupt Link [LNKD] (IRQs 3 4 5 7 *9 10 11 12 14 15)
[   26.027713] ACPI: PCI Interrupt Link [LNKE] (IRQs 3 4 5 7 9 10 11 12 14 15) *0, disabled.
[   26.027819] ACPI: PCI Interrupt Link [LNKF] (IRQs 3 4 5 7 9 10 11 12 14 15) *0, disabled.
[   26.027925] ACPI: PCI Interrupt Link [LNK0] (IRQs 3 4 5 7 9 10 11 12 14 15) *0, disabled.
[   26.028031] ACPI: PCI Interrupt Link [LNK1] (IRQs *3 4 5 7 9 10 11 12 14 15)
[   26.028142] Linux Plug and Play Support v0.97 (c) Adam Belay
[   26.028157] pnp: PnP ACPI init
[   26.028166] ACPI: bus type pnp registered
[   26.030957] pnp: PnP ACPI: found 14 devices
[   26.030959] ACPI: ACPI bus type pnp unregistered
[   26.030964] PnPBIOS: Disabled by ACPI PNP
[   26.031025] PCI: Using ACPI for IRQ routing
[   26.031028] PCI: If a device doesn't work, try "pci=routeirq".  If it helps, post a report
[   26.043739] NET: Registered protocol family 8
[   26.043741] NET: Registered protocol family 20
[   26.043813] pnp: 00:0b: ioport range 0x400-0x4bf could not be reserved
[   26.043820] pnp: 00:0d: iomem range 0xcec00-0xcffff has been reserved
[   26.043824] pnp: 00:0d: iomem range 0xf0000-0xf7fff could not be reserved
[   26.043826] pnp: 00:0d: iomem range 0xf8000-0xfbfff could not be reserved
[   26.043829] pnp: 00:0d: iomem range 0xfc000-0xfffff could not be reserved
[   26.047009] Time: tsc clocksource has been installed.
[   26.074125] PCI: Bridge: 0000:00:01.0
[   26.074128]   IO window: disabled.
[   26.074133]   MEM window: f8000000-faffffff
[   26.074137]   PREFETCH window: e0000000-efffffff
[   26.074142] PCI: Bridge: 0000:00:1e.0
[   26.074145]   IO window: a000-afff
[   26.074150]   MEM window: fb000000-fb0fffff
[   26.074154]   PREFETCH window: disabled.
[   26.074171] PCI: Setting latency timer of device 0000:00:1e.0 to 64
[   26.074182] NET: Registered protocol family 2
[   26.110979] IP route cache hash table entries: 32768 (order: 5, 131072 bytes)
[   26.111087] TCP established hash table entries: 131072 (order: 8, 1572864 bytes)
[   26.112049] TCP bind hash table entries: 65536 (order: 7, 524288 bytes)
[   26.112438] TCP: Hash tables configured (established 131072 bind 65536)
[   26.112441] TCP reno registered
[   26.123098] checking if image is initramfs... it is
[   26.573675] Switched to high resolution mode on CPU 0
[   26.573764] Switched to high resolution mode on CPU 1
[   26.680895] Freeing initrd memory: 7120k freed
[   26.681522] audit: initializing netlink socket (disabled)
[   26.681538] audit(1192461721.204:1): initialized
[   26.681637] highmem bounce pool size: 64 pages
[   26.683947] VFS: Disk quotas dquot_6.5.1
[   26.684011] Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[   26.684117] io scheduler noop registered
[   26.684120] io scheduler anticipatory registered
[   26.684122] io scheduler deadline registered
[   26.684137] io scheduler cfq registered (default)
[   26.684226] Boot video device is 0000:01:00.0
[   26.684399] isapnp: Scanning for PnP cards...
[   27.035176] isapnp: No Plug & Play device found
[   27.061870] Real Time Clock Driver v1.12ac
[   27.061974] Serial: 8250/16550 driver $Revision: 1.90 $ 4 ports, IRQ sharing enabled
[   27.062059] serial8250: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A
[   27.062753] 00:07: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A
[   27.063577] RAMDISK driver initialized: 16 RAM disks of 65536K size 1024 blocksize
[   27.063788] input: Macintosh mouse button emulation as /class/input/input0
[   27.063883] PNP: PS/2 Controller [PNP0303:PS2K,PNP0f13:PS2M] at 0x60,0x64 irq 1,12
[   27.066316] serio: i8042 KBD port at 0x60,0x64 irq 1
[   27.066324] serio: i8042 AUX port at 0x60,0x64 irq 12
[   27.066532] mice: PS/2 mouse device common for all mice
[   27.066662] EISA: Probing bus 0 at eisa.0
[   27.066701] EISA: Detected 0 cards.
[   27.066791] TCP cubic registered
[   27.066804] NET: Registered protocol family 1
[   27.066826] Using IPI No-Shortcut mode
[   27.066969]   Magic number: 11:564:386
[   27.067434] Freeing unused kernel memory: 364k freed
[   27.083059] input: AT Translated Set 2 keyboard as /class/input/input1
[   28.284428] AppArmor: AppArmor initialized<5>audit(1192461722.704:2):  type=1505 info="AppArmor initialized" pid=1201
[   28.293172] fuse init (API version 7.8)
[   28.299450] Failure registering capabilities with primary security module.
[   28.305624] ACPI: Fan [FAN] (on)
[   28.509158] ACPI: Thermal Zone [THRM] (36 C)
[   29.017947] usbcore: registered new interface driver usbfs
[   29.017982] usbcore: registered new interface driver hub
[   29.018015] usbcore: registered new device driver usb
[   29.019248] USB Universal Host Controller Interface driver v3.0
[   29.019342] ACPI: PCI Interrupt 0000:00:1d.0[A] -> GSI 16 (level, low) -> IRQ 16
[   29.019359] PCI: Setting latency timer of device 0000:00:1d.0 to 64
[   29.019365] uhci_hcd 0000:00:1d.0: UHCI Host Controller
[   29.019578] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 1
[   29.019611] uhci_hcd 0000:00:1d.0: irq 16, io base 0x0000bc00
[   29.019795] usb usb1: configuration #1 chosen from 1 choice
[   29.019844] hub 1-0:1.0: USB hub found
[   29.019855] hub 1-0:1.0: 2 ports detected
[   29.123376] ACPI: PCI Interrupt 0000:00:1d.1[B] -> GSI 19 (level, low) -> IRQ 17
[   29.123395] PCI: Setting latency timer of device 0000:00:1d.1 to 64
[   29.123402] uhci_hcd 0000:00:1d.1: UHCI Host Controller
[   29.123444] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 2
[   29.123475] uhci_hcd 0000:00:1d.1: irq 17, io base 0x0000b000
[   29.123626] usb usb2: configuration #1 chosen from 1 choice
[   29.123672] hub 2-0:1.0: USB hub found
[   29.123682] hub 2-0:1.0: 2 ports detected
[   29.206242] 8139too Fast Ethernet driver 0.9.28
[   29.212175] Floppy drive(s): fd0 is 1.44M
[   29.227098] ACPI: PCI Interrupt 0000:00:1d.7[D] -> GSI 23 (level, low) -> IRQ 18
[   29.227121] PCI: Setting latency timer of device 0000:00:1d.7 to 64
[   29.227127] ehci_hcd 0000:00:1d.7: EHCI Host Controller
[   29.227168] ehci_hcd 0000:00:1d.7: new USB bus registered, assigned bus number 3
[   29.227212] ehci_hcd 0000:00:1d.7: debug port 1
[   29.227220] PCI: cache line size of 128 is not supported by device 0000:00:1d.7
[   29.227233] ehci_hcd 0000:00:1d.7: irq 18, io mem 0xfb100000
[   29.231121] ehci_hcd 0000:00:1d.7: USB 2.0 started, EHCI 1.00, driver 10 Dec 2004
[   29.231273] usb usb3: configuration #1 chosen from 1 choice
[   29.231316] hub 3-0:1.0: USB hub found
[   29.231327] hub 3-0:1.0: 8 ports detected
[   29.241205] FDC 0 is a post-1991 82077
[   29.245966] SCSI subsystem initialized
[   29.254244] libata version 2.21 loaded.
[   29.335167] ACPI: PCI Interrupt 0000:02:02.0[A] -> GSI 18 (level, low) -> IRQ 19
[   29.335537] eth0: RealTek RTL8139 at 0xf8838000, 00:50:8d:6c:4d:c0, IRQ 19
[   29.335542] eth0:  Identified 8139 chip type 'RTL-8100B/8139D'
[   29.336673] ACPI: PCI Interrupt 0000:00:1d.2[C] -> GSI 18 (level, low) -> IRQ 19
[   29.336689] PCI: Setting latency timer of device 0000:00:1d.2 to 64
[   29.336694] uhci_hcd 0000:00:1d.2: UHCI Host Controller
[   29.336930] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 4
[   29.336965] uhci_hcd 0000:00:1d.2: irq 19, io base 0x0000b400
[   29.337544] usb usb4: configuration #1 chosen from 1 choice
[   29.337795] hub 4-0:1.0: USB hub found
[   29.337806] hub 4-0:1.0: 2 ports detected
[   29.339236] 8139cp: 10/100 PCI Ethernet driver v1.3 (Mar 22, 2004)
[   29.438518] ACPI: PCI Interrupt 0000:00:1d.3[A] -> GSI 16 (level, low) -> IRQ 16
[   29.438536] PCI: Setting latency timer of device 0000:00:1d.3 to 64
[   29.438542] uhci_hcd 0000:00:1d.3: UHCI Host Controller
[   29.438577] uhci_hcd 0000:00:1d.3: new USB bus registered, assigned bus number 5
[   29.438604] uhci_hcd 0000:00:1d.3: irq 16, io base 0x0000b800
[   29.438754] usb usb5: configuration #1 chosen from 1 choice
[   29.438798] hub 5-0:1.0: USB hub found
[   29.438809] hub 5-0:1.0: 2 ports detected
[   29.546752] ata_piix 0000:00:1f.2: version 2.11
[   29.546762] ata_piix 0000:00:1f.2: MAP [ IDE IDE P0 P1 ]
[   29.546788] ACPI: PCI Interrupt 0000:00:1f.2[A] -> GSI 18 (level, low) -> IRQ 19
[   29.546846] PCI: Setting latency timer of device 0000:00:1f.2 to 64
[   29.546962] scsi0 : ata_piix
[   29.547036] scsi1 : ata_piix
[   29.547232] ata1: PATA max UDMA/100 cmd 0x000101f0 ctl 0x000103f6 bmdma 0x0001f000 irq 14
[   29.547239] ata2: SATA max UDMA/133 cmd 0x00010170 ctl 0x00010376 bmdma 0x0001f008 irq 15
[   29.574089] usb 3-6: new high speed USB device using ehci_hcd and address 2
[   29.708195] usb 3-6: configuration #1 chosen from 1 choice
[   29.718228] ata1.00: ATA-5: QUANTUM FIREBALLlct20 20, APL.0900, max UDMA/100
[   29.718235] ata1.00: 39876480 sectors, multi 8: LBA 
[   29.764924] ata1.01: ATA-7: ST3320620A, 3.AAD, max UDMA/100
[   29.764928] ata1.01: 625142448 sectors, multi 16: LBA48 
[   29.764936] ata1.00: limited to UDMA/33 due to 40-wire cable
[   29.778052] ata1.00: configured for UDMA/33
[   29.833451] usbcore: registered new interface driver libusual
[   29.839576] ata1.01: configured for UDMA/100
[   29.841842] Uniform Multi-Platform E-IDE driver Revision: 7.00alpha2
[   29.841849] ide: Assuming 33MHz system bus speed for PIO modes; override with idebus=xx
[   29.844353] Initializing USB Mass Storage driver...
[   29.844465] scsi2 : SCSI emulation for USB Mass Storage devices
[   29.844537] usb-storage: device found at 2
[   29.844541] usb-storage: waiting for device to settle before scanning
[   29.844563] usbcore: registered new interface driver usb-storage
[   29.844568] USB Mass Storage support registered.
[   30.312347] ata2.00: ATAPI: TSSTcorp CDDVDW SH-S203N, SB01, max UDMA/100
[   30.483911] ata2.00: configured for UDMA/100
[   30.484056] scsi 0:0:0:0: Direct-Access     ATA      QUANTUM FIREBALL APL. PQ: 0 ANSI: 5
[   30.484220] scsi 0:0:1:0: Direct-Access     ATA      ST3320620A       3.AA PQ: 0 ANSI: 5
[   30.485213] scsi 1:0:0:0: CD-ROM            TSSTcorp CDDVDW SH-S203N  SB01 PQ: 0 ANSI: 5
[   30.499518] sd 0:0:0:0: [sda] 39876480 512-byte hardware sectors (20417 MB)
[   30.499542] sd 0:0:0:0: [sda] Write Protect is off
[   30.499547] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
[   30.499576] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[   30.499651] sd 0:0:0:0: [sda] 39876480 512-byte hardware sectors (20417 MB)
[   30.499681] sd 0:0:0:0: [sda] Write Protect is off
[   30.499685] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
[   30.499714] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[   30.499720]  sda:sr0: scsi3-mmc drive: 48x/48x writer dvd-ram cd/rw xa/form2 cdda tray
[   30.504922] Uniform CD-ROM driver Revision: 3.20
[   30.504986] sr 1:0:0:0: Attached scsi CD-ROM sr0
[   30.507382]  sda1 sda2 sda3
[   30.509215] sd 0:0:0:0: Attached scsi generic sg0 type 0
[   30.509246] scsi 0:0:1:0: Attached scsi generic sg1 type 0
[   30.509274] sr 1:0:0:0: Attached scsi generic sg2 type 5
[   30.527974] sd 0:0:0:0: [sda] Attached SCSI disk
[   30.528069] sd 0:0:1:0: [sdb] 625142448 512-byte hardware sectors (320073 MB)
[   30.528088] sd 0:0:1:0: [sdb] Write Protect is off
[   30.528092] sd 0:0:1:0: [sdb] Mode Sense: 00 3a 00 00
[   30.528120] sd 0:0:1:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[   30.528210] sd 0:0:1:0: [sdb] 625142448 512-byte hardware sectors (320073 MB)
[   30.528228] sd 0:0:1:0: [sdb] Write Protect is off
[   30.528232] sd 0:0:1:0: [sdb] Mode Sense: 00 3a 00 00
[   30.528321] sd 0:0:1:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[   30.528326]  sdb: sdb1
[   30.551035] sd 0:0:1:0: [sdb] Attached SCSI disk
[   31.493421] Attempting manual resume
[   31.493426] swsusp: Resume From Partition 8:3
[   31.493428] PM: Checking swsusp image.
[   31.517167] PM: Resume from disk failed.
[   31.569636] kjournald starting.  Commit interval 5 seconds
[   31.569648] EXT3-fs: mounted filesystem with ordered data mode.
[   34.828882] usb-storage: device scan complete
[   34.829501] scsi 2:0:0:0: Direct-Access     Generic  USB Storage-CFC  I20A PQ: 0 ANSI: 0 CCS
[   34.830117] scsi 2:0:0:1: Direct-Access     Generic  USB Storage-SDC  I20A PQ: 0 ANSI: 0 CCS
[   34.830733] scsi 2:0:0:2: Direct-Access     Generic  USB Storage-SMC  I20A PQ: 0 ANSI: 0 CCS
[   34.831363] scsi 2:0:0:3: Direct-Access     Generic  USB Storage-MSC  I20A PQ: 0 ANSI: 0 CCS
[   34.832636] sd 2:0:0:0: [sdc] Attached SCSI removable disk
[   34.832690] sd 2:0:0:0: Attached scsi generic sg3 type 0
[   34.833623] sd 2:0:0:1: [sdd] Attached SCSI removable disk
[   34.833657] sd 2:0:0:1: Attached scsi generic sg4 type 0
[   34.834618] sd 2:0:0:2: [sde] Attached SCSI removable disk
[   34.834654] sd 2:0:0:2: Attached scsi generic sg5 type 0
[   34.835616] sd 2:0:0:3: [sdf] Attached SCSI removable disk
[   34.835654] sd 2:0:0:3: Attached scsi generic sg6 type 0
[   42.680206] eth0: link up, 100Mbps, full-duplex, lpa 0x45E1
[   44.487644] pci_hotplug: PCI Hot Plug PCI Core version: 0.5
[   44.558277] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4
[   44.640142] intel_rng: FWH not detected
[   44.702964] input: PC Speaker as /class/input/input2
[   44.874403] Linux agpgart interface v0.102 (c) Dave Jones
[   44.890901] agpgart: Detected an Intel 865 Chipset.
[   44.896986] agpgart: AGP aperture is 128M @ 0xf0000000
[   45.001300] logips2pp: Detected unknown logitech mouse model 127
[   45.030742] NET: Registered protocol family 10
[   45.030877] lo: Disabled Privacy Extensions
[   45.297133] iTCO_vendor_support: vendor-support=0
[   45.298817] iTCO_wdt: Intel TCO WatchDog Timer Driver v1.01 (21-Jan-2007)
[   45.469823] input: ImExPS/2 Logitech Explorer Mouse as /class/input/input3
[   45.472319] iTCO_wdt: Found a ICH5 or ICH5R TCO device (Version=1, TCOBASE=0x0460)
[   45.472404] iTCO_wdt: initialized. heartbeat=30 sec (nowayout=0)
[   45.472499] parport0: PC-style at 0x378, irq 7 [PCSPP,TRISTATE]
[   45.558979] nvidia: module license 'NVIDIA' taints kernel.
[   45.908623] ACPI: PCI Interrupt 0000:01:00.0[A] -> GSI 16 (level, low) -> IRQ 16
[   45.908848] NVRM: loading NVIDIA UNIX x86 Kernel Module  100.14.19  Wed Sep 12 14:12:24 PDT 2007
[   46.183580] ACPI: PCI Interrupt 0000:00:1f.5[B] -> GSI 17 (level, low) -> IRQ 20
[   46.183601] PCI: Setting latency timer of device 0000:00:1f.5 to 64
[   46.502990] intel8x0_measure_ac97_clock: measured 53766 usecs
[   46.502993] intel8x0: clocking to 48000
[   47.415449] lp0: using parport0 (interrupt-driven).
[   47.483839] Adding 2216960k swap on /dev/sda3.  Priority:-1 extents:1 across:2216960k
[   47.932606] EXT3 FS on sda2, internal journal
[   48.845597] kjournald starting.  Commit interval 5 seconds
[   48.845791] EXT3 FS on sda1, internal journal
[   48.845796] EXT3-fs: mounted filesystem with ordered data mode.
[   48.860094] kjournald starting.  Commit interval 5 seconds
[   48.865121] EXT3 FS on sdb1, internal journal
[   48.865125] EXT3-fs: mounted filesystem with ordered data mode.
[   49.971908] No dock devices found.
[   49.997470] input: Power Button (FF) as /class/input/input4
[   49.997498] ACPI: Power Button (FF) [PWRF]
[   49.997561] input: Power Button (CM) as /class/input/input5
[   49.997583] ACPI: Power Button (CM) [PWRB]
[   51.993010] ppdev: user-space parallel port driver
[   52.412246] audit(1192461747.706:3):  type=1503 operation="inode_permission" requested_mask="a" denied_mask="a" name="/dev/tty" pid=4892 profile="/usr/sbin/cupsd"
[   52.461120] apm: BIOS not found.
[   55.611824] eth0: no IPv6 routers present
[   55.832526] Failure registering capabilities with primary security module.
[   56.401023] Bluetooth: Core ver 2.11
[   56.401252] NET: Registered protocol family 31
[   56.401257] Bluetooth: HCI device and connection manager initialized
[   56.401261] Bluetooth: HCI socket layer initialized
[   56.457927] Bluetooth: L2CAP ver 2.8
[   56.457934] Bluetooth: L2CAP socket layer initialized
[   56.554219] Bluetooth: RFCOMM socket layer initialized
[   56.554608] Bluetooth: RFCOMM TTY layer initialized
[   56.554615] Bluetooth: RFCOMM ver 1.8
[   60.649082] agpgart: Found an AGP 3.0 compliant device at 0000:00:00.0.
[   60.649099] agpgart: Putting AGP V3 device at 0000:00:00.0 into 8x mode
[   60.649133] agpgart: Putting AGP V3 device at 0000:01:00.0 into 8x mode
[ 5016.550807] usb 5-1: new full speed USB device using uhci_hcd and address 2
[ 5016.670496] usb 5-1: device descriptor read/64, error -71
[ 5016.893931] usb 5-1: device descriptor read/64, error -71
[ 5017.109384] usb 5-1: new full speed USB device using uhci_hcd and address 3
[ 5017.229079] usb 5-1: device descriptor read/64, error -71
[ 5017.452510] usb 5-1: device descriptor read/64, error -71
[ 5018.090884] usb 5-1: new full speed USB device using uhci_hcd and address 5
[ 5018.280072] usb 5-1: configuration #1 chosen from 1 choice
[ 5018.348814] /build/buildd/linux-source-2.6.22-2.6.22/drivers/usb/class/usblp.c: usblp0: USB Bidirectional printer dev 5 if 0 alt 0 proto 2 vid 0x04E8 pid 0x3242
[ 5018.349032] usbcore: registered new interface driver usblp
[ 5018.349040] /build/buildd/linux-source-2.6.22-2.6.22/drivers/usb/class/usblp.c: v0.13: USB Printer Device Class driver
[ 8901.596036] audit(1192470619.711:4):  type=1503 operation="inode_permission" requested_mask="a" denied_mask="a" name="/dev/tty" pid=9195 profile="/usr/sbin/cupsd"
[ 9887.306574] audit(1192471607.768:5):  type=1503 operation="sysctl" requested_mask="r" denied_mask="r" name="/proc/sys/kernel/osrelease" pid=19083 profile="/usr/sbin/cupsd"
[ 9887.309212] audit(1192471607.768:6):  type=1503 operation="inode_permission" requested_mask="r" denied_mask="r" name="/proc/modules" pid=19085 profile="/usr/sbin/cupsd"
[ 9887.311113] audit(1192471607.768:7):  type=1503 operation="inode_permission" requested_mask="r" denied_mask="r" name="/proc/modules" pid=19087 profile="/usr/sbin/cupsd"
[ 9887.312981] audit(1192471607.768:8):  type=1503 operation="inode_permission" requested_mask="r" denied_mask="r" name="/proc/modules" pid=19089 profile="/usr/sbin/cupsd"
[ 9887.314809] audit(1192471607.768:9):  type=1503 operation="inode_permission" requested_mask="r" denied_mask="r" name="/proc/modules" pid=19091 profile="/usr/sbin/cupsd"
[ 9887.317252] audit(1192471607.768:10):  type=1503 operation="inode_permission" requested_mask="r" denied_mask="r" name="/etc/modprobe.conf" pid=19093 profile="/usr/sbin/cupsd"
[ 9887.317286] audit(1192471607.768:11):  type=1503 operation="inode_permission" requested_mask="r" denied_mask="r" name="/etc/modprobe.d/" pid=19093 profile="/usr/sbin/cupsd"
[ 9887.317399] audit(1192471607.768:12):  type=1503 operation="inode_permission" requested_mask="r" denied_mask="r" name="/etc/modprobe.d/" pid=19093 profile="/usr/sbin/cupsd"
[ 9887.317802] audit(1192471607.768:13):  type=1503 operation="inode_permission" requested_mask="r" denied_mask="r" name="/lib/modules/2.6.22-14-generic/modules.dep" pid=19093 profile="/usr/sbin/cupsd"
[ 9887.327917] audit(1192471607.768:14):  type=1503 operation="inode_permission" requested_mask="r" denied_mask="r" name="/etc/modprobe.conf" pid=19095 profile="/usr/sbin/cupsd"
[ 9887.328362] audit(1192471607.768:15):  type=1503 operation="inode_permission" requested_mask="r" denied_mask="r" name="/etc/modprobe.d/" pid=19095 profile="/usr/sbin/cupsd"
[ 9887.328765] audit(1192471607.768:16):  type=1503 operation="inode_permission" requested_mask="r" denied_mask="r" name="/etc/modprobe.d/" pid=19095 profile="/usr/sbin/cupsd"
[ 9887.329183] audit(1192471607.768:17):  type=1503 operation="inode_permission" requested_mask="r" denied_mask="r" name="/lib/modules/2.6.22-14-generic/modules.dep" pid=19095 profile="/usr/sbin/cupsd"
[ 9887.333630] audit(1192471607.768:18):  type=1503 operation="inode_permission" requested_mask="r" denied_mask="r" name="/etc/modprobe.conf" pid=19097 profile="/usr/sbin/cupsd"
[ 9887.333662] audit(1192471607.768:19):  type=1503 operation="inode_permission" requested_mask="r" denied_mask="r" name="/etc/modprobe.d/" pid=19097 profile="/usr/sbin/cupsd"
[ 9887.333684] audit(1192471607.768:20):  type=1503 operation="inode_permission" requested_mask="r" denied_mask="r" name="/etc/modprobe.d/" pid=19097 profile="/usr/sbin/cupsd"
[ 9887.333716] audit(1192471607.768:21):  type=1503 operation="inode_permission" requested_mask="r" denied_mask="r" name="/lib/modules/2.6.22-14-generic/modules.dep" pid=19097 profile="/usr/sbin/cupsd"
[ 9887.334116] audit(1192471607.768:22):  type=1503 operation="capable" name="sys_rawio" pid=19083 profile="/usr/sbin/cupsd"
[ 9887.497638] audit(1192471607.768:23):  type=1503 operation="inode_permission" requested_mask="rw" denied_mask="rw" name="/dev/tty" pid=19103 profile="/usr/sbin/cupsd"
[ 9887.529516] audit(1192471607.768:24):  type=1503 operation="inode_permission" requested_mask="rw" denied_mask="rw" name="/dev/tty" pid=19106 profile="/usr/sbin/cupsd"

Oct 15 17:32:26 joel syslogd 1.4.1#21ubuntu3: restart.
Oct 15 17:32:26 joel anacron[5112]: Job `cron.daily' terminated
Oct 15 17:32:26 joel anacron[5112]: Normal exit (1 job run)
Oct 15 17:42:24 joel smbd[5268]: [2007/10/15 17:42:24, 0] printing/pcap.c:pcap_cache_reload(159) 
Oct 15 17:42:24 joel smbd[5268]:   Unable to open printcap file /var/run/cups/printcap for read! 
Oct 15 17:42:24 joel smbd[5268]: [2007/10/15 17:42:24, 0] printing/pcap.c:pcap_cache_reload(159) 
Oct 15 17:42:24 joel smbd[5268]:   Unable to open printcap file /var/run/cups/printcap for read! 
Oct 15 17:43:58 joel smbd[5312]: [2007/10/15 17:43:58, 0] printing/pcap.c:pcap_cache_reload(159) 
Oct 15 17:43:58 joel smbd[5312]:   Unable to open printcap file /var/run/cups/printcap for read! 
Oct 15 17:43:58 joel smbd[5312]: [2007/10/15 17:43:58, 0] printing/pcap.c:pcap_cache_reload(159) 
Oct 15 17:43:58 joel smbd[5312]:   Unable to open printcap file /var/run/cups/printcap for read! 
Oct 15 17:55:24 joel smbd[5268]: [2007/10/15 17:55:24, 0] printing/pcap.c:pcap_cache_reload(159) 
Oct 15 17:55:24 joel smbd[5268]:   Unable to open printcap file /var/run/cups/printcap for read! 
Oct 15 17:55:24 joel smbd[5268]: [2007/10/15 17:55:24, 0] printing/pcap.c:pcap_cache_reload(159) 
Oct 15 17:55:24 joel smbd[5268]:   Unable to open printcap file /var/run/cups/printcap for read! 
Oct 15 17:56:52 joel smbd[5312]: [2007/10/15 17:56:52, 0] printing/pcap.c:pcap_cache_reload(159) 
Oct 15 17:56:52 joel smbd[5312]:   Unable to open printcap file /var/run/cups/printcap for read! 
Oct 15 17:56:52 joel smbd[5312]: [2007/10/15 17:56:52, 0] printing/pcap.c:pcap_cache_reload(159) 
Oct 15 17:56:52 joel smbd[5312]:   Unable to open printcap file /var/run/cups/printcap for read! 
Oct 15 18:07:58 joel smbd[5268]: [2007/10/15 18:07:58, 0] printing/pcap.c:pcap_cache_reload(159) 
Oct 15 18:07:58 joel smbd[5268]:   Unable to open printcap file /var/run/cups/printcap for read! 
Oct 15 18:07:58 joel smbd[5268]: [2007/10/15 18:07:58, 0] printing/pcap.c:pcap_cache_reload(159) 
Oct 15 18:07:58 joel smbd[5268]:   Unable to open printcap file /var/run/cups/printcap for read! 
Oct 15 18:09:52 joel smbd[5312]: [2007/10/15 18:09:52, 0] printing/pcap.c:pcap_cache_reload(159) 
Oct 15 18:09:52 joel smbd[5312]:   Unable to open printcap file /var/run/cups/printcap for read! 
Oct 15 18:09:52 joel smbd[5312]: [2007/10/15 18:09:52, 0] printing/pcap.c:pcap_cache_reload(159) 
Oct 15 18:09:52 joel smbd[5312]:   Unable to open printcap file /var/run/cups/printcap for read! 
Oct 15 18:17:01 joel /USR/SBIN/CRON[8563]: (root) CMD (   cd / && run-parts --report /etc/cron.hourly)
Oct 15 18:20:58 joel smbd[5268]: [2007/10/15 18:20:58, 0] printing/pcap.c:pcap_cache_reload(159) 
Oct 15 18:20:58 joel smbd[5268]:   Unable to open printcap file /var/run/cups/printcap for read! 
Oct 15 18:20:58 joel smbd[5268]: [2007/10/15 18:20:58, 0] printing/pcap.c:pcap_cache_reload(159) 
Oct 15 18:20:58 joel smbd[5268]:   Unable to open printcap file /var/run/cups/printcap for read! 
Oct 15 18:22:55 joel smbd[5312]: [2007/10/15 18:22:55, 0] printing/pcap.c:pcap_cache_reload(159) 
Oct 15 18:22:55 joel smbd[5312]:   Unable to open printcap file /var/run/cups/printcap for read! 
Oct 15 18:22:55 joel smbd[5312]: [2007/10/15 18:22:55, 0] printing/pcap.c:pcap_cache_reload(159) 
Oct 15 18:22:55 joel smbd[5312]:   Unable to open printcap file /var/run/cups/printcap for read! 
Oct 15 18:32:20 joel smbd[4987]: [2007/10/15 18:32:20, 0] printing/pcap.c:pcap_cache_reload(159) 
Oct 15 18:32:20 joel smbd[4987]:   Unable to open printcap file /var/run/cups/printcap for read! 
Oct 15 18:32:20 joel smbd[4987]: [2007/10/15 18:32:20, 0] printing/pcap.c:pcap_cache_reload(159) 
Oct 15 18:32:20 joel smbd[4987]:   Unable to open printcap file /var/run/cups/printcap for read! 
Oct 15 18:35:54 joel smbd[5312]: [2007/10/15 18:35:54, 0] printing/pcap.c:pcap_cache_reload(159) 
Oct 15 18:35:54 joel smbd[5312]:   Unable to open printcap file /var/run/cups/printcap for read! 
Oct 15 18:35:54 joel smbd[5312]: [2007/10/15 18:35:54, 0] printing/pcap.c:pcap_cache_reload(159) 
Oct 15 18:35:54 joel smbd[5312]:   Unable to open printcap file /var/run/cups/printcap for read! 
Oct 15 18:44:57 joel NetworkManager: <info>  Updating allowed wireless network lists. 
Oct 15 18:44:57 joel NetworkManager: <WARN>  nm_dbus_get_networks_cb(): error received: org.freedesktop.NetworkManagerInfo.NoNetworks - org.freedesktop.NetworkManagerInfo.NoNetworks. 
Oct 15 18:45:24 joel kernel: [ 5016.550807] usb 5-1: new full speed USB device using uhci_hcd and address 2
Oct 15 18:45:25 joel kernel: [ 5016.670496] usb 5-1: device descriptor read/64, error -71
Oct 15 18:45:25 joel kernel: [ 5016.893931] usb 5-1: device descriptor read/64, error -71
Oct 15 18:45:25 joel kernel: [ 5017.109384] usb 5-1: new full speed USB device using uhci_hcd and address 3
Oct 15 18:45:25 joel kernel: [ 5017.229079] usb 5-1: device descriptor read/64, error -71
Oct 15 18:45:25 joel kernel: [ 5017.452510] usb 5-1: device descriptor read/64, error -71
Oct 15 18:45:26 joel kernel: [ 5018.090884] usb 5-1: new full speed USB device using uhci_hcd and address 5
Oct 15 18:45:26 joel NetworkManager: <debug> [1192466726.639131] nm_hal_device_added(): New device added (hal udi is '/org/freedesktop/Hal/devices/usb_device_4e8_3242_2736BAAW831392W0'). 
Oct 15 18:45:26 joel kernel: [ 5018.280072] usb 5-1: configuration #1 chosen from 1 choice
Oct 15 18:45:26 joel kernel: [ 5018.348814] /build/buildd/linux-source-2.6.22-2.6.22/drivers/usb/class/usblp.c: usblp0: USB Bidirectional printer dev 5 if 0 alt 0 proto 2 vid 0x04E8 pid 0x3242
Oct 15 18:45:26 joel kernel: [ 5018.349032] usbcore: registered new interface driver usblp
Oct 15 18:45:26 joel kernel: [ 5018.349040] /build/buildd/linux-source-2.6.22-2.6.22/drivers/usb/class/usblp.c: v0.13: USB Printer Device Class driver
Oct 15 18:45:26 joel NetworkManager: <debug> [1192466726.715609] nm_hal_device_added(): New device added (hal udi is '/org/freedesktop/Hal/devices/usb_device_4e8_3242_2736BAAW831392W0_if0'). 
Oct 15 18:45:26 joel NetworkManager: <debug> [1192466726.762475] nm_hal_device_added(): New device added (hal udi is '/org/freedesktop/Hal/devices/usb_device_4e8_3242_2736BAAW831392W0_if0_printer_noserial'). 
Oct 15 18:45:26 joel NetworkManager: <debug> [1192466726.763263] nm_hal_device_added(): New device added (hal udi is '/org/freedesktop/Hal/devices/usb_device_4e8_3242_2736BAAW831392W0_usbraw'). 
Oct 15 18:46:05 joel smbd[8565]: [2007/10/15 18:46:05, 0] printing/pcap.c:pcap_cache_reload(159) 
Oct 15 18:46:05 joel smbd[8565]:   Unable to open printcap file /var/run/cups/printcap for read! 
Oct 15 18:46:05 joel smbd[8565]: [2007/10/15 18:46:05, 0] printing/pcap.c:pcap_cache_reload(159) 
Oct 15 18:46:05 joel smbd[8565]:   Unable to open printcap file /var/run/cups/printcap for read! 
Oct 15 18:48:39 joel smbd[5312]: [2007/10/15 18:48:39, 0] printing/pcap.c:pcap_cache_reload(159) 
Oct 15 18:48:39 joel smbd[5312]:   Unable to open printcap file /var/run/cups/printcap for read! 
Oct 15 18:48:39 joel smbd[5312]: [2007/10/15 18:48:39, 0] printing/pcap.c:pcap_cache_reload(159) 
Oct 15 18:48:39 joel smbd[5312]:   Unable to open printcap file /var/run/cups/printcap for read! 
Oct 15 18:55:59 joel smbd[8565]: [2007/10/15 18:55:59, 0] lib/util_sock.c:read_data(534) 
Oct 15 18:55:59 joel smbd[8565]:   read_data: read failure for 4 bytes to client 192.168.25.169. Error = No route to host 
Oct 15 19:17:01 joel /USR/SBIN/CRON[8821]: (root) CMD (   cd / && run-parts --report /etc/cron.hourly)
Oct 15 19:42:26 joel -- MARK --
Oct 15 19:50:19 joel kernel: [ 8901.596036] audit(1192470619.711:4):  type=1503 operation="inode_permission" requested_mask="a" denied_mask="a" name="/dev/tty" pid=9195 profile="/usr/sbin/cupsd"
Oct 15 20:02:26 joel -- MARK --
Oct 15 20:06:48 joel kernel: [ 9887.306574] audit(1192471607.768:5):  type=1503 operation="sysctl" requested_mask="r" denied_mask="r" name="/proc/sys/kernel/osrelease" pid=19083 profile="/usr/sbin/cupsd"
Oct 15 20:06:48 joel rmmod: FATAL: can't open /proc/modules: Permission denied 
Oct 15 20:06:48 joel kernel: [ 9887.309212] audit(1192471607.768:6):  type=1503 operation="inode_permission" requested_mask="r" denied_mask="r" name="/proc/modules" pid=19085 profile="/usr/sbin/cupsd"
Oct 15 20:06:48 joel rmmod: FATAL: can't open /proc/modules: Permission denied 
Oct 15 20:06:48 joel kernel: [ 9887.311113] audit(1192471607.768:7):  type=1503 operation="inode_permission" requested_mask="r" denied_mask="r" name="/proc/modules" pid=19087 profile="/usr/sbin/cupsd"
Oct 15 20:06:48 joel rmmod: FATAL: can't open /proc/modules: Permission denied 
Oct 15 20:06:48 joel kernel: [ 9887.312981] audit(1192471607.768:8):  type=1503 operation="inode_permission" requested_mask="r" denied_mask="r" name="/proc/modules" pid=19089 profile="/usr/sbin/cupsd"
Oct 15 20:06:48 joel rmmod: FATAL: can't open /proc/modules: Permission denied 
Oct 15 20:06:48 joel kernel: [ 9887.314809] audit(1192471607.768:9):  type=1503 operation="inode_permission" requested_mask="r" denied_mask="r" name="/proc/modules" pid=19091 profile="/usr/sbin/cupsd"
Oct 15 20:06:48 joel kernel: [ 9887.317252] audit(1192471607.768:10):  type=1503 operation="inode_permission" requested_mask="r" denied_mask="r" name="/etc/modprobe.conf" pid=19093 profile="/usr/sbin/cupsd"
Oct 15 20:06:48 joel kernel: [ 9887.317286] audit(1192471607.768:11):  type=1503 operation="inode_permission" requested_mask="r" denied_mask="r" name="/etc/modprobe.d/" pid=19093 profile="/usr/sbin/cupsd"
Oct 15 20:06:48 joel kernel: [ 9887.317399] audit(1192471607.768:12):  type=1503 operation="inode_permission" requested_mask="r" denied_mask="r" name="/etc/modprobe.d/" pid=19093 profile="/usr/sbin/cupsd"
Oct 15 20:06:48 joel kernel: [ 9887.317802] audit(1192471607.768:13):  type=1503 operation="inode_permission" requested_mask="r" denied_mask="r" name="/lib/modules/2.6.22-14-generic/modules.dep" pid=19093 profile="/usr/sbin/cupsd"
Oct 15 20:06:48 joel kernel: [ 9887.327917] audit(1192471607.768:14):  type=1503 operation="inode_permission" requested_mask="r" denied_mask="r" name="/etc/modprobe.conf" pid=19095 profile="/usr/sbin/cupsd"
Oct 15 20:06:48 joel kernel: [ 9887.328362] audit(1192471607.768:15):  type=1503 operation="inode_permission" requested_mask="r" denied_mask="r" name="/etc/modprobe.d/" pid=19095 profile="/usr/sbin/cupsd"
Oct 15 20:06:48 joel kernel: [ 9887.328765] audit(1192471607.768:16):  type=1503 operation="inode_permission" requested_mask="r" denied_mask="r" name="/etc/modprobe.d/" pid=19095 profile="/usr/sbin/cupsd"
Oct 15 20:06:48 joel kernel: [ 9887.329183] audit(1192471607.768:17):  type=1503 operation="inode_permission" requested_mask="r" denied_mask="r" name="/lib/modules/2.6.22-14-generic/modules.dep" pid=19095 profile="/usr/sbin/cupsd"
Oct 15 20:06:48 joel kernel: [ 9887.333630] audit(1192471607.768:18):  type=1503 operation="inode_permission" requested_mask="r" denied_mask="r" name="/etc/modprobe.conf" pid=19097 profile="/usr/sbin/cupsd"
Oct 15 20:06:48 joel kernel: [ 9887.333662] audit(1192471607.768:19):  type=1503 operation="inode_permission" requested_mask="r" denied_mask="r" name="/etc/modprobe.d/" pid=19097 profile="/usr/sbin/cupsd"
Oct 15 20:06:48 joel kernel: [ 9887.333684] audit(1192471607.768:20):  type=1503 operation="inode_permission" requested_mask="r" denied_mask="r" name="/etc/modprobe.d/" pid=19097 profile="/usr/sbin/cupsd"
Oct 15 20:06:48 joel kernel: [ 9887.333716] audit(1192471607.768:21):  type=1503 operation="inode_permission" requested_mask="r" denied_mask="r" name="/lib/modules/2.6.22-14-generic/modules.dep" pid=19097 profile="/usr/sbin/cupsd"
Oct 15 20:06:48 joel kernel: [ 9887.334116] audit(1192471607.768:22):  type=1503 operation="capable" name="sys_rawio" pid=19083 profile="/usr/sbin/cupsd"
Oct 15 20:06:48 joel kernel: [ 9887.497638] audit(1192471607.768:23):  type=1503 operation="inode_permission" requested_mask="rw" denied_mask="rw" name="/dev/tty" pid=19103 profile="/usr/sbin/cupsd"
Oct 15 20:06:48 joel kernel: [ 9887.529516] audit(1192471607.768:24):  type=1503 operation="inode_permission" requested_mask="rw" denied_mask="rw" name="/dev/tty" pid=19106 profile="/usr/sbin/cupsd"