RE: xhci_hcd ERROR no room on ep ring, xhci_hcd WARN Event TRB for slot 1 ep 4 with no TDs queued?

[Date Prev][Date Next][Thread Prev][Thread Next][Date Index][Thread Index]

 



Hi Sander,

I've run 24-hour test with USB2.0 TV tuner. It's also using em28xx, and
the urb length is same with you, 185088. But I haven't seen warning
message like this. And I allocate 8 segments for isoc endpoint. Normally
this should be enough unless the xHC is unable to handle it on time. Can
you apply the attached patch to catch the information?

The WARN Event TRB for slot 1 ep 4 with no TDs queued should be a normal
case if it's not occurred continuous. If the tds on the ep ring are
cancelled and removed but the event is triggered after this, the info
will show.  

Thanks & Best regards,
Andiry
 

> -----Original Message-----
> From: Sander Eikelenboom [mailto:linux@xxxxxxxxxxxxxx]
> Sent: Sunday, July 25, 2010 11:26 PM
> To: Sarah Sharp; linux-usb@xxxxxxxxxxxxxxx
> Cc: Xu, Andiry
> Subject: xhci_hcd ERROR no room on ep ring, xhci_hcd WARN Event TRB
for
> slot 1 ep 4 with no TDs queued?
> 
> Hi Sarah,
> 
> I have tried the amd-isoc-updated branch patches, on top of a
2.6.35-rc6
> kernel and the 4 pending xhci patches from linux-next.
> 
> The full log is below, but the highlights are:
> 
> Jul 25 12:05:01 security kernel: [ 9035.162632] xhci_hcd 0000:04:00.0:
URB
> transfer length is wrong, xHC issue? req. len = 185088, act. len =
226540
> Jul 25 12:30:01 security kernel: [10534.583184] xhci_hcd 0000:04:00.0:
URB
> transfer length is wrong, xHC issue? req. len = 185088, act. len =
186592
> Jul 25 12:47:43 security kernel: [11596.938025] xhci_hcd 0000:04:00.0:
> WARN: Stalled endpoint
> Jul 25 12:47:43 security kernel: [11596.952369] usb 3-2: usbfs:
> USBDEVFS_CONTROL failed cmd lsusb rqt 128 rq 6 len 255 ret -62
> Jul 25 13:40:01 security kernel: [14735.430879] xhci_hcd 0000:04:00.0:
URB
> transfer length is wrong, xHC issue? req. len = 185088, act. len =
186052
> Jul 25 14:00:01 security kernel: [15935.206060] xhci_hcd 0000:04:00.0:
URB
> transfer length is wrong, xHC issue? req. len = 185088, act. len =
244856
> Jul 25 14:55:01 security kernel: [19234.846628] xhci_hcd 0000:04:00.0:
URB
> transfer length is wrong, xHC issue? req. len = 185088, act. len =
186052
> Jul 25 15:50:01 security kernel: [22535.096640] xhci_hcd 0000:04:00.0:
URB
> transfer length is wrong, xHC issue? req. len = 185088, act. len =
186604
> Jul 25 15:55:58 security kernel: [22892.324302] xhci_hcd 0000:04:00.0:
URB
> transfer length is wrong, xHC issue? req. len = 185088, act. len =
187980
> Jul 25 16:05:01 security kernel: [23434.794827] xhci_hcd 0000:04:00.0:
URB
> transfer length is wrong, xHC issue? req. len = 185088, act. len =
287272
> Jul 25 16:40:01 security kernel: [25534.968523] xhci_hcd 0000:04:00.0:
URB
> transfer length is wrong, xHC issue? req. len = 185088, act. len =
187016
> Jul 25 16:50:01 security kernel: [26135.312245] xhci_hcd 0000:04:00.0:
> ERROR no room on ep ring
> Jul 25 16:50:01 security kernel: [26135.312312] xhci_hcd 0000:04:00.0:
> ERROR no room on ep ring
> Jul 25 16:50:01 security kernel: [26135.312367] xhci_hcd 0000:04:00.0:
> ERROR no room on ep ring
> Jul 25 16:50:01 security kernel: [26135.312423] xhci_hcd 0000:04:00.0:
> ERROR no room on ep ring
> Jul 25 16:50:01 security kernel: [26135.312480] xhci_hcd 0000:04:00.0:
> ERROR no room on ep ring
> Jul 25 16:50:01 security kernel: [26135.312489] xhci_hcd 0000:04:00.0:
> WARN Event TRB for slot 1 ep 4 with no TDs queued?
> 
> Switching on the full xhci debug log fills up my log files and disk
before
> anything defintive happens.
> It allways happens after several hours (which are a lot of usb
packets)
> Are there some relevant printk's i could add that would make
pinpointing
> the problem easier ?
> 
> I use a usb 2.0 videograbber on the xhci (nec) host controller.
> 
> --
> Sander
> 
> 
> 
> 
> Jul 25 09:34:38 security rsyslogd: [origin software="rsyslogd"
> swVersion="4.4.2" x-pid="1421" x-info="http://www.rsyslog.com";]
(re)start
> Jul 25 09:34:38 security kernel: [    0.000000] Initializing cgroup
subsys
> cpuset
> Jul 25 09:34:38 security kernel: [    0.000000] Initializing cgroup
subsys
> cpu
> Jul 25 09:34:38 security kernel: [    0.000000] Linux version 2.6.35-
> rc6+xen-konrad-swiotlb-xhci-isoc+ (root@serveerstertje) (gcc version
4.3.2
> (Debian 4.3.2-1.1) ) #2 SMP Sun Jul 25 02:31:32 CEST 2010
> Jul 25 09:34:38 security kernel: [    0.000000] Command line:
> root=/dev/xvda2 ro iommu=soft swiotlb=force
> Jul 25 09:34:38 security kernel: [    0.000000] ACPI in unprivileged
> domain disabled
> Jul 25 09:34:38 security kernel: [    0.000000] BIOS-provided physical
RAM
> map:
> Jul 25 09:34:38 security kernel: [    0.000000]  Xen: 0000000000000000
-
> 00000000000a0000 (usable)
> Jul 25 09:34:38 security kernel: [    0.000000]  Xen: 00000000000a0000
-
> 0000000000100000 (reserved)
> Jul 25 09:34:38 security kernel: [    0.000000]  Xen: 0000000000100000
-
> 0000000020000000 (usable)
> Jul 25 09:34:38 security kernel: [    0.000000] NX (Execute Disable)
> protection: active
> Jul 25 09:34:38 security kernel: [    0.000000] DMI not present or
invalid.
> Jul 25 09:34:38 security kernel: [    0.000000] e820 update range:
> 0000000000000000 - 0000000000001000 (usable) ==> (reserved)
> Jul 25 09:34:38 security kernel: [    0.000000] e820 remove range:
> 00000000000a0000 - 0000000000100000 (usable)
> Jul 25 09:34:38 security kernel: [    0.000000] No AGP bridge found
> Jul 25 09:34:38 security kernel: [    0.000000] last_pfn = 0x20000
> max_arch_pfn = 0x400000000
> Jul 25 09:34:38 security kernel: [    0.000000] initial memory mapped
: 0
> - 20000000
> Jul 25 09:34:38 security kernel: [    0.000000] init_memory_mapping:
> 0000000000000000-0000000020000000
> Jul 25 09:34:38 security kernel: [    0.000000]  0000000000 -
0020000000
> page 4k
> Jul 25 09:34:38 security kernel: [    0.000000] kernel direct mapping
> tables up to 20000000 @ 100000-202000
> Jul 25 09:34:38 security kernel: [    0.000000] RAMDISK: 01816000 -
> 02b6e000
> Jul 25 09:34:38 security kernel: [    0.000000] Zone PFN ranges:
> Jul 25 09:34:38 security kernel: [    0.000000]   DMA      0x00000001
->
> 0x00001000
> Jul 25 09:34:38 security kernel: [    0.000000]   DMA32    0x00001000
->
> 0x00100000
> Jul 25 09:34:38 security kernel: [    0.000000]   Normal   empty
> Jul 25 09:34:38 security kernel: [    0.000000] Movable zone start PFN
for
> each node
> Jul 25 09:34:38 security kernel: [    0.000000] early_node_map[2]
active
> PFN ranges
> Jul 25 09:34:38 security kernel: [    0.000000]     0: 0x00000001 ->
> 0x000000a0
> Jul 25 09:34:38 security kernel: [    0.000000]     0: 0x00000100 ->
> 0x00020000
> Jul 25 09:34:38 security kernel: [    0.000000] On node 0 totalpages:
> 130975
> Jul 25 09:34:38 security kernel: [    0.000000]   DMA zone: 56 pages
used
> for memmap
> Jul 25 09:34:38 security kernel: [    0.000000]   DMA zone: 0 pages
> reserved
> Jul 25 09:34:38 security kernel: [    0.000000]   DMA zone: 3943
pages,
> LIFO batch:0
> Jul 25 09:34:38 security kernel: [    0.000000]   DMA32 zone: 1736
pages
> used for memmap
> Jul 25 09:34:38 security kernel: [    0.000000]   DMA32 zone: 125240
pages,
> LIFO batch:31
> Jul 25 09:34:38 security kernel: [    0.000000] SMP: Allowing 2 CPUs,
0
> hotplug CPUs
> Jul 25 09:34:38 security kernel: [    0.000000] No local APIC present
> Jul 25 09:34:38 security kernel: [    0.000000] APIC: disable apic
> facility
> Jul 25 09:34:38 security kernel: [    0.000000] APIC: switched to apic
> NOOP
> Jul 25 09:34:38 security kernel: [    0.000000] nr_irqs_gsi: 16
> Jul 25 09:34:38 security kernel: [    0.000000] Allocating PCI
resources
> starting at 20000000 (gap: 20000000:e0000000)
> Jul 25 09:34:38 security kernel: [    0.000000] Booting
paravirtualized
> kernel on Xen
> Jul 25 09:34:38 security kernel: [    0.000000] Xen version:
4.0.1-rc5-pre
> (preserve-AD)
> Jul 25 09:34:38 security kernel: [    0.000000] setup_percpu:
NR_CPUS:32
> nr_cpumask_bits:32 nr_cpu_ids:2 nr_node_ids:1
> Jul 25 09:34:38 security kernel: [    0.000000] early_res array is
doubled
> to 64 at [3000 - 37ff]
> Jul 25 09:34:38 security kernel: [    0.000000] PERCPU: Embedded 29
> pages/cpu @ffff880002c8c000 s86144 r8192 d24448 u118784
> Jul 25 09:34:38 security kernel: [    0.000000] pcpu-alloc: s86144
r8192
> d24448 u118784 alloc=29*4096
> Jul 25 09:34:38 security kernel: [    0.000000] pcpu-alloc: [0] 0 [0]
1
> Jul 25 09:34:38 security kernel: [    0.000000] trying to map
vcpu_info 0
> at ffff880002c97020, mfn 14859e, offset 32
> Jul 25 09:34:38 security kernel: [    0.000000] cpu 0 using vcpu_info
at
> ffff880002c97020
> Jul 25 09:34:38 security kernel: [    0.000000] trying to map
vcpu_info 1
> at ffff880002cb4020, mfn 148581, offset 32
> Jul 25 09:34:38 security kernel: [    0.000000] cpu 1 using vcpu_info
at
> ffff880002cb4020
> Jul 25 09:34:38 security kernel: [    0.000000] Xen: using vcpu_info
> placement
> Jul 25 09:34:38 security kernel: [    0.000000] Built 1 zonelists in
Zone
> order, mobility grouping on.  Total pages: 129183
> Jul 25 09:34:38 security kernel: [    0.000000] Kernel command line:
> root=/dev/xvda2 ro iommu=soft swiotlb=force
> Jul 25 09:34:38 security kernel: [    0.000000] PID hash table
entries:
> 2048 (order: 2, 16384 bytes)
> Jul 25 09:34:38 security kernel: [    0.000000] Dentry cache hash
table
> entries: 65536 (order: 7, 524288 bytes)
> Jul 25 09:34:38 security kernel: [    0.000000] Inode-cache hash table
> entries: 32768 (order: 6, 262144 bytes)
> Jul 25 09:34:38 security kernel: [    0.000000] Placing 64MB software
IO
> TLB between ffff880003d00000 - ffff880007d00000
> Jul 25 09:34:38 security kernel: [    0.000000] software IO TLB at
phys
> 0x3d00000 - 0x7d00000
> Jul 25 09:34:38 security kernel: [    0.000000] Subtract (42 early
> reservations)
> Jul 25 09:34:38 security kernel: [    0.000000]   #1 [0002c71000 -
> 0002c8c000]  XEN PAGETABLES
> Jul 25 09:34:38 security kernel: [    0.000000]   #2 [0001000000 -
> 00017f5664]   TEXT DATA BSS
> Jul 25 09:34:38 security kernel: [    0.000000]   #3 [0001816000 -
> 0002b6e000]         RAMDISK
> Jul 25 09:34:38 security kernel: [    0.000000]   #4 [0002b6e000 -
> 0002c71000]  XEN START INFO
> Jul 25 09:34:38 security kernel: [    0.000000]   #5 [0000001000 -
> 0000003000]      TRAMPOLINE
> Jul 25 09:34:38 security kernel: [    0.000000]   #6 [0000100000 -
> 00001e4000]         PGTABLE
> Jul 25 09:34:38 security kernel: [    0.000000]   #7 [00017f5680 -
> 00017f6680]         BOOTMEM
> Jul 25 09:34:38 security kernel: [    0.000000]   #8 [00017f6680 -
> 00017f66c0]         BOOTMEM
> Jul 25 09:34:38 security kernel: [    0.000000]   #9 [00017f7000 -
> 00017f8000]         BOOTMEM
> Jul 25 09:34:38 security kernel: [    0.000000]   #10 [00017f8000 -
> 00017f9000]         BOOTMEM
> Jul 25 09:34:38 security kernel: [    0.000000]   #11 [00017f9000 -
> 00017fa000]         BOOTMEM
> Jul 25 09:34:38 security kernel: [    0.000000]   #12 [00017fa000 -
> 00017fb000]         BOOTMEM
> Jul 25 09:34:38 security kernel: [    0.000000]   #13 [00017fb000 -
> 00017fc000]         BOOTMEM
> Jul 25 09:34:38 security kernel: [    0.000000]   #14 [00017fc000 -
> 00017fd000]         BOOTMEM
> Jul 25 09:34:38 security kernel: [    0.000000]   #15 [0003600000 -
> 0003d00000]        MEMMAP 0
> Jul 25 09:34:38 security kernel: [    0.000000]   #16 [00017f66c0 -
> 00017f6840]         BOOTMEM
> Jul 25 09:34:38 security kernel: [    0.000000]   #17 [00017fd000 -
> 0001800000]         BOOTMEM
> Jul 25 09:34:38 security kernel: [    0.000000]   #18 [0001800000 -
> 0001801000]         BOOTMEM
> Jul 25 09:34:38 security kernel: [    0.000000]   #19 [0001801000 -
> 0001802000]         BOOTMEM
> Jul 25 09:34:38 security kernel: [    0.000000]   #20 [0001802000 -
> 0001803000]         BOOTMEM
> Jul 25 09:34:38 security kernel: [    0.000000]   #21 [00017f6840 -
> 00017f6920]         BOOTMEM
> Jul 25 09:34:38 security kernel: [    0.000000]   #22 [00017f6940 -
> 00017f69a8]         BOOTMEM
> Jul 25 09:34:38 security kernel: [    0.000000]   #23 [00017f69c0 -
> 00017f6a28]         BOOTMEM
> Jul 25 09:34:38 security kernel: [    0.000000]   #24 [00017f6a40 -
> 00017f6aa8]         BOOTMEM
> Jul 25 09:34:38 security kernel: [    0.000000]   #25 [00017f6ac0 -
> 00017f6aec]         BOOTMEM
> Jul 25 09:34:38 security kernel: [    0.000000]   #26 [00017f6b00 -
> 00017f6b2c]         BOOTMEM
> Jul 25 09:34:38 security kernel: [    0.000000]   #27 [0002c8c000 -
> 0002cc6000]         BOOTMEM
> Jul 25 09:34:38 security kernel: [    0.000000]   #28 [00017f6b40 -
> 00017f6b48]         BOOTMEM
> Jul 25 09:34:38 security kernel: [    0.000000]   #29 [00017f6b80 -
> 00017f6b88]         BOOTMEM
> Jul 25 09:34:38 security kernel: [    0.000000]   #30 [00017f6bc0 -
> 00017f6bc8]         BOOTMEM
> Jul 25 09:34:38 security kernel: [    0.000000]   #31 [00017f6c00 -
> 00017f6c10]         BOOTMEM
> Jul 25 09:34:38 security kernel: [    0.000000]   #32 [00017f6c40 -
> 00017f6d40]         BOOTMEM
> Jul 25 09:34:38 security kernel: [    0.000000]   #33 [00017f6d40 -
> 00017f6d88]         BOOTMEM
> Jul 25 09:34:38 security kernel: [    0.000000]   #34 [00017f6dc0 -
> 00017f6e08]         BOOTMEM
> Jul 25 09:34:38 security kernel: [    0.000000]   #35 [0001803000 -
> 0001807000]         BOOTMEM
> Jul 25 09:34:38 security kernel: [    0.000000]   #36 [0002cc6000 -
> 0002d46000]         BOOTMEM
> Jul 25 09:34:38 security kernel: [    0.000000]   #37 [0002d46000 -
> 0002d86000]         BOOTMEM
> Jul 25 09:34:38 security kernel: [    0.000000]   #38 [0003d00000 -
> 0007d00000]         BOOTMEM
> Jul 25 09:34:38 security kernel: [    0.000000]   #39 [0002d86000 -
> 0002da6000]         BOOTMEM
> Jul 25 09:34:38 security kernel: [    0.000000]   #40 [0002da6000 -
> 0002de6000]         BOOTMEM
> Jul 25 09:34:38 security kernel: [    0.000000]   #41 [0000003800 -
> 000000b800]         BOOTMEM
> Jul 25 09:34:38 security kernel: [    0.000000] Memory:
419684k/524288k
> available (4288k kernel code, 388k absent, 104216k reserved, 2327k
data,
> 496k init)
> Jul 25 09:34:38 security kernel: [    0.000000] Hierarchical RCU
> implementation.
> Jul 25 09:34:38 security kernel: [    0.000000]         RCU-based
> detection of stalled CPUs is disabled.
> Jul 25 09:34:38 security kernel: [    0.000000]         Verbose
stalled-
> CPUs detection is disabled.
> Jul 25 09:34:38 security kernel: [    0.000000] NR_IRQS:1280
> Jul 25 09:34:38 security kernel: [    0.000000] Console: colour dummy
> device 80x25
> Jul 25 09:34:38 security kernel: [    0.000000] console [tty0] enabled
> Jul 25 09:34:38 security kernel: [    0.000000] console [hvc0] enabled
> Jul 25 09:34:38 security kernel: [    0.000000] Xen: using vcpuop
timer
> interface
> Jul 25 09:34:38 security kernel: [    0.000000] installing Xen timer
for
> CPU 0
> Jul 25 09:34:38 security kernel: [    0.000000] Detected 3200.210 MHz
> processor.
> Jul 25 09:34:38 security kernel: [    0.004000] Calibrating delay loop
> (skipped), value calculated using timer frequency.. 6400.42 BogoMIPS
> (lpj=12800840)
> Jul 25 09:34:38 security kernel: [    0.004000] pid_max: default:
32768
> minimum: 301
> Jul 25 09:34:38 security kernel: [    0.004000] Security Framework
> initialized
> Jul 25 09:34:38 security kernel: [    0.004000] SELinux:  Disabled at
boot.
> Jul 25 09:34:38 security kernel: [    0.004000] Mount-cache hash table
> entries: 256
> Jul 25 09:34:38 security kernel: [    0.004000] Initializing cgroup
subsys
> ns
> Jul 25 09:34:38 security kernel: [    0.004000] Initializing cgroup
subsys
> cpuacct
> Jul 25 09:34:38 security kernel: [    0.004000] Initializing cgroup
subsys
> devices
> Jul 25 09:34:38 security kernel: [    0.004000] tseg: 0000000000
> Jul 25 09:34:38 security kernel: [    0.004000] CPU: Physical
Processor ID:
> 0
> Jul 25 09:34:38 security kernel: [    0.004000] CPU: Processor Core
ID: 3
> Jul 25 09:34:38 security kernel: [    0.004000] Performance Events:
> Jul 25 09:34:38 security kernel: [    0.004000] no APIC, boot with the
> "lapic" boot parameter to force-enable it.
> Jul 25 09:34:38 security kernel: [    0.004000] no hardware sampling
> interrupt available.
> Jul 25 09:34:38 security kernel: [    0.004000] AMD PMU driver.
> Jul 25 09:34:38 security kernel: [    0.004000] ... version:
> 0
> Jul 25 09:34:38 security kernel: [    0.004000] ... bit width:
> 48
> Jul 25 09:34:38 security kernel: [    0.004000] ... generic registers:
> 4
> Jul 25 09:34:38 security kernel: [    0.004000] ... value mask:
> 0000ffffffffffff
> Jul 25 09:34:38 security kernel: [    0.004000] ... max period:
> 00007fffffffffff
> Jul 25 09:34:38 security kernel: [    0.004000] ... fixed-purpose
events:
> 0
> Jul 25 09:34:38 security kernel: [    0.004000] ... event mask:
> 000000000000000f
> Jul 25 09:34:38 security kernel: [    0.004000] SMP alternatives:
> switching to UP code
> Jul 25 09:34:38 security kernel: [    0.012218] installing Xen timer
for
> CPU 1
> Jul 25 09:34:38 security kernel: [    0.012253] SMP alternatives:
> switching to SMP code
> Jul 25 09:34:38 security kernel: [    0.016110] Brought up 2 CPUs
> Jul 25 09:34:38 security kernel: [    0.016659] Grant table
initialized
> Jul 25 09:34:38 security kernel: [    0.016672] regulator: core
version
> 0.5
> Jul 25 09:34:38 security kernel: [    0.016854] NET: Registered
protocol
> family 16
> Jul 25 09:34:38 security kernel: [    0.018799] PCI: setting up Xen
PCI
> frontend stub
> Jul 25 09:34:38 security kernel: [    0.018811] PCI:
pci_cache_line_size
> set to 64 bytes
> Jul 25 09:34:38 security kernel: [    0.024249] bio: create slab
<bio-0>
> at 0
> Jul 25 09:34:38 security kernel: [    0.024479] ACPI: Interpreter
disabled.
> Jul 25 09:34:38 security kernel: [    0.024686] xen_balloon:
Initialising
> balloon driver.
> Jul 25 09:34:38 security kernel: [    0.026408] vgaarb: loaded
> Jul 25 09:34:38 security kernel: [    0.026694] SCSI subsystem
initialized
> Jul 25 09:34:38 security kernel: [    0.026781] libata version 3.00
loaded.
> Jul 25 09:34:38 security kernel: [    0.026897] usbcore: registered
new
> interface driver usbfs
> Jul 25 09:34:38 security kernel: [    0.027003] usbcore: registered
new
> interface driver hub
> Jul 25 09:34:38 security kernel: [    0.027079] usbcore: registered
new
> device driver usb
> Jul 25 09:34:38 security kernel: [    0.027348] PCI: System does not
> support PCI
> Jul 25 09:34:38 security kernel: [    0.027351] PCI: System does not
> support PCI
> Jul 25 09:34:38 security kernel: [    0.027558] Switching to
clocksource
> xen
> Jul 25 09:34:38 security kernel: [    0.027663] pnp: PnP ACPI:
disabled
> Jul 25 09:34:38 security kernel: [    0.027664] NET: Registered
protocol
> family 2
> Jul 25 09:34:38 security kernel: [    0.027664] IP route cache hash
table
> entries: 4096 (order: 3, 32768 bytes)
> Jul 25 09:34:38 security kernel: [    0.027664] TCP established hash
table
> entries: 16384 (order: 6, 262144 bytes)
> Jul 25 09:34:38 security kernel: [    0.027664] TCP bind hash table
> entries: 16384 (order: 6, 262144 bytes)
> Jul 25 09:34:38 security kernel: [    0.027664] TCP: Hash tables
> configured (established 16384 bind 16384)
> Jul 25 09:34:38 security kernel: [    0.027664] TCP reno registered
> Jul 25 09:34:38 security kernel: [    0.027664] UDP hash table
entries:
> 256 (order: 1, 8192 bytes)
> Jul 25 09:34:38 security kernel: [    0.027664] UDP-Lite hash table
> entries: 256 (order: 1, 8192 bytes)
> Jul 25 09:34:38 security kernel: [    0.027664] NET: Registered
protocol
> family 1
> Jul 25 09:34:38 security kernel: [    0.027664] PCI: CLS 0 bytes,
default
> 64
> Jul 25 09:34:38 security kernel: [    0.027664] Trying to unpack
rootfs
> image as initramfs...
> Jul 25 09:34:38 security kernel: [    0.041042] Freeing initrd memory:
> 19808k freed
> Jul 25 09:34:38 security kernel: [    0.046478] PCI-DMA: Using
software
> bounce buffering for IO (SWIOTLB)
> Jul 25 09:34:38 security kernel: [    0.046488] Placing 64MB software
IO
> TLB between ffff880003d00000 - ffff880007d00000
> Jul 25 09:34:38 security kernel: [    0.046493] software IO TLB at
phys
> 0x3d00000 - 0x7d00000
> Jul 25 09:34:38 security kernel: [    0.046820] platform rtc_cmos:
> registered platform RTC device (no PNP device found)
> Jul 25 09:34:38 security kernel: [    0.047473] audit: initializing
> netlink socket (disabled)
> Jul 25 09:34:38 security kernel: [    0.047485] type=2000
> audit(1280043266.562:1): initialized
> Jul 25 09:34:38 security kernel: [    0.047738] VFS: Disk quotas
> dquot_6.5.2
> Jul 25 09:34:38 security kernel: [    0.047765] Dquot-cache hash table
> entries: 512 (order 0, 4096 bytes)
> Jul 25 09:34:38 security kernel: [    0.048082] NILFS version 2 loaded
> Jul 25 09:34:38 security kernel: [    0.048664] Btrfs loaded
> Jul 25 09:34:38 security kernel: [    0.048699] msgmni has been set to
858
> Jul 25 09:34:38 security kernel: [    0.048977] alg: No test for
stdrng
> (krng)
> Jul 25 09:34:38 security kernel: [    0.049048] Block layer SCSI
generic
> (bsg) driver version 0.4 loaded (major 253)
> Jul 25 09:34:38 security kernel: [    0.049053] io scheduler noop
> registered
> Jul 25 09:34:38 security kernel: [    0.049056] io scheduler deadline
> registered
> Jul 25 09:34:38 security kernel: [    0.049077] io scheduler cfq
> registered (default)
> Jul 25 09:34:38 security kernel: [    0.049263] pci-stub: invalid id
> string ""
> Jul 25 09:34:38 security kernel: [    0.052814] Event-channel device
> installed.
> Jul 25 09:34:38 security kernel: [    0.053546] pcifront pci-0:
Installing
> PCI frontend
> Jul 25 09:34:38 security kernel: [    0.053629] pcifront pci-0:
Creating
> PCI Frontend Bus 0000:04
> Jul 25 09:34:38 security kernel: [    0.160749] pci 0000:04:00.0: reg
10:
> [mem 0xf9700000-0xf9701fff 64bit]
> Jul 25 09:34:38 security kernel: [    0.160855] Linux agpgart
interface
> v0.103
> Jul 25 09:34:38 security kernel: [    0.160903] Serial: 8250/16550
driver,
> 4 ports, IRQ sharing enabled
> Jul 25 09:34:38 security kernel: [    0.162326] pcifront pci-0:
claiming
> resource 0000:04:00.0/0
> Jul 25 09:34:38 security kernel: [    0.162470] pcifront pci-0:
Creating
> PCI Frontend Bus 0000:0a
> Jul 25 09:34:38 security kernel: [    0.162494] brd: module loaded
> Jul 25 09:34:38 security kernel: [    0.162661] pci 0000:0a:01.0: reg
10:
> [mem 0xf9b00000-0xf9b00fff]
> Jul 25 09:34:38 security kernel: [    0.162951] pci 0000:0a:01.0:
supports
> D1 D2
> Jul 25 09:34:38 security kernel: [    0.163121] pci 0000:0a:01.1: reg
10:
> [mem 0xf9b01000-0xf9b01fff]
> Jul 25 09:34:38 security kernel: [    0.163401] pci 0000:0a:01.1:
supports
> D1 D2
> Jul 25 09:34:38 security kernel: [    0.163562] pci 0000:0a:01.2: reg
10:
> [mem 0xf9b02000-0xf9b02fff]
> Jul 25 09:34:38 security kernel: [    0.163819] pci 0000:0a:01.2:
supports
> D1 D2
> Jul 25 09:34:38 security kernel: [    0.164323] pcifront pci-0:
claiming
> resource 0000:0a:01.0/0
> Jul 25 09:34:38 security kernel: [    0.164323] pcifront pci-0:
claiming
> resource 0000:0a:01.1/0
> Jul 25 09:34:38 security kernel: [    0.164323] pcifront pci-0:
claiming
> resource 0000:0a:01.2/0
> Jul 25 09:34:38 security kernel: [    0.182850] e1000: Intel(R)
PRO/1000
> Network Driver - version 7.3.21-k6-NAPI
> Jul 25 09:34:38 security kernel: [    0.182858] e1000: Copyright (c)
1999-
> 2006 Intel Corporation.
> Jul 25 09:34:38 security kernel: [    0.182903] e1000e: Intel(R)
PRO/1000
> Network Driver - 1.0.2-k4
> Jul 25 09:34:38 security kernel: [    0.182907] e1000e: Copyright (c)
1999
> - 2009 Intel Corporation.
> Jul 25 09:34:38 security kernel: [    0.182952] Intel(R) Gigabit
Ethernet
> Network Driver - version 2.1.0-k2
> Jul 25 09:34:38 security kernel: [    0.182956] Copyright (c)
2007-2009
> Intel Corporation.
> Jul 25 09:34:38 security kernel: [    0.183039] Initialising Xen
virtual
> ethernet driver.
> Jul 25 09:34:38 security kernel: [    0.189923] blkfront: xvda1:
barriers
> enabled
> Jul 25 09:34:38 security kernel: [    0.193938] tun: Universal TUN/TAP
> device driver, 1.6
> Jul 25 09:34:38 security kernel: [    0.193945] tun: (C) 1999-2004 Max
> Krasnyansky <maxk@xxxxxxxxxxxx>
> Jul 25 09:34:38 security kernel: [    0.194089] ehci_hcd: USB 2.0
> 'Enhanced' Host Controller (EHCI) Driver
> Jul 25 09:34:38 security kernel: [    0.194202] ehci_hcd 0000:0a:01.2:
> enabling device (0000 -> 0002)
> Jul 25 09:34:38 security kernel: [    0.194409] ehci_hcd 0000:0a:01.2:
Xen
> PCI enabling IRQ: 46
> Jul 25 09:34:38 security kernel: [    0.194496] ehci_hcd 0000:0a:01.2:
> EHCI Host Controller
> Jul 25 09:34:38 security kernel: [    0.194626] ehci_hcd 0000:0a:01.2:
new
> USB bus registered, assigned bus number 1
> Jul 25 09:34:38 security kernel: [    0.194762] ehci_hcd 0000:0a:01.2:
> Enabling legacy PCI PM
> Jul 25 09:34:38 security kernel: [    0.216199] ehci_hcd 0000:0a:01.2:
irq
> 46, io mem 0xf9b02000
> Jul 25 09:34:38 security kernel: [    0.217198] blkfront: xvda2:
barriers
> enabled
> Jul 25 09:34:38 security kernel: [    0.217978] blkfront: xvdb1:
barriers
> enabled
> Jul 25 09:34:38 security kernel: [    0.228108] ehci_hcd 0000:0a:01.2:
USB
> 2.0 started, EHCI 1.00
> Jul 25 09:34:38 security kernel: [    0.228209] usb usb1: New USB
device
> found, idVendor=1d6b, idProduct=0002
> Jul 25 09:34:38 security kernel: [    0.228228] usb usb1: New USB
device
> strings: Mfr=3, Product=2, SerialNumber=1
> Jul 25 09:34:38 security kernel: [    0.228247] usb usb1: Product:
EHCI
> Host Controller
> Jul 25 09:34:38 security kernel: [    0.228262] usb usb1:
Manufacturer:
> Linux 2.6.35-rc6+xen-konrad-swiotlb-xhci-isoc+ ehci_hcd
> Jul 25 09:34:38 security kernel: [    0.228280] usb usb1:
SerialNumber:
> 0000:0a:01.2
> Jul 25 09:34:38 security kernel: [    0.228476] hub 1-0:1.0: USB hub
found
> Jul 25 09:34:38 security kernel: [    0.228493] hub 1-0:1.0: 5 ports
> detected
> Jul 25 09:34:38 security kernel: [    0.228629] ohci_hcd: USB 1.1
'Open'
> Host Controller (OHCI) Driver
> Jul 25 09:34:38 security kernel: [    0.228688] ohci_hcd 0000:0a:01.0:
> enabling device (0000 -> 0002)
> Jul 25 09:34:38 security kernel: [    0.228888] ohci_hcd 0000:0a:01.0:
Xen
> PCI enabling IRQ: 44
> Jul 25 09:34:38 security kernel: [    0.228945] ohci_hcd 0000:0a:01.0:
> OHCI Host Controller
> Jul 25 09:34:38 security kernel: [    0.229054] ohci_hcd 0000:0a:01.0:
new
> USB bus registered, assigned bus number 2
> Jul 25 09:34:38 security kernel: [    0.229097] ohci_hcd 0000:0a:01.0:
irq
> 44, io mem 0xf9b00000
> Jul 25 09:34:38 security kernel: [    0.313977] usb usb2: New USB
device
> found, idVendor=1d6b, idProduct=0001
> Jul 25 09:34:38 security kernel: [    0.314003] usb usb2: New USB
device
> strings: Mfr=3, Product=2, SerialNumber=1
> Jul 25 09:34:38 security kernel: [    0.314021] usb usb2: Product:
OHCI
> Host Controller
> Jul 25 09:34:38 security kernel: [    0.314034] usb usb2:
Manufacturer:
> Linux 2.6.35-rc6+xen-konrad-swiotlb-xhci-isoc+ ohci_hcd
> Jul 25 09:34:38 security kernel: [    0.314055] usb usb2:
SerialNumber:
> 0000:0a:01.0
> Jul 25 09:34:38 security kernel: [    0.314567] hub 2-0:1.0: USB hub
found
> Jul 25 09:34:38 security kernel: [    0.314598] hub 2-0:1.0: 3 ports
> detected
> Jul 25 09:34:38 security kernel: [    0.315103] ohci_hcd 0000:0a:01.1:
> enabling device (0000 -> 0002)
> Jul 25 09:34:38 security kernel: [    0.315553] ohci_hcd 0000:0a:01.1:
Xen
> PCI enabling IRQ: 45
> Jul 25 09:34:38 security kernel: [    0.315701] ohci_hcd 0000:0a:01.1:
> OHCI Host Controller
> Jul 25 09:34:38 security kernel: [    0.315931] ohci_hcd 0000:0a:01.1:
new
> USB bus registered, assigned bus number 3
> Jul 25 09:34:38 security kernel: [    0.316086] ohci_hcd 0000:0a:01.1:
irq
> 45, io mem 0xf9b01000
> Jul 25 09:34:38 security kernel: [    0.401923] usb usb3: New USB
device
> found, idVendor=1d6b, idProduct=0001
> Jul 25 09:34:38 security kernel: [    0.401930] usb usb3: New USB
device
> strings: Mfr=3, Product=2, SerialNumber=1
> Jul 25 09:34:38 security kernel: [    0.401934] usb usb3: Product:
OHCI
> Host Controller
> Jul 25 09:34:38 security kernel: [    0.401937] usb usb3:
Manufacturer:
> Linux 2.6.35-rc6+xen-konrad-swiotlb-xhci-isoc+ ohci_hcd
> Jul 25 09:34:38 security kernel: [    0.401941] usb usb3:
SerialNumber:
> 0000:0a:01.1
> Jul 25 09:34:38 security kernel: [    0.402085] hub 3-0:1.0: USB hub
found
> Jul 25 09:34:38 security kernel: [    0.402094] hub 3-0:1.0: 2 ports
> detected
> Jul 25 09:34:38 security kernel: [    0.402201] uhci_hcd: USB
Universal
> Host Controller Interface driver
> Jul 25 09:34:38 security kernel: [    0.402305] xhci_hcd 0000:04:00.0:
> enabling device (0000 -> 0002)
> Jul 25 09:34:38 security kernel: [    0.402511] xhci_hcd 0000:04:00.0:
Xen
> PCI enabling IRQ: 40
> Jul 25 09:34:38 security kernel: [    0.402603] xhci_hcd 0000:04:00.0:
> setting latency timer to 64
> Jul 25 09:34:38 security kernel: [    0.402615] xhci_hcd 0000:04:00.0:
> xHCI Host Controller
> Jul 25 09:34:38 security kernel: [    0.402745] xhci_hcd 0000:04:00.0:
new
> USB bus registered, assigned bus number 4
> Jul 25 09:34:38 security kernel: [    0.402917] xhci_hcd 0000:04:00.0:
irq
> 40, io mem 0xf9700000
> Jul 25 09:34:38 security kernel: [    0.406250] usb usb4: No
SuperSpeed
> endpoint companion for config 1  interface 0 altsetting 0 ep 129:
using
> minimum values
> Jul 25 09:34:38 security kernel: [    0.406268] usb usb4: New USB
device
> found, idVendor=1d6b, idProduct=0003
> Jul 25 09:34:38 security kernel: [    0.406272] usb usb4: New USB
device
> strings: Mfr=3, Product=2, SerialNumber=1
> Jul 25 09:34:38 security kernel: [    0.406276] usb usb4: Product:
xHCI
> Host Controller
> Jul 25 09:34:38 security kernel: [    0.406279] usb usb4:
Manufacturer:
> Linux 2.6.35-rc6+xen-konrad-swiotlb-xhci-isoc+ xhci_hcd
> Jul 25 09:34:38 security kernel: [    0.406283] usb usb4:
SerialNumber:
> 0000:04:00.0
> Jul 25 09:34:38 security kernel: [    0.406356] xHCI xhci_add_endpoint
> called for root hub
> Jul 25 09:34:38 security kernel: [    0.406358] xHCI
xhci_check_bandwidth
> called for root hub
> Jul 25 09:34:38 security kernel: [    0.406405] hub 4-0:1.0: USB hub
found
> Jul 25 09:34:38 security kernel: [    0.406411] hub 4-0:1.0: 4 ports
> detected
> Jul 25 09:34:38 security kernel: [    0.406679] PNP: No PS/2
controller
> found. Probing ports directly.
> Jul 25 09:34:38 security kernel: [    0.407496] i8042.c: No controller
> found.
> Jul 25 09:34:38 security kernel: [    0.407617] mice: PS/2 mouse
device
> common for all mice
> Jul 25 09:34:38 security kernel: [    0.407785] rtc_cmos rtc_cmos: rtc
> core: registered rtc_cmos as rtc0
> Jul 25 09:34:38 security kernel: [    0.407842] rtc_cmos: probe of
> rtc_cmos failed with error -38
> Jul 25 09:34:38 security kernel: [    0.408085] IR NEC protocol
handler
> initialized
> Jul 25 09:34:38 security kernel: [    0.408091] IR RC5(x) protocol
handler
> initialized
> Jul 25 09:34:38 security kernel: [    0.408095] IR RC6 protocol
handler
> initialized
> Jul 25 09:34:38 security kernel: [    0.408098] IR JVC protocol
handler
> initialized
> Jul 25 09:34:38 security kernel: [    0.408101] IR Sony protocol
handler
> initialized
> Jul 25 09:34:38 security kernel: [    0.408264] device-mapper: ioctl:
> 4.17.0-ioctl (2010-03-05) initialised: dm-devel@xxxxxxxxxx
> Jul 25 09:34:38 security kernel: [    0.408358] device-mapper:
multipath:
> version 1.1.1 loaded
> Jul 25 09:34:38 security kernel: [    0.408371] device-mapper:
multipath
> round-robin: version 1.0.0 loaded
> Jul 25 09:34:38 security kernel: [    0.408541] cpuidle: using
governor
> ladder
> Jul 25 09:34:38 security kernel: [    0.408548] No iBFT detected.
> Jul 25 09:34:38 security kernel: [    0.409610] usbcore: registered
new
> interface driver hiddev
> Jul 25 09:34:38 security kernel: [    0.409657] usbcore: registered
new
> interface driver usbhid
> Jul 25 09:34:38 security kernel: [    0.409661] usbhid: USB HID core
> driver
> Jul 25 09:34:38 security kernel: [    0.409798] TCP cubic registered
> Jul 25 09:34:38 security kernel: [    0.409803] NET: Registered
protocol
> family 17
> Jul 25 09:34:38 security kernel: [    0.409831] Bridge firewalling
> registered
> Jul 25 09:34:38 security kernel: [    0.409980] registered taskstats
> version 1
> Jul 25 09:34:38 security kernel: [    0.410247] XENBUS: Device with no
> driver: device/console/0
> Jul 25 09:34:38 security kernel: [    0.410261] drivers/rtc/hctosys.c:
> unable to open rtc device (rtc0)
> Jul 25 09:34:38 security kernel: [    0.410335] Freeing unused kernel
> memory: 496k freed
> Jul 25 09:34:38 security kernel: [    0.548108] usb 1-3: new high
speed
> USB device using ehci_hcd and address 2
> Jul 25 09:34:38 security kernel: [    0.682066] usb 1-3: New USB
device
> found, idVendor=05e3, idProduct=0608
> Jul 25 09:34:38 security kernel: [    0.682080] usb 1-3: New USB
device
> strings: Mfr=0, Product=1, SerialNumber=0
> Jul 25 09:34:38 security kernel: [    0.682088] usb 1-3: Product:
USB2.0
> Hub
> Jul 25 09:34:38 security kernel: [    0.682414] hub 1-3:1.0: USB hub
found
> Jul 25 09:34:38 security kernel: [    0.682944] hub 1-3:1.0: 4 ports
> detected
> Jul 25 09:34:38 security kernel: [    1.000020] usb 3-2: new low speed
USB
> device using ohci_hcd and address 2
> Jul 25 09:34:38 security kernel: [    1.052858] EXT3-fs: barriers not
> enabled
> Jul 25 09:34:38 security kernel: [    1.066961] kjournald starting.
> Commit interval 5 seconds
> Jul 25 09:34:38 security kernel: [    1.066983] EXT3-fs (xvda2):
mounted
> filesystem with writeback data mode
> Jul 25 09:34:38 security kernel: [    1.172116] usb 3-2: New USB
device
> found, idVendor=10cf, idProduct=5500
> Jul 25 09:34:38 security kernel: [    1.172145] usb 3-2: New USB
device
> strings: Mfr=1, Product=2, SerialNumber=0
> Jul 25 09:34:38 security kernel: [    1.172165] usb 3-2: Product: USB
> K8055
> Jul 25 09:34:38 security kernel: [    1.172178] usb 3-2: Manufacturer:
> Velleman
> Jul 25 09:34:38 security kernel: [    1.188285] generic-usb
> 0003:10CF:5500.0001: hiddev96,hidraw0: USB HID v1.00 Device [Velleman
USB
> K8055] on usb-0000:0a:01.1-2/input0
> Jul 25 09:34:38 security kernel: [    1.300186] usb 4-3: new high
speed
> USB device using xhci_hcd and address 0
> Jul 25 09:34:38 security kernel: [    1.318069] usb 4-3: New USB
device
> found, idVendor=eb1a, idProduct=2860
> Jul 25 09:34:38 security kernel: [    1.318102] usb 4-3: New USB
device
> strings: Mfr=0, Product=0, SerialNumber=0
> Jul 25 09:34:38 security kernel: [    1.318315] usb 4-3: ep 0x81 -
> rounding interval to 1024 microframes
> Jul 25 09:34:38 security kernel: [    1.318372] usb 4-3: ep 0x82 -
> rounding interval to 1 microframes
> Jul 25 09:34:38 security kernel: [    1.318437] usb 4-3: ep 0x84 -
> rounding interval to 1 microframes
> Jul 25 09:34:38 security kernel: [    1.392482] usb 1-3.4: new high
speed
> USB device using ehci_hcd and address 4
> Jul 25 09:34:38 security kernel: [    1.491787] usb 1-3.4: New USB
device
> found, idVendor=2304, idProduct=0208
> Jul 25 09:34:38 security kernel: [    1.491817] usb 1-3.4: New USB
device
> strings: Mfr=2, Product=1, SerialNumber=0
> Jul 25 09:34:38 security kernel: [    1.491838] usb 1-3.4: Product:
PCTV
> USB2 PAL
> Jul 25 09:34:38 security kernel: [    1.491852] usb 1-3.4:
Manufacturer:
> Pinnacle Systems GmbH
> Jul 25 09:34:38 security kernel: [    2.794873] udev: starting version
149
> Jul 25 09:34:38 security kernel: [    3.370948] input: PC Speaker as
> /devices/platform/pcspkr/input/input0
> Jul 25 09:34:38 security kernel: [    3.594660] Linux video capture
> interface: v2.00
> Jul 25 09:34:38 security kernel: [    3.642783] em28xx: New device @
480
> Mbps (eb1a:2860, interface 0, class 0)
> Jul 25 09:34:38 security kernel: [    3.643288] em28xx #0: chip ID is
> em2860
> Jul 25 09:34:38 security kernel: [    3.737119] em28xx #0: board has
no
> eeprom
> Jul 25 09:34:38 security kernel: [    3.748852] em28xx #0: Identified
as
> Unknown EM2750/28xx video grabber (card=1)
> Jul 25 09:34:38 security kernel: [    3.778089] em28xx #0: found i2c
> device @ 0x4a [saa7113h]
> Jul 25 09:34:38 security kernel: [    3.847003] em28xx #0: Your board
has
> no unique USB ID.
> Jul 25 09:34:38 security kernel: [    3.847030] em28xx #0: A hint were
> successfully done, based on i2c devicelist hash.
> Jul 25 09:34:38 security kernel: [    3.847051] em28xx #0: This method
is
> not 100% failproof.
> Jul 25 09:34:38 security kernel: [    3.847066] em28xx #0: If the
board
> were missdetected, please email this log to:
> Jul 25 09:34:38 security kernel: [    3.847083] em28xx #0:      V4L
> Mailing List  <linux-media@xxxxxxxxxxxxxxx>
> Jul 25 09:34:38 security kernel: [    3.847098] em28xx #0: Board
detected
> as EM2860/SAA711X Reference Design
> Jul 25 09:34:38 security kernel: [    3.847114] em28xx #0: Registering
> snapshot button...
> Jul 25 09:34:38 security kernel: [    3.847305] input: em28xx snapshot
> button as /devices/pci-0/pci0000:04/0000:04:00.0/usb4/4-3/input/input1
> Jul 25 09:34:38 security kernel: [    4.229033] saa7115 0-0025:
saa7113
> found (1f7113d0e100000) @ 0x4a (em28xx #0)
> Jul 25 09:34:38 security kernel: [    4.936556] em28xx #0: Config
register
> raw data: 0x00
> Jul 25 09:34:38 security kernel: [    4.984128] em28xx #0: v4l2 driver
> version 0.1.2
> Jul 25 09:34:38 security kernel: [    4.984182] usb 4-3: ep 0x81 -
> rounding interval to 1024 microframes
> Jul 25 09:34:38 security kernel: [    4.984277] usb 4-3: ep 0x82 -
> rounding interval to 1 microframes
> Jul 25 09:34:38 security kernel: [    4.984366] usb 4-3: ep 0x84 -
> rounding interval to 1 microframes
> Jul 25 09:34:38 security kernel: [    5.476289] em28xx #0: V4L2 video
> device registered as video0
> Jul 25 09:34:38 security kernel: [    5.476319] em28xx #0: V4L2 VBI
device
> registered as vbi0
> Jul 25 09:34:38 security kernel: [    5.476363] em28xx: New device
> Pinnacle Systems GmbH PCTV USB2 PAL @ 480 Mbps (2304:0208, interface
0,
> class 0)
> Jul 25 09:34:38 security kernel: [    5.480830] em28xx #1: chip ID is
> em2820 (or em2710)
> Jul 25 09:34:38 security kernel: [    5.612753] em28xx #1: i2c eeprom
00:
> 1a eb 67 95 04 23 08 02 10 00 1e 03 98 1e 6a 2e
> Jul 25 09:34:38 security kernel: [    5.612849] em28xx #1: i2c eeprom
10:
> 00 00 06 57 6e 00 00 00 8e 00 00 00 07 00 00 00
> Jul 25 09:34:38 security kernel: [    5.612927] em28xx #1: i2c eeprom
20:
> 16 00 01 00 00 00 00 00 00 00 00 00 00 00 00 00
> Jul 25 09:34:38 security kernel: [    5.612997] em28xx #1: i2c eeprom
30:
> 00 00 20 40 20 80 02 20 10 01 00 00 00 00 00 00
> Jul 25 09:34:38 security kernel: [    5.613065] em28xx #1: i2c eeprom
40:
> 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
> Jul 25 09:34:38 security kernel: [    5.613133] em28xx #1: i2c eeprom
50:
> 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
> Jul 25 09:34:38 security kernel: [    5.613201] em28xx #1: i2c eeprom
60:
> 00 00 00 00 00 00 00 00 00 00 2e 03 50 00 69 00
> Jul 25 09:34:38 security kernel: [    5.613268] em28xx #1: i2c eeprom
70:
> 6e 00 6e 00 61 00 63 00 6c 00 65 00 20 00 53 00
> Jul 25 09:34:38 security kernel: [    5.613336] em28xx #1: i2c eeprom
80:
> 79 00 73 00 74 00 65 00 6d 00 73 00 20 00 47 00
> Jul 25 09:34:38 security kernel: [    5.613404] em28xx #1: i2c eeprom
90:
> 6d 00 62 00 48 00 00 00 1e 03 50 00 43 00 54 00
> Jul 25 09:34:38 security kernel: [    5.613492] em28xx #1: i2c eeprom
a0:
> 56 00 20 00 55 00 53 00 42 00 32 00 20 00 50 00
> Jul 25 09:34:38 security kernel: [    5.613560] em28xx #1: i2c eeprom
b0:
> 41 00 4c 00 00 00 06 03 31 00 00 00 00 00 00 00
> Jul 25 09:34:38 security kernel: [    5.613628] em28xx #1: i2c eeprom
c0:
> 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
> Jul 25 09:34:38 security kernel: [    5.613694] em28xx #1: i2c eeprom
d0:
> 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
> Jul 25 09:34:38 security kernel: [    5.613762] em28xx #1: i2c eeprom
e0:
> 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
> Jul 25 09:34:38 security kernel: [    5.613829] em28xx #1: i2c eeprom
f0:
> 00 00 00 00 00 00 00 00 07 56 d9 35 01 ed 0b f8
> Jul 25 09:34:38 security kernel: [    5.613900] em28xx #1: EEPROM ID=
> 0x9567eb1a, EEPROM hash = 0x0fd77740
> Jul 25 09:34:38 security kernel: [    5.613915] em28xx #1: EEPROM
info:
> Jul 25 09:34:38 security kernel: [    5.613925] em28xx #1:      AC97
audio
> (5 sample rates)
> Jul 25 09:34:38 security kernel: [    5.613937] em28xx #1:      500mA
max
> power
> Jul 25 09:34:38 security kernel: [    5.613950] em28xx #1:      Table
at
> 0x06, strings=0x1e98, 0x2e6a, 0x0000
> Jul 25 09:34:38 security kernel: [    5.615641] em28xx #1: Identified
as
> Pinnacle PCTV USB 2 (card=3)
> Jul 25 09:34:38 security kernel: [    5.637877] Registered IR keymap
rc-
> pinnacle-grey
> Jul 25 09:34:38 security kernel: [    5.638119] input: i2c IR (i2c IR
> (EM28XX Pinnacle as /devices/virtual/rc/rc0/input2
> Jul 25 09:34:38 security kernel: [    5.638206] rc0: i2c IR (i2c IR
> (EM28XX Pinnacle as /devices/virtual/rc/rc0
> Jul 25 09:34:38 security kernel: [    5.638225] ir-kbd-i2c: i2c IR
(i2c IR
> (EM28XX Pinnacle detected at i2c-1/1-0047/ir0 [em28xx #1]
> Jul 25 09:34:38 security kernel: [    5.640361] i2c IR (i2c IR (EM28XX
> Pinnacle: unknown key: key=0x00 down=1
> Jul 25 09:34:38 security kernel: [    6.000831] saa7115 1-0025:
saa7113
> found (1f7113d0e100000) @ 0x4a (em28xx #1)
> Jul 25 09:34:38 security kernel: [    6.840304] tuner 1-0043: chip
found @
> 0x86 (em28xx #1)
> Jul 25 09:34:38 security kernel: [    6.857908] tda9887 1-0043:
creating
> new instance
> Jul 25 09:34:38 security kernel: [    6.857937] tda9887 1-0043:
> tda988[5/6/7] found
> Jul 25 09:34:38 security kernel: [    6.871621] tuner 1-0063: chip
found @
> 0xc6 (em28xx #1)
> Jul 25 09:34:38 security kernel: [    6.878195] tuner-simple 1-0063:
> creating new instance
> Jul 25 09:34:38 security kernel: [    6.878203] tuner-simple 1-0063:
type
> set to 37 (LG PAL (newer TAPC series))
> Jul 25 09:34:38 security kernel: [    6.924352] em28xx #1: Config
register
> raw data: 0x10
> Jul 25 09:34:38 security kernel: [    6.948241] em28xx #1: AC97 vendor
ID
> = 0xffffffff
> Jul 25 09:34:38 security kernel: [    6.960341] em28xx #1: AC97
features =
> 0x6a90
> Jul 25 09:34:38 security kernel: [    6.960363] em28xx #1: Empia 202
AC97
> audio processor detected
> Jul 25 09:34:38 security kernel: [    7.420113] em28xx #1: v4l2 driver
> version 0.1.2
> Jul 25 09:34:38 security kernel: [    8.252296] em28xx #1: V4L2 video
> device registered as video1
> Jul 25 09:34:38 security kernel: [    8.264214] em28xx audio device
> (2304:0208): interface 1, class 1
> Jul 25 09:34:38 security kernel: [    8.264258] em28xx audio device
> (2304:0208): interface 2, class 1
> Jul 25 09:34:38 security kernel: [    8.264335] usbcore: registered
new
> interface driver em28xx
> Jul 25 09:34:38 security kernel: [    8.264353] em28xx driver loaded
> Jul 25 09:34:38 security kernel: [    8.413501] 4:2:1: endpoint lacks
> sample rate attribute bit, cannot set.
> Jul 25 09:34:38 security kernel: [    8.413618] 4:2:2: endpoint lacks
> sample rate attribute bit, cannot set.
> Jul 25 09:34:38 security kernel: [    8.413738] 4:2:3: endpoint lacks
> sample rate attribute bit, cannot set.
> Jul 25 09:34:38 security kernel: [    8.413859] 4:2:4: endpoint lacks
> sample rate attribute bit, cannot set.
> Jul 25 09:34:38 security kernel: [    8.413981] 4:2:5: endpoint lacks
> sample rate attribute bit, cannot set.
> Jul 25 09:34:38 security kernel: [    8.417772] usbcore: registered
new
> interface driver snd-usb-audio
> Jul 25 09:34:38 security kernel: [    8.732176] Adding 524284k swap on
> /dev/xvda1.  Priority:-1 extents:1 across:524284k SS
> Jul 25 09:34:38 security kernel: [    8.899118] EXT3-fs (xvda2): using
> internal journal
> Jul 25 09:34:38 security kernel: [    9.996762] fuse init (API version
> 7.14)
> Jul 25 09:34:38 security kernel: [   10.112740] EXT3-fs: barriers not
> enabled
> Jul 25 09:34:38 security kernel: [   10.121746] kjournald starting.
> Commit interval 5 seconds
> Jul 25 09:34:38 security kernel: [   10.137752] EXT3-fs (xvdb1): using
> internal journal
> Jul 25 09:34:38 security kernel: [   10.137793] EXT3-fs (xvdb1):
mounted
> filesystem with journal data mode
> Jul 25 09:34:39 security nullmailer[1484]: Rescanning queue.
> Jul 25 09:34:40 security /usr/sbin/cron[1541]: (CRON) INFO (pidfile fd
= 3)
> Jul 25 09:34:40 security /usr/sbin/cron[1542]: (CRON) STARTUP (fork
ok)
> Jul 25 09:34:40 security /usr/sbin/cron[1542]: (CRON) INFO (Running
> @reboot jobs)
> Jul 25 09:34:40 security ntpdate[1370]: no server suitable for
> synchronization found
> Jul 25 09:34:41 security motion: [0] Processing thread 0 - config file
> /etc/motion/motion.conf
> Jul 25 09:34:41 security motion: [0] Processing config file
> /etc/motion/thread1.conf
> Jul 25 09:34:41 security motion: [0] Unknown config option
> "night_compensate"
> Jul 25 09:34:41 security motion: [0] Unknown config option "low_cpu"
> Jul 25 09:34:41 security motion: [0] Processing config file
> /etc/motion/thread2.conf
> Jul 25 09:34:41 security motion: [0] Unknown config option
> "night_compensate"
> Jul 25 09:34:41 security motion: [0] Unknown config option "low_cpu"
> Jul 25 09:34:41 security motion: [0] Motion 3.2.11 Started
> Jul 25 09:34:41 security motion: [0] Created process id file
> /var/run/motion/motion.pid. Process ID is 1556
> Jul 25 09:34:41 security motion: [0] Motion running as daemon process
> Jul 25 09:34:41 security motion: [0] ffmpeg LIBAVCODEC_BUILD 3412992
> LIBAVFORMAT_BUILD 3415808
> Jul 25 09:34:41 security motion: [0] Thread 1 is from
> /etc/motion/thread1.conf
> Jul 25 09:34:41 security motion: [0] Thread 2 is from
> /etc/motion/thread2.conf
> Jul 25 09:34:41 security motion: [1] Thread 1 started
> Jul 25 09:34:41 security motion: [2] Thread 2 started
> Jul 25 09:34:41 security motion: [1] cap.driver: "em28xx"
> Jul 25 09:34:41 security motion: [1] cap.card: "EM2860/SAA711X
Reference
> Design"
> Jul 25 09:34:41 security motion: [1] cap.bus_info:
"usb-0000:04:00.0-3"
> Jul 25 09:34:41 security motion: [1] cap.capabilities=0x05000051
> Jul 25 09:34:41 security motion: [1] - VIDEO_CAPTURE
> Jul 25 09:34:41 security motion: [1] - VBI_CAPTURE
> Jul 25 09:34:41 security motion: [1] - READWRITE
> Jul 25 09:34:41 security motion: [1] - STREAMING
> Jul 25 09:34:41 security motion: [1] Supported palettes:
> Jul 25 09:34:41 security motion: [1] 0: YUYV (16 bpp YUY2, 4:2:2,
packed)
> Jul 25 09:34:41 security motion: [1] Selected palette YUYV
> Jul 25 09:34:41 security motion: [1] index_format 6 Test palette YUYV
> (720x576)
> Jul 25 09:34:41 security motion: [1] Using palette YUYV (720x576)
> bytesperlines 1440 sizeimage 829440 colorspace 00000001
> Jul 25 09:34:41 security motion: [1] found control 0x00980900,
> "Brightness", range 0,255
> Jul 25 09:34:41 security motion: [1] #011"Brightness", default 128,
> current 128
> Jul 25 09:34:41 security motion: [1] found control 0x00980901,
"Contrast",
> range 0,127
> Jul 25 09:34:41 security motion: [1] #011"Contrast", default 64,
current
> 64
> Jul 25 09:34:41 security motion: [1] found control 0x00980902,
> "Saturation", range 0,127
> Jul 25 09:34:41 security motion: [1] #011"Saturation", default 64,
current
> 64
> Jul 25 09:34:41 security motion: [1] found control 0x00980903, "Hue",
> range -128,127
> Jul 25 09:34:41 security motion: [1] #011"Hue", default 0, current 0
> Jul 25 09:34:41 security motion: [1] mmap information:
> Jul 25 09:34:41 security motion: [1] frames=4
> Jul 25 09:34:41 security motion: [1] 0 length=829440
> Jul 25 09:34:41 security motion: [1] 1 length=829440
> Jul 25 09:34:41 security motion: [1] 2 length=829440
> Jul 25 09:34:41 security motion: [1] 3 length=829440
> Jul 25 09:34:42 security motion: [1] Resizing pre_capture buffer to 1
> items
> Jul 25 09:34:42 security motion: [2] cap.driver: "em28xx"
> Jul 25 09:34:42 security motion: [2] cap.card: "Pinnacle PCTV USB 2"
> Jul 25 09:34:42 security motion: [2] cap.bus_info:
"usb-0000:0a:01.2-3.4"
> Jul 25 09:34:42 security motion: [2] cap.capabilities=0x05030041
> Jul 25 09:34:42 security motion: [2] - VIDEO_CAPTURE
> Jul 25 09:34:42 security motion: [2] - TUNER
> Jul 25 09:34:42 security motion: [2] - AUDIO
> Jul 25 09:34:42 security motion: [2] - READWRITE
> Jul 25 09:34:42 security motion: [2] - STREAMING
> Jul 25 09:34:43 security motion: [2] Supported palettes:
> Jul 25 09:34:43 security motion: [2] 0: YUYV (16 bpp YUY2, 4:2:2,
packed)
> Jul 25 09:34:43 security motion: [2] Selected palette YUYV
> Jul 25 09:34:43 security motion: [2] index_format 6 Test palette YUYV
> (720x576)
> Jul 25 09:34:43 security motion: [2] Using palette YUYV (720x576)
> bytesperlines 1440 sizeimage 829440 colorspace 00000001
> Jul 25 09:34:43 security motion: [2] found control 0x00980900,
> "Brightness", range 0,255
> Jul 25 09:34:43 security motion: [2] #011"Brightness", default 128,
> current 128
> Jul 25 09:34:43 security motion: [2] found control 0x00980901,
"Contrast",
> range 0,127
> Jul 25 09:34:43 security motion: [2] #011"Contrast", default 64,
current
> 64
> Jul 25 09:34:43 security motion: [2] found control 0x00980902,
> "Saturation", range 0,127
> Jul 25 09:34:43 security motion: [2] #011"Saturation", default 64,
current
> 64
> Jul 25 09:34:43 security motion: [2] found control 0x00980903, "Hue",
> range -128,127
> Jul 25 09:34:43 security motion: [2] #011"Hue", default 0, current 0
> Jul 25 09:34:43 security motion: [2] mmap information:
> Jul 25 09:34:43 security motion: [2] frames=4
> Jul 25 09:34:43 security motion: [2] 0 length=829440
> Jul 25 09:34:43 security motion: [2] 1 length=829440
> Jul 25 09:34:43 security motion: [2] 2 length=829440
> Jul 25 09:34:43 security motion: [2] 3 length=829440
> Jul 25 09:34:43 security motion: [2] Resizing pre_capture buffer to 1
> items
> Jul 25 09:34:43 security motion: [1] Resizing pre_capture buffer to 15
> items
> Jul 25 09:34:43 security motion: [2] Resizing pre_capture buffer to 10
> items
> Jul 25 09:39:03 security /USR/SBIN/CRON[1784]: (root) CMD (  [ -x
> /usr/lib/php5/maxlifetime ] && [ -d /var/lib/php5 ] && find
/var/lib/php5/
> -type f -cmin +$(/usr/lib/php5/maxlifetime) -print0 | xargs -n 200 -r
-0
> rm)
> Jul 25 10:09:02 security /USR/SBIN/CRON[2848]: (root) CMD (  [ -x
> /usr/lib/php5/maxlifetime ] && [ -d /var/lib/php5 ] && find
/var/lib/php5/
> -type f -cmin +$(/usr/lib/php5/maxlifetime) -print0 | xargs -n 200 -r
-0
> rm)
> Jul 25 10:17:01 security /USR/SBIN/CRON[3138]: (root) CMD (   cd / &&
run-
> parts --report /etc/cron.hourly)
> Jul 25 10:39:01 security /USR/SBIN/CRON[3936]: (root) CMD (  [ -x
> /usr/lib/php5/maxlifetime ] && [ -d /var/lib/php5 ] && find
/var/lib/php5/
> -type f -cmin +$(/usr/lib/php5/maxlifetime) -print0 | xargs -n 200 -r
-0
> rm)
> Jul 25 11:09:01 security /USR/SBIN/CRON[5098]: (root) CMD (  [ -x
> /usr/lib/php5/maxlifetime ] && [ -d /var/lib/php5 ] && find
/var/lib/php5/
> -type f -cmin +$(/usr/lib/php5/maxlifetime) -print0 | xargs -n 200 -r
-0
> rm)
> Jul 25 11:17:01 security /USR/SBIN/CRON[5388]: (root) CMD (   cd / &&
run-
> parts --report /etc/cron.hourly)
> Jul 25 11:39:01 security /USR/SBIN/CRON[6320]: (root) CMD (  [ -x
> /usr/lib/php5/maxlifetime ] && [ -d /var/lib/php5 ] && find
/var/lib/php5/
> -type f -cmin +$(/usr/lib/php5/maxlifetime) -print0 | xargs -n 200 -r
-0
> rm)
> Jul 25 12:05:01 security kernel: [ 9035.162632] xhci_hcd 0000:04:00.0:
URB
> transfer length is wrong, xHC issue? req. len = 185088, act. len =
226540
> Jul 25 12:09:01 security /USR/SBIN/CRON[7430]: (root) CMD (  [ -x
> /usr/lib/php5/maxlifetime ] && [ -d /var/lib/php5 ] && find
/var/lib/php5/
> -type f -cmin +$(/usr/lib/php5/maxlifetime) -print0 | xargs -n 200 -r
-0
> rm)
> Jul 25 12:17:01 security /USR/SBIN/CRON[7741]: (root) CMD (   cd / &&
run-
> parts --report /etc/cron.hourly)
> Jul 25 12:30:01 security kernel: [10534.583184] xhci_hcd 0000:04:00.0:
URB
> transfer length is wrong, xHC issue? req. len = 185088, act. len =
186592
> Jul 25 12:39:01 security /USR/SBIN/CRON[8550]: (root) CMD (  [ -x
> /usr/lib/php5/maxlifetime ] && [ -d /var/lib/php5 ] && find
/var/lib/php5/
> -type f -cmin +$(/usr/lib/php5/maxlifetime) -print0 | xargs -n 200 -r
-0
> rm)
> Jul 25 12:47:43 security kernel: [11596.938025] xhci_hcd 0000:04:00.0:
> WARN: Stalled endpoint
> Jul 25 12:47:43 security kernel: [11596.952369] usb 3-2: usbfs:
> USBDEVFS_CONTROL failed cmd lsusb rqt 128 rq 6 len 255 ret -62
> Jul 25 13:09:02 security /USR/SBIN/CRON[9693]: (root) CMD (  [ -x
> /usr/lib/php5/maxlifetime ] && [ -d /var/lib/php5 ] && find
/var/lib/php5/
> -type f -cmin +$(/usr/lib/php5/maxlifetime) -print0 | xargs -n 200 -r
-0
> rm)
> Jul 25 13:17:01 security /USR/SBIN/CRON[10003]: (root) CMD (   cd / &&
> run-parts --report /etc/cron.hourly)
> Jul 25 13:39:01 security /USR/SBIN/CRON[10808]: (root) CMD (  [ -x
> /usr/lib/php5/maxlifetime ] && [ -d /var/lib/php5 ] && find
/var/lib/php5/
> -type f -cmin +$(/usr/lib/php5/maxlifetime) -print0 | xargs -n 200 -r
-0
> rm)
> Jul 25 13:40:01 security kernel: [14735.430879] xhci_hcd 0000:04:00.0:
URB
> transfer length is wrong, xHC issue? req. len = 185088, act. len =
186052
> Jul 25 14:00:01 security kernel: [15935.206060] xhci_hcd 0000:04:00.0:
URB
> transfer length is wrong, xHC issue? req. len = 185088, act. len =
244856
> Jul 25 14:09:01 security /USR/SBIN/CRON[11999]: (root) CMD (  [ -x
> /usr/lib/php5/maxlifetime ] && [ -d /var/lib/php5 ] && find
/var/lib/php5/
> -type f -cmin +$(/usr/lib/php5/maxlifetime) -print0 | xargs -n 200 -r
-0
> rm)
> Jul 25 14:17:01 security /USR/SBIN/CRON[12290]: (root) CMD (   cd / &&
> run-parts --report /etc/cron.hourly)
> Jul 25 14:39:01 security /USR/SBIN/CRON[13072]: (root) CMD (  [ -x
> /usr/lib/php5/maxlifetime ] && [ -d /var/lib/php5 ] && find
/var/lib/php5/
> -type f -cmin +$(/usr/lib/php5/maxlifetime) -print0 | xargs -n 200 -r
-0
> rm)
> Jul 25 14:55:01 security kernel: [19234.846628] xhci_hcd 0000:04:00.0:
URB
> transfer length is wrong, xHC issue? req. len = 185088, act. len =
186052
> Jul 25 15:09:01 security /USR/SBIN/CRON[14216]: (root) CMD (  [ -x
> /usr/lib/php5/maxlifetime ] && [ -d /var/lib/php5 ] && find
/var/lib/php5/
> -type f -cmin +$(/usr/lib/php5/maxlifetime) -print0 | xargs -n 200 -r
-0
> rm)
> Jul 25 15:17:01 security /USR/SBIN/CRON[14508]: (root) CMD (   cd / &&
> run-parts --report /etc/cron.hourly)
> Jul 25 15:39:01 security /USR/SBIN/CRON[15309]: (root) CMD (  [ -x
> /usr/lib/php5/maxlifetime ] && [ -d /var/lib/php5 ] && find
/var/lib/php5/
> -type f -cmin +$(/usr/lib/php5/maxlifetime) -print0 | xargs -n 200 -r
-0
> rm)
> Jul 25 15:50:01 security kernel: [22535.096640] xhci_hcd 0000:04:00.0:
URB
> transfer length is wrong, xHC issue? req. len = 185088, act. len =
186604
> Jul 25 15:55:58 security kernel: [22892.324302] xhci_hcd 0000:04:00.0:
URB
> transfer length is wrong, xHC issue? req. len = 185088, act. len =
187980
> Jul 25 16:05:01 security kernel: [23434.794827] xhci_hcd 0000:04:00.0:
URB
> transfer length is wrong, xHC issue? req. len = 185088, act. len =
287272
> Jul 25 16:09:01 security /USR/SBIN/CRON[16397]: (root) CMD (  [ -x
> /usr/lib/php5/maxlifetime ] && [ -d /var/lib/php5 ] && find
/var/lib/php5/
> -type f -cmin +$(/usr/lib/php5/maxlifetime) -print0 | xargs -n 200 -r
-0
> rm)
> Jul 25 16:17:01 security /USR/SBIN/CRON[16687]: (root) CMD (   cd / &&
> run-parts --report /etc/cron.hourly)
> Jul 25 16:39:02 security /USR/SBIN/CRON[17598]: (root) CMD (  [ -x
> /usr/lib/php5/maxlifetime ] && [ -d /var/lib/php5 ] && find
/var/lib/php5/
> -type f -cmin +$(/usr/lib/php5/maxlifetime) -print0 | xargs -n 200 -r
-0
> rm)
> Jul 25 16:40:01 security kernel: [25534.968523] xhci_hcd 0000:04:00.0:
URB
> transfer length is wrong, xHC issue? req. len = 185088, act. len =
187016
> Jul 25 16:50:01 security kernel: [26135.312245] xhci_hcd 0000:04:00.0:
> ERROR no room on ep ring
> Jul 25 16:50:01 security kernel: [26135.312312] xhci_hcd 0000:04:00.0:
> ERROR no room on ep ring
> Jul 25 16:50:01 security kernel: [26135.312367] xhci_hcd 0000:04:00.0:
> ERROR no room on ep ring
> Jul 25 16:50:01 security kernel: [26135.312423] xhci_hcd 0000:04:00.0:
> ERROR no room on ep ring
> Jul 25 16:50:01 security kernel: [26135.312480] xhci_hcd 0000:04:00.0:
> ERROR no room on ep ring
> Jul 25 16:50:01 security kernel: [26135.312489] xhci_hcd 0000:04:00.0:
> WARN Event TRB for slot 1 ep 4 with no TDs queued?
> Jul 25 16:50:31 security motion: [0] Thread 1 - Watchdog timeout,
trying
> to do a graceful restart
> Jul 25 16:51:31 security motion: [0] Thread 1 - Watchdog timeout, did
NOT
> restart graceful,killing it!
> Jul 25 16:51:31 security motion: [0] Calling vid_close() from
> motion_cleanup
> Jul 25 16:51:31 security motion: [0] Closing video device /dev/kworld
> Jul 25 16:54:26 security kernel: [26400.136138] INFO: task motion:1556
> blocked for more than 120 seconds.
> 
> 
> 
> 
> 
> 
> 
> 
> 
> 
> 
> 
> 
> 
> 
> 
> 

Attachment: isoc_length.patch
Description: isoc_length.patch


[Index of Archives]     [Linux Media]     [Linux Input]     [Linux Audio Users]     [Yosemite News]     [Linux Kernel]     [Linux SCSI]     [Old Linux USB Devel Archive]

  Powered by Linux