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.22k stars 5.02k forks source link

watchdog caused traceback due to USB hang #1649

Closed andersthomson closed 6 years ago

andersthomson commented 8 years ago

Hi,

I've pi2 used as a headless tvheadend server with two tuners (so lots of USB traffic). Every so often (after a day or two) I get hard hangs of all USB traffic which has prompted me to manually reboot the thing.

I've now enabled the watchdog, configured reboot on panic and gotten myself a serial cable and I now start to see results.

Attached is a large log of where the USB goes south and eventually I get a kernel backtrace triggered by a watchdog codepath somehow.

Any ideas how to fix this? (grep for 'cut here' for the kernel bits)

crashreport.txt

popcornmix commented 8 years ago

How did you enable the watchdog? This look suspicious

Sep 22 18:06:13 rpi2 systemd-shutdow[1]: Failed to set timeout to 600s: Invalid argument

The hardware watchdog is limited to around 30 seconds I believe due the number of bits in the hardware countdown timer. Ten minutes is very long for a watchdog timeout. 10 seconds would be a more common value.

lategoodbye commented 8 years ago

@andersthomson Please provide a lsusb -t output of the affected setup. Are you able to narrow down the affected setup to a minimum setup of connected USB devices?

andersthomson commented 8 years ago

watchdog is enabled as with the systemd unit, which exec's /usr/sbin/watchdog

 cat /etc/watchdog.conf 
#ping                   = 172.31.14.1
#ping                   = 192.168.1.1
#ping                   = 172.26.1.255
#interface              = eth0
#file                   = /var/log/messages
#change                 = 1407

# Uncomment to enable test. Setting one of these values to '0' disables it.
# These values will hopefully never reboot your machine during normal use
# (if your machine is really hung, the loadavg will go much higher than 25)
max-load-1              = 24
#max-load-5             = 18
#max-load-15            = 12

# Note that this is the number of pages!
# To get the real size, check how large the pagesize is on your machine.
min-memory              = 1
allocatable-memory      = 1

#repair-binary          = /usr/sbin/repair
#repair-timeout         = 
#test-binary            = 
#test-timeout           = 

watchdog-device = /dev/watchdog
watchdog-timeout = 15

# Defaults compiled into the binary
#temperature-device     =
temperature-device = /sys/class/thermal/thermal_zone0/temp
#max-temperature        = 120
max-temperature = 51000

# Defaults compiled into the binary
admin                   = root
interval                = 1
logtick                = 1
log-dir         = /var/log/watchdog

# This greatly decreases the chance that watchdog won't be scheduled before
# your machine is really loaded
realtime                = yes
priority                = 1

# Check if rsyslogd is still running by enabling the following line
#pidfile                = /var/run/rsyslogd.pid
andersthomson commented 8 years ago

lsusb -t /: Bus 01.Port 1: Dev 1, Class=root_hub, Driver=dwc_otg/1p, 480M | Port 1: Dev 2, If 0, Class=Hub, Driver=hub/5p, 480M | Port 1: Dev 3, If 0, Class=Vendor Specific Class, Driver=smsc95xx, 480M | Port 2: Dev 4, If 0, Class=Hub, Driver=hub/7p, 480M | Port 2: Dev 6, If 0, Class=Vendor Specific Class, Driver=em28xx, 480M | Port 3: Dev 5, If 0, Class=Hub, Driver=hub/4p, 480M | Port 1: Dev 7, If 0, Class=Hub, Driver=hub/4p, 480M | Port 1: Dev 9, If 0, Class=Vendor Specific Class, Driver=em28xx, 480M | Port 2: Dev 11, If 0, Class=Vendor Specific Class, Driver=usbfs, 12M | Port 4: Dev 8, If 0, Class=Hub, Driver=hub/4p, 480M | Port 1: Dev 10, If 0, Class=Vendor Specific Class, Driver=ftdi_sio, 12M |__ Port 4: Dev 12, If 0, Class=Mass Storage, Driver=usb-storage, 480M

andersthomson commented 8 years ago

My "is things in a stable state?" script detected this in dmesg yesterday, which also points to the watchdog.

[ 0.000000] Booting Linux on physical CPU 0xf00 [ 0.000000] Initializing cgroup subsys cpuset [ 0.000000] Initializing cgroup subsys cpu [ 0.000000] Initializing cgroup subsys cpuacct [ 0.000000] Linux version 4.4.15-v7+ (dc4@dc4-XPS13-9333) (gcc version 4.9.3 (crosstool-NG crosstool-ng-1.22.0-88-g8460611) ) #897 SMP Tue Jul 12 18:42:55 BST 2016 [ 0.000000] CPU: ARMv7 Processor [410fc075] revision 5 (ARMv7), cr=10c5387d [ 0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache [ 0.000000] Machine model: Raspberry Pi 2 Model B Rev 1.1 [ 0.000000] cma: Reserved 8 MiB at 0x3a800000 [ 0.000000] Memory policy: Data cache writealloc [ 0.000000] On node 0 totalpages: 241664 [ 0.000000] free_area_init_node: node 0, pgdat 808c0e00, node_mem_map b9fa6000 [ 0.000000] Normal zone: 2124 pages used for memmap [ 0.000000] Normal zone: 0 pages reserved [ 0.000000] Normal zone: 241664 pages, LIFO batch:31 [ 0.000000] [bcm2709_smp_init_cpus] enter (9520->f3003010) [ 0.000000] [bcm2709_smp_init_cpus] ncores=4 [ 0.000000] PERCPU: Embedded 13 pages/cpu @b9f63000 s22592 r8192 d22464 u53248 [ 0.000000] pcpu-alloc: s22592 r8192 d22464 u53248 alloc=13*4096 [ 0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 [ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. Total pages: 239540 [ 0.000000] Kernel command line: dma.dmachans=0x7f35 bcm2708_fb.fbwidth=656 bcm2708_fb.fbheight=416 bcm2709.boardrev=0xa01041 bcm2709.serial=0x9aa48012 smsc95xx.macaddr=B8:27:EB:A4:80:12 bcm2708_fb.fbswap=1 bcm2709.uart_clock=3000000 bcm2709.disk_led_gpio=47 bcm2709.disk_led_active_low=0 vc_mem.mem_base=0x3dc00000 vc_mem.mem_size=0x3f000000 root=/dev/mmcblk0p4 smsc95xx.turbo_mode=N rootdelay=2 console=ttyAMA0,115200 kgdboc=ttyAMA0,115200 init=/usr/lib/systemd/systemd ro [ 0.000000] PID hash table entries: 4096 (order: 2, 16384 bytes) [ 0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes) [ 0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes) [ 0.000000] Memory: 939088K/966656K available (6344K kernel code, 432K rwdata, 1712K rodata, 476K init, 764K bss, 19376K reserved, 8192K cma-reserved) [ 0.000000] Virtual kernel memory layout: vector : 0xffff0000 - 0xffff1000 ( 4 kB) fixmap : 0xffc00000 - 0xfff00000 (3072 kB) vmalloc : 0xbb800000 - 0xff800000 (1088 MB) lowmem : 0x80000000 - 0xbb000000 ( 944 MB) modules : 0x7f000000 - 0x80000000 ( 16 MB) .text : 0x80008000 - 0x807e6470 (8058 kB) .init : 0x807e7000 - 0x8085e000 ( 476 kB) .data : 0x8085e000 - 0x808ca108 ( 433 kB) .bss : 0x808cd000 - 0x8098c1ac ( 765 kB) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] Hierarchical RCU implementation. [ 0.000000] Build-time adjustment of leaf fanout to 32. [ 0.000000] NR_IRQS:16 nr_irqs:16 16 [ 0.000000] Architected cp15 timer(s) running at 19.20MHz (phys). [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x46d987e47, max_idle_ns: 440795202767 ns [ 0.000011] sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 4398046511078ns [ 0.000031] Switching to timer-based delay loop, resolution 52ns [ 0.000349] Console: colour dummy device 80x30 [ 0.000386] Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=192000) [ 0.000412] pid_max: default: 32768 minimum: 301 [ 0.000802] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes) [ 0.000824] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes) [ 0.002003] Disabling cpuset control group subsystem [ 0.002049] Initializing cgroup subsys io [ 0.002085] Initializing cgroup subsys memory [ 0.002140] Initializing cgroup subsys devices [ 0.002166] Initializing cgroup subsys freezer [ 0.002192] Initializing cgroup subsys net_cls [ 0.002262] CPU: Testing write buffer coherency: ok [ 0.002348] ftrace: allocating 21209 entries in 63 pages [ 0.054644] CPU0: update cpu_capacity 1024 [ 0.054683] CPU0: thread -1, cpu 0, socket 15, mpidr 80000f00 [ 0.054699] [bcm2709_smp_prepare_cpus] enter [ 0.054835] Setting up static identity map for 0x8240 - 0x8274 [ 0.057213] [bcm2709_boot_secondary] cpu:1 started (0) 17 [ 0.057643] [bcm2709_secondary_init] enter cpu:1 [ 0.057702] CPU1: update cpu_capacity 1024 [ 0.057710] CPU1: thread -1, cpu 1, socket 15, mpidr 80000f01 [ 0.058283] [bcm2709_boot_secondary] cpu:2 started (0) 17 [ 0.058636] [bcm2709_secondary_init] enter cpu:2 [ 0.058668] CPU2: update cpu_capacity 1024 [ 0.058676] CPU2: thread -1, cpu 2, socket 15, mpidr 80000f02 [ 0.059217] [bcm2709_boot_secondary] cpu:3 started (0) 18 [ 0.059471] [bcm2709_secondary_init] enter cpu:3 [ 0.059499] CPU3: update cpu_capacity 1024 [ 0.059507] CPU3: thread -1, cpu 3, socket 15, mpidr 80000f03 [ 0.059596] Brought up 4 CPUs [ 0.059626] SMP: Total of 4 processors activated (153.60 BogoMIPS). [ 0.059636] CPU: All CPU(s) started in HYP mode. [ 0.059645] CPU: Virtualization extensions available. [ 0.060557] devtmpfs: initialized [ 0.073059] VFP support v0.3: implementor 41 architecture 2 part 30 variant 7 rev 5 [ 0.073517] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns [ 0.074563] pinctrl core: initialized pinctrl subsystem [ 0.075348] NET: Registered protocol family 16 [ 0.081083] DMA: preallocated 4096 KiB pool for atomic coherent allocations [ 0.089191] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers. [ 0.089211] hw-breakpoint: maximum watchpoint size is 8 bytes. [ 0.089415] Serial: AMBA PL011 UART driver [ 0.089743] 3f201000.uart: ttyAMA0 at MMIO 0x3f201000 (irq = 87, base_baud = 0) is a PL011 rev2 [ 0.588754] console [ttyAMA0] enabled [ 0.593041] bcm2835-mbox 3f00b880.mailbox: mailbox enabled [ 0.672384] bcm2835-dma 3f007000.dma: DMA legacy API manager at f3007000, dmachans=0x1 [ 0.681064] SCSI subsystem initialized [ 0.685036] usbcore: registered new interface driver usbfs [ 0.690657] usbcore: registered new interface driver hub [ 0.696111] usbcore: registered new device driver usb [ 0.711255] raspberrypi-firmware soc:firmware: Attached to firmware from 2016-07-15 17:50 [ 0.746937] clocksource: Switched to clocksource arch_sys_counter [ 0.806132] FS-Cache: Loaded [ 0.809457] CacheFiles: Loaded [ 0.828931] NET: Registered protocol family 2 [ 0.834524] TCP established hash table entries: 8192 (order: 3, 32768 bytes) [ 0.841738] TCP bind hash table entries: 8192 (order: 4, 65536 bytes) [ 0.848382] TCP: Hash tables configured (established 8192 bind 8192) [ 0.854875] UDP hash table entries: 512 (order: 2, 16384 bytes) [ 0.860873] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes) [ 0.867571] NET: Registered protocol family 1 [ 0.872430] RPC: Registered named UNIX socket transport module. [ 0.878384] RPC: Registered udp transport module. [ 0.883081] RPC: Registered tcp transport module. [ 0.887802] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.895413] hw perfevents: enabled with armv7_cortex_a7 PMU driver, 5 counters available [ 0.905294] futex hash table entries: 1024 (order: 4, 65536 bytes) [ 0.927779] VFS: Disk quotas dquot_6.6.0 [ 0.932099] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) [ 0.941892] FS-Cache: Netfs 'nfs' registered for caching [ 0.948391] NFS: Registering the id_resolver key type [ 0.953496] Key type id_resolver registered [ 0.957710] Key type id_legacy registered [ 0.964992] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 252) [ 0.972624] io scheduler noop registered [ 0.976555] io scheduler deadline registered [ 0.980926] io scheduler cfq registered (default) [ 0.988618] BCM2708FB: allocated DMA memory fac00000 [ 0.993612] BCM2708FB: allocated DMA channel 0 @ f3007000 [ 1.004940] Console: switching to colour frame buffer device 82x26 [ 1.015325] KGDB: Registered I/O driver kgdboc [ 2.090000] bcm2835-rng 3f104000.rng: hwrng registered [ 2.095298] vc-cma: Videocore CMA driver [ 2.099256] vc-cma: vc_cma_base = 0x00000000 [ 2.103954] vc-cma: vc_cma_size = 0x00000000 (0 MiB) [ 2.109370] vc-cma: vc_cma_initial = 0x00000000 (0 MiB) [ 2.115074] vc-mem: phys_addr:0x00000000 mem_base=0x3dc00000 mem_size:0x3f000000(1008 MiB) [ 2.143978] brd: module loaded [ 2.158356] loop: module loaded [ 2.162591] vchiq: vchiq_init_state: slot_zero = 0xbac80000, is_master = 0 [ 2.171231] Loading iSCSI transport class v2.0-870. [ 2.177056] usbcore: registered new interface driver smsc95xx [ 2.182873] dwc_otg: version 3.00a 10-AUG-2012 (platform bus) [ 2.389062] Core Release: 2.80a [ 2.392216] Setting default values for core params [ 2.397057] Finished setting default values for core params [ 2.603034] Using Buffer DMA mode [ 2.606346] Periodic Transfer Interrupt Enhancement - disabled [ 2.612190] Multiprocessor Interrupt Enhancement - disabled [ 2.617775] OTG VER PARAM: 0, OTG VER FLAG: 0 [ 2.622130] Dedicated Tx FIFOs mode [ 2.626065] WARN::dwc_otg_hcd_init:1047: FIQ DMA bounce buffers: virt = 0xbac14000 dma = 0xfac14000 len=9024 [ 2.635939] FIQ FSM acceleration enabled for : Non-periodic Split Transactions Periodic Split Transactions High-Speed Isochronous Endpoints Interrupt/Control Split Transaction hack enabled [ 2.658285] dwc_otg: Microframe scheduler enabled [ 2.658358] WARN::hcd_init_fiq:413: FIQ on core 1 at 0x80446aa0 [ 2.664276] WARN::hcd_init_fiq:414: FIQ ASM at 0x80446e10 length 36 [ 2.670538] WARN::hcd_init_fiq:439: MPHI regs_base at 0xbb87e000 [ 2.676612] dwc_otg 3f980000.usb: DWC OTG Controller [ 2.681636] dwc_otg 3f980000.usb: new USB bus registered, assigned bus number 1 [ 2.688999] dwc_otg 3f980000.usb: irq 62, io mem 0x00000000 [ 2.694608] Init: Port Power? op_state=1 [ 2.698549] Init: Power Port (0) [ 2.702068] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002 [ 2.708882] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 2.716097] usb usb1: Product: DWC OTG Controller [ 2.720820] usb usb1: Manufacturer: Linux 4.4.15-v7+ dwc_otg_hcd [ 2.726821] usb usb1: SerialNumber: 3f980000.usb [ 2.732509] hub 1-0:1.0: USB hub found [ 2.736311] hub 1-0:1.0: 1 port detected [ 2.741046] dwc_otg: FIQ enabled [ 2.741060] dwc_otg: NAK holdoff enabled [ 2.741070] dwc_otg: FIQ split-transaction FSM enabled [ 2.741112] Module dwc_common_port init [ 2.741537] usbcore: registered new interface driver usb-storage [ 2.747937] mousedev: PS/2 mouse device common for all mice [ 2.754337] bcm2835-cpufreq: min=600000 max=900000 [ 2.759489] sdhci: Secure Digital Host Controller Interface driver [ 2.765660] sdhci: Copyright(c) Pierre Ossman [ 2.770424] sdhost: log_buf @ bac13000 (fac13000) [ 2.846979] mmc0: sdhost-bcm2835 loaded - DMA enabled (>1) [ 2.872981] sdhci-pltfm: SDHCI platform and OF driver helper [ 2.879372] ledtrig-cpu: registered to indicate activity on CPUs [ 2.885604] hidraw: raw HID events driver (C) Jiri Kosina [ 2.891290] usbcore: registered new interface driver usbhid [ 2.896854] usbhid: USB HID core driver [ 2.901377] Initializing XFRM netlink socket [ 2.905694] NET: Registered protocol family 17 [ 2.910328] Key type dns_resolver registered [ 2.915113] Registering SWP/SWPB emulation handler [ 2.921100] registered taskstats version 1 [ 2.925454] vc-sm: Videocore shared memory driver

