search

raspberrypi / linux

Kernel source tree for Raspberry Pi-provided kernel builds. Issues unrelated to the linux kernel should be posted on the community forum at https://forums.raspberrypi.com/
Other
11.14k stars 4.99k forks source link

kernel oops while dereferencing null pointer in function fiq_sof_handle #499

Closed mfs12 closed 10 years ago

mfs12 commented 10 years ago

Hallo,

found a kernel oops in the following kernel Linux rsp 3.10.25-2-ARCH #1 PREEMPT Thu Jan 9 13:39:28 MST 2014 armv6l GNU/Linux (git dee3db1c55e9a1c2efce858f44d9810cc2392b18)

while streaming audio from my mobile or linux laptop to the raspberry pi.

The bug can be triggered:

Setup:

Description: After some time the kernel just crashes. Take a look at the picture for the kernel oops.

It seems to run more stable when the following dwc_otg config values are deactivated:

20140114_221743

liamstask commented 10 years ago

Also seeing this on both 2013-12-20-wheezy-raspbian.img and 2014-01-07-wheezy-raspbian.img at least.

Edit: this also appears to be the same issue as reported in #482

liamstask commented 10 years ago

As hinted in #491, setting dwc_otg.fiq_split_enable=0 in /boot/cmdline.txt works for me as a workaround.

BranthTheDragon commented 10 years ago

I'm new to this, and getting the same error. If by "setting dwc_otg.fiq_split_enable=0 in /boot/cmdline.txt" you meant adding "dwc_otg.fiq_split_enable=0" to the end of the text file "/boot/cmdline.txt," I tried that and got the same error after perhaps 30 seconds of music.

liamstask commented 10 years ago

@BranthTheDragon try adding it into the existing line of config elements, space delimited as the other are

popcornmix commented 10 years ago

You can check if it's been disabled successfully with:

$ dmsg | grep FIQ
[    1.928577] dwc_otg: FIQ enabled
[    1.928607] dwc_otg: FIQ split fix enabled
BranthTheDragon commented 10 years ago

Well, with FIQ enabled and FIQ split fix enabled (as seen by the dmesg | grep FIQ) I still get my error. If FIQ split fix is disabled, I don't get my error anymore. Sound quality is garbage though, no matter which option I choose... >.<

I'm beginning to think that the RPi is not the best platform for a bluetooth audio setup.

popcornmix commented 10 years ago

There is work still continuing on USB driver by @P33M which may improve things when it's ready. Perhaps @P33M can comment on whether bluetooth audio is something that is expected to work better in the future?

P33M commented 10 years ago

Most certainly.

I believe the main issue causing the crashes is a "missed complete" where the FIQ has previously attempted to perform the complete-split stage but ran out of microframe time.

Currently the FIQ does not keep the SOF interrupt unconditionally unmasked, which is required to advance a split transaction. My hunch is that there's some long period where interrupts get disabled and SOF is also masked causing a case where the FIQ can never complete a transaction because it's too late.

The rewrite should address this - the FIQ always services the SOF interrupt and keeps it unmasked to progress all stages of a split transaction.

Edit: yep, can see this happening on the analyzer. The split-complete for a split transaction can get sent up to 10ms after the start-split.

richard-tx commented 10 years ago

adding the statement dwc_otg.fiq_split_enable=0 to cmdline.txt effectively disables the keyboard.

richard-tx commented 10 years ago

Just for kicks, what changed between the 9-15 release and the 12-20 release?
Is it possible to use the driver from 9-15 as a temporary workaround?

popcornmix commented 10 years ago

Possibly this: https://github.com/raspberrypi/linux/pull/380 Looks like: dwc_otg.nak_holdoff_enable=0 will revert that change.

P33M commented 10 years ago

Interesting.

I'm at a loss as to why that would break things... Useful data point, though.

richard-tx commented 10 years ago

Adding dwc_otg.nak_holdoff_enable=0 helps but it does not solve the issue. It paniced again. It just takes a long time to panic.

richard-tx commented 10 years ago

I just upgraded my wifi router to 802.11n The panics are more frequent. I am going to abandon the current load and go back to 9-20-13 wheezy. Please let me know when this issue has been resolved.

P33M commented 10 years ago

Give sudo BRANCH=next rpi-update a go. This has the new fiq_fsm rewrite which should behave much better with bluetooth devices.

richard-tx commented 10 years ago

I tried the update. A few seconds after issuing a mpc play command, the keyboard went dead.

P33M commented 10 years ago

Can you post the results of dmesg and lsusb -v? It's likely that replugging your keyboard will cause it to come back to life. Or SSH in and get the logs that way.

richard-tx commented 10 years ago 
# dmesg
[    0.000000] Booting Linux on physical CPU 0x0
[    0.000000] Initializing cgroup subsys cpu
[    0.000000] Initializing cgroup subsys cpuacct
[    0.000000] Linux version 3.10.30+ (dc4@dc4-arm-01) (gcc version 4.7.2 20120731 (prerelease) (crosstool-NG linaro-1.13.1+bzr2458 - Linaro GCC 2012.08) ) #644 PREEMPT Fri Feb 21 18:44:33 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] cma: CMA: reserved 16 MiB at 1b000000
[    0.000000] Memory policy: ECC disabled, Data cache writeback
[    0.000000] On node 0 totalpages: 114688
[    0.000000] free_area_init_node: node 0, pgdat c05d3e84, node_mem_map c0684000
[    0.000000]   Normal zone: 896 pages used for memmap
[    0.000000]   Normal zone: 0 pages reserved
[    0.000000]   Normal zone: 114688 pages, LIFO batch:31
[    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: 113792
[    0.000000] Kernel command line: dma.dmachans=0x7f35 bcm2708_fb.fbwidth=1920 bcm2708_fb.fbheight=1200 bcm2708.boardrev=0xe bcm2708.serial=0x6ecdc6c smsc95xx.macaddr=B8:27:EB:EC:DC:6C sdhci-bcm2708.emmc_clock_freq=250000000 vc_mem.mem_base=0x1ec00000 vc_mem.mem_size=0x20000000  dwc_otg.lpm_enable=0 console=ttyAMA0,115200 kgdboc=ttyAMA0,115200 console=tty1 root=/dev/mmcblk0p2 rootfstype=ext4 elevator=deadline rootwait
[    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: 448MB = 448MB total
[    0.000000] Memory: 431624k/431624k available, 27128k reserved, 0K highmem
[    0.000000] Virtual kernel memory layout:
[    0.000000]     vector  : 0xffff0000 - 0xffff1000   (   4 kB)
[    0.000000]     fixmap  : 0xfff00000 - 0xfffe0000   ( 896 kB)
[    0.000000]     vmalloc : 0xdc800000 - 0xff000000   ( 552 MB)
[    0.000000]     lowmem  : 0xc0000000 - 0xdc000000   ( 448 MB)
[    0.000000]     modules : 0xbf000000 - 0xc0000000   (  16 MB)
[    0.000000]       .text : 0xc0008000 - 0xc0575858   (5559 kB)
[    0.000000]       .init : 0xc0576000 - 0xc059a344   ( 145 kB)
[    0.000000]       .data : 0xc059c000 - 0xc05d4990   ( 227 kB)
[    0.000000]        .bss : 0xc05d4990 - 0xc0683060   ( 698 kB)
[    0.000000] SLUB: HWalign=32, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
[    0.000000] Preemptible hierarchical RCU implementation.
[    0.000000] NR_IRQS:330
[    0.000000] sched_clock: 32 bits at 1000kHz, resolution 1000ns, wraps every 4294967ms
[    0.000000] Switching to timer-based delay loop
[    0.000000] Console: colour dummy device 80x30
[    0.000000] console [tty1] enabled
[    0.001152] Calibrating delay loop (skipped), value calculated using timer frequency.. 2.00 BogoMIPS (lpj=10000)
[    0.001217] pid_max: default: 32768 minimum: 301
[    0.001688] Mount-cache hash table entries: 512
[    0.002490] Initializing cgroup subsys memory
[    0.002593] Initializing cgroup subsys devices
[    0.002634] Initializing cgroup subsys freezer
[    0.002668] Initializing cgroup subsys blkio
[    0.002827] CPU: Testing write buffer coherency: ok
[    0.003295] Setting up static identity map for 0xc0408ee8 - 0xc0408f44
[    0.005087] devtmpfs: initialized
[    0.019286] NET: Registered protocol family 16
[    0.025224] DMA: preallocated 4096 KiB pool for atomic coherent allocations
[    0.026326] bcm2708.uart_clock = 0
[    0.028019] hw-breakpoint: found 6 breakpoint and 1 watchpoint registers.
[    0.028073] hw-breakpoint: maximum watchpoint size is 4 bytes.
[    0.028112] mailbox: Broadcom VideoCore Mailbox driver
[    0.028209] bcm2708_vcio: mailbox at f200b880
[    0.028314] bcm_power: Broadcom power driver
[    0.028354] bcm_power_open() -> 0
[    0.028382] bcm_power_request(0, 8)
[    0.529104] bcm_mailbox_read -> 00000080, 0
[    0.529148] bcm_power_request -> 0
[    0.529370] Serial: AMBA PL011 UART driver
[    0.529515] dev:f1: ttyAMA0 at MMIO 0x20201000 (irq = 83) is a PL011 rev3
[    0.871979] console [ttyAMA0] enabled
[    0.898000] bio: create slab <bio-0> at 0
[    0.903305] SCSI subsystem initialized
[    0.907292] usbcore: registered new interface driver usbfs
[    0.913006] usbcore: registered new interface driver hub
[    0.918561] usbcore: registered new device driver usb
[    0.925114] Switching to clocksource stc
[    0.929456] FS-Cache: Loaded
[    0.932612] CacheFiles: Loaded
[    0.947931] NET: Registered protocol family 2
[    0.953328] TCP established hash table entries: 4096 (order: 3, 32768 bytes)
[    0.960597] TCP bind hash table entries: 4096 (order: 2, 16384 bytes)
[    0.967123] TCP: Hash tables configured (established 4096 bind 4096)
[    0.973608] TCP: reno registered
[    0.976868] UDP hash table entries: 256 (order: 0, 4096 bytes)
[    0.982778] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes)
[    0.989514] NET: Registered protocol family 1
[    0.994439] RPC: Registered named UNIX socket transport module.
[    1.000504] RPC: Registered udp transport module.
[    1.005231] RPC: Registered tcp transport module.
[    1.009983] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    1.017348] bcm2708_dma: DMA manager at f2007000
[    1.022170] bcm2708_gpio: bcm2708_gpio_probe c05a9e50
[    1.027639] vc-mem: phys_addr:0x00000000 mem_base=0x1ec00000 mem_size:0x20000000(512 MiB)
[    1.036993] audit: initializing netlink socket (disabled)
[    1.042659] type=2000 audit(0.890:1): initialized
[    1.204340] VFS: Disk quotas dquot_6.5.2
[    1.208585] Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[    1.217521] FS-Cache: Netfs 'nfs' registered for caching
[    1.224245] NFS: Registering the id_resolver key type
[    1.229546] Key type id_resolver registered
[    1.233758] Key type id_legacy registered
[    1.238478] msgmni has been set to 875
[    1.244403] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 252)
[    1.252250] io scheduler noop registered
[    1.256211] io scheduler deadline registered (default)
[    1.261795] io scheduler cfq registered
[    1.267086] BCM2708FB: allocated DMA memory 5b400000
[    1.272266] BCM2708FB: allocated DMA channel 0 @ f2007000
[    1.319957] Console: switching to colour frame buffer device 240x75
[    1.344845] uart-pl011 dev:f1: no DMA platform data
[    1.349944] kgdb: Registered I/O driver kgdboc.
[    1.355233] vc-cma: Videocore CMA driver
[    1.359337] vc-cma: vc_cma_base      = 0x00000000
[    1.364135] vc-cma: vc_cma_size      = 0x00000000 (0 MiB)
[    1.369724] vc-cma: vc_cma_initial   = 0x00000000 (0 MiB)
[    1.384651] brd: module loaded
[    1.393201] loop: module loaded
[    1.396726] vchiq: vchiq_init_state: slot_zero = 0xdb000000, is_master = 0
[    1.404848] Loading iSCSI transport class v2.0-870.
[    1.411110] usbcore: registered new interface driver smsc95xx
[    1.417227] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
[    1.623364] Core Release: 2.80a
[    1.626622] Setting default values for core params
[    1.631632] Finished setting default values for core params
[    1.837377] Using Buffer DMA mode
[    1.840817] Periodic Transfer Interrupt Enhancement - disabled
[    1.846761] Multiprocessor Interrupt Enhancement - disabled
[    1.852466] OTG VER PARAM: 0, OTG VER FLAG: 0
[    1.856917] Dedicated Tx FIFOs mode
[    1.861324] WARN::dwc_otg_hcd_init:1047: FIQ DMA bounce buffers: virt = 0xdb414000 dma = 0x5b414000 len=9024
[    1.871472] dwc_otg: Microframe scheduler enabled
[    1.871696] WARN::hcd_init:474: FIQ at 0xc02f1eac
[    1.876508] WARN::hcd_init:475: FIQ ASM at 0xc02f1f8c length 36
[    1.882595] WARN::hcd_init:501: MPHI regs_base at 0xdc806000
[    1.888378] dwc_otg bcm2708_usb: DWC OTG Controller
[    1.893437] dwc_otg bcm2708_usb: new USB bus registered, assigned bus number 1
[    1.900878] dwc_otg bcm2708_usb: irq 32, io mem 0x00000000
[    1.906490] Init: Port Power? op_state=1
[    1.910531] Init: Power Port (0)
[    1.913934] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
[    1.920896] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.928247] usb usb1: Product: DWC OTG Controller
[    1.933077] usb usb1: Manufacturer: Linux 3.10.30+ dwc_otg_hcd
[    1.939021] usb usb1: SerialNumber: bcm2708_usb
[    1.944513] hub 1-0:1.0: USB hub found
[    1.948395] hub 1-0:1.0: 1 port detected
[    1.952849] dwc_otg: FIQ enabled
[    1.952868] dwc_otg: NAK holdoff enabled
[    1.952880] dwc_otg: FIQ split-transaction FSM disabled
[    1.952902] Module dwc_common_port init
[    1.953384] usbcore: registered new interface driver usb-storage
[    1.960003] mousedev: PS/2 mouse device common for all mice
[    1.966333] bcm2835-cpufreq: min=700000 max=700000 cur=700000
[    1.972416] bcm2835-cpufreq: switching to governor powersave
[    1.983667] bcm2835-cpufreq: switching to governor powersave
[    1.994657] cpuidle: using governor ladder
[    2.004063] cpuidle: using governor menu
[    2.013297] sdhci: Secure Digital Host Controller Interface driver
[    2.024760] sdhci: Copyright(c) Pierre Ossman
[    2.034444] sdhci: Enable low-latency mode
[    2.089167] mmc0: SDHCI controller on BCM2708_Arasan [platform] using platform's DMA
[    2.102699] mmc0: BCM2708 SDHC host at 0x20300000 DMA 2 IRQ 77
[    2.114222] sdhci-pltfm: SDHCI platform and OF driver helper
[    2.127421] ledtrig-cpu: registered to indicate activity on CPUs
[    2.146381] hidraw: raw HID events driver (C) Jiri Kosina
[    2.157443] Indeed it is in host mode hprt0 = 00021501
[    2.170379] usbcore: registered new interface driver usbhid
[    2.188194] usbhid: USB HID core driver
[    2.213204] TCP: cubic registered
[    2.239162] Initializing XFRM netlink socket
[    2.250687] NET: Registered protocol family 17
[    2.270214] mmc0: new high speed SDHC card at address 0002
[    2.289360] Key type dns_resolver registered
[    2.309804] mmcblk0: mmc0:0002 00000 7.32 GiB
[    2.320034] VFP support v0.3: implementor 41 architecture 1 part 20 variant b rev 5
[    2.334618]  mmcblk0: p1 p2
[    2.343448] registered taskstats version 1
[    2.370979] EXT4-fs (mmcblk0p2): recovery complete
[    2.381390] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
[    2.395072] VFS: Mounted root (ext4 filesystem) on device 179:2.
[    2.407660] devtmpfs: mounted
[    2.416666] Freeing unused kernel memory: 144K (c0576000 - c059a000)
[    2.449222] usb 1-1: new high-speed USB device number 2 using dwc_otg
[    2.462049] Indeed it is in host mode hprt0 = 00001101
[    2.669883] usb 1-1: New USB device found, idVendor=0424, idProduct=9512
[    2.682364] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[    2.696049] hub 1-1:1.0: USB hub found
[    2.705538] hub 1-1:1.0: 3 ports detected
[    2.989438] usb 1-1.1: new high-speed USB device number 3 using dwc_otg
[    3.109643] usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00
[    3.133430] usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[    3.164211] smsc95xx v1.0.4
[    3.244230] smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-bcm2708_usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:ec:dc:6c
[    3.379487] usb 1-1.2: new high-speed USB device number 4 using dwc_otg
[    3.490941] usb 1-1.2: New USB device found, idVendor=05e3, idProduct=0608
[    3.519290] usb 1-1.2: New USB device strings: Mfr=0, Product=1, SerialNumber=0
[    3.546355] usb 1-1.2: Product: USB2.0 Hub
[    3.564727] hub 1-1.2:1.0: USB hub found
[    3.589652] hub 1-1.2:1.0: 4 ports detected
[    3.889621] usb 1-1.2.2: new low-speed USB device number 5 using dwc_otg
[    4.050912] usb 1-1.2.2: New USB device found, idVendor=04d9, idProduct=1203
[    4.077383] usb 1-1.2.2: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[    4.129375] input: HID 04d9:1203 as /devices/platform/bcm2708_usb/usb1/1-1/1-1.2/1-1.2.2/1-1.2.2:1.0/input/input0
[    4.159732] hid-generic 0003:04D9:1203.0001: input,hidraw0: USB HID v1.11 Keyboard [HID 04d9:1203] on usb-bcm2708_usb-1.2.2/input0
[    4.230419] input: HID 04d9:1203 as /devices/platform/bcm2708_usb/usb1/1-1/1-1.2/1-1.2.2/1-1.2.2:1.1/input/input1
[    4.269758] hid-generic 0003:04D9:1203.0002: input,hidraw1: USB HID v1.11 Device [HID 04d9:1203] on usb-bcm2708_usb-1.2.2/input1
[    4.366947] udevd[155]: starting version 175
[    4.379600] usb 1-1.2.3: new high-speed USB device number 6 using dwc_otg
[    4.511982] usb 1-1.2.3: New USB device found, idVendor=0bda, idProduct=8174
[    4.554736] usb 1-1.2.3: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[    4.599287] usb 1-1.2.3: Product: RTL8192S WLAN Adapter
[    4.629272] usb 1-1.2.3: Manufacturer: Manufacturer Realtek
[    4.664854] usb 1-1.2.3: SerialNumber: 00e04c000001
[    4.779707] usb 1-1.2.4: new low-speed USB device number 7 using dwc_otg
[    4.930129] usb 1-1.2.4: New USB device found, idVendor=045e, idProduct=0039
[    4.969303] usb 1-1.2.4: New USB device strings: Mfr=1, Product=3, SerialNumber=0
[    5.009352] usb 1-1.2.4: Product: Microsoft 5-Button Mouse with IntelliEye(TM)
[    5.031391] usb 1-1.2.4: Manufacturer: Microsoft
[    5.071783] input: Microsoft Microsoft 5-Button Mouse with IntelliEye(TM) as /devices/platform/bcm2708_usb/usb1/1-1/1-1.2/1-1.2.4/1-1.2.4:1.0/input/input2
[    5.110183] r8712u: module is from the staging directory, the quality is unknown, you have been warned.
[    5.139995] hid-generic 0003:045E:0039.0003: input,hidraw2: USB HID v1.10 Mouse [Microsoft Microsoft 5-Button Mouse with IntelliEye(TM)] on usb-bcm2708_usb-1.2.4/input0
[    5.234871] r8712u: Staging version
[    5.294016] r8712u: register rtl8712_netdev_ops to netdev_ops
[    5.359318] usb 1-1.2.3: r8712u: USB_SPEED_HIGH with 4 endpoints
[    5.408403] usb 1-1.2.3: r8712u: Boot from EFUSE: Autoload OK
[    7.177229] bcm2708-i2s bcm2708-i2s.0: Failed to create debugfs directory
[    7.409302] usb 1-1.2.3: r8712u: CustomerID = 0x0000
[    7.716833] usb 1-1.2.3: r8712u: MAC Address from efuse = e8:4e:06:10:65:97
[    7.993408] usb 1-1.2.3: r8712u: Loading firmware from "rtlwifi/rtl8712u.bin"
[    8.250565] usbcore: registered new interface driver r8712u
[   12.703223] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null)
[   13.320574] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null)
[   20.578361] FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck.
[   24.609660] r8712u 1-1.2.3:1.0 wlan0: 1 RCR=0x153f00e
[   24.610392] r8712u 1-1.2.3:1.0 wlan0: 2 RCR=0x553f00e
[   24.783999] smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup
[   33.783822] Adding 102396k swap on /var/swap.  Priority:-1 extents:1 across:102396k SSFS
[   36.949706] r8712u 1-1.2.3:1.0 wlan0: r8712_got_addbareq_event_callback: mac = c8:d3:a3:57:31:61, seq = 56160, tid = 0
[   75.876992] r8712u 1-1.2.3:1.0 wlan0: r8712_got_addbareq_event_callback: mac = c8:d3:a3:57:31:61, seq = 4784, tid = 6
richard-tx commented 10 years ago 
# lsusb -v

