[ 0.000000] Initializing cgroup subsys cpuset [ 0.000000] Initializing cgroup subsys cpu [ 0.000000] Linux version 3.4-4.0-dm520 (dm520@opendreambox.org) (gcc version 5.3.0 (GCC) ) #1 SMP Wed Mar 22 19:35:18 UTC 2017 [ 0.000000] Fetching vars from bootloader... found 14 vars. [ 0.000000] Options: moca=0 sata=0 pcie=0 usb=1 [ 0.000000] Using 512 MB + 0 MB RAM (from CFE) [ 0.000000] bootconsole [early0] enabled [ 0.000000] CPU revision is: 0002a065 (Broadcom BMIPS4380) [ 0.000000] FPU revision is: 00130001 [ 0.000000] Determined physical RAM map: [ 0.000000] memory: 10000000 @ 00000000 (usable) [ 0.000000] memory: 10000000 @ 20000000 (usable) [ 0.000000] bmem: adding 56 MB LINUX region at 7 MB (0x03888000@0x00778000) [ 0.000000] bmem: adding 192 MB RESERVED region at 64 MB (0x0c000000@0x04000000) [ 0.000000] bmem: adding 256 MB LINUX region at 512 MB (0x10000000@0x20000000) [ 0.000000] Initrd not found or empty - disabling initrd [ 0.000000] Zone PFN ranges: [ 0.000000] Normal 0x00000000 -> 0x00030000 [ 0.000000] Movable zone start PFN for each node [ 0.000000] Early memory PFN ranges [ 0.000000] 0: 0x00000000 -> 0x00010000 [ 0.000000] 0: 0x00020000 -> 0x00030000 [ 0.000000] On node 0 totalpages: 131072 [ 0.000000] free_area_init_node: node 0, pgdat 806ef440, node_mem_map 81000000 [ 0.000000] Normal zone: 1536 pages used for memmap [ 0.000000] Normal zone: 0 pages reserved [ 0.000000] Normal zone: 129536 pages, LIFO batch:31 [ 0.000000] PERCPU: Embedded 7 pages/cpu @81606000 s6336 r8192 d14144 u32768 [ 0.000000] pcpu-alloc: s6336 r8192 d14144 u32768 alloc=8*4096 [ 0.000000] pcpu-alloc: [0] 0 [0] 1 [ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. Total pages: 129536 [ 0.000000] Kernel command line: bmem=192M@64M console=ttyS0,1000000 ubi.mtd=rootfs root=ubi0:dreambox-rootfs rootfstype=ubifs rw [ 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] Primary instruction cache 32kB, VIPT, 2-way, linesize 64 bytes. [ 0.000000] Primary data cache 64kB, 4-way, VIPT, cache aliases, linesize 64 bytes [ 0.000000] Memory: 313404k/524288k available (5983k kernel code, 210884k reserved, 1179k data, 248k init, 0k highmem) [ 0.000000] Hierarchical RCU implementation. [ 0.000000] Additional per-CPU info printed with stalls. [ 0.000000] NR_IRQS:160 [ 0.000000] Measuring MIPS counter frequency... [ 0.000000] Detected MIPS clock frequency: 751 MHz (375.765 MHz counter) [ 0.000000] Console: colour dummy device 80x25 [ 0.001000] Calibrating delay loop... 749.56 BogoMIPS (lpj=374784) [ 0.011000] pid_max: default: 32768 minimum: 301 [ 0.012000] Mount-cache hash table entries: 512 [ 0.013000] Initializing cgroup subsys cpuacct [ 0.014000] Initializing cgroup subsys devices [ 0.015000] Initializing cgroup subsys freezer [ 0.016000] SMP: Booting CPU1... [ 0.020000] Primary instruction cache 32kB, VIPT, 2-way, linesize 64 bytes. [ 0.020000] Primary data cache 64kB, 4-way, VIPT, cache aliases, linesize 64 bytes [ 0.021000] CPU revision is: 0002a065 (Broadcom BMIPS4380) [ 0.021000] FPU revision is: 00130001 [ 0.030000] SMP: CPU1 is running [ 0.030000] Brought up 2 CPUs [ 0.059000] devtmpfs: initialized [ 0.063000] NET: Registered protocol family 16 [ 0.064000] USB0: power enable is active high; overcurrent is active low [ 0.069000] bio: create slab at 0 [ 0.070000] SCSI subsystem initialized [ 0.071000] usbcore: registered new interface driver usbfs [ 0.072000] usbcore: registered new interface driver hub [ 0.073000] usbcore: registered new device driver usb [ 0.074000] Advanced Linux Sound Architecture Driver Version 1.0.25. [ 0.075000] Bluetooth: Core ver 2.16 [ 0.076000] NET: Registered protocol family 31 [ 0.077000] Bluetooth: HCI device and connection manager initialized [ 0.078000] Bluetooth: HCI socket layer initialized [ 0.079000] Bluetooth: L2CAP socket layer initialized [ 0.080000] Bluetooth: SCO socket layer initialized [ 0.081000] cfg80211: Calling CRDA to update world regulatory domain [ 0.083000] Switching to clocksource wktmr [ 0.093000] NET: Registered protocol family 2 [ 0.113000] IP route cache hash table entries: 4096 (order: 2, 16384 bytes) [ 0.121000] TCP established hash table entries: 16384 (order: 5, 131072 bytes) [ 0.128000] TCP bind hash table entries: 16384 (order: 5, 131072 bytes) [ 0.135000] TCP: Hash tables configured (established 16384 bind 16384) [ 0.142000] TCP: reno registered [ 0.145000] UDP hash table entries: 256 (order: 1, 8192 bytes) [ 0.151000] UDP-Lite hash table entries: 256 (order: 1, 8192 bytes) [ 0.157000] NET: Registered protocol family 1 [ 0.162000] RPC: Registered named UNIX socket transport module. [ 0.168000] RPC: Registered udp transport module. [ 0.172000] RPC: Registered tcp transport module. [ 0.177000] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.185000] NFS: Registering the id_resolver key type [ 0.191000] SGI XFS with security attributes, large block/inode numbers, no debug enabled [ 0.200000] msgmni has been set to 612 [ 0.204000] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 254) [ 0.212000] io scheduler noop registered [ 0.216000] io scheduler cfq registered (default) [ 0.221000] Serial: 8250/16550 driver, 4 ports, IRQ sharing disabled [ 0.227000] serial8250.0: ttyS0 at MMIO 0x10406800 (irq = 62) is a 16550A [ 0.234000] console [ttyS0] enabled, bootconsole disabled [ 0.239000] loop: module loaded [ 0.239000] brcmstb_nand: NAND controller driver is loaded [ 0.240000] tun: Universal TUN/TAP device driver, 1.6 [ 0.241000] tun: (C) 1999-2004 Max Krasnyansky [ 0.242000] __clk_enable: network [1] [ 0.242000] __clk_enable: enet [1] [ 0.242000] bcm7552_pm_genet_enable 00 [ 0.363000] bcmgenet: configuring instance #0 for internal PHY [ 0.383000] bcmgenet bcmgenet.0: (unregistered net_device): link down [ 0.383000] __clk_disable: enet [0] [ 0.383000] bcm7552_pm_genet_disable 00 [ 0.383000] __clk_disable: network [0] [ 0.384000] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver [ 0.385000] __clk_enable: usb [1] [ 0.385000] bcm7552_pm_usb_enable 00 [ 0.385000] ehci-brcm ehci-brcm.0: Broadcom STB EHCI [ 0.385000] ehci-brcm ehci-brcm.0: new USB bus registered, assigned bus number 1 [ 0.417000] ehci-brcm ehci-brcm.0: irq 66, io mem 0x10480300 [ 0.423000] ehci-brcm ehci-brcm.0: USB 0.0 started, EHCI 1.00 [ 0.424000] hub 1-0:1.0: USB hub found [ 0.424000] hub 1-0:1.0: 2 ports detected [ 0.425000] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver [ 0.426000] ohci-brcm ohci-brcm.0: Broadcom STB OHCI [ 0.426000] ohci-brcm ohci-brcm.0: new USB bus registered, assigned bus number 2 [ 0.427000] ohci-brcm ohci-brcm.0: irq 67, io mem 0x10480400 [ 0.493000] hub 2-0:1.0: USB hub found [ 0.494000] hub 2-0:1.0: 2 ports detected [ 0.494000] Initializing USB Mass Storage driver... [ 0.495000] usbcore: registered new interface driver usb-storage [ 0.496000] USB Mass Storage support registered. [ 0.496000] i2c /dev entries driver [ 0.497000] lirc_dev: IR Remote Control driver registered, major 253 [ 0.497000] usbcore: registered new interface driver btusb [ 0.499000] TCP: cubic registered [ 0.499000] NET: Registered protocol family 10 [ 0.501000] NET: Registered protocol family 17 [ 0.501000] Bluetooth: RFCOMM TTY layer initialized [ 0.502000] Bluetooth: RFCOMM socket layer initialized [ 0.503000] Bluetooth: RFCOMM ver 1.11 [ 0.503000] Bluetooth: BNEP (Ethernet Emulation) ver 1.3 [ 0.504000] Bluetooth: BNEP filters: protocol multicast [ 0.504000] Bluetooth: HIDP (Human Interface Emulation) ver 1.2 [ 0.505000] L2TP core driver, V2.0 [ 0.506000] Registering the dns_resolver key type [ 0.506000] PM: CP0 COUNT/COMPARE frequency does not depend on divisor [ 0.508000] EBI CS2: setting up NAND flash (primary) [ 0.509000] NAND device: Manufacturer ID: 0xc8, Chip ID: 0xdc (Unknown NAND 512MiB 3,3V 8-bit) [ 0.510000] brcmnand brcmnand.0: 512MiB total, 128KiB blocks, 2KiB pages, 16B OOB, 8-bit, BCH-4 [ 0.510000] [ 0.512000] Bad block table found at page 262080, version 0x01 [ 0.513000] Bad block table found at page 262016, version 0x01 [ 0.514000] nand_read_bbt: bad block at 0x000004800000 [ 0.515000] nand_read_bbt: bad block at 0x000005580000 [ 0.515000] nand_read_bbt: bad block at 0x00000ebc0000 [ 0.517000] Creating 3 MTD partitions on "brcmnand.0": [ 0.517000] 0x000000000000-0x00001f500000 : "rootfs" [ 0.519000] 0x000000000000-0x000020000000 : "entire_device" [ 0.521000] 0x00001f500000-0x00001fa00000 : "kernel" [ 0.523000] EBI CS0: setting up SPI flash [ 0.523000] spi_brcmstb spi_brcmstb.0: 1-lane output, 3-byte address [ 0.524000] spi_brcmstb spi_brcmstb.0: master is unqueued, this is deprecated [ 0.525000] m25p80 spi0.0: found mx25l12805d, expected w25q128bv [ 0.526000] m25p80 spi0.0: mx25l12805d (16384 Kbytes) [ 0.528000] UBI: attaching mtd0 to ubi0 [ 0.529000] UBI: physical eraseblock size: 131072 bytes (128 KiB) [ 0.529000] UBI: logical eraseblock size: 126976 bytes [ 0.530000] UBI: smallest flash I/O unit: 2048 [ 0.531000] UBI: VID header offset: 2048 (aligned 2048) [ 0.531000] UBI: data offset: 4096 [ 0.727000] usb 1-1: new high-speed USB device number 2 using ehci-brcm [ 0.843000] scsi0 : usb-storage 1-1:1.0 [ 1.847000] scsi 0:0:0:0: Direct-Access SanDisk Cruzer Edge 1.27 PQ: 0 ANSI: 6 [ 1.852000] sd 0:0:0:0: [sda] 62530624 512-byte logical blocks: (32.0 GB/29.8 GiB) [ 1.854000] sd 0:0:0:0: [sda] Write Protect is off [ 1.855000] sd 0:0:0:0: [sda] Mode Sense: 43 00 00 00 [ 1.856000] sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA [ 1.889000] sda: sda1 [ 1.893000] sd 0:0:0:0: [sda] Attached SCSI removable disk [ 3.680000] UBI: max. sequence number: 220090 [ 3.696000] UBI: attached mtd0 to ubi0 [ 3.697000] UBI: MTD device name: "rootfs" [ 3.697000] UBI: MTD device size: 501 MiB [ 3.698000] UBI: number of good PEBs: 4005 [ 3.698000] UBI: number of bad PEBs: 3 [ 3.699000] UBI: number of corrupted PEBs: 0 [ 3.699000] UBI: max. allowed volumes: 128 [ 3.700000] UBI: wear-leveling threshold: 4096 [ 3.700000] UBI: number of internal volumes: 1 [ 3.701000] UBI: number of user volumes: 1 [ 3.701000] UBI: available PEBs: 0 [ 3.702000] UBI: total number of reserved PEBs: 4005 [ 3.703000] UBI: number of PEBs reserved for bad PEB handling: 40 [ 3.703000] UBI: max/mean erase counter: 96/55 [ 3.704000] UBI: image sequence number: 1411135471 [ 3.704000] UBI: background thread "ubi_bgt0d" started, PID 57 [ 3.706000] ALSA device list: [ 3.707000] No soundcards found. [ 3.803000] UBIFS: mounted UBI device 0, volume 0, name "dreambox-rootfs" [ 3.804000] UBIFS: file system size: 501555200 bytes (489800 KiB, 478 MiB, 3950 LEBs) [ 3.805000] UBIFS: journal size: 9023488 bytes (8812 KiB, 8 MiB, 72 LEBs) [ 3.806000] UBIFS: media format: w4/r0 (latest is w4/r0) [ 3.806000] UBIFS: default compressor: lzo [ 3.807000] UBIFS: reserved for root: 0 bytes (0 KiB) [ 3.809000] VFS: Mounted root (ubifs filesystem) on device 0:12. [ 3.812000] devtmpfs: mounted [ 4.247000] systemd[1]: systemd 230 running in system mode. (-PAM -AUDIT -SELINUX -IMA -APPARMOR -SMACK +SYSVINIT +UTMP -LIBCRYPTSETUP -GCRYPT +GNUTLS +ACL +XZ -LZ4 -SECCOMP +BLKID -ELFUTILS +KMOD -IDN) [ 4.250000] systemd[1]: Detected architecture mips. [ 4.263000] systemd[1]: Set hostname to . [ 4.438000] systemd-fstab-generator[63]: Checking was requested for "rootfs", but it is not a device. [ 5.006000] systemd[1]: Listening on Journal Socket (/dev/log). [ 5.016000] systemd[1]: Created slice System Slice. [ 5.020000] systemd[1]: Reached target Remote File Systems. [ 5.080000] systemd[1]: Listening on Journal Socket. [ 5.098000] systemd[1]: Starting Setup Virtual Console... [ 5.113000] systemd[1]: Starting Remount Root and Kernel File Systems... [ 5.131000] systemd[1]: Starting Load Kernel Modules... [ 5.135000] systemd[1]: Listening on udev Control Socket. [ 5.138000] systemd[1]: Created slice User and Session Slice. [ 5.141000] systemd[1]: Reached target Slices. [ 5.146000] systemd[1]: Created slice system-serial\x2dgetty.slice. [ 5.164000] systemd[1]: Starting Create list of required static device nodes for the current kernel... [ 5.179000] systemd[1]: Starting Journal Service... [ 5.183000] systemd[1]: Reached target Swap. [ 5.192000] systemd[1]: Starting Generate environment for enigma2... [ 5.196000] systemd[1]: Reached target Paths. [ 5.221000] systemd[1]: Mounting POSIX Message Queue File System... [ 5.225000] systemd[1]: Listening on udev Kernel Socket. [ 5.254000] systemd[1]: Started Setup Virtual Console. [ 5.292000] systemd[1]: Started Create list of required static device nodes for the current kernel. [ 5.342000] systemd[1]: Mounted POSIX Message Queue File System. [ 5.379000] systemd[1]: Started Remount Root and Kernel File Systems. [ 5.417000] systemd[1]: Starting udev Coldplug all Devices... [ 5.430000] systemd[1]: Starting Create Static Device Nodes in /dev... [ 5.455000] stb_core: module license 'Proprietary' taints kernel. [ 5.455000] Disabling lock debugging due to kernel taint [ 5.547000] input: dreambox remote control (native) as /devices/virtual/input/input0 [ 5.548000] input: dreambox advanced remote control (native) as /devices/virtual/input/input1 [ 6.693000] bcm73625: xvd 20160630, vdc 20160630, rap 20160630, xpt 20160630, driver version 20170328-dm520 [ 6.707000] 6:706974081 SMCA: controller reset timed out [ 6.766000] systemd[1]: Started Journal Service. [ 7.302000] systemd-journald[77]: Received request to flush runtime journal from PID 1 [ 8.319000] EXT4-fs (sda1): mounted filesystem with ordered data mode. Opts: (null) [ 8.477000] register adapter [ 8.478000] register adapter [ 8.481000] register adapter [ 8.482000] input: dreambox front panel as /devices/virtual/input/input2 [ 8.486000] OFFSET 4149896773 15813111 [ 8.487000] WKTMR 14 10576330 [ 8.487000] LAST WKTMR 3279400381 [ 8.488000] set input to 0 [ 8.488000] set fb to 2(-1) [ 8.497000] startvideomode '1080i50' read from /etc/videomode [ 8.498000] bcmfb: creating surface with pixel format 0x07e48888 (BPXL_eA8_R8_G8_B8) [ 8.500000] HDMI: now connected [ 8.529000] HDMI: Physical CEC Addr set from EDID [ 8.529000] HDMI: EDID read ok [ 8.529000] HDMI: initial mode set! [ 8.530000] using 50.00Hz as MonitorRefreshRate [ 8.530000] SET FORMAT: display[0], format='1080i50' [ 8.531000] HDMI mode [ 8.531000] use EDID to detect colorspace [ 8.532000] using colorspace: Itu_R_BT_709 [ 8.532000] using 8bit output [ 8.623000] HDMI: start CEC [ 8.625000] HDMI: use logical CEC address 3 [ 8.625000] CEC init completed! [ 8.752000] VIDEO0: enable deinterlacer [ 8.753000] VIDEO0: set fallback framerate to 60000 [ 8.753000] VIDEO0: set progressive override mode to 1 [ 8.754000] VIDEO0: set streamtype! [ 8.754000] -> set MPEG2 [ 8.755000] -> sw rave disabled! [ 8.765000] set ac3 to downmix [ 8.766000] set wmapro to downmix [ 8.766000] set ac3plus to use_hdmi_caps [ 8.767000] set dtshd to force_dts [ 8.767000] set aac to downmix [ 8.767000] DVB: registering new adapter (BCM73625) [ 8.768000] CEC send ok! [ 8.832000] Initializing adapter 0 (BCM73625 (G3)-DVB-S2) [ 8.833000] bcmast: init frontend core... [ 8.853000] DVB: registering adapter 0 frontend 0 (BCM73625 (G3))... [ 8.862000] init ALSA! [ 8.863000] registered vtuner misc/vtuner0, minor 54 [ 8.864000] registered vtuner misc/vtuner1, minor 53 [ 8.869000] CI stopped... [ 8.875000] input: dreambox ir keyboard as /devices/virtual/input/input3 [ 8.876000] input: dreambox ir mouse as /devices/virtual/input/input4 [ 9.017000] VIDEO0: set blank to 0 [ 9.018000] sync0 is off so we enable it [ 9.018000] sync0: allocated pcroffset 0 [ 9.019000] sync0: playback! [ 9.019000] VIDEO0: total delay: 0 (0 ms) [ 9.027000] VIDEO0: start decode [ 9.029000] VIDEO0: flush video decoder [ 9.030000] VIDEO0 got pts failed !!!! (ret 0) [ 9.060000] VIDEO0: first pts 0x0 [ 9.060000] VIDEO0: framerate changed 3 [ 9.061000] VIDEO0: resolution changed (1280 720) [ 9.061000] VIDEO0: aspect changed 3 [ 9.062000] set current avs auto aspect to 16:9 [ 9.062000] set current avs auto aspect to 16:9 [ 9.063000] set sb to 3(1) [ 9.190000] VIDEO0: stop decode [ 9.190000] sync0 1fff no decoder left in sync handling so disable [ 9.191000] sync0: was last pcroffset0 user so free it [ 11.300000] __clk_enable: network [1] [ 11.300000] __clk_enable: enet [1] [ 11.300000] bcm7552_pm_genet_enable 00 [ 11.515000] ADDRCONF(NETDEV_UP): eth0: link is not ready [ 13.024000] bcmgenet bcmgenet.0: eth0: link up, 100 Mbps, full duplex [ 13.025000] ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready [ 18.407000] bcmast: channel 0 is in normal mode now [ 18.414000] bcmast: channel 0 is sleeping now [ 18.494000] HDMI: use logical CEC address 3 [ 18.494000] CEC init completed! [ 18.631000] CEC send ok! [ 18.750000] bcmfb: creating surface with pixel format 0x07c68888 (BPXL_eA8_B8_G8_R8) [ 23.106000] eth0: no IPv6 routers present [ 35.059000] set input to 0 [ 35.059000] set fb to 2(-1) [ 35.060000] set current avs auto aspect to 16:9 [ 35.060000] set sb to 3(1) [ 35.064000] set ac3 to downmix [ 35.066000] set ac3plus to use_hdmi_caps [ 42.032000] set current avs auto aspect to 16:9 [ 42.032000] set sb to 3(1) [ 42.034000] set current avs auto aspect to 16:9 [ 42.034000] set sb to 3(1) [ 42.035000] set current avs auto aspect to 16:9 [ 42.036000] set sb to 3(1) [ 42.051000] set current avs auto aspect to 16:9 [ 42.051000] set sb to 3(1) [ 42.052000] set current avs auto aspect to 16:9 [ 42.053000] set sb to 3(1) [ 42.053000] set current avs auto aspect to 16:9 [ 42.054000] set sb to 3(1) [ 42.058000] set current avs auto aspect to 16:9 [ 42.059000] set sb to 3(1) [ 42.060000] set current avs auto aspect to 16:9 [ 42.060000] set sb to 3(1) [ 42.061000] set current avs auto aspect to 16:9 [ 42.062000] set sb to 3(1) [ 42.067000] set current avs auto aspect to 16:9 [ 42.067000] set sb to 3(1) [ 42.068000] set current avs auto aspect to 16:9 [ 42.068000] set sb to 3(1) [ 42.071000] set current avs auto aspect to 16:9 [ 42.071000] set sb to 3(1) [ 42.072000] set current avs auto aspect to 16:9 [ 42.073000] set sb to 3(1) [ 42.074000] set current avs auto aspect to 16:9 [ 42.074000] set sb to 3(1) [ 44.358000] set mute 0 1 [ 45.450000] bcmast: channel 0 is in normal mode now [ 45.470000] al8304: static current limiting is enabled for 11ms only [ 45.503000] start PCR0 on dmx 4 pid:202 index 3 [ 45.506000] start AUDIO0 on dmx 4 pid:28c index 1 [ 45.507000] audio ch0: xpt start [ 45.508000] audio ch0: reset GA threshold to 8ms [ 45.508000] sync0 is off so we enable it [ 45.509000] sync0: allocated pcroffset 0 [ 45.509000] sync0: demux-index 4 pcr pid 202 [ 45.510000] sync0: using 3 as pcr pidchannel [ 45.510000] audio ch0: MPEG [ 45.511000] audio ch0: streamtype set 0 [ 45.511000] audio ch0: streamtype_set: stop rave [ 45.512000] audio ch0: add stereo input to mixer DAC [ 45.512000] audio ch0: add stereo input to mixer SPDIF [ 45.513000] audio ch0: add stereo input to mixer MAI [ 45.513000] audio ch0: mai sample rate changed isr (48000) [ 45.514000] audio ch0: streamtype_set: restart rave [ 45.515000] audio ch0: decoder start [ 45.516000] audio ch0: video delay 0 (0 ms) [ 45.516000] VIDEO0: total delay: 0 (0 ms) [ 45.519000] start VIDEO0 on dmx 4 pid:202 index 3 [ 45.521000] VIDEO0: total delay: 0 (0 ms) [ 45.529000] VIDEO0: start decode [ 45.994000] CEC send ok! [ 46.112000] CEC send ok! [ 46.224000] CEC send ok! [ 47.166000] audio ch0: first pts 0x1fbe85fa0, stc 0x1fbe84c40, pcr valid [ 47.207000] audio ch0: lock [ 47.208000] audio ch0: sample rate changed isr (48000) [ 47.266000] audio ch0: status ready isr [ 47.460000] VIDEO0: first pts 0x1fbe8f02c [ 47.460000] VIDEO0: resolution changed (720 576) [ 47.560000] VIDEO0: progressive changed (0) [ 47.611000] VIDEO0: enable tsm [ 47.698000] CEC send ok! [ 47.836000] CEC recv ok! [ 48.121000] CEC send ok! [ 48.189000] CEC send ok! [ 48.397000] CEC send ok! [ 48.535000] CEC recv ok! [ 48.599000] CEC send ok! [ 49.037000] CEC send ok! [ 49.127000] CEC recv ok! [ 49.191000] CEC send ok! [ 49.329000] CEC recv ok! [ 49.922000] CEC send ok! [ 50.052000] CEC recv ok! [ 50.762000] CEC send ok! [ 50.971000] CEC send ok! [ 51.061000] CEC recv ok! [ 51.413000] CEC send ok! [ 52.087000] CEC send ok! [ 487.650000] 487:649308879 SMCA: invalid ioctl: 0x00005407 [ 495.560000] stop PCR0 on dmx 4 pid:202 [ 495.561000] stop VIDEO0 on dmx 4 pid:202 [ 495.562000] VIDEO0: stop decode [ 495.563000] VIDEO0: set blank to 1 [ 495.564000] audio ch0: decoder stop [ 495.593000] stop AUDIO0 on dmx 4 pid:28c [ 495.594000] audio ch0: xpt stop [ 495.594000] sync0 1fff no decoder left in sync handling so disable [ 495.595000] sync0: was last pcroffset0 user so free it [ 495.653000] al8304: static current limiting is enabled for 11ms only [ 495.710000] dvb_demux_feed_del: feed not in list (type=0 state=0 pid=ffff) [ 496.080000] dvb_demux_feed_del: feed not in list (type=0 state=0 pid=ffff) [ 496.082000] start PCR0 on dmx 4 pid:1f7 index 10 [ 496.083000] start AUDIO0 on dmx 4 pid:25b index 9 [ 496.084000] audio ch0: xpt start [ 496.084000] sync0 is off so we enable it [ 496.085000] sync0: allocated pcroffset 0 [ 496.085000] sync0: demux-index 4 pcr pid 1f7 [ 496.086000] sync0: using 10 as pcr pidchannel [ 496.087000] audio ch0: MPEG [ 496.088000] audio ch0: decoder start [ 496.088000] audio ch0: video delay 0 (0 ms) [ 496.089000] VIDEO0: total delay: 0 (0 ms) [ 496.091000] VIDEO0: set streamtype! [ 496.092000] -> set H264 [ 496.092000] -> sw rave disabled! [ 496.093000] audio ch0 delay 18000 (200 ms) [ 496.093000] VIDEO0: total delay: 18000 (200 ms) [ 496.096000] start VIDEO0 on dmx 4 pid:1f7 index 10 [ 496.097000] VIDEO0: total delay: 18000 (200 ms) [ 496.105000] VIDEO0: start decode [ 496.237000] audio ch0: first pts 0x38f72022, stc 0x38f70a0c, pcr valid [ 496.259000] audio ch0: lock [ 496.260000] audio ch0: sample rate changed isr (48000) [ 496.545000] audio ch0: status ready isr [ 497.066000] VIDEO0: first pts 0x38f9a14c [ 497.066000] VIDEO0: resolution changed (1920 1080) [ 497.217000] VIDEO0: enable tsm [ 608.059000] BUG: soft lockup - CPU#1 stuck for 23s! [b_event:93] [ 608.059000] Modules linked in: dreambox_keyboard(PO) bcm73625(PO) atbm781x(PO) lnb_ctrl(PO) dreambox_rc2(PO) stb_core(PO) [ 608.059000] Cpu 1 [ 608.059000] $ 0 : 00000000 10008b00 00010000 00000009 [ 608.059000] $ 4 : e0a4d3d4 000012cf 000ebc86 00000000 [ 608.059000] $ 8 : 00000246 00000007 ffffffff 00000003 [ 608.059000] $12 : a9d69f80 00000087 00000000 00000002 [ 608.059000] $16 : 805d6e58 e0a4e628 0000000a 00000000 [ 608.059000] $20 : e0a4c3e4 aff70000 806d0000 0000003b [ 608.059000] $24 : 00000000 80067068 [ 608.059000] $28 : affc4000 affc5de0 e0a4d3d4 e078b5a0 [ 608.059000] Hi : 00000000 [ 608.059000] Lo : 0000000a [ 608.059000] epc : 805d735c _raw_write_unlock_irqrestore+0x30/0x180 [ 608.059000] Tainted: P O [ 608.059000] ra : e078b5a0 bcm_dvb_ca_exit+0x1530/0x3658 [bcm73625] [ 608.059000] Status: 10008b03 KERNEL EXL IE [ 608.059000] Cause : 00808000 [ 608.059000] PrId : 0002a065 (Broadcom BMIPS4380) [ 636.059000] BUG: soft lockup - CPU#1 stuck for 22s! [b_event:93] [ 636.059000] Modules linked in: dreambox_keyboard(PO) bcm73625(PO) atbm781x(PO) lnb_ctrl(PO) dreambox_rc2(PO) stb_core(PO) [ 636.059000] Cpu 1 [ 636.059000] $ 0 : 00000000 10008b00 00010000 00000017 [ 636.059000] $ 4 : e0a4d3d4 000012cf 000ebc86 00000000 [ 636.059000] $ 8 : 00000246 00000007 ffffffff 00000003 [ 636.059000] $12 : a9d69f80 00000087 00000000 00000002 [ 636.059000] $16 : 805d6e58 e0a4e628 0000000a 00000000 [ 636.059000] $20 : e0a4c3e4 aff70000 806d0000 0000003b [ 636.059000] $24 : 00000000 80067068 [ 636.059000] $28 : affc4000 affc5de0 e0a4d3d4 e078b5a0 [ 636.059000] Hi : 00000000 [ 636.059000] Lo : 0000000a [ 636.059000] epc : 805d735c _raw_write_unlock_irqrestore+0x30/0x180 [ 636.059000] Tainted: P O [ 636.059000] ra : e078b5a0 bcm_dvb_ca_exit+0x1530/0x3658 [bcm73625] [ 636.059000] Status: 10008b03 KERNEL EXL IE [ 636.059000] Cause : 00808000 [ 636.059000] PrId : 0002a065 (Broadcom BMIPS4380) [ 642.698000] INFO: rcu_sched self-detected stall on CPU [ 642.698000] 1: (59977 ticks this GP) idle=837/140000000000001/0 [ 642.698000] (t=60000 jiffies) [ 642.698000] Call Trace: [ 642.698000] [<805cff24>] dump_stack+0x8/0x34 [ 642.698000] [<800937c4>] __rcu_pending+0x1dc/0x57c [ 642.698000] [<80094774>] rcu_check_callbacks+0xec/0x1ac [ 642.698000] [<80031e40>] update_process_times+0x48/0x74 [ 642.698000] [<80070750>] tick_sched_timer+0x74/0xe8 [ 642.698000] [<8004a620>] __run_hrtimer.isra.4+0x68/0x138 [ 642.698000] [<8004b378>] hrtimer_interrupt+0x1b4/0x4dc [ 642.698000] [<8000bd88>] c0_compare_interrupt+0x50/0x88 [ 642.698000] [<8008be38>] handle_irq_event_percpu+0x7c/0x28c [ 642.698000] [<800901c0>] handle_percpu_irq+0x88/0xb8 [ 642.698000] [<8008b48c>] generic_handle_irq+0x3c/0x54 [ 642.698000] [<800060c8>] do_IRQ+0x18/0x30 [ 642.698000] [<80003454>] plat_irq_dispatch+0xac/0x10c [ 642.698000] [<80004348>] ret_from_irq+0x0/0x4 [ 642.698000] [<805d7354>] _raw_write_unlock_irqrestore+0x28/0x180 [ 642.698000] [] BKNI_ReleaseMutex_tagged+0x0/0x4c [bcm73625] [ 642.698000] [ 668.059000] BUG: soft lockup - CPU#1 stuck for 23s! [b_event:93] [ 668.059000] Modules linked in: dreambox_keyboard(PO) bcm73625(PO) atbm781x(PO) lnb_ctrl(PO) dreambox_rc2(PO) stb_core(PO) [ 668.059000] Cpu 1 [ 668.059000] $ 0 : 00000000 10008b00 00010000 0000005b [ 668.059000] $ 4 : e0a4d3d4 000012cf 000ebc86 00000000 [ 668.059000] $ 8 : 00000246 00000007 ffffffff 00000003 [ 668.059000] $12 : a9d69f80 00000087 00000000 00000002 [ 668.059000] $16 : 805d6e58 e0a4e628 0000000a 00000000 [ 668.059000] $20 : e0a4c3e4 aff70000 806d0000 0000003b [ 668.059000] $24 : 00000000 80067068 [ 668.059000] $28 : affc4000 affc5de0 e0a4d3d4 e078b5a0 [ 668.059000] Hi : 00000000 [ 668.059000] Lo : 0000000a [ 668.059000] epc : 805d735c _raw_write_unlock_irqrestore+0x30/0x180 [ 668.059000] Tainted: P O [ 668.059000] ra : e078b5a0 bcm_dvb_ca_exit+0x1530/0x3658 [bcm73625] [ 668.059000] Status: 10008b03 KERNEL EXL IE [ 668.059000] Cause : 80808000 [ 668.059000] PrId : 0002a065 (Broadcom BMIPS4380) [ 696.059000] BUG: soft lockup - CPU#1 stuck for 23s! [b_event:93] [ 696.059000] Modules linked in: dreambox_keyboard(PO) bcm73625(PO) atbm781x(PO) lnb_ctrl(PO) dreambox_rc2(PO) stb_core(PO) [ 696.059000] Cpu 1 [ 696.059000] $ 0 : 00000000 10008b00 00010000 00000041 [ 696.059000] $ 4 : e0a4d3d4 000012cf 000ebc86 00000000 [ 696.059000] $ 8 : 00000246 00000007 ffffffff 00000003 [ 696.059000] $12 : a9d69f80 00000087 00000000 00000002 [ 696.059000] $16 : 805d6e58 e0a4e628 0000000a 00000000 [ 696.059000] $20 : e0a4c3e4 aff70000 806d0000 0000003b [ 696.059000] $24 : 00000000 80067068 [ 696.059000] $28 : affc4000 affc5de0 e0a4d3d4 e078b5a0 [ 696.059000] Hi : 00000000 [ 696.059000] Lo : 0000000a [ 696.059000] epc : 805d735c _raw_write_unlock_irqrestore+0x30/0x180 [ 696.059000] Tainted: P O [ 696.059000] ra : e078b5a0 bcm_dvb_ca_exit+0x1530/0x3658 [bcm73625] [ 696.059000] Status: 10008b03 KERNEL EXL IE [ 696.059000] Cause : 80808000 [ 696.059000] PrId : 0002a065 (Broadcom BMIPS4380) [ 724.059000] BUG: soft lockup - CPU#1 stuck for 23s! [b_event:93] [ 724.059000] Modules linked in: dreambox_keyboard(PO) bcm73625(PO) atbm781x(PO) lnb_ctrl(PO) dreambox_rc2(PO) stb_core(PO) [ 724.059000] Cpu 1 [ 724.059000] $ 0 : 00000000 10008b00 00010000 00000007 [ 724.059000] $ 4 : e0a4d3d4 000012cf 000ebc86 00000000 [ 724.059000] $ 8 : 00000246 00000007 ffffffff 00000003 [ 724.059000] $12 : a9d69f80 00000087 00000000 00000002 [ 724.059000] $16 : 805d6e58 e0a4e628 0000000a 00000000 [ 724.059000] $20 : e0a4c3e4 aff70000 806d0000 0000003b [ 724.059000] $24 : 00000000 80067068 [ 724.059000] $28 : affc4000 affc5de0 e0a4d3d4 e078b5a0 [ 724.059000] Hi : 00000000 [ 724.059000] Lo : 0000000a [ 724.059000] epc : 805d735c _raw_write_unlock_irqrestore+0x30/0x180 [ 724.059000] Tainted: P O [ 724.059000] ra : e078b5a0 bcm_dvb_ca_exit+0x1530/0x3658 [bcm73625] [ 724.059000] Status: 10008b03 KERNEL EXL IE [ 724.059000] Cause : 80808000 [ 724.059000] PrId : 0002a065 (Broadcom BMIPS4380)