[ 2.934911] [vc_sm_connected_init]: end - returning 0 [ 2.940167] Indeed it is in host mode hprt0 = 00021501 [ 2.947405] of_cfs_init [ 2.950004] of_cfs_init: OK [ 2.953763] uart-pl011 3f201000.uart: no DMA platform data [ 2.954482] mmc0: host does not support reading read-only switch, assuming write-enable [ 2.956610] mmc0: new high speed SDHC card at address 59b4 [ 2.957708] mmcblk0: mmc0:59b4 USD 7.51 GiB [ 2.967080] mmcblk0: p1 p2 p3 p4 [ 2.980606] Waiting 2 sec before mounting root device... [ 3.126988] usb 1-1: new high-speed USB device number 2 using dwc_otg [ 3.133594] Indeed it is in host mode hprt0 = 00001101 [ 3.337283] usb 1-1: New USB device found, idVendor=0424, idProduct=9514 [ 3.343997] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 [ 3.352178] hub 1-1:1.0: USB hub found [ 3.356021] hub 1-1:1.0: 5 ports detected [ 3.636974] usb 1-1.1: new high-speed USB device number 3 using dwc_otg [ 3.757235] usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00 [ 3.764107] usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 [ 3.774349] smsc95xx v1.0.4 [ 3.840843] smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:a4:80:12 [ 3.946974] usb 1-1.2: new high-speed USB device number 4 using dwc_otg [ 4.067445] usb 1-1.2: New USB device found, idVendor=1a40, idProduct=0201 [ 4.074335] usb 1-1.2: New USB device strings: Mfr=0, Product=1, SerialNumber=0 [ 4.081685] usb 1-1.2: Product: USB 2.0 Hub [MTT] [ 4.087335] hub 1-1.2:1.0: USB hub found [ 4.091372] hub 1-1.2:1.0: 7 ports detected [ 4.176973] usb 1-1.3: new high-speed USB device number 5 using dwc_otg [ 4.297299] usb 1-1.3: New USB device found, idVendor=0409, idProduct=005a [ 4.304171] usb 1-1.3: New USB device strings: Mfr=0, Product=0, SerialNumber=0 [ 4.312632] hub 1-1.3:1.0: USB hub found [ 4.316650] hub 1-1.3:1.0: 4 ports detected [ 4.386975] usb 1-1.2.2: new high-speed USB device number 6 using dwc_otg [ 4.507750] usb 1-1.2.2: New USB device found, idVendor=2013, idProduct=025f [ 4.514795] usb 1-1.2.2: New USB device strings: Mfr=3, Product=1, SerialNumber=2 [ 4.522303] usb 1-1.2.2: Product: PCTV 292e [ 4.526484] usb 1-1.2.2: Manufacturer: PCTV [ 4.530688] usb 1-1.2.2: SerialNumber: 0011412129 [ 4.626973] usb 1-1.3.1: new high-speed USB device number 7 using dwc_otg [ 4.747285] usb 1-1.3.1: New USB device found, idVendor=0409, idProduct=005a [ 4.754331] usb 1-1.3.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 [ 4.762660] hub 1-1.3.1:1.0: USB hub found [ 4.766883] hub 1-1.3.1:1.0: 4 ports detected [ 4.866974] usb 1-1.3.4: new high-speed USB device number 8 using dwc_otg [ 4.987478] usb 1-1.3.4: New USB device found, idVendor=0409, idProduct=005a [ 4.994580] usb 1-1.3.4: New USB device strings: Mfr=0, Product=0, SerialNumber=0 [ 4.994650] EXT4-fs (mmcblk0p4): couldn't mount as ext3 due to feature incompatibilities [ 5.002187] EXT4-fs (mmcblk0p4): couldn't mount as ext2 due to feature incompatibilities [ 5.019602] hub 1-1.3.4:1.0: USB hub found [ 5.023871] hub 1-1.3.4:1.0: 4 ports detected [ 5.023902] EXT4-fs (mmcblk0p4): INFO: recovery required on readonly filesystem [ 5.023909] EXT4-fs (mmcblk0p4): write access will be enabled during recovery [ 5.076993] usb 1-1.3.1.1: new high-speed USB device number 9 using dwc_otg [ 5.197939] usb 1-1.3.1.1: New USB device found, idVendor=2013, idProduct=024f [ 5.205221] usb 1-1.3.1.1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 [ 5.212949] usb 1-1.3.1.1: Product: PCTV 290e [ 5.217372] usb 1-1.3.1.1: Manufacturer: PCTV Systems [ 5.222472] usb 1-1.3.1.1: SerialNumber: 00000010VKB7 [ 5.307111] usb 1-1.3.1.2: new full-speed USB device number 10 using dwc_otg [ 5.425728] EXT4-fs (mmcblk0p4): orphan cleanup on readonly fs [ 5.431799] EXT4-fs (mmcblk0p4): 1 orphan inode deleted [ 5.437060] EXT4-fs (mmcblk0p4): recovery complete [ 5.454677] usb 1-1.3.1.2: New USB device found, idVendor=1781, idProduct=0c31 [ 5.461955] usb 1-1.3.1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 [ 5.469644] usb 1-1.3.1.2: Product: TellStick Duo [ 5.474346] usb 1-1.3.1.2: Manufacturer: Telldus [ 5.479002] usb 1-1.3.1.2: SerialNumber: A703AJYN [ 5.499814] EXT4-fs (mmcblk0p4): mounted filesystem with ordered data mode. Opts: (null) [ 5.507996] VFS: Mounted root (ext4 filesystem) readonly on device 179:4. [ 5.515650] devtmpfs: mounted [ 5.519459] Freeing unused kernel memory: 476K (807e7000 - 8085e000) [ 5.537031] usb 1-1.3.4.1: new full-speed USB device number 11 using dwc_otg [ 5.661338] usb 1-1.3.4.1: New USB device found, idVendor=0403, idProduct=6001 [ 5.668681] usb 1-1.3.4.1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 [ 5.676381] usb 1-1.3.4.1: Product: Smartreader2 plus [ 5.681532] usb 1-1.3.4.1: Manufacturer: Argolis BV [ 5.686461] usb 1-1.3.4.1: SerialNumber: [ 5.787012] usb 1-1.3.4.4: new high-speed USB device number 12 using dwc_otg [ 5.908279] usb 1-1.3.4.4: New USB device found, idVendor=04e8, idProduct=61b5 [ 5.915570] usb 1-1.3.4.4: New USB device strings: Mfr=1, Product=2, SerialNumber=3 [ 5.923320] usb 1-1.3.4.4: Product: Samsung M3 Portable [ 5.928570] usb 1-1.3.4.4: Manufacturer: Samsung M3 Portable [ 5.934299] usb 1-1.3.4.4: SerialNumber: 0A247DF106000060 [ 5.941455] usb-storage 1-1.3.4.4:1.0: USB Mass Storage device detected [ 5.949132] scsi host0: usb-storage 1-1.3.4.4:1.0 [ 5.971529] NET: Registered protocol family 10 [ 5.979343] systemd[1]: Failed to insert module 'kdbus': Function not implemented [ 6.001018] random: systemd urandom read with 79 bits of entropy available [ 6.034445] systemd[1]: systemd 228 running in system mode. (+PAM -AUDIT -SELINUX +IMA -APPARMOR +SMACK -SYSVINIT +UTMP -LIBCRYPTSETUP -GCRYPT +GNUTLS -ACL -XZ +LZ4 +SECCOMP +BLKID -ELFUTILS +KMOD -IDN) [ 6.053412] systemd[1]: Detected architecture arm. [ 6.078043] systemd[1]: Set hostname to . [ 6.930358] systemd[1]: rpc-gssd.service: Cannot add dependency job, ignoring: Unit rpc-gssd.service failed to load: No such file or directory. [ 6.943355] systemd[1]: rpc-svcgssd.service: Cannot add dependency job, ignoring: Unit rpc-svcgssd.service failed to load: No such file or directory. [ 6.948255] scsi 0:0:0:0: Direct-Access Samsung M3 Portable 1404 PQ: 0 ANSI: 6 [ 6.949764] sd 0:0:0:0: [sda] 3907029168 512-byte logical blocks: (2.00 TB/1.82 TiB) [ 6.950187] sd 0:0:0:0: [sda] Write Protect is off [ 6.950204] sd 0:0:0:0: [sda] Mode Sense: 33 00 00 08 [ 6.950603] sd 0:0:0:0: [sda] No Caching mode page found [ 6.950614] sd 0:0:0:0: [sda] Assuming drive cache: write through [ 6.989027] systemd[1]: gssproxy.service: Cannot add dependency job, ignoring: Unit gssproxy.service failed to load: No such file or directory. [ 7.002786] systemd[1]: display-manager.service: Cannot add dependency job, ignoring: Unit display-manager.service failed to load: No such file or directory. [ 7.023824] systemd[1]: Listening on Network Service Netlink Socket. [ 7.047277] systemd[1]: Reached target Host and Network Name Lookups. [ 7.079965] systemd[1]: Created slice User and Session Slice. [ 7.124696] systemd[1]: Listening on udev Kernel Socket. [ 7.147580] systemd[1]: Started Forward Password Requests to Wall Directory Watch. [ 7.177501] systemd[1]: Listening on Journal Socket (/dev/log). [ 7.205919] sda: sda1 sda2 sda3 sda4 < sda5 sda6 > [ 7.215137] sd 0:0:0:0: [sda] Attached SCSI disk [ 7.227289] systemd[1]: Reached target Remote File Systems. [ 7.247459] systemd[1]: Listening on udev Control Socket. [ 7.268273] systemd[1]: Created slice System Slice. [ 7.288333] systemd[1]: Created slice system-serial\x2dgetty.slice. [ 7.317214] systemd[1]: Reached target Slices. [ 7.338154] systemd[1]: Created slice system-getty.slice. [ 7.367523] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ 7.397176] systemd[1]: Reached target Paths. [ 7.418183] systemd[1]: Created slice system-systemd\x2dfsck.slice. [ 7.447502] systemd[1]: Listening on Journal Socket. [ 7.497909] systemd[1]: Starting Setup Virtual Console... [ 7.522007] systemd[1]: Starting Journal Service... [ 7.551979] systemd[1]: Starting File System Check on Root Device... [ 7.582506] systemd[1]: Mounting Debug File System... [ 7.612034] systemd[1]: Mounting POSIX Message Queue File System... [ 7.633571] systemd[1]: Mounting Temporary Directory... [ 7.645986] systemd[1]: Mounting NFSD configuration filesystem... [ 7.670642] systemd[1]: Starting Apply Kernel Variables... [ 7.684887] systemd[1]: Mounting Configuration File System... [ 7.696051] systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. [ 7.708823] systemd[1]: Listening on /dev/initctl Compatibility Named Pipe. [ 7.723138] systemd[1]: Starting Create list of required static device nodes for the current kernel... [ 7.750780] systemd[1]: Set up automount var-lib-media.automount. [ 7.765152] systemd[1]: Set up automount var-lib-timeshift.automount. [ 7.772393] Installing knfsd (copyright (C) 1996 okir@monad.swb.de). [ 7.877779] systemd[1]: Mounting RPC Pipe File System... [ 7.912045] systemd[1]: Mounted RPC Pipe File System. [ 7.937424] systemd[1]: Mounted NFSD configuration filesystem. [ 7.967379] systemd[1]: Mounted Debug File System. [ 7.987289] systemd[1]: Mounted Configuration File System. [ 8.007289] systemd[1]: Mounted POSIX Message Queue File System. [ 8.037262] systemd[1]: Mounted Temporary Directory. [ 8.057808] systemd[1]: Started Journal Service. [ 8.775275] EXT4-fs (mmcblk0p4): re-mounted. Opts: user_xattr [ 9.331359] systemd-journald[111]: Received request to flush runtime journal from PID 1 [ 9.364266] systemd-journald[111]: File /var/log/journal/18e841ae3401834bac9a7b7f552c54e2/system.journal corrupted or uncleanly shut down, renaming and replacing. [ 10.089576] random: nonblocking pool is initialized [ 10.199628] gpiomem-bcm2835 3f200000.gpiomem: Initialised: Registers at 0x3f200000 [ 10.245672] bcm2835-wdt 3f100000.watchdog: Broadcom BCM2835 watchdog timer [ 10.385946] usbcore: registered new interface driver usbserial [ 10.399084] usbcore: registered new interface driver usbserial_generic [ 10.409668] usbserial: USB Serial support registered for generic [ 10.443192] usbcore: registered new interface driver ftdi_sio [ 10.451120] usbserial: USB Serial support registered for FTDI USB Serial Device [ 10.461017] ftdi_sio 1-1.3.4.1:1.0: FTDI USB Serial Device converter detected [ 10.469049] usb 1-1.3.4.1: Detected FT232BM [ 10.475270] usb 1-1.3.4.1: FTDI USB Serial Device converter now attached to ttyUSB0 [ 10.509596] media: Linux media interface: v0.10 [ 10.576063] Linux video capture interface: v2.00 [ 10.663423] em28xx: New device PCTV PCTV 292e @ 480 Mbps (2013:025f, interface 0, class 0) [ 10.671845] em28xx: DVB interface 0 found: isoc [ 10.697060] em28xx: chip ID is em28178 [ 10.945462] Adding 538620k swap on /dev/mmcblk0p2. Priority:-1 extents:1 across:538620k SSFS [ 11.164544] EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Opts: user_xattr [ 11.511128] device-mapper: ioctl: 4.34.0-ioctl (2015-10-28) initialised: dm-devel@redhat.com [ 12.708036] em28178 #0: EEPROM ID = 26 00 01 00, EEPROM hash = 0x1f10fa04 [ 12.714859] em28178 #0: EEPROM info: [ 12.718543] em28178 #0: microcode start address = 0x0004, boot configuration = 0x01 [ 12.733950] em28178 #0: AC97 audio (5 sample rates) [ 12.738994] em28178 #0: 500mA max power [ 12.742955] em28178 #0: Table at offset 0x27, strings=0x146a, 0x1888, 0x0a7e [ 12.750483] em28178 #0: Identified as PCTV tripleStick (292e) (card=94) [ 12.757174] em28178 #0: dvb set to isoc mode. [ 12.761965] em28xx: New device PCTV Systems PCTV 290e @ 480 Mbps (2013:024f, interface 0, class 0) [ 12.771032] em28xx: DVB interface 0 found: isoc [ 12.775889] em28xx: chip ID is em28174 [ 13.138732] em28174 #1: EEPROM ID = 26 00 01 00, EEPROM hash = 0x11372abd [ 13.145939] em28174 #1: EEPROM info: [ 13.149770] em28174 #1: microcode start address = 0x0004, boot configuration = 0x01 [ 13.185087] em28174 #1: No audio on board. [ 13.189408] em28174 #1: 500mA max power [ 13.193368] em28174 #1: Table at offset 0x39, strings=0x1aa0, 0x14ba, 0x1ace [ 13.200966] em28174 #1: Identified as PCTV nanoStick T2 290e (card=78) [ 13.207571] em28174 #1: dvb set to isoc mode. [ 13.209233] bridge: automatic filtering via arp/ip/ip6tables has been deprecated. Update your scripts to load br_netfilter if you need this. [ 13.225072] usbcore: registered new interface driver em28xx [ 13.239341] em28178 #0: Binding DVB extension [ 13.279789] i2c i2c-4: Added multiplexed i2c bus 7 [ 13.284810] si2168 4-0064: Silicon Labs Si2168 successfully attached [ 13.296621] IPv6: ADDRCONF(NETDEV_UP): br0: link is not ready [ 13.309287] IPv6: ADDRCONF(NETDEV_UP): veth-osc: link is not ready [ 13.314888] si2157 7-0060: Silicon Labs Si2147/2148/2157/2158 successfully attached [ 13.314936] DVB: registering new adapter (em28178 #0) [ 13.314964] usb 1-1.2.2: DVB: registering adapter 0 frontend 0 (Silicon Labs Si2168)... [ 13.319928] em28178 #0: DVB extension successfully initialized [ 13.319941] em28174 #1: Binding DVB extension [ 13.354112] device veth-osc-br entered promiscuous mode [ 13.369347] IPv6: ADDRCONF(NETDEV_UP): veth-tvh: link is not ready [ 13.382592] device veth-tvh-br entered promiscuous mode [ 13.383822] tda18271 6-0060: creating new instance [ 13.398462] TDA18271HD/C2 detected @ 6-0060 [ 13.402456] device eth0 entered promiscuous mode [ 13.415371] br0: port 1(veth-osc-br) entered forwarding state [ 13.421331] br0: port 1(veth-osc-br) entered forwarding state [ 13.427860] IPv6: ADDRCONF(NETDEV_CHANGE): veth-osc: link becomes ready [ 13.434718] IPv6: ADDRCONF(NETDEV_CHANGE): br0: link becomes ready [ 13.449935] br0: port 2(veth-tvh-br) entered forwarding state [ 13.455749] br0: port 2(veth-tvh-br) entered forwarding state [ 13.461972] IPv6: ADDRCONF(NETDEV_CHANGE): veth-tvh: link becomes ready [ 13.531358] smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup [ 13.539423] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready [ 13.789257] DVB: registering new adapter (em28174 #1) [ 13.794634] usb 1-1.3.1.1: DVB: registering adapter 1 frontend 0 (Sony CXD2820R)... [ 13.819980] em28174 #1: DVB extension successfully initialized [ 13.827220] em28xx: Registered (Em28xx dvb Extension) extension [ 13.881668] em28178 #0: Registering input extension [ 13.927050] Registered IR keymap rc-pinnacle-pctv-hd [ 13.933911] input: em28xx IR (em28178 #0) as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2/1-1.2.2/rc/rc0/input0 [ 13.947773] rc0: em28xx IR (em28178 #0) as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2/1-1.2.2/rc/rc0 [ 13.958470] em28178 #0: Input extension successfully initalized [ 13.964469] em28174 #1: Registering input extension [ 13.972785] Registered IR keymap rc-pinnacle-pctv-hd [ 13.979297] input: em28xx IR (em28174 #1) as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.3/1-1.3.1/1-1.3.1.1/rc/rc1/input1 [ 13.991473] rc1: em28xx IR (em28174 #1) as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.3/1-1.3.1/1-1.3.1.1/rc/rc1 [ 14.003139] em28174 #1: Input extension successfully initalized [ 14.009251] em28xx: Registered (Em28xx Input Extension) extension [ 15.084857] smsc95xx 1-1.1:1.0 eth0: kevent 4 may have been dropped [ 15.098531] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready [ 15.105435] smsc95xx 1-1.1:1.0 eth0: link up, 100Mbps, full-duplex, lpa 0xC5E1 [ 15.116285] br0: port 3(eth0) entered forwarding state [ 15.121636] br0: port 3(eth0) entered forwarding state [ 15.522500] EXT4-fs (dm-6): mounted filesystem with ordered data mode. Opts: (null) [ 15.562494] EXT4-fs (dm-7): mounted filesystem with ordered data mode. Opts: (null) [ 16.134522] EXT4-fs (dm-3): mounted filesystem with ordered data mode. Opts: (null) [ 16.480937] EXT4-fs (dm-4): mounted filesystem with ordered data mode. Opts: (null) [ 16.966283] EXT4-fs (dm-5): mounted filesystem with ordered data mode. Opts: (null) [ 17.704661] EXT4-fs (dm-0): mounted filesystem with ordered data mode. Opts: (null) [ 17.842910] NFSD: Using /var/lib/nfs/v4recovery as the NFSv4 state recovery directory [ 17.851836] NFSD: starting 90-second grace period (net 808b7000) [ 21.259298] tda18271: performing RF tracking filter calibration [ 23.920664] tda18271: RF tracking filter calibration complete [ 23.927927] usb 1-1.3.1.1: DVB: adapter 1 frontend 0 frequency 0 out of range (45000000..864000000) [ 23.951655] si2168 4-0064: found a 'Silicon Labs Si2168-B40' [ 23.961790] si2168 4-0064: downloading firmware from file 'dvb-demod-si2168-b40-01.fw' [ 24.315230] si2168 4-0064: firmware version: 4.0.11 [ 24.324965] si2157 7-0060: found a 'Silicon Labs Si2157-A30' [ 24.379157] si2157 7-0060: firmware version: 3.0.5 [ 24.384040] usb 1-1.2.2: DVB: adapter 0 frontend 0 frequency 0 out of range (55000000..862000000) [ 28.447010] br0: port 1(veth-osc-br) entered forwarding state [ 28.486952] br0: port 2(veth-tvh-br) entered forwarding state [ 28.579625] TCP: request_sock_TCP: Possible SYN flooding on port 80. Sending cookies. Check SNMP counters. [ 30.126947] br0: port 3(eth0) entered forwarding state [60700.408873] ------------[ cut here ]------------ [60700.413587] WARNING: CPU: 0 PID: 0 at net/sched/sch_generic.c:306 dev_watchdog+0x2a4/0x2b0() [60700.422173] NETDEV WATCHDOG: eth0 (smsc95xx): transmit queue 0 timed out [60700.428976] Modules linked in: evdev rc_pinnacle_pctv_hd em28xx_rc rc_core tda18271 cxd2820r si2157 si2168 i2c_mux em28xx_dvb bridge stp llc veth dvb_core dm_mod em28xx tveeprom v4l2_common videodev media ftdi_sio usbserial bcm2835_wdt bcm2835_gpiomem uio_pdrv_genirq uio sch_fq_codel nfsd ipv6 [60700.429154] CPU: 0 PID: 0 Comm: swapper/0 Not tainted 4.4.15-v7+ #897 [60700.429158] Hardware name: BCM2709 [60700.429204] <80018724> from <80014058> [60700.429227] <80014058> from <80320764> [60700.429250] <80320764> from <80025300> [60700.429270] <80025300> from <80025370> [60700.429288] <80025370> from <804f87c8> [60700.429306] <804f87c8> from <80081be4> [60700.429322] <80081be4> from <80081f04> [60700.429338] <80081f04> from <80029050> [60700.429353] <80029050> from <80029630> [60700.429371] <80029630> from <800710a8> [60700.429391] <800710a8> from <80010a74> [60700.429408] <80010a74> from <8000951c> [60700.429430] <8000951c> from <805b9684> [60700.429437] Exception stack(0x8085ff08 to 0x8085ff50) [60700.429449] ff00: 00000000 b9f633c8 00000000 00000000 8085e000 808605dc [60700.429463] ff20: 80860500 80860580 805bde5c 808c4798 ba7ff740 8085ff64 808614f8 8085ff58 [60700.429471] ff40: 80010b34 80010b38 60010013 ffffffff [60700.429491] <805b9684> from <80010b38> [60700.429511] <80010b38> from <80063dd4> [60700.429529] <80063dd4> from <80063ffc> [60700.429546] <80063ffc> from <805b3fb0> [60700.429565] <805b3fb0> from <807e7d34> [60700.429574] ---[ end trace 42b53d8fc72d9818 ]--- [64470.701611] em28178 #0: writing to i2c device at 0xc8 failed (error=-5) [64470.901599] em28178 #0: writing to i2c device at 0xc8 failed (error=-5) [64471.111555] em28174 #1: writing to i2c device at 0xd8 failed (error=-5) [64471.118206] i2c i2c-6: cxd2820r: i2c rd failed=-5 reg=10 len=1 [64471.501568] em28178 #0: writing to i2c device at 0xc8 failed (error=-5) [64472.101881] em28178 #0: writing to i2c device at 0xc8 failed (error=-5) [64472.121581] em28174 #1: writing to i2c device at 0xd8 failed (error=-5) [64472.128889] i2c i2c-6: cxd2820r: i2c rd failed=-5 reg=10 len=1 [64472.214369] em28178 #0: write to i2c device at 0xc8 failed with unknown error (status=3) [64473.167852] em28174 #1: write to i2c device at 0xd8 failed with unknown error (status=1) [64473.176946] i2c i2c-6: cxd2820r: i2c rd failed=-5 reg=10 len=1