Bus 001 Device 002: ID 0424:9512 Standard Microsystems Corp.
Device Descriptor:
  bLength                18
  bDescriptorType         1
  bcdUSB               2.00
  bDeviceClass            9 Hub
  bDeviceSubClass         0 Unused
  bDeviceProtocol         2 TT per port
  bMaxPacketSize0        64
  idVendor           0x0424 Standard Microsystems Corp.
  idProduct          0x9512
  bcdDevice            2.00
  iManufacturer           0
  iProduct                0
  iSerial                 0
  bNumConfigurations      1
  Configuration Descriptor:
    bLength                 9
    bDescriptorType         2
    wTotalLength           41
    bNumInterfaces          1
    bConfigurationValue     1
    iConfiguration          0
    bmAttributes         0xe0
      Self Powered
      Remote Wakeup
    MaxPower                2mA
    Interface Descriptor:
      bLength                 9
      bDescriptorType         4
      bInterfaceNumber        0
      bAlternateSetting       0
      bNumEndpoints           1
      bInterfaceClass         9 Hub
      bInterfaceSubClass      0 Unused
      bInterfaceProtocol      1 Single TT
      iInterface              0
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x81  EP 1 IN
        bmAttributes            3
          Transfer Type            Interrupt
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0001  1x 1 bytes
        bInterval              12
    Interface Descriptor:
      bLength                 9
      bDescriptorType         4
      bInterfaceNumber        0
      bAlternateSetting       1
      bNumEndpoints           1
      bInterfaceClass         9 Hub
      bInterfaceSubClass      0 Unused
      bInterfaceProtocol      2 TT per port
      iInterface              0
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x81  EP 1 IN
        bmAttributes            3
          Transfer Type            Interrupt
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0001  1x 1 bytes
        bInterval              12
Hub Descriptor:
  bLength               9
  bDescriptorType      41
  nNbrPorts             3
  wHubCharacteristic 0x000d
    Per-port power switching
    Compound device
    Per-port overcurrent protection
    TT think time 8 FS bits
  bPwrOn2PwrGood       50 * 2 milli seconds
  bHubContrCurrent      1 milli Ampere
  DeviceRemovable    0x02
  PortPwrCtrlMask    0xff
 Hub Port Status:
   Port 1: 0000.0503 highspeed power enable connect
   Port 2: 0000.0503 highspeed power enable connect
   Port 3: 0000.0100 power
Device Qualifier (for other device speed):
  bLength                10
  bDescriptorType         6
  bcdUSB               2.00
  bDeviceClass            9 Hub
  bDeviceSubClass         0 Unused
  bDeviceProtocol         0 Full speed (or root) hub
  bMaxPacketSize0        64
  bNumConfigurations      1
Device Status:     0x0001
  Self Powered

Bus 001 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub
Device Descriptor:
  bLength                18
  bDescriptorType         1
  bcdUSB               2.00
  bDeviceClass            9 Hub
  bDeviceSubClass         0 Unused
  bDeviceProtocol         1 Single TT
  bMaxPacketSize0        64
  idVendor           0x1d6b Linux Foundation
  idProduct          0x0002 2.0 root hub
  bcdDevice            3.10
  iManufacturer           3 Linux 3.10.30+ dwc_otg_hcd
  iProduct                2 DWC OTG Controller
  iSerial                 1 bcm2708_usb
  bNumConfigurations      1
  Configuration Descriptor:
    bLength                 9
    bDescriptorType         2
    wTotalLength           25
    bNumInterfaces          1
    bConfigurationValue     1
    iConfiguration          0
    bmAttributes         0xe0
      Self Powered
      Remote Wakeup
    MaxPower                0mA
    Interface Descriptor:
      bLength                 9
      bDescriptorType         4
      bInterfaceNumber        0
      bAlternateSetting       0
      bNumEndpoints           1
      bInterfaceClass         9 Hub
      bInterfaceSubClass      0 Unused
      bInterfaceProtocol      0 Full speed (or root) hub
      iInterface              0
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x81  EP 1 IN
        bmAttributes            3
          Transfer Type            Interrupt
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0004  1x 4 bytes
        bInterval              12
Hub Descriptor:
  bLength               9
  bDescriptorType      41
  nNbrPorts             1
  wHubCharacteristic 0x0008
    Ganged power switching
    Per-port overcurrent protection
    TT think time 8 FS bits
  bPwrOn2PwrGood        1 * 2 milli seconds
  bHubContrCurrent      0 milli Ampere
  DeviceRemovable    0x00
  PortPwrCtrlMask    0xff
 Hub Port Status:
   Port 1: 0000.0503 highspeed power enable connect
Device Status:     0x0001
  Self Powered

Bus 001 Device 003: ID 0424:ec00 Standard Microsystems Corp.
Device Descriptor:
  bLength                18
  bDescriptorType         1
  bcdUSB               2.00
  bDeviceClass          255 Vendor Specific Class
  bDeviceSubClass         0
  bDeviceProtocol         1
  bMaxPacketSize0        64
  idVendor           0x0424 Standard Microsystems Corp.
  idProduct          0xec00
  bcdDevice            2.00
  iManufacturer           0
  iProduct                0
  iSerial                 0
  bNumConfigurations      1
  Configuration Descriptor:
    bLength                 9
    bDescriptorType         2
    wTotalLength           39
    bNumInterfaces          1
    bConfigurationValue     1
    iConfiguration          0
    bmAttributes         0xe0
      Self Powered
      Remote Wakeup
    MaxPower                2mA
    Interface Descriptor:
      bLength                 9
      bDescriptorType         4
      bInterfaceNumber        0
      bAlternateSetting       0
      bNumEndpoints           3
      bInterfaceClass       255 Vendor Specific Class
      bInterfaceSubClass      0
      bInterfaceProtocol    255
      iInterface              0
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x81  EP 1 IN
        bmAttributes            2
          Transfer Type            Bulk
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0200  1x 512 bytes
        bInterval               0
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x02  EP 2 OUT
        bmAttributes            2
          Transfer Type            Bulk
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0200  1x 512 bytes
        bInterval               0
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x83  EP 3 IN
        bmAttributes            3
          Transfer Type            Interrupt
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0010  1x 16 bytes
        bInterval               4
Device Qualifier (for other device speed):
  bLength                10
  bDescriptorType         6
  bcdUSB               2.00
  bDeviceClass          255 Vendor Specific Class
  bDeviceSubClass         0
  bDeviceProtocol         1
  bMaxPacketSize0        64
  bNumConfigurations      1
Device Status:     0x0001
  Self Powered

Bus 001 Device 004: ID 05e3:0608 Genesys Logic, Inc. USB-2.0 4-Port HUB
Device Descriptor:
  bLength                18
  bDescriptorType         1
  bcdUSB               2.00
  bDeviceClass            9 Hub
  bDeviceSubClass         0 Unused
  bDeviceProtocol         1 Single TT
  bMaxPacketSize0        64
  idVendor           0x05e3 Genesys Logic, Inc.
  idProduct          0x0608 USB-2.0 4-Port HUB
  bcdDevice            9.01
  iManufacturer           0
  iProduct                1 USB2.0 Hub
  iSerial                 0
  bNumConfigurations      1
  Configuration Descriptor:
    bLength                 9
    bDescriptorType         2
    wTotalLength           25
    bNumInterfaces          1
    bConfigurationValue     1
    iConfiguration          0
    bmAttributes         0xe0
      Self Powered
      Remote Wakeup
    MaxPower              100mA
    Interface Descriptor:
      bLength                 9
      bDescriptorType         4
      bInterfaceNumber        0
      bAlternateSetting       0
      bNumEndpoints           1
      bInterfaceClass         9 Hub
      bInterfaceSubClass      0 Unused
      bInterfaceProtocol      0 Full speed (or root) hub
      iInterface              0
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x81  EP 1 IN
        bmAttributes            3
          Transfer Type            Interrupt
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0001  1x 1 bytes
        bInterval              12
Hub Descriptor:
  bLength               9
  bDescriptorType      41
  nNbrPorts             4
  wHubCharacteristic 0x00e9
    Per-port power switching
    Per-port overcurrent protection
    TT think time 32 FS bits
    Port indicators
  bPwrOn2PwrGood       50 * 2 milli seconds
  bHubContrCurrent    100 milli Ampere
  DeviceRemovable    0x00
  PortPwrCtrlMask    0xff
 Hub Port Status:
   Port 1: 0000.0100 power
   Port 2: 0000.0303 lowspeed power enable connect
   Port 3: 0000.0503 highspeed power enable connect
   Port 4: 0000.0303 lowspeed power enable connect
Device Qualifier (for other device speed):
  bLength                10
  bDescriptorType         6
  bcdUSB               2.00
  bDeviceClass            9 Hub
  bDeviceSubClass         0 Unused
  bDeviceProtocol         0 Full speed (or root) hub
  bMaxPacketSize0        64
  bNumConfigurations      1
Device Status:     0x0001
  Self Powered

Bus 001 Device 005: ID 04d9:1203 Holtek Semiconductor, Inc. Keyboard
Device Descriptor:
  bLength                18
  bDescriptorType         1
  bcdUSB               2.00
  bDeviceClass            0 (Defined at Interface level)
  bDeviceSubClass         0
  bDeviceProtocol         0
  bMaxPacketSize0         8
  idVendor           0x04d9 Holtek Semiconductor, Inc.
  idProduct          0x1203 Keyboard
  bcdDevice            2.80
  iManufacturer           0
  iProduct                0
  iSerial                 0
  bNumConfigurations      1
  Configuration Descriptor:
    bLength                 9
    bDescriptorType         2
    wTotalLength           59
    bNumInterfaces          2
    bConfigurationValue     1
    iConfiguration          0
    bmAttributes         0xa0
      (Bus Powered)
      Remote Wakeup
    MaxPower              100mA
    Interface Descriptor:
      bLength                 9
      bDescriptorType         4
      bInterfaceNumber        0
      bAlternateSetting       0
      bNumEndpoints           1
      bInterfaceClass         3 Human Interface Device
      bInterfaceSubClass      1 Boot Interface Subclass
      bInterfaceProtocol      1 Keyboard
      iInterface              0
        HID Device Descriptor:
          bLength                 9
          bDescriptorType        33
          bcdHID               1.11
          bCountryCode            0 Not supported
          bNumDescriptors         1
          bDescriptorType        34 Report
          wDescriptorLength      62
         Report Descriptors:
           ** UNAVAILABLE **
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x81  EP 1 IN
        bmAttributes            3
          Transfer Type            Interrupt
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0008  1x 8 bytes
        bInterval              10
    Interface Descriptor:
      bLength                 9
      bDescriptorType         4
      bInterfaceNumber        1
      bAlternateSetting       0
      bNumEndpoints           1
      bInterfaceClass         3 Human Interface Device
      bInterfaceSubClass      0 No Subclass
      bInterfaceProtocol      0 None
      iInterface              0
        HID Device Descriptor:
          bLength                 9
          bDescriptorType        33
          bcdHID               1.11
          bCountryCode            0 Not supported
          bNumDescriptors         1
          bDescriptorType        34 Report
          wDescriptorLength     101
         Report Descriptors:
           ** UNAVAILABLE **
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x82  EP 2 IN
        bmAttributes            3
          Transfer Type            Interrupt
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0008  1x 8 bytes
        bInterval              10
Device Status:     0x0000
  (Bus Powered)

