B+W
Posts: 7
Joined: Wed Mar 12, 2014 10:43 pm

Re: Call for beta testers: FIQ_FSM USB driver rewrite

Thu Mar 20, 2014 5:21 pm

The next branch has gone missing? Or am I missing something?

Forage
Posts: 5
Joined: Sun Dec 16, 2012 2:53 pm

Re: Call for beta testers: FIQ_FSM USB driver rewrite

Thu Mar 20, 2014 5:27 pm

jdb wrote:Update:

The BRANCH=next firmware now has a fix for one of the potential sources of the "FIQ reported NYET" messages seen when using a heavily loaded system. It may also fix cases where network devices suddenly stop responding.
Unfortunately no success. The network connection still drops after a while.

jdb
Raspberry Pi Engineer & Forum Moderator
Raspberry Pi Engineer & Forum Moderator
Posts: 2152
Joined: Thu Jul 11, 2013 2:37 pm

Re: Call for beta testers: FIQ_FSM USB driver rewrite

Thu Mar 20, 2014 6:53 pm

Forage wrote:
jdb wrote:Update:

The BRANCH=next firmware now has a fix for one of the potential sources of the "FIQ reported NYET" messages seen when using a heavily loaded system. It may also fix cases where network devices suddenly stop responding.
Unfortunately no success. The network connection still drops after a while.
What is your Pi being used as?

Can you post a full dmesg since boot?
Rockets are loud.
https://astro-pi.org

Forage
Posts: 5
Joined: Sun Dec 16, 2012 2:53 pm

Re: Call for beta testers: FIQ_FSM USB driver rewrite

Thu Mar 20, 2014 7:06 pm

jdb wrote:
Forage wrote:
jdb wrote:Update:

The BRANCH=next firmware now has a fix for one of the potential sources of the "FIQ reported NYET" messages seen when using a heavily loaded system. It may also fix cases where network devices suddenly stop responding.
Unfortunately no success. The network connection still drops after a while.
What is your Pi being used as?
I'm using MilhouseVH's 'daily' build of OpenElec, thus using it solely as an XBMC machine.
jdb wrote:Can you post a full dmesg since boot?
You mean the output of dmesg right after boot or as soon as the network connection is lost again?

jdb
Raspberry Pi Engineer & Forum Moderator
Raspberry Pi Engineer & Forum Moderator
Posts: 2152
Joined: Thu Jul 11, 2013 2:37 pm

Re: Call for beta testers: FIQ_FSM USB driver rewrite

Thu Mar 20, 2014 7:07 pm

The dmesg log since boot, right up to (and including) the time where things first go wrong.
Rockets are loud.
https://astro-pi.org

dsc3507
Posts: 40
Joined: Sun Apr 28, 2013 5:08 am

Re: Call for beta testers: FIQ_FSM USB driver rewrite

Thu Mar 20, 2014 10:36 pm

Ok I have a little more information. The output is to a CM119 DAC. Audio output is distorted but consistently distorted. It sounds like bits of the data are lost. It is not choppy. The audio is understandable but consistently distorted across the entire period. I have tried different codecs - I typically use g726aa12 but a lower rate gsm codec creates the same distortion. So sample rate does not seem to be a factor. This only happens when using usb 2.0 full speed. If I use usb 1.1 the audio is fine. This is independent of data on the Ethernet. Locally generated data or incoming Ethernet data have the same distortion. I get no error messages. The A/D side works fine. Somehow some data is being lost in USB 2.0 mode with this DAC. The DAC is spec'ed for and works fine in USB2.0 mode in other computers.

dsc3507
Posts: 40
Joined: Sun Apr 28, 2013 5:08 am

Re: Call for beta testers: FIQ_FSM USB driver rewrite

Fri Mar 21, 2014 3:27 am

Adding to the above I would clarify that the sound has a modulated effect. Maybe like it is being sampled at a lower rate and missing some data.

Is there anything I can do to check this further? Look at interupts, etc.?

B+W
Posts: 7
Joined: Wed Mar 12, 2014 10:43 pm

Re: Call for beta testers: FIQ_FSM USB driver rewrite

Fri Mar 21, 2014 2:32 pm

Sorry for mentioning this again, but am I the only one unable to redownload the BRANCH=next rpi-update?

https://github.com/Hexxeh/rpi-update is now missing the next branch and "sudo BRANCH=next rpi-update" is giving me this error:

Code: Select all

Unable to establish SSL connection.
 !!! Failed to download update for rpi-update!
 !!! Make sure you have ca-certificates installed and that the time is set correctly
ca-certificates is installed. Date and time is correct.

Thank you.

MrEngman
Posts: 3868
Joined: Fri Feb 03, 2012 2:17 pm
Location: Southampton, UK

Re: Call for beta testers: FIQ_FSM USB driver rewrite

Fri Mar 21, 2014 2:37 pm

B+W wrote:Sorry for mentioning this again, but am I the only one unable to redownload the BRANCH=next rpi-update?

https://github.com/Hexxeh/rpi-update is now missing the next branch and "sudo BRANCH=next rpi-update" is giving me this error:

Code: Select all

Unable to establish SSL connection.
 !!! Failed to download update for rpi-update!
 !!! Make sure you have ca-certificates installed and that the time is set correctly
ca-certificates is installed. Date and time is correct.

Thank you.
A load of people have come across this problem in the last few days. Take a look here for a fix. http://www.raspberrypi.org/phpBB3/viewt ... 19#p523519
Simplicity is a prerequisite for reliability. Edsger W. Dijkstra

Please post ALL technical questions on the forum. Please Do Not send private messages.

B+W
Posts: 7
Joined: Wed Mar 12, 2014 10:43 pm

Re: Call for beta testers: FIQ_FSM USB driver rewrite

Fri Mar 21, 2014 3:30 pm

MrEngman wrote: A load of people have come across this problem in the last few days. Take a look here for a fix. http://www.raspberrypi.org/phpBB3/viewt ... 19#p523519
Thank you for the reply. I don't think I've ever manually updated this way. How would this work for installing the next branch?

milhouse
Posts: 641
Joined: Mon Jan 16, 2012 12:59 pm

Re: Call for beta testers: FIQ_FSM USB driver rewrite

Sat Mar 22, 2014 8:50 am

jdb wrote:Update:

The BRANCH=next firmware now has a fix for one of the potential sources of the "FIQ reported NYET" messages seen when using a heavily loaded system. It may also fix cases where network devices suddenly stop responding.

Please test and report back any changes.
Just to let you know that after this fix I haven't seen any "FIQ reported NYET" messages (OpenELEC based on 3.13.y-next) - 2.5 days uptime and dmesg is 100% clear, excellent stuff! :)

Forage
Posts: 5
Joined: Sun Dec 16, 2012 2:53 pm

Re: Call for beta testers: FIQ_FSM USB driver rewrite

Sat Mar 22, 2014 9:53 am

jdb wrote:
Forage wrote:
jdb wrote:Update:

The BRANCH=next firmware now has a fix for one of the potential sources of the "FIQ reported NYET" messages seen when using a heavily loaded system. It may also fix cases where network devices suddenly stop responding.
Unfortunately no success. The network connection still drops after a while.
Can you post a full dmesg since boot?
Getting a log was easier said than done on OpenElec. To get it I used a script that created a log every 15 minutes.
Since your last update the network connection holds up much longer. Where it used to be a matter of 1-30 minutes, it now takes 4-10 hours. The dmesg log, however, shows no entries of this. The first created log just after booting the RPi, is identical to the one created 10 hours later, created just after the network failed.

Code: Select all