P33M commented 8 years ago

You appear to have 4 (?!) USB hubs of various sizes plugged into the Pi. Can you post the output of lsusb -t please?

Can you also say whether these hubs are self-powered or bus-powered?

andersthomson commented 8 years ago

lsusb.txt

Two self powered hubs off the pi2, one of them is a 10 port with carries three 4way splitters internally.

I got the hubs in an attempt to rule out power issues as I have to (supposedly power hungry) TV tuner sticks in there.

P33M commented 7 years ago

Can you try again after running rpi-update? There have been some bug fixes that may affect your use case.

andersthomson commented 7 years ago

I'm compiling my own stuff. Can you indicate git commit and config to use?

Thanks! /Anders

On May 4, 2017 5:28:26 PM GMT+02:00, P33M notifications@github.com wrote:

Can you try again after running rpi-update? There have been some bug fixes that may affect your use case.

-- You are receiving this because you were mentioned. Reply to this email directly or view it on GitHub: https://github.com/raspberrypi/linux/issues/1649#issuecomment-299220601

-- Sent from my Android device with K-9 Mail. Please excuse my brevity.

popcornmix commented 7 years ago

He'll be referring to top of rpi-4.9.y tree (currently https://github.com/raspberrypi/linux/commit/9a5f215eda12bad29b35040dff00d0346fe517e2) and using the bcm2709_defconfig. See: https://www.raspberrypi.org/documentation/linux/kernel/building.md