Bus 001 Device 006: ID 0bda:8174 Realtek Semiconductor Corp. RTL8192SU 802.11n WLAN Adapter
Device Descriptor:
  bLength                18
  bDescriptorType         1
  bcdUSB               2.00
  bDeviceClass            0 (Defined at Interface level)
  bDeviceSubClass         0
  bDeviceProtocol         0
  bMaxPacketSize0        64
  idVendor           0x0bda Realtek Semiconductor Corp.
  idProduct          0x8174 RTL8192SU 802.11n WLAN Adapter
  bcdDevice            2.00
  iManufacturer           1 Manufacturer Realtek
  iProduct                2 RTL8192S WLAN Adapter
  iSerial                 3 00e04c000001
  bNumConfigurations      1
  Configuration Descriptor:
    bLength                 9
    bDescriptorType         2
    wTotalLength           46
    bNumInterfaces          1
    bConfigurationValue     1
    iConfiguration          0
    bmAttributes         0x80
      (Bus Powered)
    MaxPower              500mA
    Interface Descriptor:
      bLength                 9
      bDescriptorType         4
      bInterfaceNumber        0
      bAlternateSetting       0
      bNumEndpoints           4
      bInterfaceClass       255 Vendor Specific Class
      bInterfaceSubClass    255 Vendor Specific Subclass
      bInterfaceProtocol    255 Vendor Specific Protocol
      iInterface              0
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x83  EP 3 IN
        bmAttributes            2
          Transfer Type            Bulk
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0200  1x 512 bytes
        bInterval               0
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x04  EP 4 OUT
        bmAttributes            2
          Transfer Type            Bulk
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0200  1x 512 bytes
        bInterval               0
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x06  EP 6 OUT
        bmAttributes            2
          Transfer Type            Bulk
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0200  1x 512 bytes
        bInterval               0
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x0d  EP 13 OUT
        bmAttributes            2
          Transfer Type            Bulk
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0200  1x 512 bytes
        bInterval               0
Device Qualifier (for other device speed):
  bLength                10
  bDescriptorType         6
  bcdUSB               2.00
  bDeviceClass            0 (Defined at Interface level)
  bDeviceSubClass         0
  bDeviceProtocol         0
  bMaxPacketSize0        64
  bNumConfigurations      1
Device Status:     0x0000
  (Bus Powered)

Bus 001 Device 007: ID 045e:0039 Microsoft Corp. IntelliMouse Optical
Device Descriptor:
  bLength                18
  bDescriptorType         1
  bcdUSB               1.10
  bDeviceClass            0 (Defined at Interface level)
  bDeviceSubClass         0
  bDeviceProtocol         0
  bMaxPacketSize0         8
  idVendor           0x045e Microsoft Corp.
  idProduct          0x0039 IntelliMouse Optical
  bcdDevice            3.00
  iManufacturer           1 Microsoft
  iProduct                3 Microsoft 5-Button Mouse with IntelliEye(TM)
  iSerial                 0
  bNumConfigurations      1
  Configuration Descriptor:
    bLength                 9
    bDescriptorType         2
    wTotalLength           34
    bNumInterfaces          1
    bConfigurationValue     1
    iConfiguration          0
    bmAttributes         0xa0
      (Bus Powered)
      Remote Wakeup
    MaxPower              100mA
    Interface Descriptor:
      bLength                 9
      bDescriptorType         4
      bInterfaceNumber        0
      bAlternateSetting       0
      bNumEndpoints           1
      bInterfaceClass         3 Human Interface Device
      bInterfaceSubClass      1 Boot Interface Subclass
      bInterfaceProtocol      2 Mouse
      iInterface              0
        HID Device Descriptor:
          bLength                 9
          bDescriptorType        33
          bcdHID               1.10
          bCountryCode            0 Not supported
          bNumDescriptors         1
          bDescriptorType        34 Report
          wDescriptorLength      72
         Report Descriptors:
           ** UNAVAILABLE **
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x81  EP 1 IN
        bmAttributes            3
          Transfer Type            Interrupt
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0004  1x 4 bytes
        bInterval              10
Device Status:     0x0000
  (Bus Powered)
ghollingworth commented 10 years ago

There are some command line parameters required for the FSM enabling, P33M have you a github issue link you can use to describe how to use the new code correctly?

[ 1.952880] dwc_otg: FIQ split-transaction FSM disabled

P33M commented 10 years ago

Link here:

http://www.raspberrypi.org/phpBB3/viewtopic.php?f=28&t=70437

section 4.

@richard-tx these options must be specified in /boot/cmdline.txt: dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0x3

audetto commented 10 years ago

I don't know if this is the same issues, please let me know if I should open a new one. Internal Audio + Bluetooth activity = kernel panic

Using arch linux 3.15.5, with this firmware

Feb 21 2014 17:44:46 
Copyright (c) 2012 Broadcom
version 67f299c3a92a793fb8b8efcaf94f7f553153f89a (clean) (release)

and here is: dmesg | grep dwc

[    1.895257] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
[    2.296022] dwc_otg: Microframe scheduler enabled
[    2.296080] dwc_otg bcm2708_usb: DWC OTG Controller
[    2.296138] dwc_otg bcm2708_usb: new USB bus registered, assigned bus number 1
[    2.296215] dwc_otg bcm2708_usb: irq 32, io mem 0x00000000
[    2.296668] usb usb1: Manufacturer: Linux 3.13.5-1-ARCH dwc_otg_hcd
[    2.298204] dwc_otg: FIQ enabled
[    2.298223] dwc_otg: NAK holdoff enabled
[    2.298233] dwc_otg: FIQ split fix enabled

Here is the output I got from a serial console

it is not a NPE, but it is the same location.