[    0.000000] Booting Linux on physical CPU 0x0
[    0.000000] Linux version 3.13.6 (openelec.tv@nmacleod.com) (gcc version 4.8.2 (GCC) ) #1 PREEMPT Wed Mar 19 22:38:17 GMT 2014
[    0.000000] CPU: ARMv6-compatible processor [410fb767] revision 7 (ARMv7), cr=00c5387d
[    0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT nonaliasing instruction cache
[    0.000000] Machine: BCM2708
[    0.000000] Memory policy: Data cache writeback
[    0.000000] On node 0 totalpages: 81920
[    0.000000] free_area_init_node: node 0, pgdat c0a8d0c8, node_mem_map c0ae7000
[    0.000000]   Normal zone: 640 pages used for memmap
[    0.000000]   Normal zone: 0 pages reserved
[    0.000000]   Normal zone: 81920 pages, LIFO batch:15
[    0.000000] pcpu-alloc: s0 r0 d32768 u32768 alloc=1*32768
[    0.000000] pcpu-alloc: [0] 0 
[    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 81280
[    0.000000] Kernel command line: dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0x3 root=/dev/ram0 rdinit=/init BOOT_IMAGE=/kernel.img dma.dmachans=0x7f35 bcm2708_fb.fbwidth=1920 bcm2708_fb.fbheight=1080 bcm2708.boardrev=0xd bcm2708.serial=0xbdbbc159 smsc95xx.macaddr=B8:27:EB:BB:C1:59 sdhci-bcm2708.emmc_clock_freq=250000000 vc_mem.mem_base=0x1ec00000 vc_mem.mem_size=0x20000000  boot=/dev/mmcblk0p1 disk=/dev/mmcblk0p2 quiet
[    0.000000] PID hash table entries: 2048 (order: 1, 8192 bytes)
[    0.000000] Dentry cache hash table entries: 65536 (order: 6, 262144 bytes)
[    0.000000] Inode-cache hash table entries: 32768 (order: 5, 131072 bytes)
[    0.000000] Memory: 313500K/327680K available (6183K kernel code, 246K rwdata, 1364K rodata, 2979K init, 353K bss, 14180K reserved)
[    0.000000] Virtual kernel memory layout:
[    0.000000]     vector  : 0xffff0000 - 0xffff1000   (   4 kB)
[    0.000000]     fixmap  : 0xfff00000 - 0xfffe0000   ( 896 kB)
[    0.000000]     vmalloc : 0xd4800000 - 0xff000000   ( 680 MB)
[    0.000000]     lowmem  : 0xc0000000 - 0xd4000000   ( 320 MB)
[    0.000000]     modules : 0xbf000000 - 0xc0000000   (  16 MB)
[    0.000000]       .text : 0xc0008000 - 0xc0766f1c   (7548 kB)
[    0.000000]       .init : 0xc0767000 - 0xc0a4fd4c   (2980 kB)
[    0.000000]       .data : 0xc0a50000 - 0xc0a8db10   ( 247 kB)
[    0.000000]        .bss : 0xc0a8db1c - 0xc0ae62f0   ( 354 kB)
[    0.000000] SLUB: HWalign=32, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
[    0.000000] Preemptible hierarchical RCU implementation.
[    0.000000] 	Offload RCU callbacks from all CPUs
[    0.000000] 	Offload RCU callbacks from CPUs: 0.
[    0.000000] NR_IRQS:330
[    0.000000] sched_clock: 32 bits at 1000kHz, resolution 1000ns, wraps every 2147483648000ns
[    0.000000] Switching to timer-based delay loop
[    0.000000] Console: colour dummy device 80x30
[    0.000000] console [tty0] enabled
[    0.000472] Calibrating delay loop (skipped), value calculated using timer frequency.. 2.08 BogoMIPS (lpj=3333)
[    0.000497] pid_max: default: 32768 minimum: 301
[    0.000775] Mount-cache hash table entries: 512
[    0.001592] CPU: Testing write buffer coherency: ok
[    0.001958] Setting up static identity map for 0x60f7e8 - 0x60f820
[    0.004372] devtmpfs: initialized
[    0.016522] VFP support v0.3: implementor 41 architecture 1 part 20 variant b rev 5
[    0.016973] xor: measuring software checksum speed
[    0.050337]    arm4regs  :   973.200 MB/sec
[    0.083748]    8regs     :   470.400 MB/sec
[    0.117165]    32regs    :   416.400 MB/sec
[    0.117183] xor: using function: arm4regs (973.200 MB/sec)
[    0.117479] NET: Registered protocol family 16
[    0.122484] DMA: preallocated 4096 KiB pool for atomic coherent allocations
[    0.123073] cpuidle: using governor ladder
[    0.123095] cpuidle: using governor menu
[    0.123613] bcm2708.uart_clock = 0
[    0.125462] ------------[ cut here ]------------
[    0.125517] WARNING: CPU: 0 PID: 1 at fs/sysfs/dir.c:486 sysfs_warn_dup+0x74/0x98()
[    0.125532] sysfs: cannot create duplicate filename '/devices/platform/pcm5102a-codec'
[    0.125544] Modules linked in:
[    0.125568] CPU: 0 PID: 1 Comm: swapper Not tainted 3.13.6 #1
[    0.125579] Backtrace: 
[    0.125627] [<c0011410>] (dump_backtrace+0x0/0x10c) from [<c00116e8>] (show_stack+0x18/0x1c)
[    0.125642]  r6:c01103bc r5:00000009 r4:d3839ce8 r3:00000000
[    0.125689] [<c00116d0>] (show_stack+0x0/0x1c) from [<c060b5d8>] (dump_stack+0x20/0x28)
[    0.125719] [<c060b5b8>] (dump_stack+0x0/0x28) from [<c001dc2c>] (warn_slowpath_common+0x70/0x90)
[    0.125746] [<c001dbbc>] (warn_slowpath_common+0x0/0x90) from [<c001dc84>] (warn_slowpath_fmt+0x38/0x40)
[    0.125760]  r8:c0a7dae0 r7:00002001 r6:d387de00 r5:d3804dc0 r4:d381a000
[    0.125799] [<c001dc4c>] (warn_slowpath_fmt+0x0/0x40) from [<c01103bc>] (sysfs_warn_dup+0x74/0x98)
[    0.125812]  r3:d381a000 r2:c06f6550
[    0.125841] [<c0110348>] (sysfs_warn_dup+0x0/0x98) from [<c0110418>] (sysfs_add_one+0x38/0x40)
[    0.125855]  r6:d3804dc0 r5:d3880440 r4:ffffffef r3:00000000
[    0.125890] [<c01103e0>] (sysfs_add_one+0x0/0x40) from [<c0110554>] (create_dir+0x70/0xdc)
[    0.125903]  r6:c0a5e3d0 r5:d3804dc0 r4:d3880440 r3:00000000
[    0.125938] [<c01104e4>] (create_dir+0x0/0xdc) from [<c01108d0>] (sysfs_create_dir_ns+0x84/0xb8)
[    0.125952]  r7:c0a7dad8 r6:00000000 r5:d3804dc0 r4:c0a5e3d0
[    0.125997] [<c011084c>] (sysfs_create_dir_ns+0x0/0xb8) from [<c03d1640>] (kobject_add_internal+0xc4/0x23c)
[    0.126011]  r6:00000000 r5:c0a7dae0 r4:c0a5e3d0
[    0.126044] [<c03d157c>] (kobject_add_internal+0x0/0x23c) from [<c03d1b44>] (kobject_add+0x54/0x98)
[    0.126058]  r8:c076a28c r7:c0a7dad8 r6:00000000 r5:c0a7dae0 r4:c0a5e3d0
[    0.126106] [<c03d1af0>] (kobject_add+0x0/0x98) from [<c042c908>] (device_add+0xe8/0x4f8)
[    0.126120]  r3:00000000 r2:00000000
[    0.126133]  r6:c0a5e3c8 r5:00000000 r4:c0a5e3d0
[    0.126165] [<c042c820>] (device_add+0x0/0x4f8) from [<c0430918>] (platform_device_add+0xb4/0x218)
[    0.126178]  r9:000000fc r8:c076a28c r7:c0a8dea0 r6:c0a5e3c8 r5:c0a5e3b8
[    0.126178] r4:00000000
[    0.126220] [<c0430864>] (platform_device_add+0x0/0x218) from [<c0430fe4>] (platform_device_register+0x28/0x2c)
[    0.126233]  r7:c0a8dea0 r6:c0a5c3b8 r5:c0a5ccf8 r4:c0a5e3b8
[    0.126284] [<c0430fbc>] (platform_device_register+0x0/0x2c) from [<c076d460>] (bcm_register_device+0x10/0x14)
[    0.126298]  r4:c0789414 r3:00000010
[    0.126330] [<c076d450>] (bcm_register_device+0x0/0x14) from [<c076d61c>] (bcm2708_init+0x1b8/0x26c)
[    0.126360] [<c076d464>] (bcm2708_init+0x0/0x26c) from [<c076a2b0>] (customize_machine+0x24/0x30)
[    0.126373]  r7:c0a8dc00 r6:c0a8dc00 r5:00000003 r4:c078cd18
[    0.126408] [<c076a28c>] (customize_machine+0x0/0x30) from [<c0008670>] (do_one_initcall+0x100/0x160)
[    0.126436] [<c0008570>] (do_one_initcall+0x0/0x160) from [<c0767bc4>] (kernel_init_freeable+0xfc/0x1f4)
[    0.126479] [<c0767ac8>] (kernel_init_freeable+0x0/0x1f4) from [<c0603354>] (kernel_init+0x10/0x120)
[    0.126510] [<c0603344>] (kernel_init+0x0/0x120) from [<c000e638>] (ret_from_fork+0x14/0x3c)
[    0.126524]  r4:00000000 r3:00000000
[    0.126593] ---[ end trace eab86e3d9c90d00a ]---
[    0.126619] ------------[ cut here ]------------
[    0.126647] WARNING: CPU: 0 PID: 1 at lib/kobject.c:223 kobject_add_internal+0x214/0x23c()
[    0.126663] kobject_add_internal failed for pcm5102a-codec with -EEXIST, don't try to register things with the same name in the same directory.
[    0.126673] Modules linked in:
[    0.126692] CPU: 0 PID: 1 Comm: swapper Tainted: G        W    3.13.6 #1
[    0.126701] Backtrace: 
[    0.126733] [<c0011410>] (dump_backtrace+0x0/0x10c) from [<c00116e8>] (show_stack+0x18/0x1c)
[    0.126747]  r6:c03d1790 r5:00000009 r4:d3839d80 r3:00000000
[    0.126787] [<c00116d0>] (show_stack+0x0/0x1c) from [<c060b5d8>] (dump_stack+0x20/0x28)
[    0.126815] [<c060b5b8>] (dump_stack+0x0/0x28) from [<c001dc2c>] (warn_slowpath_common+0x70/0x90)
[    0.126841] [<c001dbbc>] (warn_slowpath_common+0x0/0x90) from [<c001dc84>] (warn_slowpath_fmt+0x38/0x40)
[    0.126855]  r8:c0a7dae0 r7:c0a7dad8 r6:ffffffef r5:c0a7dae0 r4:c0a5e3d0
[    0.126896] [<c001dc4c>] (warn_slowpath_fmt+0x0/0x40) from [<c03d1790>] (kobject_add_internal+0x214/0x23c)
[    0.126909]  r3:c06317ac r2:c07198a8
[    0.126938] [<c03d157c>] (kobject_add_internal+0x0/0x23c) from [<c03d1b44>] (kobject_add+0x54/0x98)
[    0.126951]  r8:c076a28c r7:c0a7dad8 r6:00000000 r5:c0a7dae0 r4:c0a5e3d0
[    0.126994] [<c03d1af0>] (kobject_add+0x0/0x98) from [<c042c908>] (device_add+0xe8/0x4f8)
[    0.127007]  r3:00000000 r2:00000000
[    0.127021]  r6:c0a5e3c8 r5:00000000 r4:c0a5e3d0
[    0.127051] [<c042c820>] (device_add+0x0/0x4f8) from [<c0430918>] (platform_device_add+0xb4/0x218)
[    0.127064]  r9:000000fc r8:c076a28c r7:c0a8dea0 r6:c0a5e3c8 r5:c0a5e3b8
[    0.127064] r4:00000000
[    0.127106] [<c0430864>] (platform_device_add+0x0/0x218) from [<c0430fe4>] (platform_device_register+0x28/0x2c)
[    0.127120]  r7:c0a8dea0 r6:c0a5c3b8 r5:c0a5ccf8 r4:c0a5e3b8
[    0.127161] [<c0430fbc>] (platform_device_register+0x0/0x2c) from [<c076d460>] (bcm_register_device+0x10/0x14)
[    0.127175]  r4:c0789414 r3:00000010
[    0.127207] [<c076d450>] (bcm_register_device+0x0/0x14) from [<c076d61c>] (bcm2708_init+0x1b8/0x26c)
[    0.127236] [<c076d464>] (bcm2708_init+0x0/0x26c) from [<c076a2b0>] (customize_machine+0x24/0x30)
[    0.127306]  r7:c0a8dc00 r6:c0a8dc00 r5:00000003 r4:c078cd18
[    0.127352] [<c076a28c>] (customize_machine+0x0/0x30) from [<c0008670>] (do_one_initcall+0x100/0x160)
[    0.127381] [<c0008570>] (do_one_initcall+0x0/0x160) from [<c0767bc4>] (kernel_init_freeable+0xfc/0x1f4)
[    0.127415] [<c0767ac8>] (kernel_init_freeable+0x0/0x1f4) from [<c0603354>] (kernel_init+0x10/0x120)
[    0.127447] [<c0603344>] (kernel_init+0x0/0x120) from [<c000e638>] (ret_from_fork+0x14/0x3c)
[    0.127461]  r4:00000000 r3:00000000
[    0.127477] ---[ end trace eab86e3d9c90d00b ]---
[    0.127605] mailbox: Broadcom VideoCore Mailbox driver
[    0.127695] bcm2708_vcio: mailbox at f200b880
[    0.127788] bcm_power: Broadcom power driver
[    0.127808] bcm_power_open() -> 0
[    0.127819] bcm_power_request(0, 8)
[    0.628517] bcm_mailbox_read -> 00000080, 0
[    0.628536] bcm_power_request -> 0
[    0.628802] Serial: AMBA PL011 UART driver
[    0.628936] dev:f1: ttyAMA0 at MMIO 0x20201000 (irq = 83, base_baud = 0) is a PL011 rev3
[    0.653202] bio: create slab <bio-0> at 0
[    0.709425] raid6: int32x1     46 MB/s
[    0.766000] raid6: int32x2     65 MB/s
[    0.823009] raid6: int32x4     89 MB/s
[    0.879966] raid6: int32x8     98 MB/s
[    0.879987] raid6: using algorithm int32x8 (98 MB/s)
[    0.879999] raid6: using intx1 recovery algorithm
[    0.880639] SCSI subsystem initialized
[    0.880980] usbcore: registered new interface driver usbfs
[    0.881072] usbcore: registered new interface driver hub
[    0.881310] usbcore: registered new device driver usb
[    0.882984] Switched to clocksource stc
[    0.895108] NET: Registered protocol family 2
[    0.896045] TCP established hash table entries: 4096 (order: 2, 16384 bytes)
[    0.896149] TCP bind hash table entries: 4096 (order: 2, 16384 bytes)
[    0.896249] TCP: Hash tables configured (established 4096 bind 4096)
[    0.896416] TCP: reno registered
[    0.896454] UDP hash table entries: 256 (order: 0, 4096 bytes)
[    0.896490] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes)
[    0.896939] NET: Registered protocol family 1
[    0.897459] RPC: Registered named UNIX socket transport module.
[    0.897486] RPC: Registered udp transport module.
[    0.897497] RPC: Registered tcp transport module.
[    0.897509] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    1.142433] bcm2708_dma: DMA manager at f2007000
[    1.142580] bcm2708_gpio: bcm2708_gpio_probe c0a5c5e0
[    1.143120] vc-mem: phys_addr:0x00000000 mem_base=0x1ec00000 mem_size:0x20000000(512 MiB)
[    1.155584] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    1.157985] NFS: Registering the id_resolver key type
[    1.158086] Key type id_resolver registered
[    1.158105] Key type id_legacy registered
[    1.158136] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[    1.160233] JFS: nTxBlock = 2449, nTxLock = 19593
[    1.161499] SGI XFS with security attributes, large block/inode numbers, no debug enabled
[    1.165953] bio: create slab <bio-1> at 1
[    1.167675] Btrfs loaded
[    1.168151] msgmni has been set to 612
[    1.171473] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 252)
[    1.171503] io scheduler noop registered (default)
[    1.171517] io scheduler deadline registered
[    1.171574] io scheduler cfq registered
[    1.171823] bcm2708_fb_debugfs_init: could not create debugfs entry
[    1.171942] BCM2708FB: allocated DMA memory 52f40000
[    1.171995] BCM2708FB: allocated DMA channel 0 @ f2007000
[    1.207787] Console: switching to colour frame buffer device 240x67
[    1.225165] uart-pl011 dev:f1: no DMA platform data
[    1.225218] bcm2708_rng_init=d4d8c000
[    1.233708] brd: module loaded
[    1.233931] loop: module loaded
[    1.234246] nbd: registered device at major 43
[    1.242714] vchiq: vchiq_init_state: slot_zero = 0xd4804000, is_master = 0
[    1.243822] Loading iSCSI transport class v2.0-870.
[    1.244518] iscsi: registered transport (tcp)
[    1.245495] bcm2708_spi bcm2708_spi.0: master is unqueued, this is deprecated
[    1.246252] bcm2708_spi bcm2708_spi.0: SPI Controller at 0x20204000 (irq 80)
[    1.246481] tun: Universal TUN/TAP device driver, 1.6
[    1.246503] tun: (C) 1999-2004 Max Krasnyansky <maxk@qualcomm.com>
[    1.246852] PPP generic driver version 2.4.2
[    1.247282] usbcore: registered new interface driver smsc95xx
[    1.247422] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
[    1.447600] Core Release: 2.80a
[    1.447635] Setting default values for core params
[    1.447684] Finished setting default values for core params
[    1.647773] Using Buffer DMA mode
[    1.647802] Periodic Transfer Interrupt Enhancement - disabled
[    1.647815] Multiprocessor Interrupt Enhancement - disabled
[    1.647829] OTG VER PARAM: 0, OTG VER FLAG: 0
[    1.647847] Dedicated Tx FIFOs mode
[    1.648097] WARN::dwc_otg_hcd_init:1040: FIQ DMA bounce buffers: virt = 0xd4d9c000 dma = 0x52f98000 len=9024
[    1.648134] FIQ FSM acceleration enabled for :
[    1.648134] Non-periodic Split Transactions
[    1.648134] Periodic Split Transactions
[    1.648179] dwc_otg: Microframe scheduler enabled
[    1.648223] WARN::hcd_init:473: FIQ at 0xc04c5328
[    1.648241] WARN::hcd_init:474: FIQ ASM at 0xc04c5600 length 36
[    1.648274] WARN::hcd_init:500: MPHI regs_base at 0xd4d96000
[    1.648307] dwc_otg bcm2708_usb: DWC OTG Controller
[    1.648364] dwc_otg bcm2708_usb: new USB bus registered, assigned bus number 1
[    1.648426] dwc_otg bcm2708_usb: irq 32, io mem 0x00000000
[    1.648469] Init: Port Power? op_state=1
[    1.648481] Init: Power Port (0)
[    1.648796] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
[    1.648823] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.648842] usb usb1: Product: DWC OTG Controller
[    1.648860] usb usb1: Manufacturer: Linux 3.13.6 dwc_otg_hcd
[    1.648875] usb usb1: SerialNumber: bcm2708_usb
[    1.649808] hub 1-0:1.0: USB hub found
[    1.649902] hub 1-0:1.0: 1 port detected
[    1.650369] dwc_otg: FIQ enabled
[    1.650388] dwc_otg: NAK holdoff enabled
[    1.650398] dwc_otg: FIQ split-transaction FSM enabled
[    1.650420] Module dwc_common_port init
[    1.650848] usbcore: registered new interface driver usb-storage
[    1.651201] mousedev: PS/2 mouse device common for all mice
[    1.651406] i2c /dev entries driver
[    1.651570] bcm2708_i2c_init_pinmode(0,0)
[    1.651590] bcm2708_i2c_init_pinmode(0,1)
[    1.652000] bcm2708_i2c bcm2708_i2c.0: BSC0 Controller at 0x20205000 (irq 79) (baudrate 100k)
[    1.652067] bcm2708_i2c_init_pinmode(1,2)
[    1.652084] bcm2708_i2c_init_pinmode(1,3)
[    1.652668] bcm2708_i2c bcm2708_i2c.1: BSC1 Controller at 0x20804000 (irq 79) (baudrate 100k)
[    1.653663] bcm2835-cpufreq: min=700000 max=900000 cur=700000
[    1.653825] bcm2835-cpufreq: switching to governor ondemand
[    1.653847] bcm2835-cpufreq: switching to governor ondemand
[    1.655180] sdhci: Secure Digital Host Controller Interface driver
[    1.655196] sdhci: Copyright(c) Pierre Ossman
[    1.655265] sdhci: Enable low-latency mode
[    1.686360] mmc0: SDHCI controller on BCM2708_Arasan [platform] using platform's DMA
[    1.686543] mmc0: BCM2708 SDHC host at 0x20300000 DMA 2 IRQ 77
[    1.686629] sdhci-pltfm: SDHCI platform and OF driver helper
[    1.686937] hidraw: raw HID events driver (C) Jiri Kosina
[    1.688243] usbcore: registered new interface driver usbhid
[    1.688258] usbhid: USB HID core driver
[    1.688476] TCP: cubic registered
[    1.689294] NET: Registered protocol family 10
[    1.696800] NET: Registered protocol family 17
[    1.700094] Key type dns_resolver registered
[    1.724038] Freeing unused kernel memory: 2976K (c0767000 - c0a4f000)
[    1.755606] mmc0: read SD Status register (SSR) after 2 attempts
[    1.761146] mmc0: new high speed SDHC card at address 0007
[    1.763385] mmcblk0: mmc0:0007 SD08G 7.42 GiB 
[    1.764772]  mmcblk0: p1 p2
[    1.849927] Indeed it is in host mode hprt0 = 00021501
[    2.043155] usb 1-1: new high-speed USB device number 2 using dwc_otg
[    2.043330] Indeed it is in host mode hprt0 = 00001101
[    2.266744] usb 1-1: New USB device found, idVendor=0424, idProduct=9512
[    2.266776] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[    2.267459] hub 1-1:1.0: USB hub found
[    2.267590] hub 1-1:1.0: 3 ports detected
[    2.546512] usb 1-1.1: new high-speed USB device number 3 using dwc_otg
[    2.640091] usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00
[    2.640119] usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[    2.642838] smsc95xx v1.0.4
[    2.686555] smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-bcm2708_usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:bb:c1:59
[    2.769884] usb 1-1.3: new low-speed USB device number 4 using dwc_otg
[    2.884543] usb 1-1.3: New USB device found, idVendor=413c, idProduct=2003
[    2.884576] usb 1-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[    2.884593] usb 1-1.3: Product: Dell USB Keyboard
[    2.884606] usb 1-1.3: Manufacturer: Dell
[    2.898727] EXT4-fs (mmcblk0p2): couldn't mount as ext3 due to feature incompatibilities
[    2.903195] EXT4-fs (mmcblk0p2): couldn't mount as ext2 due to feature incompatibilities
[    2.905117] input: Dell Dell USB Keyboard as /devices/platform/bcm2708_usb/usb1/1-1/1-1.3/1-1.3:1.0/input/input0
[    2.907390] hid-generic 0003:413C:2003.0001: input,hidraw0: USB HID v1.10 Keyboard [Dell Dell USB Keyboard] on usb-bcm2708_usb-1.3/input0
[    2.923434] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
[    3.515522] random: systemd urandom read with 98 bits of entropy available
[    3.517477] systemd[1]: systemd 211 running in system mode. (-PAM -LIBWRAP -AUDIT -SELINUX -IMA -SYSVINIT -LIBCRYPTSETUP -GCRYPT -ACL -XZ -SECCOMP -APPARMOR)
[    3.518093] systemd[1]: Detected architecture 'arm'.
[    3.519446] systemd[1]: Set hostname to <openelec>.
[    3.520193] systemd[1]: Initializing machine ID from random generator.
[    3.605792] systemd-gpt-auto-generator[98]: Out of memory.
[    3.607067] systemd[96]: /usr/lib/systemd/system-generators/systemd-gpt-auto-generator failed with error code 1.
[    3.791235] systemd[1]: Cannot add dependency job for unit display-manager.service, ignoring: Unit display-manager.service failed to load: No such file or directory.
[    3.796143] systemd[1]: Starting Paths.
[    3.796508] systemd[1]: Reached target Paths.
[    3.796746] systemd[1]: Starting Root Slice.
[    3.797074] systemd[1]: Created slice Root Slice.
[    3.797244] systemd[1]: Starting User and Session Slice.
[    3.797655] systemd[1]: Created slice User and Session Slice.
[    3.797825] systemd[1]: Starting /dev/initctl Compatibility Named Pipe.
[    3.798225] systemd[1]: Listening on /dev/initctl Compatibility Named Pipe.
[    3.798396] systemd[1]: Starting Delayed Shutdown Socket.
[    3.798750] systemd[1]: Listening on Delayed Shutdown Socket.
[    3.798958] systemd[1]: Starting udev Control Socket.
[    3.799325] systemd[1]: Listening on udev Control Socket.
[    3.799537] systemd[1]: Starting udev Kernel Socket.
[    3.800046] systemd[1]: Listening on udev Kernel Socket.
[    3.800270] systemd[1]: Starting Journal Socket.
[    3.800836] systemd[1]: Listening on Journal Socket.
[    3.801144] systemd[1]: Starting System Slice.
[    3.801615] systemd[1]: Created slice System Slice.
[    3.802241] systemd[1]: Starting Remount Root and Kernel File Systems...
[    3.805162] systemd[1]: Mounting Temporary Directory...
[    3.811848] systemd[1]: Starting Setup User config dir...
[    3.823326] systemd[1]: Mounted Huge Pages File System.
[    3.823680] systemd[1]: Mounting POSIX Message Queue File System...
[    3.843548] systemd[1]: Starting Create list of required static device nodes for the current kernel...
[    3.861488] systemd[1]: Starting Setup Loopback interface...
[    3.879401] systemd[1]: Mounting Debug File System...
[    3.907559] systemd[1]: Starting Set ondemand threshold...
[    3.928162] systemd[1]: Starting Show Version...
[    3.979547] systemd[1]: Starting Mounting swapfile...
[    4.007698] random: nonblocking pool is initialized
[    4.045078] systemd[1]: Starting Load Kernel Modules...
[    4.094325] systemd[1]: Starting Apply Kernel Variables...
[    4.133933] systemd[1]: Starting udev Coldplug all Devices...
[    4.166856] systemd[1]: Starting Slices.
[    4.167054] systemd[1]: Reached target Slices.
[    4.167304] systemd[1]: Mounting Variable Directory...
[    4.200192] systemd[1]: Started Setup debug config.
[    4.229275] systemd[1]: Mounted Debug File System.
[    4.237538] systemd[1]: Mounted POSIX Message Queue File System.
[    4.237909] systemd[1]: Mounted Temporary Directory.
[    4.260193] systemd[1]: Started Remount Root and Kernel File Systems.
[    4.296243] systemd[1]: Started Create list of required static device nodes for the current kernel.
[    4.386930] systemd[1]: Started Set ondemand threshold.
[    4.388840] systemd[1]: Started Show Version.
[    4.420904] systemd[1]: Started Apply Kernel Variables.
[    4.422129] systemd[1]: Mounted Variable Directory.
[    4.451448] lirc_dev: IR Remote Control driver registered, major 249 
[    4.463896] systemd[1]: Started Setup Loopback interface.
[    4.480138] lirc_rpi: module is from the staging directory, the quality is unknown, you have been warned.
[    4.690609] systemd[1]: Started Mounting swapfile.
[    4.871530] systemd[1]: Started udev Coldplug all Devices.
[    4.917988] systemd[1]: Started Setup User config dir.
[    5.138142] systemd[1]: Starting Swap.
[    5.138356] systemd[1]: Reached target Swap.
[    5.138556] systemd[1]: Starting Restoring Backup...
[    5.141726] systemd[1]: Started Factory reset.
[    5.142041] systemd[1]: Started FS Resize.
[    5.142748] systemd[1]: Starting Create Static Device Nodes in /dev...
[    5.208535] systemd[1]: Started Restoring Backup.
[    5.246055] systemd[1]: Started Create Static Device Nodes in /dev.
[    5.260133] systemd[1]: Starting Local File Systems (Pre).
[    5.260337] systemd[1]: Reached target Local File Systems (Pre).
[    5.260541] systemd[1]: Starting Local File Systems.
[    5.260688] systemd[1]: Reached target Local File Systems.
[    5.261018] systemd[1]: Starting Setup Hostname...
[    5.267271] systemd[1]: Starting Setup machine-id...
[    5.277611] systemd[1]: Starting Create Volatile Files and Directories...
[    5.336116] systemd[1]: Started Setup Hostname.
[    5.389783] lirc_rpi: auto-detected active high receiver on GPIO pin 18
[    5.390082] lirc_rpi lirc_rpi.0: lirc_dev: driver lirc_rpi registered at minor = 0
[    5.390099] lirc_rpi: driver registered!
[    5.408001] systemd[1]: Started Load Kernel Modules.
[    5.420739] systemd[1]: Started Setup machine-id.
[    5.435618] systemd[1]: Started Create Volatile Files and Directories.
[    5.515818] systemd[1]: Starting Update hwdb.bin...
[    5.519640] systemd[1]: Started Debug /var/log relink.
[    5.523417] systemd[1]: Starting Journal Service...
[    5.533603] systemd[1]: Started Journal Service.
[    5.863395] systemd-udevd[187]: starting version 211
[    6.310903] Console: switching to colour dummy device 80x30
[    6.960903] systemd-journald[183]: Received request to flush runtime journal from PID 1
[    7.211282] bcm2708-i2s bcm2708-i2s.0: Failed to create debugfs directory
[    9.085521] smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup
[    9.088132] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[   10.098581] input: lircd as /devices/virtual/input/input1
[   10.649065] smsc95xx 1-1.1:1.0 eth0: link up, 100Mbps, full-duplex, lpa 0x45E1
[   10.666811] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
I know when the connection dropped because of the XBMC log:

