← Back to team overview

touch-packages team mailing list archive

[Bug 1458014] Re: audit_printk_skb slowing down boot

 

Here are the outputs of 2 consecutive boots for dmesg | less, they both
indicate 26 "lost" CPU seconds:

[   12.580984] vboxdrv: Successfully loaded version 4.3.10_Ubuntu (interface 0x001a0007).
[   12.593834] vboxpci: IOMMU not found (not registered)
[   13.307547] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
[   13.307807] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
[   13.671770] r8169 0000:04:00.0 eth0: link down
[   13.671790] r8169 0000:04:00.0 eth0: link down
[   13.671819] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[   13.672126] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[   15.219548] r8169 0000:04:00.0 eth0: link up
[   15.219570] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[   15.687163] init: plymouth-upstart-bridge main process ended, respawning
[   41.953317] audit_printk_skb: 177 callbacks suppressed
[   41.953319] type=1400 audit(1432326862.429:71): apparmor="STATUS" operation="profile_replace" profile="unconfined" name="/usr/lib/cups/backend/cups-pdf" pid=2677 comm="apparmor_parser"
[   41.953324] type=1400 audit(1432326862.429:72): apparmor="STATUS" operation="profile_replace" profile="unconfined" name="/usr/sbin/cupsd" pid=2677 comm="apparmor_parser"
[   41.953664] type=1400 audit(1432326862.429:73): apparmor="STATUS" operation="profile_replace" profile="unconfined" name="/usr/sbin/cupsd" pid=2677 comm="apparmor_parser"
(END)


[   12.716182] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[   12.861751] vboxdrv: module verification failed: signature and/or  required key missing - tainting kernel
[   12.864483] vboxdrv: Found 4 processor cores.
[   12.864950] vboxdrv: fAsync=0 offMin=0x214 offMax=0x1658
[   12.865004] vboxdrv: TSC mode is 'synchronous', kernel timer mode is 'normal'.
[   12.865006] vboxdrv: Successfully loaded version 4.3.10_Ubuntu (interface 0x001a0007).
[   12.878317] vboxpci: IOMMU not found (not registered)
[   14.374026] r8169 0000:04:00.0 eth0: link up
[   14.374033] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[   15.556422] init: plymouth-upstart-bridge main process ended, respawning
[   41.489482] audit_printk_skb: 159 callbacks suppressed
[   41.489484] type=1400 audit(1432327140.961:65): apparmor="STATUS" operation="profile_replace" profile="unconfined" name="/usr/lib/cups/backend/cups-pdf" pid=2788 comm="apparmor_parser"
[   41.489489] type=1400 audit(1432327140.961:66): apparmor="STATUS" operation="profile_replace" profile="unconfined" name="/usr/sbin/cupsd" pid=2788 comm="apparmor_parser"
[   41.489832] type=1400 audit(1432327140.961:67): apparmor="STATUS" operation="profile_replace" profile="unconfined" name="/usr/sbin/cupsd" pid=2788 comm="apparmor_parser"
(END)


The PC is a Dell Inspiron 660 Core i5-3330 quad core (physical, not virtual) all running at 3GHz.

26 "lost" CPU seconds on this machine is a long time.

Is this ok?

-- 
You received this bug notification because you are a member of Ubuntu
Touch seeded packages, which is subscribed to apparmor in Ubuntu.
https://bugs.launchpad.net/bugs/1458014

Title:
  audit_printk_skb slowing down boot

Status in apparmor package in Ubuntu:
  New

Bug description:
  Subjectively, my system slowed down after the recent GRUB update.

  As you can see from the following, audit_printk_skb is consuming a lot
  of boot time:

  [   13.243280] vboxdrv: Successfully loaded version 4.3.10_Ubuntu (interface 0x001a0007).
  [   13.257947] vboxpci: IOMMU not found (not registered)
  [   13.862999] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
  [   13.865996] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
  [   14.195776] r8169 0000:04:00.0 eth0: link down
  [   14.195796] r8169 0000:04:00.0 eth0: link down
  [   14.195827] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
  [   14.196138] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
  [   15.769090] r8169 0000:04:00.0 eth0: link up
  [   15.769097] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
  [   16.223084] init: plymouth-upstart-bridge main process ended, respawning
  [   42.424836] audit_printk_skb: 195 callbacks suppressed
  [   42.424839] type=1400 audit(1431891089.974:77): apparmor="STATUS" operation="profile_replace" profile="unconfined" name="/usr/lib/cups/backend/cups-pdf" pid=2632 comm="apparmor_parser"
  [   42.424844] type=1400 audit(1431891089.974:78): apparmor="STATUS" operation="profile_replace" profile="unconfined" name="/usr/sbin/cupsd" pid=2632 comm="apparmor_parser"
  [   42.425185] type=1400 audit(1431891089.974:79): apparmor="STATUS" operation="profile_replace" profile="unconfined" name="/usr/sbin/cupsd" pid=2632 comm="apparmor_parser"
  (END)

  ProblemType: Bug
  DistroRelease: Ubuntu 14.04
  Package: apparmor 2.8.95~2430-0ubuntu5.1
  ProcVersionSignature: Ubuntu 3.13.0-53.88-generic 3.13.11-ckt19
  Uname: Linux 3.13.0-53-generic i686
  ApportVersion: 2.14.1-0ubuntu3.10
  Architecture: i386
  CurrentDesktop: Unity
  Date: Fri May 22 14:18:46 2015
  EcryptfsInUse: Yes
  InstallationDate: Installed on 2014-04-29 (388 days ago)
  InstallationMedia: Ubuntu 14.04 LTS "Trusty Tahr" - Release i386 (20140417)
  ProcKernelCmdline: BOOT_IMAGE=/boot/vmlinuz-3.13.0-53-generic root=UUID=8cf458ab-4ff9-4505-9a16-27da1ea7ec10 ro quiet splash vt.handoff=7
  SourcePackage: apparmor
  Syslog:
   
  UpgradeStatus: No upgrade log present (probably fresh install)

To manage notifications about this bug go to:
https://bugs.launchpad.net/ubuntu/+source/apparmor/+bug/1458014/+subscriptions


References