========================================================================
[  265.070229] Bad mode in undefined instruction handler detected
[  265.076211] Internal error: Oops - bad mode: 0 [#1] PREEMPT ARM
[  265.082230] Modules linked in: btusb bluetooth snd_bcm2835 snd_soc_wm8731 snd_soc_wm8804 ip6t_REJECT xt_hl snd_soc_bcm2708_i2s ip6t_rt regmap_mmio nf_conntrack_ipv6 nf_defrag_ipv6 snd_soc_core ipt_REJECT snd_compress xt_comment snd_pcm_dmaengine xt_recent snd_pcm snd_page_alloc leds_gpio snd_timer led_class xt_limit snd xt_tcpudp xt_addrtype spi_bcm2708 regmap_spi bcm2708_dmaengine regmap_i2c i2c_bcm2708 virt_dma nf_conntrack_ipv4 nf_defrag_ipv4 xt_conntrack ip6table_filter ip6_tables nf_conntrack_netbios_ns nf_conntrack_broadcast nf_nat_ftp nf_nat nf_conntrack_ftp nf_conntrack iptable_filter ip_tables x_tables bcm2708_rng rng_core ipv6
[  265.140189] CPU: 0 PID: 412 Comm: mpd Tainted: G        W    3.13.5-1-ARCH #1
[  265.147437] task: ddcb0000 ti: da186000 task.ti: da186000
[  265.152934] PC is at fiq_sof_handle+0xa0/0x10c
[  265.157473] LR is at 0xb550bac4
[  265.160673] pc : [<c057fc54>]    lr : [<b550bac4>]    psr: 800001d1
[  265.160673] sp : da187fb0  ip : 00000000  fp : b22f3d0c
[  265.172307] r10: 00074d58  r9 : 00000000  r8 : b5af8fc8
[  265.177608] r7 : 00004108  r6 : c0b27a78  r5 : c0a3b128  r4 : 00000003
[  265.184230] r3 : 0000022e  r2 : 0000020d  r1 : f301080e  r0 : 001f0109
[  265.190850] Flags: Nzcv  IRQs off  FIQs off  Mode FIQ_32  ISA ARM  Segment user
[  265.198262] Control: 00c5387d  Table: 1c720008  DAC: 00000015
[  265.204091] Process mpd (pid: 412, stack limit = 0xda1861b8)
[  265.209832] Stack: (0xda187fb0 to 0xda188000)
[  265.216690] 7fa0:                                     001f0109 f301080e 0000020d 0000022e
[  265.230058] 7fc0: 00000003 c0a3b128 c0b27a78 00004108 b5af8fc8 00000000 00074d58 b22f3d0c
[  265.243515] 7fe0: 00000000 da187fb0 b550bac4 c057fc54 800001d1 ffffffff 00000000 00000000
[  265.257184] [<c057fc54>] (fiq_sof_handle+0xa0/0x10c) from [<00000000>] (  (null))
[  265.270306] Code: e1520008 e5863050 8affffee e7f001f2 (e5962044) 
[  265.279292] ---[ end trace 92750d3eb1480a48 ]---
[  265.287137] Bad mode in prefetch abort handler detected
[  265.295232] Internal error: Oops - bad mode: 0 [#2] PREEMPT ARM
[  265.304000] Modules linked in: btusb bluetooth snd_bcm2835 snd_soc_wm8731 snd_soc_wm8804 ip6t_REJECT xt_hl snd_soc_bcm2708_i2s ip6t_rt regmap_mmio nf_conntrack_ipv6 nf_defrag_ipv6 snd_soc_core ipt_REJECT snd_compress xt_comment snd_pcm_dmaengine xt_recent snd_pcm snd_page_alloc leds_gpio snd_timer led_class xt_limit snd xt_tcpudp xt_addrtype spi_bcm2708 regmap_spi bcm2708_dmaengine regmap_i2c i2c_bcm2708 virt_dma nf_conntrack_ipv4 nf_defrag_ipv4 xt_conntrack ip6table_filter ip6_tables nf_conntrack_netbios_ns nf_conntrack_broadcast nf_nat_ftp nf_nat nf_conntrack_ftp nf_conntrack iptable_filter ip_tables x_tables bcm2708_rng rng_core ipv6
[  265.384587] CPU: 0 PID: 65 Comm: systemd-journal Tainted: G      D W    3.13.5-1-ARCH #1
[  265.398544] task: ddcb1900 ti: dddb0000 task.ti: dddb0000
[  265.406974] PC is at 0x3edc
[  265.412747] LR is at 0xb6d8513c
[  265.418776] pc : [<00003edc>]    lr : [<b6d8513c>]    psr: 600001d1
[  265.418776] sp : dddb1fb0  ip : 00000000  fp : ffffffff
[  265.435966] r10: 0002e5b4  r9 : fffffea8  r8 : 0003e018
[  265.443959] r7 : 000000fc  r6 : 000003e7  r5 : 0004f38a  r4 : b11755bb
[  265.453243] r3 : ffffffff  r2 : 00000001  r1 : becfcb88  r0 : 00000001
[  265.462429] Flags: nZCv  IRQs off  FIQs off  Mode FIQ_32  ISA ARM  Segment user
[  265.472450] Control: 00c5387d  Table: 1ddc8008  DAC: 00000015
[  265.480854] Process systemd-journal (pid: 65, stack limit = 0xdddb01b8)
[  265.490115] Stack: (0xdddb1fb0 to 0xdddb2000)
[  265.497058] 1fa0:                                     00000001 becfcb88 00000001 ffffffff
[  265.510442] 1fc0: b11755bb 0004f38a 000003e7 000000fc 0003e018 fffffea8 0002e5b4 ffffffff
[  265.523945] 1fe0: 00000000 dddb1fb0 b6d8513c 00003edc 600001d1 ffffffff c0c0c0c0 c0c0c0c0
[  265.537694] Code: bad PC value
[  265.543526] ---[ end trace 92750d3eb1480a49 ]---
er detected
[  265.565618] Internal error: Oops - bad mode: 0 [#3] PREEMPT ARM
[  265.574285] Modules linked in: btusb bluetooth snd_bcm2835 snd_soc_wm8731 snd_soc_wm8804 ip6t_REJECT xt_hl snd_soc_bcm2708_i2s ip6t_rt regmap_mmio nf_conntrack_ipv6 nf_defrag_ipv6 snd_soc_core ipt_REJECT snd_compress xt_comment snd_pcm_dmaengine xt_recent snd_pcm snd_page_alloc leds_gpio snd_timer led_class xt_limit snd xt_tcpudp xt_addrtype spi_bcm2708 regmap_spi bcm2708_dmaengine regmap_i2c i2c_bcm2708 virt_dma nf_conntrack_ipv4 nf_defrag_ipv4 xt_conntrack ip6table_filter ip6_tables nf_conntrack_netbios_ns nf_conntrack_broadcast nf_nat_ftp nf_nat nf_conntrack_ftp nf_conntrack iptable_filter ip_tables x_tables bcm2708_rng rng_core ipv6
[  265.653419] CPU: 0 PID: 1 Comm: systemd Tainted: G      D W    3.13.5-1-ARCH #1
[  265.663532] task: de848000 ti: de83a000 task.ti: de83a000
[  265.671771] PC is at 0x3edc
[  265.677366] LR is at 0xb6e4513c
[  265.683208] pc : [<00003edc>]    lr : [<b6e4513c>]    psr: 600001d1
[  265.683208] sp : de83bfb0  ip : 00000000  fp : beae2a0c
[  265.700050] r10: 000938a4  r9 : 0091e848  r8 : 00000957
[  265.707965] r7 : 000000fc  r6 : 00000000  r5 : 00000960  r4 : 00000000
[  265.717151] r3 : ffffffff  r2 : 00000001  r1 : beae2938  r0 : 00000001
[  265.726306] Flags: nZCv  IRQs off  FIQs off  Mode FIQ_32  ISA ARM  Segment user
[  265.736261] Control: 00c5387d  Table: 1dc9c008  DAC: 00000015
[  265.744551] Process systemd (pid: 1, stack limit = 0xde83a1b8)
[  265.752885] Stack: (0xde83bfb0 to 0xde83c000)
[  265.759664] bfa0:                                     00000001 beae2938 00000001 ffffffff
[  265.772852] bfc0: 00000000 00000960 00000000 000000fc 00000957 0091e848 000938a4 beae2a0c
[  265.786240] bfe0: 00000000 de83bfb0 b6e4513c 00003edc 600001d1 ffffffff c0c0c0c0 c0c0c0c0
[  265.799777] Code: bad PC value
[  265.805548] ---[ end trace 92750d3eb1480a4a ]---
[  265.813231] Bad mode in prefetch abort handler detected
[  265.821187] Internal error: Oops - bad mode: 0 [#4] PREEMPT ARM
[  265.829774] Modules linked in: btusb bluetooth snd_bcm2835 snd_soc_wm8731 snd_soc_wm8804 ip6t_REJECT xt_hl snd_soc_bcm2708_i2s ip6t_rt regmap_mmio nf_conntrack_ipv6 nf_defrag_ipv6 snd_soc_core ipt_REJECT snd_compress xt_comment snd_pcm_dmaengine xt_recent snd_pcm snd_page_alloc leds_gpio snd_timer led_class xt_limit snd xt_tcpudp xt_addrtype spi_bcm2708 regmap_spi bcm2708_dmaengine regmap_i2c i2c_bcm2708 virt_dma nf_conntrack_ipv4 nf_defrag_ipv4 xt_conntrack ip6table_filter ip6_tables nf_conntrack_netbios_ns nf_conntrack_broadcast nf_nat_ftp nf_nat nf_conntrack_ftp nf_conntrack iptable_filter ip_tables x_tables bcm2708_rng rng_core ipv6
[  265.908449] CPU: 0 PID: 346 Comm: ntpd Tainted: G      D W    3.13.5-1-ARCH #1
[  265.918477] task: dc663e80 ti: ddf46000 task.ti: ddf46000
[  265.926638] PC is at 0x3edc
[  265.932214] LR is at 0x35e08
[  265.937739] pc : [<00003edc>]    lr : [<00035e08>]    psr: 000001d1
[  265.937739] sp : ddf47fb0  ip : 00000000  fp : 000d7234
[  265.954520] r10: 000d818c  r9 : 000890fc  r8 : 00000001
[  265.962354] r7 : 0000008e  r6 : 000d818c  r5 : 000d7684  r4 : 00000000
[  265.971489] r3 : 00000000  r2 : 00000000  r1 : bee30c88  r0 : 0000000e
[  265.980574] Flags: nzcv  IRQs off  FIQs off  Mode FIQ_32  ISA ARM  Segment user
[  265.990474] Control: 00c5387d  Table: 1c5f8008  DAC: 00000015
[  265.998757] Process ntpd (pid: 346, stack limit = 0xddf461b8)
[  266.007011] Stack: (0xddf47fb0 to 0xddf48000)
[  266.013806] 7fa0:                                     0000000e bee30c88 00000000 00000000
[  266.026992] 7fc0: 00000000 000d7684 000d818c 0000008e 00000001 000890fc 000d818c 000d7234
[  266.040387] 7fe0: 00000000 ddf47fb0 00035e08 00003edc 000001d1 ffffffff 1effd821 1effdc21
[  266.053965] Code: bad PC value
[  266.059692] ---[ end trace 92750d3eb1480a4b ]---
[  266.070310] Bad mode in prefetch abort handler detected
[  266.078200] Internal error: Oops - bad mode: 0 [#5] PREEMPT ARM
[  266.086812] Modules linked in: btusb bluetooth snd_bcm2835 snd_soc_wm8snd_soc_wm88708_i2s ip6tmmionf_conntracknd_soc_coresnd_compressnloc leds_gpio snd_timer led_class xt_limit snd xt_tcpudp xt_addrtype spi_bcm2708 regmap_spi bcm2708_dmaengine regmap_i2c i2c_bcm2708 virt_dma nf_conntrack_ipv4 nf_defrag_ipv4 xt_conntrack ip6table_filter ip6_tables nf_conntrack_netbios_ns nf_conntrack_broadcast nf_nat_ftp nf_nat nf_conntrack_ftp nf_conntrack iptable_filter ip_tables x_tables bcm2708_rng rng_core ipv6
[  266.165710] CPU: 0 PID: 438 Comm: systemd-cgroups Tainted: G      D W    3.13.5-1-ARCH #1
[  266.179486] task: dc58cb00 ti: da17e000 task.ti: da17e000
[  266.187824] PC is at 0x3edc
[  266.193445] LR is at 0xb6f33ee0
[  266.199415] pc : [<00003edc>]    lr : [<b6f33ee0>]    psr: 000001d1
[  266.199415] sp : da17ffb0  ip : 00000000  fp : 00000000
[  266.216407] r10: 00000000  r9 : 00000000  r8 : 00000000
[  266.224338] r7 : 00000000  r6 : 00000000  r5 : 00000000  r4 : 00000000
[  266.233530] r3 : 00000000  r2 : 00000000  r1 : 00000000  r0 : 00000000
[  266.242604] Flags: nzcv  IRQs off  FIQs off  Mode FIQ_32  ISA ARM  Segment user
[  266.252475] Control: 00c5387d  Table: 1ddc8008  DAC: 00000015
[  266.260680] Process systemd-cgroups (pid: 438, stack limit = 0xda17e1b8)
[  266.269829] Stack: (0xda17ffb0 to 0xda180000)
[  266.276610] ffa0:                                     00000000 00000000 00000000 00000000
[  266.289805] ffc0: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
[  266.303107] ffe0: 00000000 da17ffb0 b6f33ee0 00003edc 000001d1 ffffffff 00000000 00000000
[  266.316599] Code: bad PC value
[  26.322356] -ce 92750d3eb  266.332833] Bad mode in prefetch abort handler detected
[  266.340728] Internal error: Oops - bad mode: 0 [#6] PREEMPT ARM
[  266.349311] Modules linked in: btusb bluetooth snd_bcm2835 snd_soc_wm8731 snd_soc_REJECT xt ip6t_rt rntrack_ipv6ompress xt_t_recent sndge_alloc le_timer led_cit sndxt_tcpudp  spi_bcm270i bcm2708_dmmap_i2c i2c_bcm2708 virt_dma nf_conntrack_ipv4 nf_defrag_ipv4 xt_conntrack ip6table_filter ip6_tables nf_conntrack_netbios_ns nf_conntrack_broadcast nf_nat_ftp nf_nat nf_conntrack_ftp nf_conntrack iptable_filter ip_tables x_tables bcm2708_rng rng_core ipv6
[266.427603]  213 Comm: dTainted: G  3.13.5-1-ARC[.440903] tas ti: dc5cc00dc5cc000
  266.449245] PC is at 0x3edc
[  266.454869] LR is at 0xb6e3a13c
[  266.460727] pc : [<00003edc>]    lr : [<b6e3a13c>]    psr: 600001d1
[  266.460727] sp : dc5cdfb0  ip : 00000000  fp : 00000000
[  266.477742] r10: bea97a30  r9 : 00000001  r8 : 00000001
[  266.485674] r7 : 000000fc  r6 : 00e375e0  r5 : bea97a30  r4 : 00e27318
[  266.494900] r3 : ffffffff  r2 : 00000040  r1 : bea975d8  r0 : 00000001
[  266.504063] Flags: nZCv  IRQs off  FIQs off  Mode FIQ_32  ISA ARM  Segment user
[  266.513995] Control: 00c5387d  Table: 1c5d0008  DAC: 00000015
[  266.522335] Process dbus-daemon (pid: 213, stack limit = 0xdc5cc1b8)
[  266.531203] Stack: (0xdc5cdfb0 to 0xdc5ce000)
[  266.538121] dfa0:                                     00000001 bea975d8 00000040 ffffffff
[  266.551475] dfc0: 00e27318 bea97a30 00e375e0 000000fc 00000001 00000001 bea97a30 00000000
[  266.564959] dfe0: 00000000 dc5cdfb0 b6e3a13c 00003edc 600001d1 ffffffff 00000000 00000000
[  266.578685] Code: bad PC value
[  266.584566] ---[ end trace 92750d3eb1480a4d ]---
[  266.595581] Kernel panic - not syncing: Attempted to kill ini0000000b
81]
richard-tx commented 10 years ago

I am seeing some strange messages in dmesg

[48624.325399] Transfer to device 5 endpoint 0x1 failed - FIQ reported NYET. Data may have been lost. [48626.343689] Transfer to device 5 endpoint 0x1 failed - FIQ reported NYET. Data may have been lost. [48627.204538] Transfer to device 7 endpoint 0x1 failed - FIQ reported NYET. Data may have been lost. [48629.524174] Transfer to device 7 endpoint 0x1 failed - FIQ reported NYET. Data may have been lost. [48629.895416] Transfer to device 5 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost. [48630.351553] Transfer to device 5 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost. [48630.766677] Transfer to device 5 endpoint 0x1 failed - FIQ reported NYET. Data may have been lost. [48631.385420] Transfer to device 7 endpoint 0x1 failed - FIQ reported NYET. Data may have been lost. [48632.971690] Transfer to device 7 endpoint 0x1 failed - FIQ reported NYET. Data may have been lost. [48633.334551] Transfer to device 7 endpoint 0x1 failed - FIQ reported NYET. Data may have been lost. [48634.355425] Transfer to device 7 endpoint 0x1 failed - FIQ reported NYET. Data may have been lost.

richard-tx commented 10 years ago

EEK! The syslogs are HUGE -rw-r----- 1 root adm 1806484 Mar 1 08:27 syslog -rw-r----- 1 root adm 9221503 Mar 1 06:25 syslog.1 All are filled with the same message. "Transfer to device 7..."

On the plus side, no panics

P33M commented 10 years ago

Erm. That's a printk_ratelimited() call. Perhaps not ratelimited enough.

This is somewhat odd because all split-transaction transfers should be unaffected by interrupt latency. You're also getting NYETs approx 2 per second which is far below the actual polling rate of the device (typical 125 per second).

I shall attempt to recreate the conditions.

tvjon commented 10 years ago

Here's part of kern.log (the same SD which generates the KVM crash)

Mar  2 14:34:08 swag0 kernel: [    1.416628] usbcore: registered new interface driver smsc95xx
Mar  2 14:34:08 swag0 kernel: [    1.422835] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
Mar  2 14:34:08 swag0 kernel: [    1.628956] Core Release: 2.80a
Mar  2 14:34:08 swag0 kernel: [    1.632200] Setting default values for core params
Mar  2 14:34:08 swag0 kernel: [    1.637109] Finished setting default values for core params
Mar  2 14:34:08 swag0 kernel: [    1.843065] Using Buffer DMA mode
Mar  2 14:34:08 swag0 kernel: [    1.846469] Periodic Transfer Interrupt Enhancement - disabled
Mar  2 14:34:08 swag0 kernel: [    1.852442] Multiprocessor Interrupt Enhancement - disabled
Mar  2 14:34:08 swag0 kernel: [    1.858153] OTG VER PARAM: 0, OTG VER FLAG: 0

Mar  2 14:42:37 swag0 kernel: [   56.958527] fuse init (API version 7.22)
Mar  2 14:42:38 swag0 kernel: [   58.021238] FAT-fs (sda1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck.
Mar  2 14:42:40 swag0 kernel: [   60.011908] EXT4-fs (sdb1): mounted filesystem with ordered data mode. Opts: (null)
Mar  2 14:42:45 swag0 kernel: [   65.205694] EXT4-fs (sdd1): mounted filesystem with ordered data mode. Opts: (null)
Mar  2 15:36:47 swag0 kernel: [ 3307.732795] Transfer to device 9 endpoint 0x1 failed - FIQ reported NYET. Data may have been lost.
Mar  2 15:37:29 swag0 kernel: [ 3349.142015] usb 1-1.3.1: new high-speed USB device number 11 using dwc_otg
Mar  2 15:37:29 swag0 kernel: [ 3349.243421] usb 1-1.3.1: New USB device found, idVendor=1058, idProduct=070a
Mar  2 15:37:29 swag0 kernel: [ 3349.243446] usb 1-1.3.1: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Mar  2 15:37:29 swag0 kernel: [ 3349.243458] usb 1-1.3.1: Product: My Passport 070A
Mar  2 15:37:29 swag0 kernel: [ 3349.243470] usb 1-1.3.1: Manufacturer: Western Digital
Mar  2 15:37:29 swag0 kernel: [ 3349.243481] usb 1-1.3.1: SerialNumber: 57584B314133303039373538
Mar  2 15:37:29 swag0 kernel: [ 3349.248827] usb-storage 1-1.3.1:1.0: USB Mass Storage device detected
Mar  2 15:37:29 swag0 kernel: [ 3349.262063] usb-storage 1-1.3.1:1.0: Quirks match for vid 1058 pid 070a: 200000
Mar  2 15:37:29 swag0 kernel: [ 3349.262280] scsi4 : usb-storage 1-1.3.1:1.0
Mar  2 15:37:30 swag0 kernel: [ 3350.265031] scsi 4:0:0:0: Direct-Access     WD       My Passport 070A 2018 PQ: 0 ANSI: 4
Mar  2 15:37:30 swag0 kernel: [ 3350.271703] sd 4:0:0:0: [sde] 1463775232 512-byte logical blocks: (749 GB/697 GiB)
Mar  2 15:37:30 swag0 kernel: [ 3350.274043] sd 4:0:0:0: [sde] Write Protect is off
Mar  2 15:37:30 swag0 kernel: [ 3350.274086] sd 4:0:0:0: [sde] Mode Sense: 2b 00 10 08
Mar  2 15:37:30 swag0 kernel: [ 3350.276671] scsi 4:0:0:1: Enclosure         WD       SES Device       2018 PQ: 0 ANSI: 4
Mar  2 15:37:30 swag0 kernel: [ 3350.278402] sd 4:0:0:0: [sde] No Caching mode page found
Mar  2 15:37:30 swag0 kernel: [ 3350.278440] sd 4:0:0:0: [sde] Assuming drive cache: write back
Mar  2 15:37:30 swag0 kernel: [ 3350.286136] sd 4:0:0:0: [sde] No Caching mode page found
Mar  2 15:37:30 swag0 kernel: [ 3350.286179] sd 4:0:0:0: [sde] Assuming drive cache: write back
Mar  2 15:37:30 swag0 kernel: [ 3350.364609]  sde: sde1 sde2
Mar  2 15:37:30 swag0 kernel: [ 3350.373178] sd 4:0:0:0: [sde] No Caching mode page found
Mar  2 15:37:30 swag0 kernel: [ 3350.373222] sd 4:0:0:0: [sde] Assuming drive cache: write back
Mar  2 15:37:30 swag0 kernel: [ 3350.373249] sd 4:0:0:0: [sde] Attached SCSI disk
Mar  2 16:17:51 swag0 kernel: [ 5771.859308] Transfer to device 9 endpoint 0x1 failed - FIQ reported NYET. Data may have been lost.
Mar  2 16:23:17 swag0 kernel: [ 6097.140138] Transfer to device 9 endpoint 0x1 failed - FIQ reported NYET. Data may have been lost.
Mar  2 16:23:21 swag0 kernel: [ 6101.420148] Transfer to device 9 endpoint 0x1 failed - FIQ reported NYET. Data may have been lost.
Mar  2 23:28:05 swag0 kernel: [31585.972957] Transfer to device 9 endpoint 0x1 failed - FIQ reported NYET. Data may have been lost.
Mar  2 23:28:34 swag0 kernel: [31615.492658] Transfer to device 9 endpoint 0x1 failed - FIQ reported NYET. Data may have been lost.
Mar  2 23:34:36 swag0 kernel: [31977.453713] Transfer to device 9 endpoint 0x1 failed - FIQ reported NYET. Data may have been lost.
Mar  2 23:37:16 swag0 kernel: [32137.094354] Transfer to device 9 endpoint 0x1 failed - FIQ reported NYET. Data may have been lost.
Mar  2 23:40:11 swag0 kernel: [32312.494806] Transfer to device 9 endpoint 0x1 failed - FIQ reported NYET. Data may have been lost.
Mar  2 23:42:17 swag0 kernel: [32438.135122] Transfer to device 9 endpoint 0x1 failed - FIQ reported NYET. Data may have been lost.
Mar  2 23:53:55 swag0 kernel: [33136.977410] Transfer to device 9 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 00:03:23 swag0 kernel: [33704.525842] Transfer to device 9 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 00:04:47 swag0 kernel: [33788.490039] Transfer to device 9 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 00:06:23 swag0 kernel: [33884.764524] Transfer to device 9 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 00:07:19 swag0 kernel: [33940.936826] Transfer to device 9 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 00:08:06 swag0 kernel: [33987.944800] Transfer to device 9 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 00:08:30 swag0 kernel: [34011.552878] Transfer to device 9 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 00:09:45 swag0 kernel: [34086.165309] Transfer to device 9 endpoint 0x1 failed - FIQ reported NYET. Data may have been lost.
Mar  3 00:10:22 swag0 kernel: [34123.925131] Transfer to device 9 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 00:11:59 swag0 kernel: [34220.498143] Transfer to device 9 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 00:15:55 swag0 kernel: [34456.474739] Transfer to device 9 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 00:16:09 swag0 kernel: [34470.142045] Transfer to device 9 endpoint 0x1 failed - FIQ reported NYET. Data may have been lost.
Mar  3 00:16:55 swag0 kernel: [34516.965161] Transfer to device 9 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 00:17:36 swag0 kernel: [34557.854499] Transfer to device 9 endpoint 0x1 failed - FIQ reported NYET. Data may have been lost.
Mar  3 00:17:39 swag0 kernel: [34560.934252] Transfer to device 9 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 00:18:30 swag0 kernel: [34611.319149] Transfer to device 9 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 00:18:44 swag0 kernel: [34626.014063] Transfer to device 9 endpoint 0x1 failed - FIQ reported NYET. Data may have been lost.
Mar  3 00:19:07 swag0 kernel: [34648.397116] Transfer to device 9 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 00:21:08 swag0 kernel: [34769.629557] Transfer to device 9 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 00:22:36 swag0 kernel: [34857.179772] Transfer to device 9 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 00:22:41 swag0 kernel: [34862.588036] Transfer to device 9 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 00:22:51 swag0 kernel: [34872.212067] Transfer to device 9 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 00:23:31 swag0 kernel: [34912.207681] Transfer to device 9 endpoint 0x1 failed - FIQ reported NYET. Data may have been lost.
Mar  3 00:23:55 swag0 kernel: [34936.450834] Transfer to device 9 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 00:24:13 swag0 kernel: [34954.763530] Transfer to device 9 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 00:26:36 swag0 kernel: [35097.335889] Transfer to device 9 endpoint 0x1 failed - FIQ reported NYET. Data may have been lost.
Mar  3 00:26:41 swag0 kernel: [35103.087671] Transfer to device 9 endpoint 0x1 failed - FIQ reported NYET. Data may have been lost.
Mar  3 00:26:44 swag0 kernel: [35105.823678] Transfer to device 9 endpoint 0x1 failed - FIQ reported NYET. Data may have been lost.
Mar  3 00:26:48 swag0 kernel: [35109.474414] Transfer to device 9 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 00:26:49 swag0 kernel: [35111.020294] Transfer to device 9 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 00:26:56 swag0 kernel: [35117.496692] Transfer to device 9 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 00:26:58 swag0 kernel: [35119.534315] Transfer to device 9 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 00:27:00 swag0 kernel: [35121.864699] Transfer to device 9 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 00:27:11 swag0 kernel: [35132.427725] Transfer to device 9 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 00:27:11 swag0 kernel: [35133.025474] Transfer to device 9 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 00:27:12 swag0 kernel: [35133.187475] Transfer to device 9 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 00:27:15 swag0 kernel: [35136.536007] Transfer to device 9 endpoint 0x1 failed - FIQ reported NYET. Data may have been lost.
Mar  3 00:27:18 swag0 kernel: [35139.981491] Transfer to device 9 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 00:27:26 swag0 kernel: [35147.943514] Transfer to device 9 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 00:27:31 swag0 kernel: [35152.897790] Transfer to device 9 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 00:27:32 swag0 kernel: [35153.261526] Transfer to device 9 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 00:27:32 swag0 kernel: [35153.309527] Transfer to device 9 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 00:27:35 swag0 kernel: [35156.703771] Transfer to device 9 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 00:27:35 swag0 kernel: [35156.789534] Transfer to device 9 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 00:27:41 swag0 kernel: [35162.905551] Transfer to device 9 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 00:27:43 swag0 kernel: [35164.333808] Transfer to device 9 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 00:27:47 swag0 kernel: [35168.857565] Transfer to device 9 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 00:27:48 swag0 kernel: [35169.505832] Transfer to device 9 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 00:27:51 swag0 kernel: [35172.216070] Transfer to device 9 endpoint 0x1 failed - FIQ reported NYET. Data may have been lost.
Mar  3 00:27:58 swag0 kernel: [35179.863844] Transfer to device 9 endpoint 0x1 failed - FIQ reported NYET. Data may have been lost.
Mar  3 00:28:01 swag0 kernel: [35182.499597] Transfer to device 9 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 00:28:01 swag0 kernel: [35182.547603] Transfer to device 9 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 00:28:02 swag0 kernel: [35183.857893] Transfer to device 9 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 00:28:22 swag0 kernel: [35203.989910] Transfer to device 9 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 00:28:39 swag0 kernel: [35220.617576] Transfer to device 9 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 00:28:50 swag0 kernel: [35232.083726] Transfer to device 9 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 00:28:58 swag0 kernel: [35240.076001] Transfer to device 9 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 00:29:06 swag0 kernel: [35247.524023] Transfer to device 9 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 00:29:07 swag0 kernel: [35248.686531] Transfer to device 9 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 00:29:50 swag0 kernel: [35292.061864] Transfer to device 9 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 00:41:05 swag0 kernel: [35967.057838] Transfer to device 9 endpoint 0x1 failed - FIQ reported NYET. Data may have been lost.
Mar  3 00:41:06 swag0 kernel: [35967.539884] Transfer to device 9 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 00:41:06 swag0 kernel: [35967.837862] Transfer to device 9 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 00:41:07 swag0 kernel: [35968.319865] Transfer to device 9 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 00:46:18 swag0 kernel: [36279.190396] Transfer to device 9 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 00:58:35 swag0 kernel: [37017.119501] Transfer to device 9 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 01:14:26 swag0 kernel: [37967.660936] Transfer to device 9 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 01:15:21 swag0 kernel: [38023.127061] Transfer to device 9 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 01:15:51 swag0 kernel: [38052.419136] Transfer to device 9 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 01:15:52 swag0 kernel: [38053.909136] Transfer to device 9 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 01:16:38 swag0 kernel: [38100.103255] Transfer to device 9 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 01:17:02 swag0 kernel: [38123.871569] Transfer to device 9 endpoint 0x1 failed - FIQ reported NYET. Data may have been lost.
Mar  3 01:17:19 swag0 kernel: [38140.290130] Transfer to device 9 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 01:17:29 swag0 kernel: [38150.921384] Transfer to device 9 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 01:18:50 swag0 kernel: [38232.077334] Transfer to device 9 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 01:18:55 swag0 kernel: [38237.084625] Transfer to device 9 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 01:18:59 swag0 kernel: [38240.590644] Transfer to device 9 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 01:20:54 swag0 kernel: [38355.519673] Transfer to device 9 endpoint 0x1 failed - FIQ reported NYET. Data may have been lost.
Mar  3 01:21:24 swag0 kernel: [38385.319729] Transfer to device 9 endpoint 0x1 failed - FIQ reported NYET. Data may have been lost.
Mar  3 01:21:27 swag0 kernel: [38388.799758] Transfer to device 9 endpoint 0x1 failed - FIQ reported NYET. Data may have been lost.
Mar  3 01:21:52 swag0 kernel: [38413.280092] Transfer to device 9 endpoint 0x1 failed - FIQ reported NYET. Data may have been lost.
Mar  3 01:31:10 swag0 kernel: [38971.402496] Transfer to device 9 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 01:39:14 swag0 kernel: [39456.138473] Transfer to device 9 endpoint 0x1 failed - FIQ reported NYET. Data may have been lost.
Mar  3 01:42:12 swag0 kernel: [39634.147171] Transfer to device 9 endpoint 0x1 failed - FIQ reported NYET. Data may have been lost.
Mar  3 01:46:15 swag0 kernel: [39876.635521] Transfer to device 9 endpoint 0x1 failed - FIQ reported NYET. Data may have been lost.
Mar  3 02:07:23 swag0 kernel: [41144.559038] Transfer to device 9 endpoint 0x1 failed - FIQ reported NYET. Data may have been lost.
Mar  3 02:08:39 swag0 kernel: imklog 5.8.11, log source = /proc/kmsg started.
Mar  3 02:08:39 swag0 kernel: [    0.000000] Booting Linux on physical CPU 0x0
Mar  3 02:08:39 swag0 kernel: [    0.000000] Initializing cgroup subsys cpu
Mar  3 02:08:39 swag0 kernel: [    0.000000] Initializing cgroup subsys cpuacct
Mar  3 02:08:39 swag0 kernel: [    0.000000] Linux version 3.10.30+ (dc4@dc4-arm-01) (gcc version 4.7.2 20120731 (prerelease) (crosstool-NG linaro-1.13.1+bzr2458 - Linaro GCC 2012.08) ) #644 PREEMPT Fri Feb 21 18:44:33 GMT 2014
Mar  3 02:08:39 swag0 kernel: [    0.000000] CPU: ARMv6-compatible processor [410fb767] revision 7 (ARMv7), cr=00c5387d
Mar  3 02:08:39 swag0 kernel: [    0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT nonaliasing instruction cache
Mar  3 02:08:39 swag0 kernel: [    0.000000] Machine: BCM2708
Mar  3 02:08:39 swag0 kernel: [    0.000000] cma: CMA: reserved 16 MiB at 17000000
Mar  3 02:08:39 swag0 kernel: [    0.000000] Memory policy: ECC disabled, Data cache writeback
Mar  3 02:08:39 swag0 kernel: [    0.000000] On node 0 totalpages: 98304
Mar  3 02:08:39 swag0 kernel: [    0.000000] free_area_init_node: node 0, pgdat c05d3e84, node_mem_map c0684000
Mar  3 02:08:39 swag0 kernel: [    0.000000]   Normal zone: 768 pages used for memmap
Mar  3 02:08:39 swag0 kernel: [    0.000000]   Normal zone: 0 pages reserved
Mar  3 02:08:39 swag0 kernel: [    0.000000]   Normal zone: 98304 pages, LIFO batch:31
Mar  3 02:08:39 swag0 kernel: [    0.000000] pcpu-alloc: s0 r0 d32768 u32768 alloc=1*32768
Mar  3 02:08:39 swag0 kernel: [    0.000000] pcpu-alloc: [0] 0 
Mar  3 02:08:39 swag0 kernel: [    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 97536
Mar  3 02:08:39 swag0 kernel: [    0.000000] Kernel command line: dma.dmachans=0x7f35 bcm2708_fb.fbwidth=1920 bcm2708_fb.fbheight=1080 bcm2708.boardrev=0x100000e bcm2708.serial=0xbbb8cfd3 smsc95xx.macaddr=B8:27:EB:B8:CF:D3 bcm2708_fb.fbswap=1 sdhci-bcm2708.emmc_clock_freq=250000000 vc_mem.mem_base=0x1ec00000 vc_mem.mem_size=0x20000000  dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0x3 dwc_otg.lpm_enable=0 console=ttyAMA0,115200 kgdboc=ttyAMA0,115200 console=tty1 root=/dev/mmcblk0p2 rootfstype=ext4 elevator=deadline rootwait
Mar  3 02:08:39 swag0 kernel: [    0.000000] PID hash table entries: 2048 (order: 1, 8192 bytes)
Mar  3 02:08:39 swag0 kernel: [    0.000000] Dentry cache hash table entries: 65536 (order: 6, 262144 bytes)
Mar  3 02:08:39 swag0 kernel: [    0.000000] Inode-cache hash table entries: 32768 (order: 5, 131072 bytes)
Mar  3 02:08:39 swag0 kernel: [    0.000000] Memory: 384MB = 384MB total
Mar  3 02:08:39 swag0 kernel: [    0.000000] Memory: 366600k/366600k available, 26616k reserved, 0K highmem
Mar  3 02:08:39 swag0 kernel: [    0.000000] Virtual kernel memory layout:
Mar  3 02:08:39 swag0 kernel: [    0.000000]     vector  : 0xffff0000 - 0xffff1000   (   4 kB)
Mar  3 02:08:39 swag0 kernel: [    0.000000]     fixmap  : 0xfff00000 - 0xfffe0000   ( 896 kB)
Mar  3 02:08:39 swag0 kernel: [    0.000000]     vmalloc : 0xd8800000 - 0xff000000   ( 616 MB)
Mar  3 02:08:39 swag0 kernel: [    0.000000]     lowmem  : 0xc0000000 - 0xd8000000   ( 384 MB)
Mar  3 02:08:39 swag0 kernel: [    0.000000]     modules : 0xbf000000 - 0xc0000000   (  16 MB)
Mar  3 02:08:39 swag0 kernel: [    0.000000]       .text : 0xc0008000 - 0xc0575858   (5559 kB)
Mar  3 02:08:39 swag0 kernel: [    0.000000]       .init : 0xc0576000 - 0xc059a344   ( 145 kB)
Mar  3 02:08:39 swag0 kernel: [    0.000000]       .data : 0xc059c000 - 0xc05d4990   ( 227 kB)
Mar  3 02:08:39 swag0 kernel: [    0.000000]        .bss : 0xc05d4990 - 0xc0683060   ( 698 kB)
Mar  3 02:08:39 swag0 kernel: [    0.000000] SLUB: HWalign=32, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
Mar  3 02:08:39 swag0 kernel: [    0.000000] Preemptible hierarchical RCU implementation.
Mar  3 02:08:39 swag0 kernel: [    0.000000] NR_IRQS:330
Mar  3 02:08:39 swag0 kernel: [    0.000000] sched_clock: 32 bits at 1000kHz, resolution 1000ns, wraps every 4294967ms
Mar  3 02:08:39 swag0 kernel: [    0.000000] Switching to timer-based delay loop
Mar  3 02:08:39 swag0 kernel: [    0.000000] Console: colour dummy device 80x30
Mar  3 02:08:39 swag0 kernel: [    0.000000] console [tty1] enabled
Mar  3 02:08:39 swag0 kernel: [    0.001180] Calibrating delay loop (skipped), value calculated using timer frequency.. 2.00 BogoMIPS (lpj=10000)
Mar  3 02:08:39 swag0 kernel: [    0.001244] pid_max: default: 32768 minimum: 301
Mar  3 02:08:39 swag0 kernel: [    0.001713] Mount-cache hash table entries: 512
Mar  3 02:08:39 swag0 kernel: [    0.002515] Initializing cgroup subsys memory
Mar  3 02:08:39 swag0 kernel: [    0.002618] Initializing cgroup subsys devices
Mar  3 02:08:39 swag0 kernel: [    0.002659] Initializing cgroup subsys freezer
Mar  3 02:08:39 swag0 kernel: [    0.002693] Initializing cgroup subsys blkio
Mar  3 02:08:39 swag0 kernel: [    0.002853] CPU: Testing write buffer coherency: ok
Mar  3 02:08:39 swag0 kernel: [    0.003325] Setting up static identity map for 0xc0408ee8 - 0xc0408f44
Mar  3 02:08:39 swag0 kernel: [    0.005113] devtmpfs: initialized
Mar  3 02:08:39 swag0 kernel: [    0.019476] NET: Registered protocol family 16
Mar  3 02:08:39 swag0 kernel: [    0.025510] DMA: preallocated 4096 KiB pool for atomic coherent allocations
Mar  3 02:08:39 swag0 kernel: [    0.026705] bcm2708.uart_clock = 0
Mar  3 02:08:39 swag0 kernel: [    0.028385] hw-breakpoint: found 6 breakpoint and 1 watchpoint registers.
Mar  3 02:08:39 swag0 kernel: [    0.028438] hw-breakpoint: maximum watchpoint size is 4 bytes.
Mar  3 02:08:39 swag0 kernel: [    0.028476] mailbox: Broadcom VideoCore Mailbox driver
Mar  3 02:08:39 swag0 kernel: [    0.028576] bcm2708_vcio: mailbox at f200b880
Mar  3 02:08:39 swag0 kernel: [    0.028680] bcm_power: Broadcom power driver
Mar  3 02:08:39 swag0 kernel: [    0.028720] bcm_power_open() -> 0
Mar  3 02:08:39 swag0 kernel: [    0.028748] bcm_power_request(0, 8)
Mar  3 02:08:39 swag0 kernel: [    0.529470] bcm_mailbox_read -> 00000080, 0
Mar  3 02:08:39 swag0 kernel: [    0.529513] bcm_power_request -> 0
Mar  3 02:08:39 swag0 kernel: [    0.529736] Serial: AMBA PL011 UART driver
Mar  3 02:08:39 swag0 kernel: [    0.529896] dev:f1: ttyAMA0 at MMIO 0x20201000 (irq = 83) is a PL011 rev3
Mar  3 02:08:39 swag0 kernel: [    0.880605] console [ttyAMA0] enabled
Mar  3 02:08:39 swag0 kernel: [    0.906746] bio: create slab <bio-0> at 0
Mar  3 02:08:39 swag0 kernel: [    0.912034] SCSI subsystem initialized
Mar  3 02:08:39 swag0 kernel: [    0.916016] usbcore: registered new interface driver usbfs
Mar  3 02:08:39 swag0 kernel: [    0.921728] usbcore: registered new interface driver hub
Mar  3 02:08:39 swag0 kernel: [    0.927285] usbcore: registered new device driver usb
Mar  3 02:08:39 swag0 kernel: [    0.933948] Switching to clocksource stc
Mar  3 02:08:39 swag0 kernel: [    0.938282] FS-Cache: Loaded
Mar  3 02:08:39 swag0 kernel: [    0.941437] CacheFiles: Loaded
Mar  3 02:08:39 swag0 kernel: [    0.956732] NET: Registered protocol family 2
Mar  3 02:08:39 swag0 kernel: [    0.962137] TCP established hash table entries: 4096 (order: 3, 32768 bytes)
Mar  3 02:08:39 swag0 kernel: [    0.969402] TCP bind hash table entries: 4096 (order: 2, 16384 bytes)
Mar  3 02:08:39 swag0 kernel: [    0.975925] TCP: Hash tables configured (established 4096 bind 4096)
Mar  3 02:08:39 swag0 kernel: [    0.982404] TCP: reno registered
Mar  3 02:08:39 swag0 kernel: [    0.985662] UDP hash table entries: 256 (order: 0, 4096 bytes)
Mar  3 02:08:39 swag0 kernel: [    0.991572] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes)
Mar  3 02:08:39 swag0 kernel: [    0.998318] NET: Registered protocol family 1
Mar  3 02:08:39 swag0 kernel: [    1.003239] RPC: Registered named UNIX socket transport module.
Mar  3 02:08:39 swag0 kernel: [    1.009300] RPC: Registered udp transport module.
Mar  3 02:08:39 swag0 kernel: [    1.014026] RPC: Registered tcp transport module.
Mar  3 02:08:39 swag0 kernel: [    1.018778] RPC: Registered tcp NFSv4.1 backchannel transport module.
Mar  3 02:08:39 swag0 kernel: [    1.026238] bcm2708_dma: DMA manager at f2007000
Mar  3 02:08:39 swag0 kernel: [    1.031059] bcm2708_gpio: bcm2708_gpio_probe c05a9e50
Mar  3 02:08:39 swag0 kernel: [    1.036528] vc-mem: phys_addr:0x00000000 mem_base=0x1ec00000 mem_size:0x20000000(512 MiB)
Mar  3 02:08:39 swag0 kernel: [    1.045884] audit: initializing netlink socket (disabled)
Mar  3 02:08:39 swag0 kernel: [    1.051554] type=2000 audit(0.890:1): initialized
Mar  3 02:08:39 swag0 kernel: [    1.213222] VFS: Disk quotas dquot_6.5.2
Mar  3 02:08:39 swag0 kernel: [    1.217465] Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
Mar  3 02:08:39 swag0 kernel: [    1.226387] FS-Cache: Netfs 'nfs' registered for caching
Mar  3 02:08:39 swag0 kernel: [    1.233113] NFS: Registering the id_resolver key type
Mar  3 02:08:39 swag0 kernel: [    1.238440] Key type id_resolver registered
Mar  3 02:08:39 swag0 kernel: [    1.242652] Key type id_legacy registered
Mar  3 02:08:39 swag0 kernel: [    1.247369] msgmni has been set to 748
Mar  3 02:08:39 swag0 kernel: [    1.253294] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 252)
Mar  3 02:08:39 swag0 kernel: [    1.261134] io scheduler noop registered
Mar  3 02:08:39 swag0 kernel: [    1.265094] io scheduler deadline registered (default)
Mar  3 02:08:39 swag0 kernel: [    1.270674] io scheduler cfq registered
Mar  3 02:08:39 swag0 kernel: [    1.275973] BCM2708FB: allocated DMA memory 57400000
Mar  3 02:08:39 swag0 kernel: [    1.281153] BCM2708FB: allocated DMA channel 0 @ f2007000
Mar  3 02:08:39 swag0 kernel: [    1.312883] Console: switching to colour frame buffer device 240x67
Mar  3 02:08:39 swag0 kernel: [    1.335807] uart-pl011 dev:f1: no DMA platform data
Mar  3 02:08:39 swag0 kernel: [    1.340903] kgdb: Registered I/O driver kgdboc.
Mar  3 02:08:39 swag0 kernel: [    1.346167] vc-cma: Videocore CMA driver
Mar  3 02:08:39 swag0 kernel: [    1.350267] vc-cma: vc_cma_base      = 0x00000000
Mar  3 02:08:39 swag0 kernel: [    1.355066] vc-cma: vc_cma_size      = 0x00000000 (0 MiB)
Mar  3 02:08:39 swag0 kernel: [    1.360649] vc-cma: vc_cma_initial   = 0x00000000 (0 MiB)
Mar  3 02:08:39 swag0 kernel: [    1.375560] brd: module loaded
Mar  3 02:08:39 swag0 kernel: [    1.384068] loop: module loaded
Mar  3 02:08:39 swag0 kernel: [    1.387589] vchiq: vchiq_init_state: slot_zero = 0xd7000000, is_master = 0
Mar  3 02:08:39 swag0 kernel: [    1.395648] Loading iSCSI transport class v2.0-870.
Mar  3 02:08:39 swag0 kernel: [    1.401878] usbcore: registered new interface driver smsc95xx
Mar  3 02:08:39 swag0 kernel: [    1.408096] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
Mar  3 02:08:39 swag0 kernel: [    1.614172] Core Release: 2.80a
Mar  3 02:08:39 swag0 kernel: [    1.617413] Setting default values for core params
Mar  3 02:08:39 swag0 kernel: [    1.622416] Finished setting default values for core params
Mar  3 02:08:39 swag0 kernel: [    1.828203] Using Buffer DMA mode
Mar  3 02:08:39 swag0 kernel: [    1.831604] Periodic Transfer Interrupt Enhancement - disabled
Mar  3 02:08:39 swag0 kernel: [    1.837538] Multiprocessor Interrupt Enhancement - disabled
Mar  3 02:08:39 swag0 kernel: [    1.843246] OTG VER PARAM: 0, OTG VER FLAG: 0
Mar  3 02:08:39 swag0 kernel: [    1.847693] Dedicated Tx FIFOs mode
Mar  3 02:08:39 swag0 kernel: [    1.852088] WARN::dwc_otg_hcd_init:1047: FIQ DMA bounce buffers: virt = 0xd7414000 dma = 0x57414000 len=9024
Mar  3 02:08:39 swag0 kernel: [    1.862175] FIQ FSM acceleration enabled for :
Mar  3 02:08:39 swag0 kernel: [    1.862175] Non-periodic Split Transactions
Mar  3 02:08:39 swag0 kernel: [    1.862175] Periodic Split Transactions
Mar  3 02:08:39 swag0 kernel: [    1.874853] dwc_otg: Microframe scheduler enabled
Mar  3 02:08:39 swag0 kernel: [    1.875086] WARN::hcd_init:474: FIQ at 0xc02f1d10
Mar  3 02:08:39 swag0 kernel: [    1.880009] WARN::hcd_init:475: FIQ ASM at 0xc02f1f8c length 36
Mar  3 02:08:39 swag0 kernel: [    1.886058] WARN::hcd_init:501: MPHI regs_base at 0xd8806000
Mar  3 02:08:39 swag0 kernel: [    1.891884] dwc_otg bcm2708_usb: DWC OTG Controller
Mar  3 02:08:39 swag0 kernel: [    1.896907] dwc_otg bcm2708_usb: new USB bus registered, assigned bus number 1
Mar  3 02:08:39 swag0 kernel: [    1.904359] dwc_otg bcm2708_usb: irq 32, io mem 0x00000000
Mar  3 02:08:39 swag0 kernel: [    1.910016] Init: Port Power? op_state=1
Mar  3 02:08:39 swag0 kernel: [    1.914019] Init: Power Port (0)
Mar  3 02:08:39 swag0 kernel: [    1.922216] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
Mar  3 02:08:39 swag0 kernel: [    1.933834] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
Mar  3 02:08:39 swag0 kernel: [    1.945837] usb usb1: Product: DWC OTG Controller
Mar  3 02:08:39 swag0 kernel: [    1.955292] usb usb1: Manufacturer: Linux 3.10.30+ dwc_otg_hcd
Mar  3 02:08:39 swag0 kernel: [    1.965853] usb usb1: SerialNumber: bcm2708_usb
Mar  3 02:08:39 swag0 kernel: [    1.975960] hub 1-0:1.0: USB hub found
Mar  3 02:08:39 swag0 kernel: [    1.984605] hub 1-0:1.0: 1 port detected
Mar  3 02:08:39 swag0 kernel: [    1.993740] dwc_otg: FIQ enabled
Mar  3 02:08:39 swag0 kernel: [    1.993762] dwc_otg: NAK holdoff enabled
Mar  3 02:08:39 swag0 kernel: [    1.993773] dwc_otg: FIQ split-transaction FSM enabled
Mar  3 02:08:39 swag0 kernel: [    1.993794] Module dwc_common_port init
Mar  3 02:08:39 swag0 kernel: [    1.994270] usbcore: registered new interface driver usb-storage
Mar  3 02:08:39 swag0 kernel: [    2.005606] mousedev: PS/2 mouse device common for all mice
Mar  3 02:08:39 swag0 kernel: [    2.016727] bcm2835-cpufreq: min=700000 max=1000000 cur=700000
Mar  3 02:08:39 swag0 kernel: [    2.027648] bcm2835-cpufreq: switching to governor powersave
Mar  3 02:08:39 swag0 kernel: [    2.038148] bcm2835-cpufreq: switching to governor powersave
Mar  3 02:08:39 swag0 kernel: [    2.048531] cpuidle: using governor ladder
Mar  3 02:08:39 swag0 kernel: [    2.057226] cpuidle: using governor menu
Mar  3 02:08:39 swag0 kernel: [    2.065790] sdhci: Secure Digital Host Controller Interface driver
Mar  3 02:08:39 swag0 kernel: [    2.076622] sdhci: Copyright(c) Pierre Ossman
Mar  3 02:08:39 swag0 kernel: [    2.085747] sdhci: Enable low-latency mode
Mar  3 02:08:39 swag0 kernel: [    2.137998] mmc0: SDHCI controller on BCM2708_Arasan [platform] using platform's DMA
Mar  3 02:08:39 swag0 kernel: [    2.150796] mmc0: BCM2708 SDHC host at 0x20300000 DMA 2 IRQ 77
Mar  3 02:08:39 swag0 kernel: [    2.161592] sdhci-pltfm: SDHCI platform and OF driver helper
Mar  3 02:08:39 swag0 kernel: [    2.174123] ledtrig-cpu: registered to indicate activity on CPUs
Mar  3 02:08:39 swag0 kernel: [    2.188106] Indeed it is in host mode hprt0 = 00021501
Mar  3 02:08:39 swag0 kernel: [    2.202417] hidraw: raw HID events driver (C) Jiri Kosina
Mar  3 02:08:39 swag0 kernel: [    2.217590] usbcore: registered new interface driver usbhid
Mar  3 02:08:39 swag0 kernel: [    2.251052] usbhid: USB HID core driver
Mar  3 02:08:39 swag0 kernel: [    2.268511] TCP: cubic registered
Mar  3 02:08:39 swag0 kernel: [    2.276519] Initializing XFRM netlink socket
Mar  3 02:08:39 swag0 kernel: [    2.308031] NET: Registered protocol family 17
Mar  3 02:08:39 swag0 kernel: [    2.338165] Key type dns_resolver registered
Mar  3 02:08:39 swag0 kernel: [    2.353047] mmc0: read SD Status register (SSR) after 3 attempts
Mar  3 02:08:39 swag0 kernel: [    2.347680] VFP support v0.3: implementor 41 architecture 1 part 20 variant b rev 5
Mar  3 02:08:39 swag0 kernel: [    2.380352] registered taskstats version 1
Mar  3 02:08:39 swag0 kernel: [    2.390752] Waiting for root device /dev/mmcblk0p2...
Mar  3 02:08:39 swag0 kernel: [    2.403177] mmc0: new high speed SDHC card at address 59b4
Mar  3 02:08:39 swag0 kernel: [    2.414440] mmcblk0: mmc0:59b4 00000 7.35 GiB 
Mar  3 02:08:39 swag0 kernel: [    2.425450]  mmcblk0: p1 p2
Mar  3 02:08:39 swag0 kernel: [    2.488020] usb 1-1: new high-speed USB device number 2 using dwc_otg
Mar  3 02:08:39 swag0 kernel: [    2.499676] Indeed it is in host mode hprt0 = 00001101
Mar  3 02:08:39 swag0 kernel: [    2.595515] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
Mar  3 02:08:39 swag0 kernel: [    2.608894] VFS: Mounted root (ext4 filesystem) on device 179:2.
Mar  3 02:08:39 swag0 kernel: [    2.621944] devtmpfs: mounted
Mar  3 02:08:39 swag0 kernel: [    2.630574] Freeing unused kernel memory: 144K (c0576000 - c059a000)
Mar  3 02:08:39 swag0 kernel: [    2.708582] usb 1-1: New USB device found, idVendor=0424, idProduct=9514
Mar  3 02:08:39 swag0 kernel: [    2.721555] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
Mar  3 02:08:39 swag0 kernel: [    2.735214] hub 1-1:1.0: USB hub found
Mar  3 02:08:39 swag0 kernel: [    2.744304] hub 1-1:1.0: 5 ports detected
Mar  3 02:08:39 swag0 kernel: [    3.028147] usb 1-1.1: new high-speed USB device number 3 using dwc_otg
Mar  3 02:08:39 swag0 kernel: [    3.138597] usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00
Mar  3 02:08:39 swag0 kernel: [    3.152068] usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
Mar  3 02:08:39 swag0 kernel: [    3.169748] smsc95xx v1.0.4
Mar  3 02:08:39 swag0 kernel: [    3.242983] smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-bcm2708_usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:b8:cf:d3
Mar  3 02:08:39 swag0 kernel: [    3.358253] usb 1-1.3: new high-speed USB device number 4 using dwc_otg
Mar  3 02:08:39 swag0 kernel: [    3.468727] usb 1-1.3: New USB device found, idVendor=1a40, idProduct=0101
Mar  3 02:08:39 swag0 kernel: [    3.491693] usb 1-1.3: New USB device strings: Mfr=0, Product=1, SerialNumber=0
Mar  3 02:08:39 swag0 kernel: [    3.518108] usb 1-1.3: Product: USB 2.0 Hub
Mar  3 02:08:39 swag0 kernel: [    3.538981] hub 1-1.3:1.0: USB hub found
Mar  3 02:08:39 swag0 kernel: [    3.556780] hub 1-1.3:1.0: 4 ports detected
Mar  3 02:08:39 swag0 kernel: [    3.848260] usb 1-1.3.2: new high-speed USB device number 5 using dwc_otg
Mar  3 02:08:39 swag0 kernel: [    3.989881] usb 1-1.3.2: New USB device found, idVendor=0781, idProduct=5581
Mar  3 02:08:39 swag0 kernel: [    4.018133] usb 1-1.3.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Mar  3 02:08:39 swag0 kernel: [    4.040358] usb 1-1.3.2: Product: SanDisk Ultra
Mar  3 02:08:39 swag0 kernel: [    4.061185] usb 1-1.3.2: Manufacturer: SanDisk
Mar  3 02:08:39 swag0 kernel: [    4.085335] usb 1-1.3.2: SerialNumber: A20041391401C485
Mar  3 02:08:39 swag0 kernel: [    4.107325] usb-storage 1-1.3.2:1.0: USB Mass Storage device detected
Mar  3 02:08:39 swag0 kernel: [    4.138272] scsi0 : usb-storage 1-1.3.2:1.0
Mar  3 02:08:39 swag0 kernel: [    4.228228] usb 1-1.3.3: new high-speed USB device number 6 using dwc_otg
Mar  3 02:08:39 swag0 kernel: [    4.343670] usb 1-1.3.3: New USB device found, idVendor=26bd, idProduct=9917
Mar  3 02:08:39 swag0 kernel: [    4.368134] usb 1-1.3.3: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Mar  3 02:08:39 swag0 kernel: [    4.385877] usb 1-1.3.3: Product: USB DISK 2.0
Mar  3 02:08:39 swag0 kernel: [    4.395907] usb 1-1.3.3: Manufacturer:         
Mar  3 02:08:39 swag0 kernel: [    4.406921] usb 1-1.3.3: SerialNumber: 070B3A1D8E99D055
Mar  3 02:08:39 swag0 kernel: [    4.418810] usb-storage 1-1.3.3:1.0: USB Mass Storage device detected
Mar  3 02:08:39 swag0 kernel: [    4.431248] scsi1 : usb-storage 1-1.3.3:1.0
Mar  3 02:08:39 swag0 kernel: [    4.518239] usb 1-1.3.4: new high-speed USB device number 7 using dwc_otg
Mar  3 02:08:39 swag0 kernel: [    4.638942] usb 1-1.3.4: New USB device found, idVendor=1a40, idProduct=0101
Mar  3 02:08:39 swag0 kernel: [    4.658247] usb 1-1.3.4: New USB device strings: Mfr=0, Product=1, SerialNumber=0
Mar  3 02:08:39 swag0 kernel: [    4.687198] usb 1-1.3.4: Product: USB 2.0 Hub
Mar  3 02:08:39 swag0 kernel: [    4.718910] hub 1-1.3.4:1.0: USB hub found
Mar  3 02:08:39 swag0 kernel: [    4.744672] hub 1-1.3.4:1.0: 4 ports detected
Mar  3 02:08:39 swag0 kernel: [    5.078295] usb 1-1.3.4.3: new full-speed USB device number 8 using dwc_otg
Mar  3 02:08:39 swag0 kernel: [    5.149419] scsi 0:0:0:0: Direct-Access     SanDisk  SanDisk Ultra    PMAP PQ: 0 ANSI: 6
Mar  3 02:08:39 swag0 kernel: [    5.181291] sd 0:0:0:0: [sda] 60532992 512-byte logical blocks: (30.9 GB/28.8 GiB)
Mar  3 02:08:39 swag0 kernel: [    5.216529] usb 1-1.3.4.3: New USB device found, idVendor=046d, idProduct=c52b
Mar  3 02:08:39 swag0 kernel: [    5.238670] sd 0:0:0:0: [sda] Write Protect is off
Mar  3 02:08:39 swag0 kernel: [    5.256754] usb 1-1.3.4.3: New USB device strings: Mfr=1, Product=2, SerialNumber=0
Mar  3 02:08:39 swag0 kernel: [    5.283250] sd 0:0:0:0: [sda] Mode Sense: 2b 00 00 08
Mar  3 02:08:39 swag0 kernel: [    5.287877] sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA
Mar  3 02:08:39 swag0 kernel: [    5.321865] usb 1-1.3.4.3: Product: USB Receiver
Mar  3 02:08:39 swag0 kernel: [    5.348117] usb 1-1.3.4.3: Manufacturer: Logitech
Mar  3 02:08:39 swag0 kernel: [    5.382888] input: Logitech USB Receiver as /devices/platform/bcm2708_usb/usb1/1-1/1-1.3/1-1.3.4/1-1.3.4.3/1-1.3.4.3:1.0/input/input0
Mar  3 02:08:39 swag0 kernel: [    5.440356] hid-generic 0003:046D:C52B.0001: input,hidraw0: USB HID v1.11 Keyboard [Logitech USB Receiver] on usb-bcm2708_usb-1.3.4.3/input0
Mar  3 02:08:39 swag0 kernel: [    5.505935] scsi 1:0:0:0: Direct-Access              USB DISK 2.0     PMAP PQ: 0 ANSI: 6
Mar  3 02:08:39 swag0 kernel: [    5.532522] input: Logitech USB Receiver as /devices/platform/bcm2708_usb/usb1/1-1/1-1.3/1-1.3.4/1-1.3.4.3/1-1.3.4.3:1.1/input/input1
Mar  3 02:08:39 swag0 kernel: [    5.609334] hid-generic 0003:046D:C52B.0002: input,hiddev0,hidraw1: USB HID v1.11 Mouse [Logitech USB Receiver] on usb-bcm2708_usb-1.3.4.3/input1
Mar  3 02:08:39 swag0 kernel: [    5.639969]  sda: sda1
Mar  3 02:08:39 swag0 kernel: [    5.671386] sd 0:0:0:0: [sda] Attached SCSI removable disk
Mar  3 02:08:39 swag0 kernel: [    5.708777] hid-generic 0003:046D:C52B.0003: hiddev0,hidraw2: USB HID v1.11 Device [Logitech USB Receiver] on usb-bcm2708_usb-1.3.4.3/input2
Mar  3 02:08:39 swag0 kernel: [    5.848352] usb 1-1.3.4.4: new high-speed USB device number 9 using dwc_otg
Mar  3 02:08:39 swag0 kernel: [    5.991945] usb 1-1.3.4.4: New USB device found, idVendor=05dc, idProduct=a833
Mar  3 02:08:39 swag0 kernel: [    6.035708] usb 1-1.3.4.4: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Mar  3 02:08:39 swag0 kernel: [    6.073177] usb 1-1.3.4.4: Product: USB Flash Drive
Mar  3 02:08:39 swag0 kernel: [    6.098172] usb 1-1.3.4.4: Manufacturer: Lexar
Mar  3 02:08:39 swag0 kernel: [    6.128231] usb 1-1.3.4.4: SerialNumber: AAHIA5L30RGJSZVX
Mar  3 02:08:39 swag0 kernel: [    6.147845] usb-storage 1-1.3.4.4:1.0: USB Mass Storage device detected
Mar  3 02:08:39 swag0 kernel: [    6.178254] scsi2 : usb-storage 1-1.3.4.4:1.0
Mar  3 02:08:39 swag0 kernel: [    6.412007] bcm2708_i2c_init_pinmode(0,0)
Mar  3 02:08:39 swag0 kernel: [    6.437486] sd 1:0:0:0: [sdb] 124846080 512-byte logical blocks: (63.9 GB/59.5 GiB)
Mar  3 02:08:39 swag0 kernel: [    6.452180] usb 1-1.3.1: new high-speed USB device number 10 using dwc_otg
Mar  3 02:08:39 swag0 kernel: [    6.480293] sd 1:0:0:0: [sdb] Write Protect is off
Mar  3 02:08:39 swag0 kernel: [    6.508131] sd 1:0:0:0: [sdb] Mode Sense: 23 00 00 00
Mar  3 02:08:39 swag0 kernel: [    6.510031] sd 1:0:0:0: [sdb] No Caching mode page found
Mar  3 02:08:39 swag0 kernel: [    6.534767] sd 1:0:0:0: [sdb] Assuming drive cache: write through
Mar  3 02:08:39 swag0 kernel: [    6.565624] sd 1:0:0:0: [sdb] No Caching mode page found
Mar  3 02:08:39 swag0 kernel: [    6.579398] usb 1-1.3.1: New USB device found, idVendor=1058, idProduct=070a
Mar  3 02:08:39 swag0 kernel: [    6.608166] sd 1:0:0:0: [sdb] Assuming drive cache: write through
Mar  3 02:08:39 swag0 kernel: [    6.620862] usb 1-1.3.1: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Mar  3 02:08:39 swag0 kernel: [    6.645348] usb 1-1.3.1: Product: My Passport 070A
Mar  3 02:08:39 swag0 kernel: [    6.668224] usb 1-1.3.1: Manufacturer: Western Digital
Mar  3 02:08:39 swag0 kernel: [    6.680410]  sdb: sdb1
Mar  3 02:08:39 swag0 kernel: [    6.689264] bcm2708_i2c_init_pinmode(0,1)
Mar  3 02:08:39 swag0 kernel: [    6.704577] usb 1-1.3.1: SerialNumber: 57584B314133303039373538
Mar  3 02:08:39 swag0 kernel: [    6.717365] sd 1:0:0:0: [sdb] No Caching mode page found
Mar  3 02:08:39 swag0 kernel: [    6.748128] sd 1:0:0:0: [sdb] Assuming drive cache: write through
Mar  3 02:08:39 swag0 kernel: [    6.761872] usb-storage 1-1.3.1:1.0: USB Mass Storage device detected
Mar  3 02:08:39 swag0 kernel: [    6.778140] sd 1:0:0:0: [sdb] Attached SCSI removable disk
Mar  3 02:08:39 swag0 kernel: [    6.797843] usb-storage 1-1.3.1:1.0: Quirks match for vid 1058 pid 070a: 200000
Mar  3 02:08:39 swag0 kernel: [    6.825203] scsi3 : usb-storage 1-1.3.1:1.0
Mar  3 02:08:39 swag0 kernel: [    6.958337] bcm2708_i2c bcm2708_i2c.0: BSC0 Controller at 0x20205000 (irq 79) (baudrate 100000)
Mar  3 02:08:39 swag0 kernel: [    7.196674] bcm2708_spi bcm2708_spi.0: master is unqueued, this is deprecated
Mar  3 02:08:39 swag0 kernel: [    7.358198] bcm2708_spi bcm2708_spi.0: SPI Controller at 0x20204000 (irq 80)
Mar  3 02:08:39 swag0 kernel: [    7.372413] scsi 2:0:0:0: Direct-Access     Lexar    USB Flash Drive  1100 PQ: 0 ANSI: 6
Mar  3 02:08:39 swag0 kernel: [    7.403714] sd 2:0:0:0: [sdc] 62652416 512-byte logical blocks: (32.0 GB/29.8 GiB)
Mar  3 02:08:39 swag0 kernel: [    7.432853] sd 2:0:0:0: [sdc] Write Protect is off
Mar  3 02:08:39 swag0 kernel: [    7.457802] sd 2:0:0:0: [sdc] Mode Sense: 22 00 00 00
Mar  3 02:08:39 swag0 kernel: [    7.459209] sd 2:0:0:0: [sdc] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA
Mar  3 02:08:39 swag0 kernel: [    7.503257]  sdc: sdc1
Mar  3 02:08:39 swag0 kernel: [    7.527724] sd 2:0:0:0: [sdc] Attached SCSI removable disk
Mar  3 02:08:39 swag0 kernel: [    7.558327] bcm2708_i2c_init_pinmode(1,2)
Mar  3 02:08:39 swag0 kernel: [    7.764350] bcm2708_i2c_init_pinmode(1,3)
Mar  3 02:08:39 swag0 kernel: [    7.850894] scsi 3:0:0:0: Direct-Access     WD       My Passport 070A 2018 PQ: 0 ANSI: 4
Mar  3 02:08:39 swag0 kernel: [    7.954018] scsi 3:0:0:1: Enclosure         WD       SES Device       2018 PQ: 0 ANSI: 4
Mar  3 02:08:39 swag0 kernel: [    7.969696] sd 3:0:0:0: [sdd] 1463775232 512-byte logical blocks: (749 GB/697 GiB)
Mar  3 02:08:39 swag0 kernel: [    7.998272] bcm2708_i2c bcm2708_i2c.1: BSC1 Controller at 0x20804000 (irq 79) (baudrate 100000)
Mar  3 02:08:39 swag0 kernel: [    8.014199] sd 3:0:0:0: [sdd] Write Protect is off
Mar  3 02:08:39 swag0 kernel: [    8.026462] sd 3:0:0:0: [sdd] Mode Sense: 2b 00 10 08
Mar  3 02:08:39 swag0 kernel: [    8.028557] sd 3:0:0:0: [sdd] No Caching mode page found
Mar  3 02:08:39 swag0 kernel: [    8.056555] sd 3:0:0:0: [sdd] Assuming drive cache: write back
Mar  3 02:08:39 swag0 kernel: [    8.084256] sd 3:0:0:0: [sdd] No Caching mode page found
Mar  3 02:08:39 swag0 kernel: [    8.108071] sd 3:0:0:0: [sdd] Assuming drive cache: write back
Mar  3 02:08:39 swag0 kernel: [    8.217888]  sdd: sdd1 sdd2
Mar  3 02:08:39 swag0 kernel: [    8.244877] sd 3:0:0:0: [sdd] No Caching mode page found
Mar  3 02:08:39 swag0 kernel: [    8.268108] sd 3:0:0:0: [sdd] Assuming drive cache: write back
Mar  3 02:08:39 swag0 kernel: [    8.298145] sd 3:0:0:0: [sdd] Attached SCSI disk
Mar  3 02:08:39 swag0 kernel: [    8.602239] bcm2708-i2s bcm2708-i2s.0: Failed to create debugfs directory
Mar  3 02:08:39 swag0 kernel: [   16.959218] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null)
Mar  3 02:08:39 swag0 kernel: [   23.267371] FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck.
Mar  3 02:08:39 swag0 kernel: [   25.741023] smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup
Mar  3 02:08:39 swag0 kernel: [   27.400348] smsc95xx 1-1.1:1.0 eth0: link up, 100Mbps, full-duplex, lpa 0xCDE1
Mar  3 02:08:39 swag0 kernel: [   30.440998] bcm2835-cpufreq: switching to governor ondemand
Mar  3 02:08:39 swag0 kernel: [   30.441033] bcm2835-cpufreq: switching to governor ondemand
Mar  3 02:08:40 swag0 kernel: [   32.736726] Adding 102396k swap on /var/swap.  Priority:-1 extents:1 across:102396k SSFS
Mar  3 02:08:41 swag0 kernel: [   33.385956] Bluetooth: Core ver 2.16
Mar  3 02:08:41 swag0 kernel: [   33.387579] NET: Registered protocol family 31
Mar  3 02:08:41 swag0 kernel: [   33.387606] Bluetooth: HCI device and connection manager initialized
Mar  3 02:08:41 swag0 kernel: [   33.387640] Bluetooth: HCI socket layer initialized
Mar  3 02:08:41 swag0 kernel: [   33.387658] Bluetooth: L2CAP socket layer initialized
Mar  3 02:08:41 swag0 kernel: [   33.387704] Bluetooth: SCO socket layer initialized
Mar  3 02:08:41 swag0 kernel: [   33.417814] Bluetooth: RFCOMM TTY layer initialized
Mar  3 02:08:41 swag0 kernel: [   33.417874] Bluetooth: RFCOMM socket layer initialized
Mar  3 02:08:41 swag0 kernel: [   33.417886] Bluetooth: RFCOMM ver 1.11
Mar  3 02:08:41 swag0 kernel: [   33.430920] Bluetooth: BNEP (Ethernet Emulation) ver 1.3
Mar  3 02:08:41 swag0 kernel: [   33.430943] Bluetooth: BNEP filters: protocol multicast
Mar  3 02:08:41 swag0 kernel: [   33.430979] Bluetooth: BNEP socket layer initialized
Mar  3 08:18:05 swag0 kernel: [   58.904587] EXT4-fs (sdc1): mounted filesystem with ordered data mode. Opts: (null)
Mar  3 08:18:06 swag0 kernel: [   59.369285] FAT-fs (sda1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck.
Mar  3 08:18:08 swag0 kernel: [   61.475650] EXT4-fs (sdb1): mounted filesystem with ordered data mode. Opts: (null)
Mar  3 08:18:14 swag0 kernel: [   67.659506] fuse init (API version 7.22)
Mar  3 08:34:44 swag0 kernel: [ 1057.789975] usb 1-1.3.4.1: new high-speed USB device number 11 using dwc_otg
Mar  3 08:34:44 swag0 kernel: [ 1057.891273] usb 1-1.3.4.1: New USB device found, idVendor=13fd, idProduct=1240
Mar  3 08:34:44 swag0 kernel: [ 1057.891310] usb 1-1.3.4.1: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Mar  3 08:34:44 swag0 kernel: [ 1057.891332] usb 1-1.3.4.1: Product: External        
Mar  3 08:34:44 swag0 kernel: [ 1057.891349] usb 1-1.3.4.1: Manufacturer: Generic 
Mar  3 08:34:44 swag0 kernel: [ 1057.891366] usb 1-1.3.4.1: SerialNumber: V605G41G                
Mar  3 08:34:44 swag0 kernel: [ 1057.898782] usb-storage 1-1.3.4.1:1.0: USB Mass Storage device detected
Mar  3 08:34:44 swag0 kernel: [ 1057.902824] scsi4 : usb-storage 1-1.3.4.1:1.0
Mar  3 08:34:45 swag0 kernel: [ 1058.900988] scsi 4:0:0:0: Direct-Access     Generic  External         1.04 PQ: 0 ANSI: 4
Mar  3 08:34:45 swag0 kernel: [ 1058.904523] sd 4:0:0:0: [sde] 586114704 512-byte logical blocks: (300 GB/279 GiB)
Mar  3 08:34:45 swag0 kernel: [ 1058.909185] sd 4:0:0:0: [sde] Write Protect is off
Mar  3 08:34:45 swag0 kernel: [ 1058.909230] sd 4:0:0:0: [sde] Mode Sense: 21 00 00 00
Mar  3 08:34:45 swag0 kernel: [ 1058.912033] sd 4:0:0:0: [sde] No Caching mode page found
Mar  3 08:34:45 swag0 kernel: [ 1058.912078] sd 4:0:0:0: [sde] Assuming drive cache: write through
Mar  3 08:34:45 swag0 kernel: [ 1058.915373] sd 4:0:0:0: [sde] No Caching mode page found
Mar  3 08:34:45 swag0 kernel: [ 1058.915411] sd 4:0:0:0: [sde] Assuming drive cache: write through
Mar  3 08:34:45 swag0 kernel: [ 1058.940311]  sde: sde1
Mar  3 08:34:45 swag0 kernel: [ 1058.944187] sd 4:0:0:0: [sde] No Caching mode page found
Mar  3 08:34:45 swag0 kernel: [ 1058.944227] sd 4:0:0:0: [sde] Assuming drive cache: write through
Mar  3 08:34:45 swag0 kernel: [ 1058.944252] sd 4:0:0:0: [sde] Attached SCSI disk
Mar  3 10:55:11 swag0 kernel: [ 9485.308174] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:55:17 swag0 kernel: [ 9490.569922] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:55:17 swag0 kernel: [ 9490.750421] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:55:17 swag0 kernel: [ 9490.987920] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:55:17 swag0 kernel: [ 9491.220170] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:55:23 swag0 kernel: [ 9496.589689] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:55:30 swag0 kernel: [ 9504.200225] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:55:33 swag0 kernel: [ 9506.560462] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:55:33 swag0 kernel: [ 9506.788483] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:55:34 swag0 kernel: [ 9507.970214] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:55:34 swag0 kernel: [ 9508.162968] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:55:34 swag0 kernel: [ 9508.288353] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:55:36 swag0 kernel: [ 9509.590010] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:55:36 swag0 kernel: [ 9509.690217] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:55:36 swag0 kernel: [ 9509.780036] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:34 swag0 kernel: [ 9748.110830] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:37 swag0 kernel: [ 9750.599659] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:39 swag0 kernel: [ 9752.580866] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:39 swag0 kernel: [ 9753.310595] Transfer to device 8 endpoint 0x1 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:40 swag0 kernel: [ 9754.160843] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:40 swag0 kernel: [ 9754.234842] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:40 swag0 kernel: [ 9754.297843] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:41 swag0 kernel: [ 9754.364585] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:41 swag0 kernel: [ 9754.404852] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:41 swag0 kernel: [ 9754.503350] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:42 swag0 kernel: [ 9755.688378] dwc_otg_hcd_handle_hc_fsm: 11 callbacks suppressed
Mar  3 10:59:42 swag0 kernel: [ 9755.688414] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:42 swag0 kernel: [ 9755.693380] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:42 swag0 kernel: [ 9755.750114] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:43 swag0 kernel: [ 9756.411113] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:43 swag0 kernel: [ 9756.440620] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:43 swag0 kernel: [ 9756.483371] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:43 swag0 kernel: [ 9756.544626] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:43 swag0 kernel: [ 9756.677358] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:43 swag0 kernel: [ 9756.743364] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:43 swag0 kernel: [ 9756.807613] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:50 swag0 kernel: [ 9763.469876] dwc_otg_hcd_handle_hc_fsm: 12 callbacks suppressed
Mar  3 10:59:50 swag0 kernel: [ 9763.469897] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:50 swag0 kernel: [ 9764.092397] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:51 swag0 kernel: [ 9764.385146] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:51 swag0 kernel: [ 9764.685263] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:51 swag0 kernel: [ 9765.114393] Transfer to device 8 endpoint 0x1 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:51 swag0 kernel: [ 9765.122146] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:52 swag0 kernel: [ 9766.064129] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:52 swag0 kernel: [ 9766.094869] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:52 swag0 kernel: [ 9766.110120] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:52 swag0 kernel: [ 9766.176374] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:57 swag0 kernel: [ 9770.545160] dwc_otg_hcd_handle_hc_fsm: 30 callbacks suppressed
Mar  3 10:59:57 swag0 kernel: [ 9770.545196] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:58 swag0 kernel: [ 9771.397156] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:58 swag0 kernel: [ 9771.533005] Transfer to device 8 endpoint 0x1 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:58 swag0 kernel: [ 9771.958153] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:58 swag0 kernel: [ 9772.043910] Transfer to device 8 endpoint 0x1 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:59 swag0 kernel: [ 9772.445031] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:59 swag0 kernel: [ 9772.474163] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:59 swag0 kernel: [ 9772.563913] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:59 swag0 kernel: [ 9772.581155] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:59 swag0 kernel: [ 9772.619165] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
tvjon commented 10 years ago

& here's the corresponding syslog (so far):

Mar  3 08:22:43 swag0 rsyslogd: [origin software="rsyslogd" swVersion="5.8.11" x-pid="2129" x-info="http://www.rsyslog.com"] rsyslogd was HUPed
Mar  3 08:22:45 swag0 anacron[2182]: Job `cron.daily' terminated
Mar  3 08:22:45 swag0 anacron[2182]: Normal exit (1 job run)
Mar  3 08:34:44 swag0 kernel: [ 1057.789975] usb 1-1.3.4.1: new high-speed USB device number 11 using dwc_otg
Mar  3 08:34:44 swag0 kernel: [ 1057.891273] usb 1-1.3.4.1: New USB device found, idVendor=13fd, idProduct=1240
Mar  3 08:34:44 swag0 kernel: [ 1057.891310] usb 1-1.3.4.1: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Mar  3 08:34:44 swag0 kernel: [ 1057.891332] usb 1-1.3.4.1: Product: External        
Mar  3 08:34:44 swag0 kernel: [ 1057.891349] usb 1-1.3.4.1: Manufacturer: Generic 
Mar  3 08:34:44 swag0 kernel: [ 1057.891366] usb 1-1.3.4.1: SerialNumber: V605G41G                
Mar  3 08:34:44 swag0 kernel: [ 1057.898782] usb-storage 1-1.3.4.1:1.0: USB Mass Storage device detected
Mar  3 08:34:44 swag0 kernel: [ 1057.902824] scsi4 : usb-storage 1-1.3.4.1:1.0
Mar  3 08:34:44 swag0 mtp-probe: checking bus 1, device 11: "/sys/devices/platform/bcm2708_usb/usb1/1-1/1-1.3/1-1.3.4/1-1.3.4.1"
Mar  3 08:34:44 swag0 mtp-probe: bus: 1, device: 11 was not an MTP device
Mar  3 08:34:45 swag0 kernel: [ 1058.900988] scsi 4:0:0:0: Direct-Access     Generic  External         1.04 PQ: 0 ANSI: 4
Mar  3 08:34:45 swag0 kernel: [ 1058.904523] sd 4:0:0:0: [sde] 586114704 512-byte logical blocks: (300 GB/279 GiB)
Mar  3 08:34:45 swag0 kernel: [ 1058.909185] sd 4:0:0:0: [sde] Write Protect is off
Mar  3 08:34:45 swag0 kernel: [ 1058.909230] sd 4:0:0:0: [sde] Mode Sense: 21 00 00 00
Mar  3 08:34:45 swag0 kernel: [ 1058.912033] sd 4:0:0:0: [sde] No Caching mode page found
Mar  3 08:34:45 swag0 kernel: [ 1058.912078] sd 4:0:0:0: [sde] Assuming drive cache: write through
Mar  3 08:34:45 swag0 kernel: [ 1058.915373] sd 4:0:0:0: [sde] No Caching mode page found
Mar  3 08:34:45 swag0 kernel: [ 1058.915411] sd 4:0:0:0: [sde] Assuming drive cache: write through
Mar  3 08:34:45 swag0 kernel: [ 1058.940311]  sde: sde1
Mar  3 08:34:45 swag0 kernel: [ 1058.944187] sd 4:0:0:0: [sde] No Caching mode page found
Mar  3 08:34:45 swag0 kernel: [ 1058.944227] sd 4:0:0:0: [sde] Assuming drive cache: write through
Mar  3 08:34:45 swag0 kernel: [ 1058.944252] sd 4:0:0:0: [sde] Attached SCSI disk
Mar  3 08:37:01 swag0 ntfs-3g[2928]: Version 2012.1.15AR.5 external FUSE 29
Mar  3 08:37:01 swag0 ntfs-3g[2928]: Mounted /dev/sde1 (Read-Write, label "EB0406", NTFS 3.1)
Mar  3 08:37:01 swag0 ntfs-3g[2928]: Cmdline options: rw,nosuid,nodev,uhelper=udisks,uid=1000,gid=1000,dmask=0077,fmask=0177
Mar  3 08:37:01 swag0 ntfs-3g[2928]: Mount options: rw,nosuid,nodev,uhelper=udisks,allow_other,nonempty,relatime,default_permissions,fsname=/dev/sde1,blkdev,blksize=4096
Mar  3 08:37:01 swag0 ntfs-3g[2928]: Global ownership and permissions enforced, configuration type 7
Mar  3 09:04:17 swag0 dbus[2264]: [system] Activating service name='org.freedesktop.UPower' (using servicehelper)
Mar  3 09:04:17 swag0 dbus[2264]: [system] Successfully activated service 'org.freedesktop.UPower'
Mar  3 09:04:18 swag0 anacron[3089]: Anacron 2.3 started on 2014-03-03
Mar  3 09:04:18 swag0 anacron[3089]: Normal exit (0 jobs run)
Mar  3 09:17:01 swag0 /USR/SBIN/CRON[3266]: (root) CMD (   cd / && run-parts --report /etc/cron.hourly)
Mar  3 09:17:01 swag0 /USR/SBIN/CRON[3265]: (CRON) info (No MTA installed, discarding output)
Mar  3 10:17:01 swag0 /USR/SBIN/CRON[9439]: (root) CMD (   cd / && run-parts --report /etc/cron.hourly)
Mar  3 10:17:01 swag0 /USR/SBIN/CRON[9438]: (CRON) info (No MTA installed, discarding output)
Mar  3 10:55:11 swag0 kernel: [ 9485.308174] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:55:17 swag0 kernel: [ 9490.569922] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:55:17 swag0 kernel: [ 9490.750421] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:55:17 swag0 kernel: [ 9490.987920] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:55:17 swag0 kernel: [ 9491.220170] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:55:23 swag0 kernel: [ 9496.589689] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:55:30 swag0 kernel: [ 9504.200225] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:55:33 swag0 kernel: [ 9506.560462] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:55:33 swag0 kernel: [ 9506.788483] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:55:34 swag0 kernel: [ 9507.970214] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:55:34 swag0 kernel: [ 9508.162968] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:55:34 swag0 kernel: [ 9508.288353] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:55:36 swag0 kernel: [ 9509.590010] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:55:36 swag0 kernel: [ 9509.690217] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:55:36 swag0 kernel: [ 9509.780036] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:34 swag0 kernel: [ 9748.110830] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:37 swag0 kernel: [ 9750.599659] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:39 swag0 kernel: [ 9752.580866] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:39 swag0 kernel: [ 9753.310595] Transfer to device 8 endpoint 0x1 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:40 swag0 kernel: [ 9754.160843] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:40 swag0 kernel: [ 9754.234842] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:40 swag0 kernel: [ 9754.297843] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:41 swag0 kernel: [ 9754.364585] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:41 swag0 kernel: [ 9754.404852] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:41 swag0 kernel: [ 9754.503350] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:42 swag0 kernel: [ 9755.688378] dwc_otg_hcd_handle_hc_fsm: 11 callbacks suppressed
Mar  3 10:59:42 swag0 kernel: [ 9755.688414] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:42 swag0 kernel: [ 9755.693380] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:42 swag0 kernel: [ 9755.750114] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:43 swag0 kernel: [ 9756.411113] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:43 swag0 kernel: [ 9756.440620] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:43 swag0 kernel: [ 9756.483371] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:43 swag0 kernel: [ 9756.544626] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:43 swag0 kernel: [ 9756.677358] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:43 swag0 kernel: [ 9756.743364] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:43 swag0 kernel: [ 9756.807613] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:50 swag0 kernel: [ 9763.469876] dwc_otg_hcd_handle_hc_fsm: 12 callbacks suppressed
Mar  3 10:59:50 swag0 kernel: [ 9763.469897] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:50 swag0 kernel: [ 9764.092397] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:51 swag0 kernel: [ 9764.385146] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:51 swag0 kernel: [ 9764.685263] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:51 swag0 kernel: [ 9765.114393] Transfer to device 8 endpoint 0x1 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:51 swag0 kernel: [ 9765.122146] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:52 swag0 kernel: [ 9766.064129] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:52 swag0 kernel: [ 9766.094869] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:52 swag0 kernel: [ 9766.110120] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:52 swag0 kernel: [ 9766.176374] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:57 swag0 kernel: [ 9770.545160] dwc_otg_hcd_handle_hc_fsm: 30 callbacks suppressed
Mar  3 10:59:57 swag0 kernel: [ 9770.545196] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:58 swag0 kernel: [ 9771.397156] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:58 swag0 kernel: [ 9771.533005] Transfer to device 8 endpoint 0x1 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:58 swag0 kernel: [ 9771.958153] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:58 swag0 kernel: [ 9772.043910] Transfer to device 8 endpoint 0x1 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:59 swag0 kernel: [ 9772.445031] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:59 swag0 kernel: [ 9772.474163] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:59 swag0 kernel: [ 9772.563913] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:59 swag0 kernel: [ 9772.581155] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
Mar  3 10:59:59 swag0 kernel: [ 9772.619165] Transfer to device 8 endpoint 0x2 failed - FIQ reported NYET. Data may have been lost.
 x-info=
richard-tx commented 10 years ago

Most impressive.

P33M commented 10 years ago

I can see the issue: despite my best efforts it appears that split-completes are slipping a couple of microframes in rare cases.

P33M commented 10 years ago

The BRANCH=next firmware should now fix the "FIQ reported NYET" messages. Please test and see if they keep occuring.

richard-tx commented 10 years ago

I started with a fresh reimaging of a sd card with the 1-7-14 release, ran

BRANCH=next rpi-update

It is currently running my test. Results to come.

Richard

richard-tx commented 10 years ago

I decided to enable the I2c buses.

Something strange is going on with I2C bus 1. I do have a device at 0x48 but there is no device at 0x3b nor was that address ever reserved in previous releases.

i2cdetect -y 1

     0  1  2  3  4  5  6  7  8  9  a  b  c  d  e  f
00:          -- -- -- -- -- -- -- -- -- -- -- -- --
10: -- -- -- -- -- -- -- -- -- -- -- -- -- -- -- --
20: -- -- -- -- -- -- -- -- -- -- -- -- -- -- -- --
30: -- -- -- -- -- -- -- -- -- -- -- UU -- -- -- --
40: -- -- -- -- -- -- -- -- 48 -- -- -- -- -- -- --
50: -- -- -- -- -- -- -- -- -- -- -- -- -- -- -- --
60: -- -- -- -- -- -- -- -- -- -- -- -- -- -- -- --
70: -- -- -- -- -- -- -- --
richard-tx commented 10 years ago

Something definitely happened to the I2C bus with the latest rpi-update.

tvjon commented 10 years ago

Like Richard, I'm getting 12c errors, but although the log date & time is correct, the entries appear not to be so before posting any I'll do more investigating.

I'm on another SD card today as after installing gstreamer last night I'm getting the SSL cert' refusal problem.

Anyway, eyetoy webcam is back to severe picture breakup:

e) VIDIOC_DQBUF - Unable to dequeue buffer : Resource temporarily unavailable Error grabbing image ioctl (-1069263343) retried 4 times - giving up: Resource temporarily unavailable) VIDIOC_DQBUF - Unable to dequeue buffer : Resource temporarily unavailable Error grabbing image ioctl (-1069263343) retried 4 times - giving up: Resource temporarily unavailable) VIDIOC_DQBUF - Unable to dequeue buffer : Resource temporarily unavailable Error grabbing image ioctl (-1069263343) retried 4 times - giving up: Resource temporarily unavailable)

With eyetoy in situ, mouse & keyboard are sluggish. Unplugging eyetoy improves their response. Same problem with FSM mask at 3 or 7.

popcornmix commented 10 years ago

@richard-tx Can you be specific. What version of kernel has the I2C problem? Can you identify the most recent kernel that has working I2C?

(You can get revert to older kernel/firmware with rpi-update [git hash].)

tvjon commented 10 years ago

I have a DVD drive plugged in & see that occasionally the ET camera video freezes when a disc (any optical, eg. Data) is being accessed. However the ET video usually continues after the freeze. Increasing ET's video size to 640*480 kills video after a few seconds which is when the endless VIDIOC_DQBUF errors appear (from guvcview). There's nothing apparent in the logs on this SD other than what looks like corruption, so as soon as I can fix the can't RPi-update problem, I'll resume testing on that SD.

richard-tx commented 10 years ago

b42b4d8a038b2d3f13c3c7b4dc9e9cb9307b78ed (1-7-2014) - I2cdetect is OK.

fa76789ba3cd41cfdd3cf6b14f2940cdfc007a8e (2-9-2014) - i2cdetect NOT OK.

43f305ced0a037d700f3fd7873b0de623e18b63d (1-17-2014) i2cdetect is NOT OK

1d78a22d866b69454c062443db9d4b42f00f0215 (1-10-2014) i2cdetect is OK

So the answer is that I2cdetect is broken starting with 1-17-2014

richard-tx commented 10 years ago

I started with the latest official release. (1-17-2014)

Anyway I ran my streaming audio test overnight and the kernel panic seems to be resolved. I experienced no hangs or other USB anomalies. Even the flash rate on the wifi card appears normal with no intermittent pauses.

Richard

popcornmix commented 10 years ago

@richard-tx Possibly the hifiberry/i2s audio modules? Try adding the new modules to /etc/modprobe.d/raspi-blacklist.conf

blacklist snd_soc_bcm2708_i2s
blacklist bcm2708_dmaengine
blacklist snd_soc_pcm5102a
blacklist snd_soc_hifiberry_dac
blacklist snd_soc_wm8804
blacklist snd_soc_hifiberry_digi

(and other modules that appeared then...)

richard-tx commented 10 years ago

blacklisting the modules listed above resolved the problem.

The question is this I suppose. Is what I am seeing (UU in the i2cdetect output) normal now?

richard-tx commented 10 years ago

adding the line blacklist snd_soc_wm8804

solved the problem

P33M commented 10 years ago

@tvjon Eyetoy is working fine for me (but I am only stressing the Pi with occasional network access/keyboard/mouse). There is a known weakness where the FIQ will erroneously start a long transfer too late in a frame - this can happen if there is a "bad" driver holding off interrupts for a particularly long time.

I'm closing this issue as the driver crashes have gone away with BRANCH=next.

pierz commented 10 years ago

I was also seeing this bug with 3.10.25 so I today ran rpi-update which took me to 3.10.34 (27 March 14) and the crash was NOT fixed. However when I [today] ran BRANCH-next rpi-update it took me [back] to 3.10.33 (19 Mar 14) which did fix the bug. But this seems to indicate to me the bug got fixed in 3.10.33 and broken again in 3.10.34, or am I misunderstanding what BRANCH=next is about?

popcornmix commented 10 years ago

BRANCH=next has the USB driver rewrite in. The default branch does not, so what you are seeing is expected.

PeterPablo commented 10 years ago

Sorry if I am asking an obvious question. Is this fixed now in the regular branch? I am seeing plenty of the following messages in my log, when I am playing audio using gmediarenderer (https://github.com/hzeller/gmrender-resurrect): rpi kernel: [94731.636042] Transfer to device 7 endpoint 0x2 frame 593 failed - FIQ reported NYET. Data may have been lost. I have a Sound Blaster X-Fi Surround 5.1 connected directly to the Raspberry Pi. uname -a results in: Linux rpi 3.12.19+ #681 PREEMPT Fri May 9 16:36:10 BST 2014 armv6l GNU/Linux

popcornmix commented 10 years ago

Master branch now has latest USB FIQ FSM driver in. next branch is not being updated (until the next experimental feature needs testing) so should not be used.

PeterPablo commented 10 years ago

Alright. So should I worry about that message that is flooding my logs? The audio output does not seem to be affected (no crackling, breaks, etc.). In that respect the new driver brought a great improvement!

PeterPablo commented 10 years ago

I am still experiencing those errors regularly when playing back audio on my external sound card using shairport: Transfer to device 6 endpoint 0x1 frame 1313 failed - FIQ reported NYET. Data may have been lost. In order to verify it is not related to an outdated firmware I updated it: Linux rpi 3.12.20+ #687 PREEMPT Fri May 30 16:39:11 BST 2014 armv6l GNU/Linux

Shairport reports WARNING: underrun., increasing the buffer does not solve the issue of short breaks during audio playback. Interestingly I have no issues with audio, when playing videos with omxplayer, though there audio is always output over HDMI (and not the external sound card). Is this issue here the proper place to report my issue?

P33M commented 10 years ago

Please open a new issue, posting full lsusb -v, dmesg and lsusb -t.