Code: Select all

20:30:52  12.961643 T:3057811456  NOTICE: special://profile/ is mapped to: special://masterprofile/
20:30:52  12.962186 T:3057811456  NOTICE: -----------------------------------------------------------------------
20:30:52  12.962389 T:3057811456  NOTICE: Starting XBMC (14.0-ALPHA1 Git:a1cab7a). Platform: ARM Linux 32-bit
20:30:52  12.962662 T:3057811456  NOTICE: Using Release XBMC x32 build, compiled Mar 19 2014 by GCC 4.8.2 for ARM Linux 32-bit 3.13.6
20:30:52  13.009518 T:3057811456  NOTICE: Running on Linux 32-bit (OpenELEC (Milhouse) - Version: devel-20140320013122-r17977-ge57e1bc, 3.13.6 armv6l)
20:30:52  13.009814 T:3057811456  NOTICE: Host CPU: ARMv6-compatible processor rev 7 (v6l), 1 core available
20:30:52  13.009969 T:3057811456  NOTICE: ARM Features: Neon disabled
20:30:52  13.010262 T:3057811456  NOTICE: special://xbmc/ is mapped to: /usr/share/xbmc
20:30:52  13.010433 T:3057811456  NOTICE: special://xbmcbin/ is mapped to: /usr/lib/xbmc
20:30:52  13.010592 T:3057811456  NOTICE: special://masterprofile/ is mapped to: /storage/.xbmc/userdata
20:30:52  13.010751 T:3057811456  NOTICE: special://home/ is mapped to: /storage/.xbmc
20:30:52  13.010911 T:3057811456  NOTICE: special://temp/ is mapped to: /storage/.xbmc/temp
20:30:52  13.011152 T:3057811456  NOTICE: The executable running is: /usr/lib/xbmc/xbmc.bin
20:30:52  13.011465 T:3057811456  NOTICE: Local hostname: OpenELEC
20:30:52  13.011639 T:3057811456  NOTICE: Log File is located: /storage/.xbmc/temp/xbmc.log
20:30:52  13.011838 T:3057811456  NOTICE: -----------------------------------------------------------------------
20:30:52  13.031824 T:3057811456   ERROR: DBus: Error org.freedesktop.DBus.Error.ServiceUnknown - The name org.freedesktop.UPower was not provided by any .service files
20:30:52  13.057186 T:3057811456  NOTICE: load settings...
20:30:52  13.361464 T:3057811456  NOTICE: Found 1 Lists of Devices
20:30:52  13.361764 T:3057811456  NOTICE: Enumerated PI devices:
20:30:52  13.361933 T:3057811456  NOTICE:     Device 1
20:30:52  13.364422 T:3057811456  NOTICE:         m_deviceName      : HDMI
20:30:52  13.364689 T:3057811456  NOTICE:         m_displayName     : HDMI
20:30:52  13.364861 T:3057811456  NOTICE:         m_displayNameExtra:
20:30:52  13.365028 T:3057811456  NOTICE:         m_deviceType      : AE_DEVTYPE_HDMI
20:30:52  13.365196 T:3057811456  NOTICE:         m_channels        : FL,FR
20:30:52  13.365360 T:3057811456  NOTICE:         m_sampleRates     : 8000,11025,16000,22050,24000,32000,41400,48000,88200,96000,176400,192000
20:30:52  13.365525 T:3057811456  NOTICE:         m_dataFormats     : AE_FMT_FLOAT,AE_FMT_S32LE,AE_FMT_S16LE,AE_FMT_AC3,AE_FMT_DTS,AE_FMT_EAC3
20:30:52  13.365805 T:3057811456  NOTICE:     Device 2
20:30:52  13.366126 T:3057811456  NOTICE:         m_deviceName      : Analogue
20:30:52  13.366313 T:3057811456  NOTICE:         m_displayName     : Analogue
20:30:52  13.366479 T:3057811456  NOTICE:         m_displayNameExtra:
20:30:52  13.366644 T:3057811456  NOTICE:         m_deviceType      : AE_DEVTYPE_PCM
20:30:52  13.367167 T:3057811456  NOTICE:         m_channels        : FL,FR
20:30:52  13.367738 T:3057811456  NOTICE:         m_sampleRates     : 48000
20:30:52  13.367941 T:3057811456  NOTICE:         m_dataFormats     : AE_FMT_FLOAT,AE_FMT_S32LE,AE_FMT_S16LE
20:30:52  13.438671 T:3057811456  NOTICE: Loaded settings file from special://xbmc/system/advancedsettings.xml
20:30:52  13.439209 T:3057811456  NOTICE: Contents of special://xbmc/system/advancedsettings.xml are...
                                            <?xml version="1.0" encoding="UTF-8" ?>
                                            <advancedsettings>
                                              <showexitbutton>false</showexitbutton>
                                              <destroywindowcontrols>false</destroywindowcontrols>
                                              <fanartres>720</fanartres>
                                              <imageres>540</imageres>
                                              <useddsfanart>false</useddsfanart>
                                              <video>
                                                <defaultplayer>omxplayer</defaultplayer>
                                                <defaultdvdplayer>omxplayer</defaultdvdplayer>
                                                <busydialogdelayms>750</busydialogdelayms>
                                              </video>
                                              <network>
                                                <cachemembuffersize>20971520</cachemembuffersize>
                                              </network>
                                              <samba>
                                                <clienttimeout>30</clienttimeout>
                                              </samba>
                                              <network>
                                                <readbufferfactor>4.0</readbufferfactor>
                                              </network>
                                            </advancedsettings>