But it is more useful for us if you test with a default kernel. Reporting a bug with a custom built kernel adds another level of unknown when we try to diagnose.

andersthomson commented 7 years ago

Building that commit against that config right now. As it happens, I'm on rpi-4.9.17 right now, and cannot recall seeing the Watchdog in the oops for quite some time. I do still get regular oopes from the usb otg driver though (and an attempt at vanilla-4.11 with dwc2 consistently fails to get the tuners operational)

On Sun, May 14, 2017 at 5:06 PM, popcornmix notifications@github.com wrote:

He'll be referring to top of rpi-4.9.y tree (currently raspberrypi/linux@ 9a5f215 https://github.com/raspberrypi/linux/commit/9a5f215eda12bad29b35040dff00d0346fe517e2) and using the bcm2709_defconfig. See: https://www.raspberrypi.org/documentation/linux/kernel/building.md

But it is more useful for us if you test with a default kernel. Reporting a bug with a custom built kernel adds another level of unknown when we try to diagnose.

— You are receiving this because you were mentioned. Reply to this email directly, view it on GitHub https://github.com/raspberrypi/linux/issues/1649#issuecomment-301318961, or mute the thread https://github.com/notifications/unsubscribe-auth/AGC9C9ieaTXmR-baqFoyHzo4_B8eN9Wnks5r5xhagaJpZM4KES2I .

lategoodbye commented 7 years ago

@andersthomson Could you please explain what goes wrong on dwc2?

andersthomson commented 7 years ago

So, for what its worth. Freshly compiled. Not sure where this oops is pointing.

/Anders