20:30:52  13.446994 T:3057811456  NOTICE: No settings file to load (special://masterprofile/advancedsettings.xml)
20:30:52  13.447322 T:3057811456  NOTICE: Default DVD Player: omxplayer
20:30:52  13.447536 T:3057811456  NOTICE: Default Video Player: omxplayer
20:30:52  13.447701 T:3057811456  NOTICE: Default Audio Player: paplayer
20:30:52  13.447894 T:3057811456  NOTICE: Disabled debug logging due to GUI setting. Level 0.
20:30:52  13.448057 T:3057811456  NOTICE: Log level changed to 0
20:30:52  13.448714 T:3057811456  NOTICE: CMediaSourceSettings: loading media sources from special://masterprofile/sources.xml
20:30:52  13.478711 T:3057811456  NOTICE: Loading player core factory settings from special://xbmc/system/playercorefactory.xml.
20:30:53  13.487093 T:3057811456  NOTICE: Loaded playercorefactory configuration
20:30:53  13.488166 T:3057811456  NOTICE: Loading player core factory settings from special://masterprofile/playercorefactory.xml.
20:30:53  13.488798 T:3057811456  NOTICE: special://masterprofile/playercorefactory.xml does not exist. Skipping.
20:30:53  13.882479 T:3057800272  NOTICE: Thread ActiveAE start, auto delete: false
20:30:53  13.883228 T:3048207440  NOTICE: Thread AESink start, auto delete: false
20:30:53  13.912287 T:2987390032  NOTICE: Thread CRBPWorker start, auto delete: false
20:30:53  14.053675 T:3057811456  NOTICE: Running database version Addons16
20:30:54  14.952456 T:3057811456  NOTICE: ADDONS: Using repository repository.xbmc.org
20:30:54  14.952718 T:3057811456  NOTICE: ADDONS: Using repository repository.xbmcadult
20:30:54  14.952882 T:3057811456  NOTICE: ADDONS: Using repository repository.openelec.tv
20:30:54  15.129735 T:3057811456  NOTICE: Register - new cec device registered on cec->RPI: CEC Adapter (2708:1001)
20:30:54  15.130475 T:2970612816  NOTICE: Thread PeripBusCEC start, auto delete: false
20:30:54  15.133780 T:2979001424  NOTICE: Thread CECAdapter start, auto delete: false
20:30:54  15.267135 T:2962224208  NOTICE: Thread PeripBusUSBUdev start, auto delete: false
20:30:54  15.287459 T:3057811456  NOTICE: Raspberry PI firmware version: Mar 19 2014 23:26:47 
                                            Copyright (c) 2012 Broadcom
                                            version 98eb97cd0061f2fcff808fe5f32f851d5eada1ee (tainted) (release)
20:30:54  15.287744 T:3057811456  NOTICE: ARM mem: 320MB GPU mem: 192MB MPG2:0 WVC1:0
20:30:54  15.288207 T:3057811456  NOTICE: InitWindowSystem: Using EGL Implementation: raspberrypi
20:30:54  15.298067 T:3057811456  NOTICE: Found resolution 1920 x 1080 for display 0 with 1920 x 1080 @ 60.000000 Hz
20:30:54  15.298328 T:3057811456  NOTICE: Found (1920x1080@60.000000) at 16, setting to RES_DESKTOP at 16
20:30:54  15.298571 T:3057811456  NOTICE: Checking resolution 16
20:30:54  15.315450 T:3057811456  NOTICE: GL_VENDOR = Broadcom
20:30:54  15.315714 T:3057811456  NOTICE: GL_RENDERER = VideoCore IV HW
20:30:54  15.315876 T:3057811456  NOTICE: GL_VERSION = OpenGL ES 2.0
20:30:54  15.316084 T:3057811456  NOTICE: GL_SHADING_LANGUAGE_VERSION = OpenGL ES GLSL ES 1.00
20:30:54  15.316430 T:3057811456  NOTICE: GL_EXTENSIONS = GL_OES_compressed_ETC1_RGB8_texture GL_OES_compressed_paletted_texture GL_OES_texture_npot GL_OES_depth24 GL_OES_vertex_half_float GL_OES_EGL_image GL_OES_EGL_image_external GL_EXT_discard_framebuffer GL_OES_rgb8_rgba8 GL_OES_depth32 GL_OES_mapbuffer GL_EXT_texture_format_BGRA8888 GL_APPLE_rgb_422 GL_EXT_debug_marker
20:30:55  16.209620 T:3057811456  NOTICE: No Joystick name specified, loading default map
20:30:55  16.350344 T:2935915600  NOTICE: Previous line repeats 35 times.
20:30:55  16.350344 T:2935915600  NOTICE: Thread CECAdapterUpdate start, auto delete: false
20:30:56  16.585342 T:3057811456  NOTICE: Running database version Addons16
20:30:56  16.596008 T:3057811456  NOTICE: Running database version ViewModes6
20:30:56  16.666973 T:3057811456  NOTICE: Running database version Textures13
20:30:56  16.705563 T:3057811456  NOTICE: Running database version MyMusic46
20:30:56  16.885105 T:3057811456  NOTICE: Running database version MyVideos78
20:30:56  16.900305 T:3057811456  NOTICE: Running database version TV22
20:30:56  16.911190 T:3057811456  NOTICE: Running database version Epg7
20:30:56  16.912064 T:3057811456  NOTICE: start dvd mediatype detection
20:30:56  16.912598 T:3057811456  NOTICE: initializing playlistplayer
20:30:56  16.912893 T:3057811456  NOTICE: DONE initializing playlistplayer
20:30:56  16.916965 T:2925470800  NOTICE: Thread DetectDVDMedia start, auto delete: false
20:30:58  19.173252 T:3057800272  NOTICE: ActiveAE::ResampleSounds - resample /usr/share/xbmc/addons/skin.confluence/sounds/cursor.wav took 287ms
20:30:59  19.490385 T:3057800272  NOTICE: ActiveAE::ResampleSounds - resample /usr/share/xbmc/addons/skin.confluence/sounds/click.wav took 314ms
20:30:59  19.571733 T:3057811456 WARNING: JSONRPC: Could not parse type "GUI.Property.Value"
20:30:59  19.624193 T:3057811456 WARNING: JSONRPC: Could not parse type "Setting.Details.SettingList"
20:30:59  19.800726 T:3057800272  NOTICE: ActiveAE::ResampleSounds - resample /usr/share/xbmc/addons/skin.confluence/sounds/back.wav took 310ms
20:30:59  20.114149 T:3057800272  NOTICE: ActiveAE::ResampleSounds - resample /usr/share/xbmc/addons/skin.confluence/sounds/shutter.wav took 313ms
20:30:59  20.153660 T:2924823632  NOTICE: Thread LanguageInvoker start, auto delete: false
20:30:59  20.297245 T:3057811456  NOTICE: Previous line repeats 2 times.
20:30:59  20.297245 T:3057811456  NOTICE: initialize done
20:30:59  20.297632 T:3057811456  NOTICE: Running the application...
20:31:00  20.902248 T:3057800272  NOTICE: ActiveAE::ResampleSounds - resample /usr/share/xbmc/addons/skin.confluence/sounds/notify.wav took 788ms
20:31:00  20.914499 T:3057800272  NOTICE: ActiveAE::ResampleSounds - resample /usr/share/xbmc/addons/skin.confluence/sounds/out.wav took 6ms
20:31:02  22.493666 T:2899653712  NOTICE: Thread JobWorker start, auto delete: true
20:31:02  22.936249 T:3057811456  NOTICE: starting zeroconf publishing
20:31:02  22.936972 T:3057811456  NOTICE: Webserver: Starting...
20:31:02  22.940435 T:2878202960  NOTICE: Thread JobWorker start, auto delete: true
20:31:02  22.943756 T:3057811456  NOTICE: WebServer: Started the webserver
20:31:02  22.944574 T:3057811456  NOTICE: starting upnp controller
20:31:02  23.000950 T:3057811456  NOTICE: starting upnp server
20:31:02  23.117041 T:3057811456  NOTICE: ES: Starting event server
20:31:02  23.136967 T:2649572432  NOTICE: Thread EventServer start, auto delete: false
20:31:02  23.137285 T:2649572432  NOTICE: ES: Starting UDP Event server on 0.0.0.0:9777
20:31:02  23.137568 T:2649572432  NOTICE: UDP: Listening on port 9777
20:31:02  23.140381 T:2641183824  NOTICE: Thread TCPServer start, auto delete: false
20:31:02  23.143908 T:2632795216  NOTICE: Thread JobWorker start, auto delete: true
20:31:02  23.254751 T:2624406608  NOTICE: Thread AirPlayServer start, auto delete: false
20:31:03  23.696493 T:2802705488  NOTICE: Thread LanguageInvoker start, auto delete: false
20:31:03  24.178778 T:2908046416  NOTICE: -->Python Interpreter Initialized<--
20:31:04  25.000313 T:2792178768  NOTICE: Thread JobWorker start, auto delete: true
20:31:05  26.207535 T:2916435024  NOTICE: -->Python Interpreter Initialized<--
20:31:05  26.218884 T:2802705488  NOTICE: -->Python Interpreter Initialized<--
20:31:05  26.339567 T:2924823632  NOTICE: -->Python Interpreter Initialized<--
20:31:07  27.997267 T:2916435024  NOTICE: ## OpenELEC Addon ## 0.3.14
20:31:08  29.373655 T:2908046416  NOTICE:  StorageServer Module loaded RUN
20:31:10  31.342607 T:2908046416  NOTICE: StorageClient-2.5.4 Starting server
20:31:10  31.343725 T:2908046416  NOTICE: StorageServer-2.5.4 Storage Server starting /storage/.xbmc/temp/commoncache.db
20:31:16  37.060932 T:3048207440  NOTICE: CAESinkPi:AddPackets Underrun (delay:0.00 frames:4410)
20:48:20 1061.178589 T:2632795216  NOTICE: Thread JobWorker start, auto delete: true
20:48:20 1061.186157 T:2811094096  NOTICE: Thread LanguageInvoker start, auto delete: false
20:48:21 1062.125122 T:2811094096  NOTICE: -->Python Interpreter Initialized<--
21:05:32 2092.760254 T:2691515472  NOTICE: Thread JobWorker start, auto delete: true
21:05:32 2092.765381 T:2792178768  NOTICE: Thread LanguageInvoker start, auto delete: false
21:05:33 2093.689697 T:2792178768  NOTICE: -->Python Interpreter Initialized<--
21:35:35 3896.017334 T:2716681296  NOTICE: Thread JobWorker start, auto delete: true
21:35:35 3896.019531 T:2792178768  NOTICE: Thread LanguageInvoker start, auto delete: false
21:35:36 3896.511230 T:2792178768  NOTICE: -->Python Interpreter Initialized<--
22:05:37 5697.642578 T:2708292688  NOTICE: Thread JobWorker start, auto delete: true
22:05:37 5697.645020 T:2699904080  NOTICE: Thread LanguageInvoker start, auto delete: false
22:05:37 5698.154297 T:2699904080  NOTICE: -->Python Interpreter Initialized<--
22:35:39 7499.727539 T:2683126864  NOTICE: Thread JobWorker start, auto delete: true
22:35:39 7499.729980 T:2699904080  NOTICE: Thread LanguageInvoker start, auto delete: false
22:35:39 7500.253906 T:2699904080  NOTICE: -->Python Interpreter Initialized<--
22:35:40 7501.366699 T:2699904080   ERROR: EXCEPTION Thrown (PythonToCppException) : -->Python callback/script returned the following error<--
                                             - NOTE: IGNORING THIS CAN LEAD TO MEMORY LEAKS!
                                            Error Type: <type 'exceptions.ValueError'>
                                            Error Contents: could not convert string to float: 
                                            Traceback (most recent call last):
                                              File "/storage/.xbmc/addons/weather.yahoo/default.py", line 212, in <module>
                                                forecast(location, locationid)
                                              File "/storage/.xbmc/addons/weather.yahoo/default.py", line 120, in forecast
                                                properties(query,loc)
                                              File "/storage/.xbmc/addons/weather.yahoo/default.py", line 169, in properties
                                                set_property('Current.FeelsLike'     , feelslike(int(condition[0].attributes['temp'].value), int(round(float(wind[0].attributes['speed'].value) + 0.5))))
                                            ValueError: could not convert string to float: 
                                            -->End of Python script error report<--
22:42:23 7903.556152 T:2878202960  NOTICE: Thread JobWorker start, auto delete: true
22:42:29 7909.546387 T:2792354896  NOTICE: Thread LanguageInvoker start, auto delete: false
22:42:29 7910.461914 T:2792354896  NOTICE: -->Python Interpreter Initialized<--
22:45:15 8076.280273 T:2691515472  NOTICE: Thread JobWorker start, auto delete: true
22:45:18 8078.695801 T:2699904080  NOTICE: Previous line repeats 1 times.
22:45:18 8078.695801 T:2699904080  NOTICE: Thread BackgroundLoader start, auto delete: false
22:45:21 8082.400879 T:2691515472  NOTICE: Previous line repeats 1 times.
22:45:21 8082.400879 T:2691515472 WARNING: GetIcon - Unknown nodetype requested videodb://tvshows/titles/6/1/?tvshowid=6
22:45:21 8082.401855 T:2691515472 WARNING: GetIcon - Unknown nodetype requested videodb://tvshows/titles/6/2/?tvshowid=6
22:45:21 8082.402832 T:2691515472 WARNING: GetIcon - Unknown nodetype requested videodb://tvshows/titles/6/-1/?tvshowid=6
22:45:22 8082.542969 T:2699904080  NOTICE: Thread BackgroundLoader start, auto delete: false
22:45:29 8090.233887 T:2792354896  NOTICE: Previous line repeats 2 times.
22:45:29 8090.233887 T:2792354896 WARNING: GetIcon - Unknown nodetype requested videodb://tvshows/titles/6/1/?tvshowid=6
22:45:29 8090.234863 T:2792354896 WARNING: GetIcon - Unknown nodetype requested videodb://tvshows/titles/6/2/?tvshowid=6
22:45:29 8090.235840 T:2792354896 WARNING: GetIcon - Unknown nodetype requested videodb://tvshows/titles/6/-1/?tvshowid=6
22:45:29 8090.409668 T:2699904080  NOTICE: Thread BackgroundLoader start, auto delete: false
22:45:38 8098.543457 T:2765091920  NOTICE: Previous line repeats 3 times.
22:45:38 8098.543457 T:2765091920  NOTICE: Thread LanguageInvoker start, auto delete: false
22:45:38 8099.479980 T:2765091920  NOTICE: -->Python Interpreter Initialized<--
23:15:41 9901.791016 T:2756703312  NOTICE: Thread JobWorker start, auto delete: true
23:15:41 9901.793945 T:2699904080  NOTICE: Thread LanguageInvoker start, auto delete: false
23:15:41 9902.258789 T:2699904080  NOTICE: -->Python Interpreter Initialized<--
23:39:24 11324.698242 T:2756703312  NOTICE: Thread JobWorker start, auto delete: true
23:39:24 11324.706055 T:2878202960  NOTICE: Thread LanguageInvoker start, auto delete: false
23:39:25 11325.633789 T:2878202960  NOTICE: -->Python Interpreter Initialized<--
00:09:27 13128.077148 T:2756703312  NOTICE: Thread JobWorker start, auto delete: true
00:09:27 13128.080078 T:2765091920  NOTICE: Thread LanguageInvoker start, auto delete: false
00:09:28 13128.606445 T:2765091920  NOTICE: -->Python Interpreter Initialized<--
00:13:21 13361.797852 T:2765091920  NOTICE: Thread JobWorker start, auto delete: true
00:13:21 13361.806641 T:2756703312  NOTICE: Thread LanguageInvoker start, auto delete: false
00:13:22 13362.715820 T:2756703312  NOTICE: -->Python Interpreter Initialized<--
00:43:24 15165.032227 T:2765091920  NOTICE: Thread JobWorker start, auto delete: true
00:43:24 15165.034180 T:2792354896  NOTICE: Thread LanguageInvoker start, auto delete: false
00:43:25 15165.502930 T:2792354896  NOTICE: -->Python Interpreter Initialized<--
00:59:02 16102.861328 T:2708292688  NOTICE: Thread JobWorker start, auto delete: true
00:59:02 16102.864258 T:2878202960  NOTICE: Thread LanguageInvoker start, auto delete: false
00:59:03 16103.782227 T:2878202960  NOTICE: -->Python Interpreter Initialized<--
01:11:11 16831.515625 T:2756703312  NOTICE: Thread JobWorker start, auto delete: true
01:11:13 16834.228516 T:2878202960  NOTICE: Thread FileCache start, auto delete: false
01:11:23 16844.335938 T:2756703312   ERROR: CCurlFile::FillBuffer - Failed: Timeout was reached(28)
01:11:23 16844.335938 T:2756703312   ERROR: CCurlFile::CReadState::Connect, didn't get any data from stream.
01:11:23 16844.335938 T:2756703312   ERROR: Open - failed to open source <http://mirrors.xbmc.org/addons/frodo/addons.xml.md5>
01:11:43 16864.431641 T:2756703312   ERROR: CCurlFile::FillBuffer - Failed: Timeout was reached(28)
01:11:43 16864.431641 T:2756703312   ERROR: CCurlFile::CReadState::Connect, didn't get any data from stream.
01:11:43 16864.431641 T:2756703312   ERROR: Open - failed to open source <http://mirrors.xbmc.org/addons/gotham/addons.xml|Encoding=gzip>
01:11:45 16866.238281 T:2765091920  NOTICE: Thread FileCache start, auto delete: false
01:17:02 17183.257812 T:2878202960  NOTICE: Previous line repeats 2 times.
01:17:02 17183.257812 T:2878202960  NOTICE: Thread JobWorker start, auto delete: true
01:17:02 17183.265625 T:2756703312  NOTICE: Thread LanguageInvoker start, auto delete: false
01:17:03 17184.179688 T:2756703312  NOTICE: -->Python Interpreter Initialized<--
01:47:06 18986.791016 T:2699904080  NOTICE: Thread JobWorker start, auto delete: true
01:47:06 18986.794922 T:2765091920  NOTICE: Thread LanguageInvoker start, auto delete: false
01:47:06 18987.273438 T:2765091920  NOTICE: -->Python Interpreter Initialized<--
02:17:08 20788.523438 T:2756703312  NOTICE: Thread JobWorker start, auto delete: true
02:17:08 20788.525391 T:2792354896  NOTICE: Thread LanguageInvoker start, auto delete: false
02:17:08 20789.089844 T:2792354896  NOTICE: -->Python Interpreter Initialized<--
02:47:09 22590.333984 T:2878202960  NOTICE: Thread JobWorker start, auto delete: true
02:47:09 22590.335938 T:2792354896  NOTICE: Thread LanguageInvoker start, auto delete: false
02:47:10 22590.806641 T:2792354896  NOTICE: -->Python Interpreter Initialized<--
03:17:11 24392.160156 T:2699904080  NOTICE: Thread JobWorker start, auto delete: true
03:17:11 24392.162109 T:2708292688  NOTICE: Thread LanguageInvoker start, auto delete: false
03:17:12 24392.664062 T:2708292688  NOTICE: -->Python Interpreter Initialized<--
03:50:13 26373.876953 T:2708292688  NOTICE: Thread JobWorker start, auto delete: true
03:50:13 26373.878906 T:2878202960  NOTICE: Thread LanguageInvoker start, auto delete: false
03:50:13 26374.359375 T:2878202960  NOTICE: -->Python Interpreter Initialized<--
04:23:14 28355.410156 T:2699904080  NOTICE: Thread JobWorker start, auto delete: true
04:23:14 28355.412109 T:2708292688  NOTICE: Thread LanguageInvoker start, auto delete: false
04:23:15 28355.871094 T:2708292688  NOTICE: -->Python Interpreter Initialized<--
04:56:16 30336.896484 T:2708292688  NOTICE: Thread JobWorker start, auto delete: true
04:56:16 30336.900391 T:2699904080  NOTICE: Thread LanguageInvoker start, auto delete: false
04:56:16 30337.402344 T:2699904080  NOTICE: -->Python Interpreter Initialized<--
05:29:18 32318.507812 T:2878202960  NOTICE: Thread JobWorker start, auto delete: true
05:29:18 32318.511719 T:2756703312  NOTICE: Thread LanguageInvoker start, auto delete: false
05:29:18 32318.976562 T:2756703312  NOTICE: -->Python Interpreter Initialized<--
06:02:19 34300.000000 T:2699904080  NOTICE: Thread JobWorker start, auto delete: true
06:02:19 34300.003906 T:2756703312  NOTICE: Thread LanguageInvoker start, auto delete: false
06:02:19 34300.464844 T:2756703312  NOTICE: -->Python Interpreter Initialized<--
06:35:20 36281.457031 T:2708292688  NOTICE: Thread JobWorker start, auto delete: true
06:35:20 36281.464844 T:2878202960  NOTICE: Thread LanguageInvoker start, auto delete: false
06:35:21 36282.003906 T:2878202960  NOTICE: -->Python Interpreter Initialized<--
07:08:22 38263.003906 T:2878202960  NOTICE: Thread JobWorker start, auto delete: true
07:08:22 38263.007812 T:2708292688  NOTICE: Thread LanguageInvoker start, auto delete: false
07:08:23 38263.617188 T:2708292688  NOTICE: -->Python Interpreter Initialized<--
07:21:21 39041.652344 T:2756703312  NOTICE: Thread JobWorker start, auto delete: true
07:21:31 39051.847656 T:2756703312   ERROR: CCurlFile::FillBuffer - Failed: Timeout was reached(28)
07:21:31 39051.847656 T:2756703312   ERROR: CCurlFile::CReadState::Connect, didn't get any data from stream.
07:21:41 39061.750000 T:2756703312   ERROR: Open - failed to open source <http://mirrors.xbmc.org/addons/gotham/addons.xml.md5>
07:21:51 39071.863281 T:2756703312   ERROR: CCurlFile::FillBuffer - Failed: Timeout was reached(28)
07:21:51 39071.863281 T:2756703312   ERROR: CCurlFile::CReadState::Connect, didn't get any data from stream.
07:22:01 39081.824219 T:2756703312   ERROR: Open - failed to open source <http://mirrors.xbmc.org/addons/frodo/addons.xml.md5>
07:22:12 39092.625000 T:2756703312   ERROR: CCurlFile::FillBuffer - Failed: Timeout was reached(28)
07:22:12 39092.625000 T:2756703312   ERROR: CCurlFile::CReadState::Connect, didn't get any data from stream.
07:22:22 39102.687500 T:2756703312   ERROR: Open - failed to open source <http://mirrors.xbmc.org/addons/gotham/addons.xml|Encoding=gzip>
07:22:32 39112.695312 T:2756703312   ERROR: CCurlFile::FillBuffer - Failed: Timeout was reached(28)
07:22:32 39112.695312 T:2756703312   ERROR: CCurlFile::CReadState::Connect, didn't get any data from stream.
07:22:42 39122.789062 T:2756703312   ERROR: Open - failed to open source <http://mirrors.xbmc.org/addons/frodo/addons.xml|Encoding=gzip>
07:22:42 39122.789062 T:2756703312   ERROR: Repository XBMC.org Add-ons returned no add-ons, listing may have failed
07:22:54 39134.597656 T:2756703312   ERROR: CCurlFile::FillBuffer - Failed: Timeout was reached(28)
07:22:54 39134.597656 T:2756703312   ERROR: CCurlFile::CReadState::Connect, didn't get any data from stream.
07:23:04 39144.648438 T:2756703312   ERROR: Open - failed to open source <http://github.com/xbmc-adult/xbmc-adult/raw/ghmaster/addons.xml.md5>
07:23:14 39154.660156 T:2756703312   ERROR: CCurlFile::FillBuffer - Failed: Timeout was reached(28)
07:23:14 39154.660156 T:2756703312   ERROR: CCurlFile::CReadState::Connect, didn't get any data from stream.
07:23:24 39164.710938 T:2756703312   ERROR: Open - failed to open source <http://github.com/xbmc-adult/xbmc-adult/raw/ghmaster/addons.xml>
07:23:24 39164.714844 T:2756703312   ERROR: Repository XBMC-Adult Addons returned no add-ons, listing may have failed
07:23:36 39176.503906 T:2756703312   ERROR: CCurlFile::FillBuffer - Failed: Timeout was reached(28)
07:23:36 39176.503906 T:2756703312   ERROR: CCurlFile::CReadState::Connect, didn't get any data from stream.
07:23:46 39186.554688 T:2756703312   ERROR: Open - failed to open source <http://addons.openelec.tv/4.1/RPi/arm/addons.xml.md5>
07:23:56 39196.562500 T:2756703312   ERROR: CCurlFile::FillBuffer - Failed: Timeout was reached(28)
07:23:56 39196.562500 T:2756703312   ERROR: CCurlFile::CReadState::Connect, didn't get any data from stream.
07:24:06 39206.632812 T:2756703312   ERROR: Open - failed to open source <http://addons.openelec.tv/4.1/RPi/arm/addons.xml>
07:24:06 39206.636719 T:2756703312   ERROR: Repository [COLOR FF757677]Open[/COLOR][COLOR FF8ABEE2]ELEC[/COLOR] Mediacenter OS Add-ons returned no add-ons, listing may have failed
07:41:24 40244.640625 T:2756703312  NOTICE: Thread JobWorker start, auto delete: true
07:41:24 40244.644531 T:2708292688  NOTICE: Thread LanguageInvoker start, auto delete: false
07:41:24 40245.089844 T:2708292688  NOTICE: -->Python Interpreter Initialized<--
08:14:25 42226.171875 T:2708292688  NOTICE: Thread JobWorker start, auto delete: true
08:14:25 42226.175781 T:2756703312  NOTICE: Thread LanguageInvoker start, auto delete: false
08:14:26 42226.707031 T:2756703312  NOTICE: -->Python Interpreter Initialized<--
08:47:27 44207.742188 T:2708292688  NOTICE: Thread JobWorker start, auto delete: true
08:47:27 44207.746094 T:2878202960  NOTICE: Thread LanguageInvoker start, auto delete: false
08:47:27 44208.191406 T:2878202960  NOTICE: -->Python Interpreter Initialized<--
09:20:28 46189.175781 T:2878202960  NOTICE: Thread JobWorker start, auto delete: true
09:20:28 46189.179688 T:2708292688  NOTICE: Thread LanguageInvoker start, auto delete: false
09:20:29 46189.679688 T:2708292688  NOTICE: -->Python Interpreter Initialized<--
09:53:30 48170.750000 T:2878202960  NOTICE: Thread JobWorker start, auto delete: true
09:53:30 48170.750000 T:2699904080  NOTICE: Thread LanguageInvoker start, auto delete: false
09:53:30 48171.261719 T:2699904080  NOTICE: -->Python Interpreter Initialized<--
10:26:31 50152.296875 T:2878202960  NOTICE: Thread JobWorker start, auto delete: true
10:26:31 50152.300781 T:2756703312  NOTICE: Thread LanguageInvoker start, auto delete: false
10:26:32 50152.746094 T:2756703312  NOTICE: -->Python Interpreter Initialized<--
10:27:49 50229.617188 T:3057811456  NOTICE: Storing total System Uptime
10:27:49 50229.621094 T:3057811456  NOTICE: Saving settings
10:27:49 50229.917969 T:3057811456  NOTICE: stop all
Script used to create the logs:

Code: Select all

#!/bin/sh
while : ; do
  mkdir -p /storage/dmesg
  now=$(date +%Y-%m-%d-%H.%M.%S)
  dmesg > /storage/dmesg/$now.log
  sleep 900 # 15 min
done &
Let me know if you need any more information or if I should try something else.

User avatar
fbutler
Posts: 302
Joined: Thu Mar 15, 2012 4:09 pm
Location: Surrey, England

Re: Call for beta testers: FIQ_FSM USB driver rewrite

Mon Mar 24, 2014 6:40 pm

jdb wrote:The BRANCH=next firmware now includes support for FIQ acceleration of high-speed isochronous transactions.
jdb,

Thanks for the work on this.

I've now tested this with the Pi capturing, and streaming, H.264 video from a Logitech c920 webcam. Previously streaming H.264 video using the c920 produced a significant number of artifacts especially where there was movement in the captured video.

With the new FIQ firmware with and dwc_otg.fiq_fsm_mask set to 0x7 in /boot/cmdline.txt I can happily stream H.264 from the c920 at 1920x1024 with no discernable artifacts so far. :)

With dwc_otg.fiq_fsm_mask set to 0x3 the artifacts return

For reference the command I'm using to stream is:

Code: Select all

cvlc -vvv v4l2:///dev/video0:chroma="H264":width=1920:height=1024:fps=30 --sout '#rtp{sdp=rtsp://:8554/}' :demux=h264
and I'm receiving the stream on a Windows machine with vlc using:

Code: Select all

rtsp://192.168.1.101:8554/

jdb
Raspberry Pi Engineer & Forum Moderator
Raspberry Pi Engineer & Forum Moderator
Posts: 2152
Joined: Thu Jul 11, 2013 2:37 pm

Re: Call for beta testers: FIQ_FSM USB driver rewrite

Tue Mar 25, 2014 10:38 am

Thanks for the feedback.

If anyone has any high-speed isochronous devices that are data sinks (i.e. video playback or output) it'd be interesting to see if those work.
Rockets are loud.
https://astro-pi.org

User avatar
Defiant
Posts: 179
Joined: Tue Oct 30, 2012 6:17 pm
Location: Hamburg, Germany

Re: Call for beta testers: FIQ_FSM USB driver rewrite

Tue Mar 25, 2014 8:23 pm

No visible improvement on stk1160 Video grabber.

jdb
Raspberry Pi Engineer & Forum Moderator
Raspberry Pi Engineer & Forum Moderator
Posts: 2152
Joined: Thu Jul 11, 2013 2:37 pm