[ 15.761953] EXT4-fs (dm-7): mounted filesystem with ordered data mode. Opts: (null) [ 17.237677] EXT4-fs (dm-4): mounted filesystem with ordered data mode. Opts: (null) [ 18.265822] bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. [ 18.386555] IPv6: ADDRCONF(NETDEV_UP): br0: link is not ready [ 18.402539] IPv6: ADDRCONF(NETDEV_UP): veth-osc: link is not ready [ 18.417437] br0: port 1(veth-osc-br) entered blocking state [ 18.417446] br0: port 1(veth-osc-br) entered disabled state [ 18.418049] device veth-osc-br entered promiscuous mode [ 18.423906] IPv6: ADDRCONF(NETDEV_UP): veth-tvh: link is not ready [ 18.430086] br0: port 2(veth-tvh-br) entered blocking state [ 18.430095] br0: port 2(veth-tvh-br) entered disabled state [ 18.430869] device veth-tvh-br entered promiscuous mode [ 18.438490] br0: port 3(eth0) entered blocking state [ 18.438498] br0: port 3(eth0) entered disabled state [ 18.439088] device eth0 entered promiscuous mode [ 18.456215] br0: port 1(veth-osc-br) entered blocking state [ 18.456228] br0: port 1(veth-osc-br) entered forwarding state [ 18.456559] IPv6: ADDRCONF(NETDEV_CHANGE): veth-osc: link becomes ready [ 18.456744] IPv6: ADDRCONF(NETDEV_CHANGE): br0: link becomes ready [ 18.465730] br0: port 2(veth-tvh-br) entered blocking state [ 18.465741] br0: port 2(veth-tvh-br) entered forwarding state [ 18.465875] IPv6: ADDRCONF(NETDEV_CHANGE): veth-tvh: link becomes ready [ 18.620480] smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup [ 18.622287] br0: port 3(eth0) entered blocking state [ 18.622300] br0: port 3(eth0) entered forwarding state [ 19.692512] br0: port 3(eth0) entered disabled state [ 20.153028] smsc95xx 1-1.1:1.0 eth0: link up, 100Mbps, full-duplex, lpa 0xC5E1 [ 20.166829] br0: port 3(eth0) entered blocking state [ 20.174763] br0: port 3(eth0) entered forwarding state [ 20.575884] IPv6: ADDRCONF(NETDEV_UP): vb-openhab: link is not ready [ 20.575922] br0: port 4(vb-openhab) entered blocking state [ 20.575931] br0: port 4(vb-openhab) entered disabled state [ 20.576706] device vb-openhab entered promiscuous mode [ 20.576868] br0: port 4(vb-openhab) entered blocking state [ 20.576880] br0: port 4(vb-openhab) entered forwarding state [ 20.700858] br0: port 4(vb-openhab) entered disabled state [ 21.291864] EXT4-fs (dm-1): mounted filesystem with ordered data mode. Opts: (null) [ 21.404967] NFSD: Using /var/lib/nfs/v4recovery as the NFSv4 state recovery directory [ 21.426990] NFSD: starting 90-second grace period (net 80c64dc0) [ 21.536141] IPv6: ADDRCONF(NETDEV_UP): vb-revproxy: link is not ready [ 21.536171] br0: port 5(vb-revproxy) entered blocking state [ 21.536177] br0: port 5(vb-revproxy) entered disabled state [ 21.536780] device vb-revproxy entered promiscuous mode [ 21.536917] br0: port 5(vb-revproxy) entered blocking state [ 21.536926] br0: port 5(vb-revproxy) entered forwarding state [ 21.710696] br0: port 5(vb-revproxy) entered disabled state [ 23.163571] EXT4-fs (dm-5): mounted filesystem with writeback data mode. Opts: data=writeback [ 23.255947] IPv6: ADDRCONF(NETDEV_UP): vb-tvhead: link is not ready [ 23.262728] br0: port 6(vb-tvhead) entered blocking state [ 23.262768] br0: port 6(vb-tvhead) entered disabled state [ 23.263451] device vb-tvhead entered promiscuous mode [ 23.355693] IPv6: ADDRCONF(NETDEV_UP): vb-work: link is not ready [ 23.365037] br0: port 7(vb-work) entered blocking state [ 23.365047] br0: port 7(vb-work) entered disabled state [ 23.365808] device vb-work entered promiscuous mode [ 23.365957] br0: port 7(vb-work) entered blocking state [ 23.365966] br0: port 7(vb-work) entered forwarding state [ 24.330578] br0: port 7(vb-work) entered disabled state [ 24.330578] br0: port 7(vb-work) entered disabled state [ 24.330578] br0: port 7(vb-work) entered disabled state [ 24.330578] br0: port 7(vb-work) entered disabled state [ 24.330578] br0: port 7(vb-work) entered disabled state [ 24.330578] br0: port 7(vb-work) entered disabled state [ 27.618683] EXT4-fs (dm-4): re-mounted. Opts: (null) [ 30.152270] EXT4-fs (dm-4): re-mounted. Opts: (null) [ 31.855509] EXT4-fs (dm-4): re-mounted. Opts: (null) [ 33.171578] EXT4-fs (dm-4): re-mounted. Opts: (null) [ 34.076424] IPv6: ADDRCONF(NETDEV_CHANGE): vb-openhab: link becomes ready [ 34.088169] br0: port 4(vb-openhab) entered blocking state [ 34.121883] br0: port 4(vb-openhab) entered forwarding state [ 47.169224] IPv6: ADDRCONF(NETDEV_CHANGE): vb-tvhead: link becomes ready [ 47.180090] br0: port 6(vb-tvhead) entered blocking state [ 47.210036] br0: port 6(vb-tvhead) entered forwarding state [ 51.039654] IPv6: ADDRCONF(NETDEV_CHANGE): vb-revproxy: link becomes ready [ 51.050869] br0: port 5(vb-revproxy) entered blocking state [ 51.079802] br0: port 5(vb-revproxy) entered forwarding state [ 54.009910] IPv6: ADDRCONF(NETDEV_CHANGE): vb-work: link becomes ready [ 54.020644] br0: port 7(vb-work) entered blocking state [ 54.054763] br0: port 7(vb-work) entered forwarding state [ 55.835320] ttyAMA ttyAMA0: 1 input overrun(s) [ 56.247939] si2168 6-0064: downloading firmware from file 'dvb-demod-si2168-b40-01.fw' [ 56.674229] si2168 6-0064: firmware version: B 4.0.11 [ 56.691193] si2157 7-0060: found a 'Silicon Labs Si2157-A30' [ 56.753019] si2157 7-0060: firmware version: 3.0.5 [ 56.762819] usb 1-1.3.3: DVB: adapter 1 frontend 0 frequency 0 out of range (42000000..870000000) [ 57.404588] tda18271: performing RF tracking filter calibration [ 62.238586] tda18271: RF tracking filter calibration complete [ 62.250196] usb 1-1.3.2: DVB: adapter 0 frontend 0 frequency 0 out of range (45000000..864000000) [ 480.390310] Unable to handle kernel paging request at virtual address 00002244 [ 480.390320] pgd = 80004000 [ 480.390333] [00002244] pgd=00000000 [ 480.390351] Internal error: Oops: 5 [#1] SMP ARM [ 480.390446] Modules linked in: bridge stp llc veth evdev rc_pinnacle_pctv_hd em28xx_rc rc_core si2157 si2168 i2c_mux tda18271 cxd2820r em28xx_dvb dm_mod dvb_core em28xx tveeprom v4l2_common videodev ftdi_sio media usbserial bcm2835_gpiomem uio_pdrv_genirq uio fixed sch_fq_codel nfsd ipv6 [ 480.390464] CPU: 0 PID: 126 Comm: kworker/u8:3 Not tainted 4.9.27-v7+ #23 [ 480.390468] Hardware name: BCM2835 [ 480.390496] Workqueue: events_unbound flush_to_ldisc [ 480.390502] task: b8cd6740 task.stack: b9332000 [ 480.390516] PC is at n_tty_receive_buf_common+0x7c/0xa00 [ 480.390527] LR is at down_read+0x1c/0x60 [ 480.390537] pc : [<804c4518>] lr : [<80716214>] psr: 800f0013 [ 480.390537] sp : b9333e20 ip : b9333e08 fp : b9333e84 [ 480.390542] r10: b2b50400 r9 : 00000088 r8 : b915c004 [ 480.390549] r7 : b915c014 r6 : b3140080 r5 : 00005556 r4 : 00000000 [ 480.390555] r3 : 00002000 r2 : 0000377c r1 : b2ca384f r0 : 00000000 [ 480.390564] Flags: Nzcv IRQs on FIQs on Mode SVC_32 ISA ARM Segment user [ 480.390572] Control: 10c5387d Table: 1f67006a DAC: 00000055 [ 480.390578] Process kworker/u8:3 (pid: 126, stack limit = 0xb9332210) [ 480.390586] Stack: (0xb9333e20 to 0xb9334000) [ 480.390600] 3e20: b2b50480 80157138 8014d314 55555556 00000000 b32bbb00 8082377c 80713cc4 [ 480.390612] 3e40: 00002000 00000000 00000000 b2ca384f 000000c9 b2ca394f 9f4f6018 000000c9 [ 480.390625] 3e60: b915c000 b3140080 b915c014 b915c004 00000088 00000000 b9333e9c b9333e88 [ 480.390638] 3e80: 804c4ec0 804c44a8 00000001 80715dc8 b9333eb4 b9333ea0 804c7310 804c4ea8 [ 480.390650] 3ea0: 000000c9 b2ca3800 b9333edc b9333eb8 804c78cc 804c72ec 804c7800 b99a2880 [ 480.390663] 3ec0: b915c004 b9882800 b98a1000 00000000 b9333f14 b9333ee0 801365b4 804c780c [ 480.390675] 3ee0: b9882818 80c02d00 00000088 b9882800 b99a2898 b9882800 b9882818 80c02d00 [ 480.390688] 3f00: 00000088 b99a2880 b9333f5c b9333f18 80136914 80136468 b9333f3c b9332000 [ 480.390700] 3f20: 80c02d00 b99a2880 80c15e42 b9332018 00000000 00000000 b8c1d100 b99a2880 [ 480.390712] 3f40: 801368b0 00000000 00000000 00000000 b9333fac b9333f60 8013c81c 801368bc [ 480.390725] 3f60: 39404000 00000000 7261705f b99a2880 00000000 00000000 b9333f78 b9333f78 [ 480.390738] 3f80: 00000000 00000000 b9333f88 b9333f88 b8c1d100 8013c710 00000000 00000000 [ 480.390750] 3fa0: 00000000 b9333fb0 80108148 8013c71c 00000000 00000000 00000000 00000000 [ 480.390761] 3fc0: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000 [ 480.390773] 3fe0: 00000000 00000000 00000000 00000000 00000013 00000000 72665f67 73656d61 [ 480.390813] [<804c4518>] (n_tty_receive_buf_common) from [<804c4ec0>] (n_tty_receive_buf2+0x24/0x2c) [ 480.390833] [<804c4ec0>] (n_tty_receive_buf2) from [<804c7310>] (tty_ldisc_receive_buf+0x30/0x6c) [ 480.390854] [<804c7310>] (tty_ldisc_receive_buf) from [<804c78cc>] (flush_to_ldisc+0xcc/0xe4) [ 480.390879] [<804c78cc>] (flush_to_ldisc) from [<801365b4>] (process_one_work+0x158/0x454) [ 480.390903] [<801365b4>] (process_one_work) from [<80136914>] (worker_thread+0x64/0x568) [ 480.390922] [<80136914>] (worker_thread) from [<8013c81c>] (kthread+0x10c/0x124) [ 480.390945] [<8013c81c>] (kthread) from [<80108148>] (ret_from_fork+0x14/0x2c) [ 480.390962] Code: e50b304c e3a03000 e50b303c e51b3044 (e593c244) [ 480.391339] ---[ end trace 2a38d63a0670631f ]--- [ 480.391606] Unable to handle kernel paging request at virtual address ffffffec [ 480.391612] pgd = 80004000 [ 480.391629] [ffffffec] pgd=3a7fa861, pte=00000000, ppte=00000000 [ 480.391643] Internal error: Oops: 37 [#2] SMP ARM [ 480.391734] Modules linked in: bridge stp llc veth evdev rc_pinnacle_pctv_hd em28xx_rc rc_core si2157 si2168 i2c_mux tda18271 cxd2820r em28xx_dvb dm_mod dvb_core em28xx tveeprom v4l2_common videodev ftdi_sio media usbserial bcm2835_gpiomem uio_pdrv_genirq uio fixed sch_fq_codel nfsd ipv6 [ 480.391747] CPU: 0 PID: 126 Comm: kworker/u8:3 Tainted: G D 4.9.27-v7+ #23 [ 480.391751] Hardware name: BCM2835 [ 480.391770] task: b8cd6740 task.stack: b9332000 [ 480.391787] PC is at kthread_data+0x18/0x20 [ 480.391801] LR is at wq_worker_sleeping+0x18/0xd8 [ 480.391811] pc : [<8013d428>] lr : [<80137868>] psr: 200f0193 [ 480.391811] sp : b9333b30 ip : b9333b40 fp : b9333b3c [ 480.391817] r10: 80b7e940 r9 : 00000000 r8 : 80c0442c [ 480.391824] r7 : 80b7e940 r6 : b8cd6b68 r5 : b8cd6740 r4 : b9f66940 [ 480.391830] r3 : 00000000 r2 : 00000000 r1 : b8cd6740 r0 : b8cd6740 [ 480.391838] Flags: nzCv IRQs off FIQs on Mode SVC_32 ISA ARM Segment user [ 480.391845] Control: 10c5387d Table: 1f67006a DAC: 00000055 [ 480.391851] Process kworker/u8:3 (pid: 126, stack limit = 0xb9332210) [ 480.391859] Stack: (0xb9333b30 to 0xb9334000) [ 480.391869] 3b20: b9333b4c b9333b40 80137868 8013d41c [ 480.391881] 3b40: b9333bb4 b9333b50 80713ef4 8013785c 600f0113 00000001 00000001 b98d0000 [ 480.391894] 3b60: b9333b8c b9333b70 393e8000 8025ec98 00000000 801495c4 b9f66940 b384058c [ 480.391906] 3b80: b9333bac b9333b90 8042f59c b8cd6740 b9333814 b9333bd0 b8cd6ae0 00000001 [ 480.391920] 3ba0: 00000001 b98d0000 b9333bc4 b9333bb8 801495c4 807139e4 b9333c04 b9333bc8 [ 480.391933] 3bc0: 801216b8 80149538 00000000 80c7a2c4 b9333bd0 b9333bd0 0000000b 80c7a2c4 [ 480.391945] 3be0: 600f0113 80c08998 0000000b 804c451c 00000001 804c451a b9333c8c b9333c08 [ 480.391957] 3c00: 8010c2b0 80120f70 b9332210 0000000b 00000000 8094de4c 00000008 7f000000 [ 480.391970] 3c20: 00000000 80c08998 65171f20 33623035 20633430 30613365 30303033 30356520 [ 480.391983] 3c40: 33303362 35652063 30336231 28203434 33393565 34343263 80002029 8020981c [ 480.391995] 3c60: 8094f740 00002244 00000005 b9333dd0 00000000 00000000 b9332000 b2b50400 [ 480.392008] 3c80: b9333ca4 b9333c90 80118e74 8010c068 b9333dd0 00002244 b9333cfc b9333ca8 [ 480.392021] 3ca0: 80719640 80118e0c 000003f3 000003f3 000003f3 000003f3 00000400 00000274 [ 480.392033] 3cc0: 00000001 00000000 00000001 00000000 00000000 00000005 00000005 807197a8 [ 480.392047] 3ce0: 00002244 b9333dd0 b9332000 b2b50400 b9333d1c b9333d00 80719864 80719404 [ 480.392060] 3d00: 80c040a4 80c093d4 00000005 807197a8 b9333dcc b9333d20 801011e8 807197b4 [ 480.392072] 3d20: 00000000 00000400 00000151 8014e458 b8cd67c0 b9f66978 0000da19 8014e4c4 [ 480.392085] 3d40: b8cd67c0 b9f66978 b9333d8c b9333d58 8014e4c4 80169bc4 00000001 b988dd10 [ 480.392097] 3d60: b9333e24 b8cd6740 b9f66978 8015292c 00000001 b9f66978 002070be 80152984 [ 480.392111] 3d80: b9333e6c 801513c8 b32bbb00 b8eb0400 b8eb0408 b8eb0400 b9333e24 b9333da8 [ 480.392123] 3da0: 80157178 804c4518 800f0013 804c4518 800f0013 ffffffff b9333e04 b915c004 [ 480.392136] 3dc0: b9333e84 b9333dd0 80718bf4 801011ac 00000000 b2ca384f 0000377c 00002000 [ 480.392149] 3de0: 00000000 00005556 b3140080 b915c014 b915c004 00000088 b2b50400 b9333e84 [ 480.392161] 3e00: b9333e08 b9333e20 80716214 804c4518 800f0013 ffffffff 804c44e4 00000000 [ 480.392174] 3e20: b2b50480 80157138 8014d314 55555556 00000000 b32bbb00 8082377c 80713cc4 [ 480.392187] 3e40: 00002000 00000000 00000000 b2ca384f 000000c9 b2ca394f 9f4f6018 000000c9 [ 480.392199] 3e60: b915c000 b3140080 b915c014 b915c004 00000088 00000000 b9333e9c b9333e88 [ 480.392212] 3e80: 804c4ec0 804c44a8 00000001 80715dc8 b9333eb4 b9333ea0 804c7310 804c4ea8 [ 480.392225] 3ea0: 000000c9 b2ca3800 b9333edc b9333eb8 804c78cc 804c72ec 804c7800 b99a2880 [ 480.392238] 3ec0: b915c004 b9882800 b98a1000 00000000 b9333f14 b9333ee0 801365b4 804c780c [ 480.392250] 3ee0: b9882818 80c02d00 00000088 b9882800 b99a2898 b9882800 b9882818 80c02d00 [ 480.392264] 3f00: 00000088 b99a2880 b9333f5c b9333f18 80136914 80136468 b9333f3c b9332000 [ 480.392276] 3f20: 80c02d00 b99a2880 80c15e42 b9332018 00000000 00000000 b8c1d100 b99a2880 [ 480.392288] 3f40: 801368b0 00000000 00000000 00000000 b9333fac b9333f60 8013c81c 801368bc [ 480.392301] 3f60: 39404000 00000000 7261705f b99a2880 00000000 00000000 b9333f78 b9333f78 [ 480.392313] 3f80: 00000001 00010001 b9333f88 b9333f88 b8c1d100 8013c710 00000000 00000000 [ 480.392325] 3fa0: 00000000 b9333fb0 80108148 8013c71c 00000000 00000000 00000000 00000000 [ 480.392336] 3fc0: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000 [ 480.392348] 3fe0: 00000000 00000000 00000000 00000000 00000013 00000000 72665f67 73656d61 [ 480.392381] [<8013d428>] (kthread_data) from [<80137868>] (wq_worker_sleeping+0x18/0xd8) [ 480.392405] [<80137868>] (wq_worker_sleeping) from [<80713ef4>] (schedule+0x51c/0x7bc) [ 480.392430] [<80713ef4>] (schedule) from [<801495c4>] (do_task_dead+0x98/0x9c) [ 480.392449] [<801495c4>] (do_task_dead) from [<801216b8>] (do_exit+0x754/0xaec) [ 480.392465] [<801216b8>] (do_exit) from [<8010c2b0>] (die+0x254/0x440) [ 480.392485] [<8010c2b0>] (die) from [<80118e74>] (do_kernel_fault.part.0+0x74/0x1f4) [ 480.392505] [<80118e74>] (do_kernel_fault.part.0) from [<80719640>] (do_page_fault+0x248/0x3b0) [ 480.392522] [<80719640>] (do_page_fault) from [<80719864>] (do_translation_fault+0xbc/0xc0) [ 480.392538] [<80719864>] (do_translation_fault) from [<801011e8>] (do_DataAbort+0x48/0xc4) [ 480.392554] [<801011e8>] (do_DataAbort) from [<80718bf4>] (__dabt_svc+0x54/0x80) [ 480.392560] Exception stack(0xb9333dd0 to 0xb9333e18) [ 480.392569] 3dc0: 00000000 b2ca384f 0000377c 00002000 [ 480.392582] 3de0: 00000000 00005556 b3140080 b915c014 b915c004 00000088 b2b50400 b9333e84 [ 480.392593] 3e00: b9333e08 b9333e20 80716214 804c4518 800f0013 ffffffff [ 480.392615] [<80718bf4>] (__dabt_svc) from [<804c4518>] (n_tty_receive_buf_common+0x7c/0xa00) [ 480.392635] [<804c4518>] (n_tty_receive_buf_common) from [<804c4ec0>] (n_tty_receive_buf2+0x24/0x2c) [ 480.392654] [<804c4ec0>] (n_tty_receive_buf2) from [<804c7310>] (tty_ldisc_receive_buf+0x30/0x6c) [ 480.392672] [<804c7310>] (tty_ldisc_receive_buf) from [<804c78cc>] (flush_to_ldisc+0xcc/0xe4) [ 480.392692] [<804c78cc>] (flush_to_ldisc) from [<801365b4>] (process_one_work+0x158/0x454) [ 480.392710] [<801365b4>] (process_one_work) from [<80136914>] (worker_thread+0x64/0x568) [ 480.392728] [<80136914>] (worker_thread) from [<8013c81c>] (kthread+0x10c/0x124) [ 480.392746] [<8013c81c>] (kthread) from [<80108148>] (ret_from_fork+0x14/0x2c) [ 480.392759] Code: e24cb004 e52de004 e8bd4000 e59033f8 (e5130014) [ 480.392768] ---[ end trace 2a38d63a06706320 ]--- [ 480.392773] Fixing recursive fault but reboot is needed! [ 480.530484] em28174 #0: writing to i2c device at 0xd8 failed (error=-5) [ 480.640453] em28174 #0: writing to i2c device at 0xd8 failed (error=-5) [ 480.750456] em28174 #0: writing to i2c device at 0xd8 failed (error=-5) [ 480.860471] em28178 #1: writing to i2c device at 0xc8 failed (error=-5) [ 481.007698] em28178 #1: writing to i2c device at 0xc8 failed (error=-5) [ 481.118457] em28178 #1: writing to i2c device at 0xc8 failed (error=-5) [ 481.120448] em28174 #0: writing to i2c device at 0xd8 failed (error=-5) [ 481.226715] em28178 #1: writing to i2c device at 0xc8 failed (error=-5) [ 481.260451] em28174 #0: writing to i2c device at 0xd8 failed (error=-5) [ 481.370454] em28174 #0: writing to i2c device at 0xd8 failed (error=-5) [ 481.990471] em28174 #0: writing to i2c device at 0xd8 failed (error=-5) [ 482.100449] em28174 #0: writing to i2c device at 0xd8 failed (error=-5) [ 482.210453] em28174 #0: writing to i2c device at 0xd8 failed (error=-5) [ 482.306389] em28178 #1: writing to i2c device at 0xc8 failed (error=-5) [ 482.412840] em28178 #1: writing to i2c device at 0xc8 failed (error=-5) [ 482.535394] em28178 #1: writing to i2c device at 0xc8 failed (error=-5) [ 482.720449] em28174 #0: writing to i2c device at 0xd8 failed (error=-5)

On Sun, May 14, 2017 at 2:17 PM, Anders aeriksson2@gmail.com wrote:

I'm compiling my own stuff. Can you indicate git commit and config to use?

Thanks! /Anders

On May 4, 2017 5:28:26 PM GMT+02:00, P33M notifications@github.com wrote:

Can you try again after running rpi-update? There have been some bug fixes that may affect your use case.

— You are receiving this because you were mentioned. Reply to this email directly, view it on GitHub https://github.com/raspberrypi/linux/issues/1649#issuecomment-299220601, or mute the thread https://github.com/notifications/unsubscribe-auth/AGC9C9BdLjDFHgFOpx7pO7ui6ms3tVasks5r2e6agaJpZM4KES2I .

-- Sent from my Android device with K-9 Mail. Please excuse my brevity.

popcornmix commented 7 years ago

@andersthomson are your own builds less stable than rpi-update ones?

andersthomson commented 7 years ago

Long story. I've had continuous issues with the rpi kernel and the two tv dvb-t2 dongles I have. Oopses related to the otg driver. Apparently upstream devs won't touch it in favor of the now mainline dwc2. Reports elsewhere suggested that the dvwc2 driver has issues with isoc transfers though (and the tv stuff uses a ton of it I understand). Anyway, I've compiled a vanilla 4.11, enabled the dwc2 and got the dt sorted (methinks).

The net result is that it boots, tend to have a few hiccups when probing, finding my two disks/serial cards/two tv cards, but moves on into operation. The TV stuff fails to come up though. No life from it and a stream of i2c errors (during mux scanning, I guess). I kind of hope that I'm missing a module or something, as this happens right after start.

I'll grab a log once I can get the tv grear from the family.

-Anders

On Sun, May 14, 2017 at 10:05 PM, Stefan Wahren notifications@github.com wrote:

@andersthomson https://github.com/andersthomson Could you please explain what goes wrong on dwc2?

— You are receiving this because you were mentioned. Reply to this email directly, view it on GitHub https://github.com/raspberrypi/linux/issues/1649#issuecomment-301336308, or mute the thread https://github.com/notifications/unsubscribe-auth/AGC9C7ek3XV5HKi1buZnXO6IbTBZ6ZDeks5r516agaJpZM4KES2I .

andersthomson commented 7 years ago

Historically no difference. I started out grabbing the kernels etc from the rasp github tree manually, and they fail in the same way (gentoo here, so no rpi-update.) In an attenpt at being able to (help with) debuggning the thing, I've moved to compiling it myself (and there was the odd config I wanted to change), and eventually move to vanilla.

I've been through two rpi2 about 5 power adapters and got myself two self-powered usb hub to remove power issues. No difference. USB related oopses one way or the other...

/Anders

On Mon, May 15, 2017 at 7:53 PM, popcornmix notifications@github.com wrote:

@andersthomson https://github.com/andersthomson are your own builds less stable than rpi-update ones?

— You are receiving this because you were mentioned. Reply to this email directly, view it on GitHub https://github.com/raspberrypi/linux/issues/1649#issuecomment-301552514, or mute the thread https://github.com/notifications/unsubscribe-auth/AGC9C0cgFurlHfr_hsVm5dTJtGCQYX-Kks5r6JEUgaJpZM4KES2I .

andersthomson commented 7 years ago

Alright, here's a boot log of vanilla 4.11.1. I've no idea what causes the i2c errors, but it's consistent on each boot, and I guess timed with tvheadend starting to do its thing. Tvheadend shows no evidence of the DVB adapters doing any good.

Ideas?

/Anders

[ 0.000000] Booting Linux on physical CPU 0xf00 [ 0.000000] Linux version 4.11.1 (root@rpi2.lan) (gcc version 4.9.4 (Gentoo 4.9.4 p1.0, pie-0.6.4) ) #25 SMP Tue May 16 11:11:15 CEST 2017 [ 0.000000] CPU: ARMv7 Processor [410fc075] revision 5 (ARMv7), cr=10c5387d [ 0.000000] CPU: div instructions available: patching division code [ 0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache [ 0.000000] OF: fdt: Machine model: Raspberry Pi 2 Model B Rev 1.1 [ 0.000000] efi: Getting EFI parameters from FDT: [ 0.000000] efi: UEFI not found. [ 0.000000] cma: Reserved 64 MiB at 0x37000000 [ 0.000000] Memory policy: Data cache writealloc [ 0.000000] percpu: Embedded 16 pages/cpu @ef761000 s35840 r8192 d21504 u65536 [ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. Total pages: 239936 [ 0.000000] Kernel command line: earlyprintk console=ttyAMA0 bcm2708_fb.fbwidth=656 bcm2708_fb.fbheight=416 bcm2708_fb.fbswap=1 dma.dmachans=0x7f35 bcm2709.boardrev=0xa01041 bcm2709.serial=0x9aa48012 bcm2709.u1 [ 0.000000] PID hash table entries: 4096 (order: 2, 16384 bytes) [ 0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes) [ 0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes) [ 0.000000] Memory: 873404K/966656K available (9216K kernel code, 1204K rwdata, 3936K rodata, 2048K init, 481K bss, 27716K reserved, 65536K cma-reserved, 114688K highmem) [ 0.000000] Virtual kernel memory layout: [ 0.000000] vector : 0xffff0000 - 0xffff1000 ( 4 kB) [ 0.000000] fixmap : 0xffc00000 - 0xfff00000 (3072 kB) [ 0.000000] vmalloc : 0xf0800000 - 0xff800000 ( 240 MB) [ 0.000000] lowmem : 0xc0000000 - 0xf0000000 ( 768 MB) [ 0.000000] pkmap : 0xbfe00000 - 0xc0000000 ( 2 MB) [ 0.000000] modules : 0xbf000000 - 0xbfe00000 ( 14 MB) [ 0.000000] .text : 0xc0208000 - 0xc0c00000 (10208 kB) [ 0.000000] .init : 0xc1100000 - 0xc1300000 (2048 kB) [ 0.000000] .data : 0xc1300000 - 0xc142d220 (1205 kB) [ 0.000000] .bss : 0xc142f000 - 0xc14a7640 ( 482 kB) [ 0.000000] SLUB: HWalign=128, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] Hierarchical RCU implementation. [ 0.000000] Build-time adjustment of leaf fanout to 32. [ 0.000000] RCU restricting CPUs from NR_CPUS=16 to nr_cpu_ids=4. [ 0.000000] RCU: Adjusting geometry for rcu_fanout_leaf=32, nr_cpu_ids=4 [ 0.000000] NR_IRQS:16 nr_irqs:16 16 [ 0.000021] sched_clock: 32 bits at 1000kHz, resolution 1000ns, wraps every 2147483647500ns [ 0.000053] clocksource: timer: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275 ns [ 0.000138] bcm2835: system timer (irq = 33) [ 0.002153] arm_arch_timer: WARNING: Invalid trigger for IRQ16, assuming level low [ 0.002164] arm_arch_timer: WARNING: Please fix your firmware [ 0.002180] arm_arch_timer: WARNING: Invalid trigger for IRQ17, assuming level low [ 0.002189] arm_arch_timer: WARNING: Please fix your firmware [ 0.002349] arm_arch_timer: Architected cp15 timer(s) running at 19.20MHz (phys). [ 0.002371] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x46d987e47, max_idle_ns: 440795202767 ns [ 0.002395] sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 4398046511078ns [ 0.002412] Switching to timer-based delay loop, resolution 52ns [ 0.002891] Console: colour dummy device 80x30 [ 0.004019] console [tty0] enabled [ 0.004085] Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=192000) [ 0.004146] pid_max: default: 32768 minimum: 301 [ 0.004379] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes) [ 0.004420] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes) [ 0.005601] CPU: Testing write buffer coherency: ok [ 0.006279] CPU0: update cpu_capacity 1024 [ 0.006320] CPU0: thread -1, cpu 0, socket 15, mpidr 80000f00 [ 0.006918] Setting up static identity map for 0x300000 - 0x300098 [ 0.009306] EFI services will not be available. [ 0.009774] smp: Bringing up secondary CPUs ... [ 0.010835] arm_arch_timer: WARNING: Invalid trigger for IRQ16, assuming level low [ 0.010840] arm_arch_timer: WARNING: Please fix your firmware [ 0.010850] arm_arch_timer: WARNING: Invalid trigger for IRQ17, assuming level low [ 0.010854] arm_arch_timer: WARNING: Please fix your firmware [ 0.010868] CPU1: update cpu_capacity 1024 [ 0.010876] CPU1: thread -1, cpu 1, socket 15, mpidr 80000f01 [ 0.011903] arm_arch_timer: WARNING: Invalid trigger for IRQ16, assuming level low [ 0.011907] arm_arch_timer: WARNING: Please fix your firmware [ 0.011914] arm_arch_timer: WARNING: Invalid trigger for IRQ17, assuming level low [ 0.011917] arm_arch_timer: WARNING: Please fix your firmware [ 0.011928] CPU2: update cpu_capacity 1024 [ 0.011936] CPU2: thread -1, cpu 2, socket 15, mpidr 80000f02 [ 0.012913] arm_arch_timer: WARNING: Invalid trigger for IRQ16, assuming level low [ 0.012917] arm_arch_timer: WARNING: Please fix your firmware [ 0.012924] arm_arch_timer: WARNING: Invalid trigger for IRQ17, assuming level low [ 0.012927] arm_arch_timer: WARNING: Please fix your firmware [ 0.012938] CPU3: update cpu_capacity 1024 [ 0.012946] CPU3: thread -1, cpu 3, socket 15, mpidr 80000f03 [ 0.013045] smp: Brought up 1 node, 4 CPUs [ 0.013607] SMP: Total of 4 processors activated (153.60 BogoMIPS). [ 0.013638] CPU: All CPU(s) started in HYP mode. [ 0.013665] CPU: Virtualization extensions available. [ 0.014891] devtmpfs: initialized [ 0.023863] VFP support v0.3: implementor 41 architecture 2 part 30 variant 7 rev 5 [ 0.024278] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns [ 0.024347] futex hash table entries: 1024 (order: 5, 131072 bytes) [ 0.027904] pinctrl core: initialized pinctrl subsystem [ 0.030152] NET: Registered protocol family 16 [ 0.034252] DMA: preallocated 256 KiB pool for atomic coherent allocations [ 0.040025] cpuidle: using governor menu [ 0.041867] No ATAGs? [ 0.042029] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers. [ 0.042103] hw-breakpoint: maximum watchpoint size is 8 bytes. [ 0.044619] Serial: AMBA PL011 UART driver [ 0.083284] vgaarb: loaded [ 0.084488] SCSI subsystem initialized [ 0.085154] usbcore: registered new interface driver usbfs [ 0.085263] usbcore: registered new interface driver hub [ 0.085371] usbcore: registered new device driver usb [ 0.086680] pps_core: LinuxPPS API ver. 1 registered [ 0.086715] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti giometti@linux.it [ 0.086794] PTP clock support registered [ 0.087237] EDAC MC: Ver: 3.0.0 [ 0.091520] clocksource: Switched to clocksource arch_sys_counter [ 0.092958] simple-framebuffer 3db75000.framebuffer: framebuffer at 0x3db75000, 0x85400 bytes, mapped to 0xf0880000 [ 0.093026] simple-framebuffer 3db75000.framebuffer: format=r5g6b5, mode=656x416x16, linelength=1312 [ 0.097729] Console: switching to colour frame buffer device 82x26 [ 0.104539] simple-framebuffer 3db75000.framebuffer: fb0: simplefb registered! [ 0.121630] NET: Registered protocol family 2 [ 0.125338] TCP established hash table entries: 8192 (order: 3, 32768 bytes) [ 0.128347] TCP bind hash table entries: 8192 (order: 4, 65536 bytes) [ 0.131378] TCP: Hash tables configured (established 8192 bind 8192) [ 0.134483] UDP hash table entries: 512 (order: 2, 16384 bytes) [ 0.137413] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes) [ 0.140517] NET: Registered protocol family 1 [ 0.143865] RPC: Registered named UNIX socket transport module. [ 0.146690] RPC: Registered udp transport module. [ 0.149478] RPC: Registered tcp transport module. [ 0.152264] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.157709] hw perfevents: enabled with armv7_cortex_a7 PMU driver, 5 counters available [ 0.166116] workingset: timestamp_bits=14 max_order=18 bucket_order=4 [ 0.184925] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ 0.189307] NFS: Registering the id_resolver key type [ 0.192360] Key type id_resolver registered [ 0.195315] Key type id_legacy registered [ 0.198255] Installing knfsd (copyright (C) 1996 okir@monad.swb.de). [ 0.202339] ntfs: driver 2.1.32 [Flags: R/O]. [ 0.207804] bounce: pool size: 64 pages [ 0.210925] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248) [ 0.216595] io scheduler noop registered (default) [ 0.219408] io scheduler deadline registered [ 0.222387] io scheduler cfq registered [ 0.225024] io scheduler mq-deadline registered [ 0.344281] Serial: 8250/16550 driver, 4 ports, IRQ sharing disabled [ 0.350347] SuperH (H)SCI(F) driver initialized [ 0.353976] msm_serial: driver initialized [ 0.356695] STMicroelectronics ASC driver initialized [ 0.361597] bcm2835-rng 3f104000.rng: hwrng registered [ 0.387146] brd: module loaded [ 0.402489] loop: module loaded [ 0.414405] libphy: Fixed MDIO Bus: probed [ 0.420344] e1000e: Intel(R) PRO/1000 Network Driver - 3.2.6-k [ 0.422966] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. [ 0.425613] igb: Intel(R) Gigabit Ethernet Network Driver - version 5.4.0-k [ 0.428150] igb: Copyright (c) 2007-2014 Intel Corporation. [ 0.433829] pegasus: v0.9.3 (2013/04/25), Pegasus/Pegasus II USB Ethernet driver [ 0.438897] usbcore: registered new interface driver pegasus [ 0.441594] usbcore: registered new interface driver asix [ 0.444215] usbcore: registered new interface driver ax88179_178a [ 0.446764] usbcore: registered new interface driver cdc_ether [ 0.449247] usbcore: registered new interface driver smsc75xx [ 0.451721] usbcore: registered new interface driver smsc95xx [ 0.454027] usbcore: registered new interface driver net1080 [ 0.456222] usbcore: registered new interface driver cdc_subset [ 0.458350] usbcore: registered new interface driver zaurus [ 0.460477] usbcore: registered new interface driver cdc_ncm [ 0.464453] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver [ 0.466562] ehci-pci: EHCI PCI platform driver [ 0.468668] ehci-platform: EHCI generic platform driver [ 0.470888] ehci-orion: EHCI orion driver [ 0.473070] SPEAr-ehci: EHCI SPEAr driver [ 0.475164] ehci-st: EHCI STMicroelectronics driver [ 0.477265] ehci-exynos: EHCI EXYNOS driver [ 0.479352] ehci-atmel: EHCI Atmel driver [ 0.481435] tegra-ehci: Tegra EHCI driver [ 0.483551] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver [ 0.485557] ohci-pci: OHCI PCI platform driver [ 0.487587] ohci-platform: OHCI generic platform driver [ 0.489670] ohci-omap3: OHCI OMAP3 driver [ 0.491695] SPEAr-ohci: OHCI SPEAr driver [ 0.493600] ohci-st: OHCI STMicroelectronics driver [ 0.495457] ohci-atmel: OHCI Atmel driver [ 0.497879] usbcore: registered new interface driver usb-storage [ 0.501370] mousedev: PS/2 mouse device common for all mice [ 0.506469] i2c /dev entries driver [ 0.509843] i2c-bcm2835 3f805000.i2c: Could not read clock-frequency property [ 0.521333] bcm2835-wdt 3f100000.watchdog: Broadcom BCM2835 watchdog timer [ 0.526756] sdhci: Secure Digital Host Controller Interface driver [ 0.528991] sdhci: Copyright(c) Pierre Ossman [ 0.532683] Synopsys Designware Multimedia Card Interface Driver [ 0.536076] sdhci-pltfm: SDHCI platform and OF driver helper [ 0.601557] mmc0: SDHCI controller on 3f300000.sdhci [3f300000.sdhci] using PIO [ 0.605775] ledtrig-cpu: registered to indicate activity on CPUs [ 0.608962] usbcore: registered new interface driver usbhid [ 0.611559] usbhid: USB HID core driver [ 0.615145] bcm2835-mbox 3f00b880.mailbox: mailbox enabled [ 0.620888] NET: Registered protocol family 10 [ 0.624787] Segment Routing with IPv6 [ 0.627482] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver [ 0.631353] NET: Registered protocol family 17 [ 0.634490] Key type dns_resolver registered [ 0.638714] ThumbEE CPU extension supported. [ 0.641311] Registering SWP/SWPB emulation handler [ 0.649598] 3f201000.serial: ttyAMA0 at MMIO 0x3f201000 (irq = 87, base_baud = 0) is a PL011 rev2 [ 0.883934] mmc0: new high speed SDHC card at address 59b4 [ 0.884819] mmcblk0: mmc0:59b4 USD 7.51 GiB [ 0.887409] mmcblk0: p1 p2 p3 p4 [ 1.802575] console [ttyAMA0] enabled [ 1.821846] raspberrypi-firmware soc:firmware: Attached to firmware from 2017-03-02 15:32 [ 1.840423] vc4-drm soc:gpu: bound 3f902000.hdmi (ops 0xc0d4c894) [ 1.849599] vc4-drm soc:gpu: bound 3f806000.vec (ops 0xc0d4cb60) [ 1.858524] vc4-drm soc:gpu: bound 3f400000.hvs (ops 0xc0d4cfd4) [ 1.867517] vc4-drm soc:gpu: bound 3f206000.pixelvalve (ops 0xc0d4bbdc) [ 1.877044] vc4-drm soc:gpu: bound 3f207000.pixelvalve (ops 0xc0d4bbdc) [ 1.886470] vc4-drm soc:gpu: bound 3f807000.pixelvalve (ops 0xc0d4bbdc) [ 1.895712] vc4-drm soc:gpu: bound 3fc00000.v3d (ops 0xc0d4d384) [ 1.904233] fb: switching to vc4drmfb from simple [ 1.911543] Console: switching to colour dummy device 80x30 [ 1.919173] [drm] Initialized vc4 0.0.0 20140616 for soc:gpu on minor 0 [ 1.925864] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013). [ 1.932506] [drm] Driver supports precise vblank timestamp query. [ 1.982998] Console: switching to colour frame buffer device 90x30 [ 2.010028] vc4-drm soc:gpu: fb0: frame buffer device [ 2.047783] dwc2 3f980000.usb: 3f980000.usb supply vusb_d not found, using dummy regulator [ 2.066182] dwc2 3f980000.usb: 3f980000.usb supply vusb_a not found, using dummy regulator [ 2.135819] dwc2 3f980000.usb: DWC OTG Controller [ 2.145960] dwc2 3f980000.usb: new USB bus registered, assigned bus number 1 [ 2.158469] dwc2 3f980000.usb: irq 39, io mem 0x3f980000 [ 2.170512] hub 1-0:1.0: USB hub found [ 2.179625] hub 1-0:1.0: 1 port detected [ 2.190070] hctosys: unable to open rtc device (rtc0) [ 2.201124] Waiting 2 sec before mounting root device... [ 2.611546] usb 1-1: new high-speed USB device number 2 using dwc2 [ 2.862532] hub 1-1:1.0: USB hub found [ 2.871579] hub 1-1:1.0: 5 ports detected [ 3.109944] random: fast init done [ 3.201543] usb 1-1.1: new high-speed USB device number 3 using dwc2 [ 3.344810] smsc95xx v1.0.5 [ 3.447234] smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:a4:80:12 [ 3.561540] usb 1-1.3: new high-speed USB device number 4 using dwc2 [ 3.703134] hub 1-1.3:1.0: USB hub found [ 3.712897] hub 1-1.3:1.0: 7 ports detected [ 3.821546] usb 1-1.5: new high-speed USB device number 5 using dwc2 [ 3.962904] hub 1-1.5:1.0: USB hub found [ 3.972651] hub 1-1.5:1.0: 4 ports detected [ 4.061544] usb 1-1.3.2: new high-speed USB device number 6 using dwc2 [ 4.241613] md: Waiting for all devices to be available before autodetect [ 4.254094] md: If you don't use raid, use raid=noautodetect [ 4.266841] md: Autodetecting RAID arrays. [ 4.276484] md: autorun ... [ 4.284596] md: ... autorun DONE. [ 4.294774] EXT4-fs (mmcblk0p4): couldn't mount as ext3 due to feature incompatibilities [ 4.313243] usb 1-1.5.1: new high-speed USB device number 7 using dwc2 [ 4.325779] EXT4-fs (mmcblk0p4): couldn't mount as ext2 due to feature incompatibilities [ 4.357578] EXT4-fs (mmcblk0p4): mounted filesystem with ordered data mode. Opts: (null) [ 4.376932] VFS: Mounted root (ext4 filesystem) readonly on device 179:4. [ 4.390900] devtmpfs: mounted [ 4.405905] Freeing unused kernel memory: 2048K [ 4.459401] hub 1-1.5.1:1.0: USB hub found [ 4.476337] hub 1-1.5.1:1.0: 4 ports detected [ 4.555265] usb 1-1.3.3: new high-speed USB device number 8 using dwc2 [ 4.792038] usb 1-1.5.3: new high-speed USB device number 9 using dwc2 [ 4.885177] systemd[1]: systemd 228 running in system mode. (+PAM -AUDIT -SELINUX +IMA -APPARMOR +SMACK -SYSVINIT +UTMP -LIBCRYPTSETUP -GCRYPT +GNUTLS -ACL -XZ +LZ4 +SECCOMP +BLKID -ELFUTILS +KMOD -IDN) [ 4.921395] systemd[1]: Detected architecture arm. [ 4.935016] usb-storage 1-1.5.3:1.0: USB Mass Storage device detected [ 4.935677] scsi host0: usb-storage 1-1.5.3:1.0 [ 4.982624] systemd[1]: Set hostname to . [ 5.031787] usb 1-1.5.1.1: new high-speed USB device number 10 using dwc2 [ 5.120562] uart-pl011 3f201000.serial: no DMA platform data [ 5.179143] random: crng init done [ 5.202044] usb-storage 1-1.5.1.1:1.0: USB Mass Storage device detected [ 5.222508] usb-storage 1-1.5.1.1:1.0: Quirks match for vid 152d pid 2329: 8020 [ 5.240006] scsi host1: usb-storage 1-1.5.1.1:1.0 [ 5.296283] usb 1-1.5.4: new high-speed USB device number 11 using dwc2 [ 5.440479] hub 1-1.5.4:1.0: USB hub found [ 5.452928] hub 1-1.5.4:1.0: 4 ports detected [ 5.532999] usb 1-1.5.1.4: new full-speed USB device number 12 using dwc2 [ 5.791734] usb 1-1.5.4.1: new full-speed USB device number 13 using dwc2 [ 6.004854] scsi 0:0:0:0: Direct-Access Samsung M3 Portable 1404 PQ: 0 ANSI: 6 [ 6.026347] sd 0:0:0:0: [sda] 3907029168 512-byte logical blocks: (2.00 TB/1.82 TiB) [ 6.040672] sd 0:0:0:0: [sda] Write Protect is off [ 6.058512] sd 0:0:0:0: [sda] No Caching mode page found [ 6.069646] sd 0:0:0:0: [sda] Assuming drive cache: write through [ 6.238467] systemd[1]: rpc-svcgssd.service: Cannot add dependency job, ignoring: Unit rpc-svcgssd.service failed to load: No such file or directory. [ 6.263594] systemd[1]: gssproxy.service: Cannot add dependency job, ignoring: Unit gssproxy.service failed to load: No such file or directory. [ 6.288357] systemd[1]: rpc-gssd.service: Cannot add dependency job, ignoring: Unit rpc-gssd.service failed to load: No such file or directory. [ 6.322999] scsi 1:0:0:0: Direct-Access ST950032 5AS 0002 PQ: 0 ANSI: 2 CCS [ 6.344644] systemd[1]: Set up automount var-lib-timeshift.automount. [ 6.345336] sda: sda1 sda2 sda3 sda4 < sda5 sda6 > [ 6.358810] sd 1:0:0:0: [sdb] 976773168 512-byte logical blocks: (500 GB/466 GiB) [ 6.359540] sd 1:0:0:0: [sdb] Write Protect is off [ 6.360288] sd 1:0:0:0: [sdb] No Caching mode page found [ 6.360298] sd 1:0:0:0: [sdb] Assuming drive cache: write through [ 6.360986] sd 0:0:0:0: [sda] Attached SCSI disk [ 6.436211] systemd[1]: Listening on Network Service Netlink Socket. [ 6.456168] systemd[1]: Listening on udev Control Socket. [ 6.492611] systemd[1]: Created slice Virtual Machine and Container Slice. [ 6.610903] sdb: sdb1 [ 6.614160] sd 1:0:0:0: [sdb] Attached SCSI disk [ 7.335947] EXT4-fs (mmcblk0p4): re-mounted. Opts: user_xattr [ 7.641174] systemd-journald[138]: Received request to flush runtime journal from PID 1 [ 9.555069] media: Linux media interface: v0.10 [ 9.643297] Linux video capture interface: v2.00 [ 9.760039] em28xx 1-1.3.2:1.0: New device PCTV Systems PCTV 290e @ 480 Mbps (2013:024f, interface 0, class 0) [ 9.782184] em28xx 1-1.3.2:1.0: DVB interface 0 found: isoc [ 9.862679] em28xx 1-1.3.2:1.0: chip ID is em28174 [ 10.316544] em28xx 1-1.3.2:1.0: EEPROM ID = 26 00 01 00, EEPROM hash = 0x11372abd [ 10.330029] em28xx 1-1.3.2:1.0: EEPROM info: [ 10.340019] em28xx 1-1.3.2:1.0: microcode start address = 0x0004, boot configuration = 0x01 [ 10.393656] em28xx 1-1.3.2:1.0: No audio on board. [ 10.404329] em28xx 1-1.3.2:1.0: 500mA max power [ 10.414391] em28xx 1-1.3.2:1.0: Table at offset 0x39, strings=0x1aa0, 0x14ba, 0x1ace [ 10.430799] em28xx 1-1.3.2:1.0: Identified as PCTV nanoStick T2 290e (card=78) [ 10.443528] em28xx 1-1.3.2:1.0: dvb set to isoc mode. [ 10.454773] em28xx 1-1.3.3:1.0: New device PCTV PCTV 292e @ 480 Mbps (2013:025f, interface 0, class 0) [ 10.475453] em28xx 1-1.3.3:1.0: DVB interface 0 found: isoc [ 10.486801] em28xx 1-1.3.3:1.0: chip ID is em28178 [ 11.384619] EXT4-fs (sdb1): mounted filesystem with ordered data mode. Opts: (null) [ 12.442536] Adding 1023996k swap on /BACKUP_NEW/swapfile. Priority:-1 extents:5 across:1056764k [ 12.630409] em28xx 1-1.3.3:1.0: EEPROM ID = 26 00 01 00, EEPROM hash = 0x1f10fa04 [ 12.643408] em28xx 1-1.3.3:1.0: EEPROM info: [ 12.652939] em28xx 1-1.3.3:1.0: microcode start address = 0x0004, boot configuration = 0x01 [ 12.679518] em28xx 1-1.3.3:1.0: AC97 audio (5 sample rates) [ 12.690676] em28xx 1-1.3.3:1.0: 500mA max power [ 12.700843] em28xx 1-1.3.3:1.0: Table at offset 0x27, strings=0x146a, 0x1888, 0x0a7e [ 12.715201] em28xx 1-1.3.3:1.0: Identified as PCTV tripleStick (292e) (card=94) [ 12.728502] em28xx 1-1.3.3:1.0: dvb set to isoc mode. [ 12.740191] usbcore: registered new interface driver em28xx [ 12.759150] em28xx 1-1.3.2:1.0: Binding DVB extension [ 12.791083] cxd2820r 4-006c: Sony CXD2820R successfully identified [ 12.817233] tda18271 4-0060: creating new instance [ 12.837902] tda18271: TDA18271HD/C2 detected @ 4-0060 [ 13.338789] device-mapper: uevent: version 1.0.3 [ 13.346645] dvbdev: DVB: registering new adapter (1-1.3.2:1.0) [ 13.346672] em28xx 1-1.3.2:1.0: DVB: registering adapter 0 frontend 0 (Sony CXD2820R)... [ 13.346708] dvbdev: dvb_create_media_entity: media entity 'Sony CXD2820R' registered. [ 13.351893] dvbdev: dvb_create_media_entity: media entity 'dvb-demux' registered. [ 13.361409] em28xx 1-1.3.2:1.0: DVB extension successfully initialized [ 13.361424] em28xx 1-1.3.3:1.0: Binding DVB extension [ 13.434108] device-mapper: ioctl: 4.35.0-ioctl (2016-06-23) initialised: dm-devel@redhat.com [ 13.497695] i2c i2c-6: Added multiplexed i2c bus 7 [ 13.508427] si2168 6-0064: Silicon Labs Si2168-B40 successfully identified [ 13.521313] si2168 6-0064: firmware version: B 4.0.2 [ 13.557368] si2157 7-0060: Silicon Labs Si2147/2148/2157/2158 successfully attached [ 13.571828] dvbdev: DVB: registering new adapter (1-1.3.3:1.0) [ 13.584807] em28xx 1-1.3.3:1.0: DVB: registering adapter 1 frontend 0 (Silicon Labs Si2168)... [ 13.606156] dvbdev: dvb_create_media_entity: media entity 'Silicon Labs Si2168' registered. [ 13.630441] dvbdev: dvb_create_media_entity: media entity 'dvb-demux' registered. [ 13.659999] em28xx 1-1.3.3:1.0: DVB extension successfully initialized [ 13.673245] em28xx: Registered (Em28xx dvb Extension) extension [ 13.720271] em28xx 1-1.3.2:1.0: Registering input extension [ 13.734626] rc rc0: 1-1.3.2:1.0 IR as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.3/1-1.3.2/1-1.3.2:1.0/rc/rc0 [ 13.821612] Registered IR keymap rc-pinnacle-pctv-hd [ 13.822831] input: 1-1.3.2:1.0 IR as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.3/1-1.3.2/1-1.3.2:1.0/rc/rc0/input0 [ 13.823439] em28xx 1-1.3.2:1.0: Input extension successfully initalized [ 13.823456] em28xx 1-1.3.3:1.0: Registering input extension [ 13.824116] rc rc1: 1-1.3.3:1.0 IR as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.3/1-1.3.3/1-1.3.3:1.0/rc/rc1 [ 13.824126] Registered IR keymap rc-pinnacle-pctv-hd [ 13.824921] input: 1-1.3.3:1.0 IR as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.3/1-1.3.3/1-1.3.3:1.0/rc/rc1/input1 [ 13.825514] em28xx 1-1.3.3:1.0: Input extension successfully initalized [ 13.825522] em28xx: Registered (Em28xx Input Extension) extension [ 15.928074] EXT4-fs (dm-8): mounted filesystem with ordered data mode. Opts: (null) [ 16.120269] EXT4-fs (dm-6): mounted filesystem with ordered data mode. Opts: (null) [ 16.134886] EXT4-fs (dm-4): mounted filesystem with ordered data mode. Opts: (null) [ 16.563731] EXT4-fs (dm-7): mounted filesystem with ordered data mode. Opts: (null) [ 18.188116] IPv6: ADDRCONF(NETDEV_UP): br0: link is not ready [ 18.194958] IPv6: ADDRCONF(NETDEV_UP): veth-osc: link is not ready [ 18.201308] br0: port 1(veth-osc-br) entered blocking state [ 18.201321] br0: port 1(veth-osc-br) entered disabled state [ 18.202070] device veth-osc-br entered promiscuous mode [ 18.207399] IPv6: ADDRCONF(NETDEV_UP): veth-tvh: link is not ready [ 18.213584] br0: port 2(veth-tvh-br) entered blocking state [ 18.213598] br0: port 2(veth-tvh-br) entered disabled state [ 18.214153] device veth-tvh-br entered promiscuous mode [ 18.242369] br0: port 3(eth0) entered blocking state [ 18.242382] br0: port 3(eth0) entered disabled state [ 18.242863] device eth0 entered promiscuous mode [ 18.253051] br0: port 1(veth-osc-br) entered blocking state [ 18.253064] br0: port 1(veth-osc-br) entered forwarding state [ 18.253362] IPv6: ADDRCONF(NETDEV_CHANGE): veth-osc: link becomes ready [ 18.253536] IPv6: ADDRCONF(NETDEV_CHANGE): br0: link becomes ready [ 18.257674] br0: port 2(veth-tvh-br) entered blocking state [ 18.257688] br0: port 2(veth-tvh-br) entered forwarding state [ 18.257811] IPv6: ADDRCONF(NETDEV_CHANGE): veth-tvh: link becomes ready [ 18.355248] smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup [ 18.355654] br0: port 3(eth0) entered blocking state [ 18.355666] br0: port 3(eth0) entered forwarding state [ 20.110597] smsc95xx 1-1.1:1.0 eth0: link up, 100Mbps, full-duplex, lpa 0xC5E1 [ 20.209448] smsc95xx 1-1.1:1.0 eth0: link up, 100Mbps, full-duplex, lpa 0xC5E1 [ 20.993109] EXT4-fs (dm-0): mounted filesystem with ordered data mode. Opts: (null) [ 21.232196] NFSD: Using /var/lib/nfs/v4recovery as the NFSv4 state recovery directory [ 21.239160] NFSD: starting 90-second grace period (net c1423f00) [ 21.262503] IPv6: ADDRCONF(NETDEV_UP): vb-revproxy: link is not ready [ 21.262558] br0: port 4(vb-revproxy) entered blocking state [ 21.262571] br0: port 4(vb-revproxy) entered disabled state [ 21.263023] device vb-revproxy entered promiscuous mode [ 21.817604] EXT4-fs (dm-5): mounted filesystem with writeback data mode. Opts: data=writeback [ 21.971302] IPv6: ADDRCONF(NETDEV_UP): vb-tvhead: link is not ready [ 21.984691] br0: port 5(vb-tvhead) entered blocking state [ 21.984709] br0: port 5(vb-tvhead) entered disabled state [ 21.985221] device vb-tvhead entered promiscuous mode [ 21.985333] br0: port 5(vb-tvhead) entered blocking state [ 21.985345] br0: port 5(vb-tvhead) entered forwarding state [ 22.056471] IPv6: ADDRCONF(NETDEV_UP): vb-work: link is not ready [ 22.056543] br0: port 6(vb-work) entered blocking state [ 22.056555] br0: port 6(vb-work) entered disabled state [ 22.057113] device vb-work entered promiscuous mode [ 22.057232] br0: port 6(vb-work) entered blocking state [ 22.057247] br0: port 6(vb-work) entered forwarding state [ 22.261792] br0: port 5(vb-tvhead) entered disabled state [ 22.273012] br0: port 6(vb-work) entered disabled state [ 28.001264] EXT4-fs (dm-4): re-mounted. Opts: (null) [ 28.673475] EXT4-fs (dm-4): re-mounted. Opts: (null) [ 40.940359] IPv6: ADDRCONF(NETDEV_CHANGE): vb-revproxy: link becomes ready [ 40.940543] br0: port 4(vb-revproxy) entered blocking state [ 40.940557] br0: port 4(vb-revproxy) entered forwarding state [ 41.544239] IPv6: ADDRCONF(NETDEV_CHANGE): vb-tvhead: link becomes ready [ 41.556967] br0: port 5(vb-tvhead) entered blocking state [ 41.568643] br0: port 5(vb-tvhead) entered forwarding state [ 47.650221] IPv6: ADDRCONF(NETDEV_CHANGE): vb-work: link becomes ready [ 47.662847] br0: port 6(vb-work) entered blocking state [ 47.673882] br0: port 6(vb-work) entered forwarding state [ 51.041822] si2168 6-0064: downloading firmware from file 'dvb-demod-si2168-b40-01.fw' [ 51.480061] si2168 6-0064: firmware version: B 4.0.11 [ 51.499319] si2157 7-0060: found a 'Silicon Labs Si2157-A30' [ 51.559555] si2157 7-0060: firmware version: 3.0.5 [ 51.570039] em28xx 1-1.3.3:1.0: DVB: adapter 1 frontend 0 frequency 0 out of range (42000000..870000000) [ 52.126416] tda18271: performing RF tracking filter calibration [ 56.649288] tda18271: RF tracking filter calibration complete [ 56.663002] em28xx 1-1.3.2:1.0: DVB: adapter 0 frontend 0 frequency 0 out of range (45000000..864000000) [ 60.502572] em28xx 1-1.3.2:1.0: writing to i2c device at 0xd8 failed (error=-5) [ 60.662544] em28xx 1-1.3.3:1.0: writing to i2c device at 0xc8 failed (error=-5) [ 61.022548] em28xx 1-1.3.2:1.0: writing to i2c device at 0xd8 failed (error=-5) [ 61.102547] em28xx 1-1.3.3:1.0: writing to i2c device at 0xc8 failed (error=-5) [ 61.322548] em28xx 1-1.3.3:1.0: writing to i2c device at 0xc8 failed (error=-5) [ 61.542544] em28xx 1-1.3.3:1.0: writing to i2c device at 0xc8 failed (error=-5) [ 61.762543] em28xx 1-1.3.3:1.0: writing to i2c device at 0xc8 failed (error=-5) [ 61.982556] em28xx 1-1.3.3:1.0: writing to i2c device at 0xc8 failed (error=-5) [ 62.122615] em28xx 1-1.3.2:1.0: writing to i2c device at 0xd8 failed (error=-5) [ 63.082589] em28xx 1-1.3.3:1.0: writing to i2c device at 0xc8 failed (error=-5) [ 63.222612] em28xx 1-1.3.2:1.0: writing to i2c device at 0xd8 failed (error=-5) [ 64.182616] em28xx 1-1.3.3:1.0: writing to i2c device at 0xc8 failed (error=-5) [ 64.322650] em28xx 1-1.3.2:1.0: writing to i2c device at 0xd8 failed (error=-5) [ 65.282658] em28xx 1-1.3.3:1.0: writing to i2c device at 0xc8 failed (error=-5) [ 66.382703] em28xx 1-1.3.3:1.0: writing to i2c device at 0xc8 failed (error=-5) [ 68.582764] em28xx 1-1.3.3:1.0: writing to i2c device at 0xc8 failed (error=-5) [ 69.682787] em28xx 1-1.3.3:1.0: writing to i2c device at 0xc8 failed (error=-5) [ 70.782804] em28xx 1-1.3.3:1.0: writing to i2c device at 0xc8 failed (error=-5) [ 71.892862] em28xx 1-1.3.3:1.0: writing to i2c device at 0xc8 failed (error=-5) [ 72.992898] em28xx 1-1.3.3:1.0: writing to i2c device at 0xc8 failed (error=-5) [ 74.092900] em28xx 1-1.3.3:1.0: writing to i2c device at 0xc8 failed (error=-5) [ 74.972948] em28xx 1-1.3.2:1.0: writing to i2c device at 0xd8 failed (error=-5) [ 74.983764] em28xx 1-1.3.3:1.0: writing to i2c device at 0xc8 failed (error=-5) [ 75.092996] em28xx 1-1.3.2:1.0: writing to i2c device at 0xd8 failed (error=-5)

On Mon, May 15, 2017 at 8:01 PM, Anders Eriksson aeriksson2@gmail.com wrote:

Historically no difference. I started out grabbing the kernels etc from the rasp github tree manually, and they fail in the same way (gentoo here, so no rpi-update.) In an attenpt at being able to (help with) debuggning the thing, I've moved to compiling it myself (and there was the odd config I wanted to change), and eventually move to vanilla.

I've been through two rpi2 about 5 power adapters and got myself two self-powered usb hub to remove power issues. No difference. USB related oopses one way or the other...

/Anders

On Mon, May 15, 2017 at 7:53 PM, popcornmix notifications@github.com wrote:

@andersthomson https://github.com/andersthomson are your own builds less stable than rpi-update ones?

— You are receiving this because you were mentioned. Reply to this email directly, view it on GitHub https://github.com/raspberrypi/linux/issues/1649#issuecomment-301552514, or mute the thread https://github.com/notifications/unsubscribe-auth/AGC9C0cgFurlHfr_hsVm5dTJtGCQYX-Kks5r6JEUgaJpZM4KES2I .

lategoodbye commented 7 years ago

@andersthomson In order to keep track of this issue i suggested to open a separate issue for dwc2. I'm interested to get as much as possible dwc2 issues fixed, but unfortunately i'm no USB expert. It would be helpful in narrowing down the issue, if you reduce the affected device to only 1 device per test. So you could make a good / bad list. I also tried tvheadend, but it's too complex for debugging. Aren't there any simpler commands?

andersthomson commented 7 years ago

This is on vanilla kernel, so an issue on rpi's github would be out of place, right?

The testing I've done shows that all the USB devices are working find under dwc2. Well, at least the disks. I've not yet tested the serial devices (one card reader, one tellstick home automation dev). But no signs of complaints there.

DVB cli commands are scarce. I've tested scan-dvb from linuxtv-dvb-apps, but it didn't go anywhere either (same i2c error, no forward progress shown).

I'll make another scan-dvb run and see if I can correlate i2c error with the syscall stream.

-A

On Tue, May 16, 2017 at 8:58 PM, Stefan Wahren notifications@github.com wrote:

@andersthomson https://github.com/andersthomson In order to keep track of this issue i suggested to open a separate issue for dwc2. I'm interested to get as much as possible dwc2 issues fixed, but unfortunately i'm no USB expert. It would be helpful in narrowing down the issue, if you reduce the affected device to only 1 device per test. So you could make a good / bad list. I also tried tvheadend, but it's too complex for debugging. Aren't there any simpler commands?

— You are receiving this because you were mentioned. Reply to this email directly, view it on GitHub https://github.com/raspberrypi/linux/issues/1649#issuecomment-301880843, or mute the thread https://github.com/notifications/unsubscribe-auth/AGC9CyJe0tYHeajjQyySrw5XnJn8tRIeks5r6fG8gaJpZM4KES2I .

lategoodbye commented 7 years ago

Yes, the mailing lists linux-usb or linux-rpi-kernel would be the right place. But i usually didn't expect so much motiviation. Would be nice if you could provide your scan-dvb cli calls.

JamesH65 commented 6 years ago

Is this issue still valid on latest kernel?

It's been a year since any comments so this issue will be closed within 30 days unless further interactions are posted. If you wish this issue to remain open, please add a comment. A closed issue may be reopened if requested.

JamesH65 commented 6 years ago

Closing due to lack of activity. Please request to be reopened if you feel this issue is still relevant.