Re: Call for beta testers: FIQ_FSM USB driver rewrite

Tue Mar 25, 2014 9:29 pm

Defiant wrote:No visible improvement on stk1160 Video grabber.
Please post full dmesg since boot and lsusb -v for the device.
Rockets are loud.
https://astro-pi.org

User avatar
Defiant
Posts: 179
Joined: Tue Oct 30, 2012 6:17 pm
Location: Hamburg, Germany

Re: Call for beta testers: FIQ_FSM USB driver rewrite

Wed Mar 26, 2014 7:18 am

Sure, please note that the device needs a lot bandwidth: It uses a resolution of 720x480px with 30 fps and 2 Byte/px uncompressed.

lsusb: http://pastebin.com/mEvKz2xv
dmesg: http://pastebin.com/xjhUY59g

User avatar
fbutler
Posts: 302
Joined: Thu Mar 15, 2012 4:09 pm
Location: Surrey, England

Re: Call for beta testers: FIQ_FSM USB driver rewrite

Wed Mar 26, 2014 9:07 am

Defiant wrote:No visible improvement on stk1160 Video grabber.
Hi Defiant, What command are you using to perform the capture?

I've got a em28xx device that I can test for comparison, although I have a feeling that it's just the sheer amount of data that is the issue now.

What percentage of CPU is the command consuming when capturing?

jdb
Raspberry Pi Engineer & Forum Moderator
Raspberry Pi Engineer & Forum Moderator
Posts: 2152
Joined: Thu Jul 11, 2013 2:37 pm

Re: Call for beta testers: FIQ_FSM USB driver rewrite

Wed Mar 26, 2014 1:04 pm

Defiant wrote:Sure, please note that the device needs a lot bandwidth: It uses a resolution of 720x480px with 30 fps and 2 Byte/px uncompressed.

lsusb: http://pastebin.com/mEvKz2xv
dmesg: http://pastebin.com/xjhUY59g
Ah.

Random packets within the sequence assigned to the FIQ are reporting frame overruns. This is bad, for given values of bad. For a 3x1024byte bus transaction takes approx 60uS. It then takes the DMA engine "some time" to finish pushing the data to system RAM but shouldn't be much longer than a few uS and signal completion. The FIQ has to service the interrupt and reprogram the channel before the point at which a periodic channel enable will result in a frameoverrun interrupt (should be EOF1, but depends on when the bus write actually gets there). The longest I've seen a FIQ take is approx 15uS which should leave enough time to poke the channel enable bit.

The bad thing about frame overrun in this fashion is the data gets transferred, but the core throws it away. This means that for devices that have some stream-specific method of tracking bytecount in and out means that whole frames get thrown away.

Lower bandwidth/resolutions shouldn't put quite so much stress on the CPU or USB - I would expect those to work.

Edit: are you overclocked?
Rockets are loud.
https://astro-pi.org

User avatar
Defiant
Posts: 179
Joined: Tue Oct 30, 2012 6:17 pm
Location: Hamburg, Germany

Re: Call for beta testers: FIQ_FSM USB driver rewrite

Thu Mar 27, 2014 6:40 am

Or in this case will end up with parts of the Image missing. Unfortunately this device/driver does not support lower bandwidth modes.

I'm not overclocked, the command I used for testing was

Code: Select all

gst-launch-1.0 v4l2src ! fakesink
and top reports about 5% for this process.

When I want to see the output I usually use something like

Code: Select all

gst-launch-1.0 v4l2src num-buffers=100 ! jpegenc ! avimux ! filesink location=/tmp/test.avi

jdb
Raspberry Pi Engineer & Forum Moderator
Raspberry Pi Engineer & Forum Moderator
Posts: 2152
Joined: Thu Jul 11, 2013 2:37 pm

Re: Call for beta testers: FIQ_FSM USB driver rewrite

Thu Mar 27, 2014 8:55 am

One thought: do you have dwc_otg.fiq_fsm_mask=0x7 and dwc_otg.fiq_fsm_enable=1 set in /boot/cmdline.txt? Your dmesg output is truncated.
Rockets are loud.
https://astro-pi.org

User avatar
Defiant
Posts: 179
Joined: Tue Oct 30, 2012 6:17 pm
Location: Hamburg, Germany

Re: Call for beta testers: FIQ_FSM USB driver rewrite

Thu Mar 27, 2014 5:27 pm

Above dmesg is with dwc_otg.fiq_fsm_mask=0x3, just tried it with 0x7 and got the same Buffer overrun errors.

TomasC
Posts: 2
Joined: Mon Mar 03, 2014 11:44 am

Re: Call for beta testers: FIQ_FSM USB driver rewrite

Fri Mar 28, 2014 3:15 am

B+W wrote: Thank you for the reply. I don't think I've ever manually updated this way. How would this work for installing the next branch?
not needed, just do: sudo UPDATE_SELF=0 BRANCH=next rpi-update

kursus
Posts: 25
Joined: Thu Oct 31, 2013 2:36 pm

Re: Call for beta testers: FIQ_FSM USB driver rewrite

Sun Mar 30, 2014 6:38 pm

Thank you very much for your work, I have noticed huge improvements on a Hifimediy Sabre SE9023 DAC with Pulseaudio streams, especially with the latest version of drivers. (Updating to Pulseaudio 5 may have also improve the whole thing.)

Situation before :

- Buffer overrun after less than an hour of playback, forced to reboot
- Changing the source (eg from Spotify to VLC) or actions like change track, fast forward, pause or volume change were likely to trigger overrun
- High-quality audio files could barely be played

Situation with new driver :

- Everything above is (almost) fixed. I can play high-quality audio files for hours, sometimes days, with no problem, switch software, skip tracks, and enjoy my music the way I want.
- Puseaudio monitor states an average 1s of delay whatever I do.

Thank you very much.

A fair amount of free space seems mandatory !

pmmd
Posts: 3
Joined: Tue Apr 01, 2014 11:13 pm

Re: Call for beta testers: FIQ_FSM USB driver rewrite

Tue Apr 01, 2014 11:26 pm

Defiant wrote:No visible improvement on stk1160 Video grabber.
I can confirm.
$ lsusb
Bus 001 Device 004: ID 05e1:0408 Syntek Semiconductor Co., Ltd STK1160 Video Capture Device
$ uname -a
Linux **** 3.10.33+ #659 PREEMPT Wed Mar 19 16:33:57 GMT 2014 armv6l GNU/Linux
$ cat /boot/cmdline.txt
dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0x7 dwc_otg.lpm_enable=0 console=ttyAMA0,115200 kgdboc=ttyAMA0,115200 console=tty1 root=/dev/mmcblk0p2 rootfstype=ext4 elevator=deadline rootwait
$ dmesg
[...]
[ 1143.187315] URB packet 0, status -63 [Buffer error (overrun)].
[ 1143.260192] URB packet 0, status -63 [Buffer error (overrun)].
[ 1146.717841] print_err_status: 14 callbacks suppressed
[ 1146.717877] URB packet 0, status -63 [Buffer error (overrun)].
[ 1146.968293] URB packet 0, status -63 [Buffer error (overrun)].
[ 1147.097833] URB packet 0, status -63 [Buffer error (overrun)].
[...]
I tried with the following command:

Code: Select all

mplayer -tv driver=v4l2:width=320:height=240:outfmt=rgb32:input=1 tv://
with a lot of the following lines
[...]Frame too small! (827920<829440) Wrong format?
Frame too small! (823098<829440) Wrong format?
V: 0.0 54/ 54 ??% ??% ??,?% 0 0
Frame too small! (825758<829440) Wrong format?
Frame too small! (825420<829440) Wrong format?[...]
more information:

STK1160 doesn't allow downscaling and is always using UYVY as colorspace.
The video stream from the grabber is worse with this update than before.

If I can provide more information, don't hesitate to ask

Return to “Troubleshooting”