Dreambox DM520 M0CZS L2=1 LLMB=1 BrCfg=E30FB7C6 #@ BCM973625A0 CFE v15.3, Endian Mode: Little Build Date: Fri Aug 19 11:48:16 2016 (Jann@Cynric) Copyright (C) Broadcom Corporation. CI box detected card if inited Enabling NAND flash at CS2: A5U4GA31ATS(DEVICE ID=0xC8DC9095), ECC Level=4, Spar e Area Size=16 MACRONIX SPI flash detected: Size = 16MB, Sector Size 4096, Page Size 256 CPU speed: 751MHz DDR Frequency: 1061 MHz DDR Mode: DDR3 Total memory(MEMC 0): 512MB MEMC 0 DDR Width: 16 Boot Device: SPI Total flash: 16MB RTS VERSION: rts_hevc10_1067 ETH0_PHY: INT ETH0_MDIO_MODE: 1 ETH0_SPEED: 100 ETH0_PHYADDR: 1 DM init finalization yxml: processing file of 131072 bytes... succeeded! no file loaded, create xml restoring default xml loaded network params of: DHCP, 192.168.0.219, 255.255.255.0, 192.168.0.1 [1] type 3, dev nandflash0.kernel, loader elf, filesys raw, file , options [2] type 3, dev flash0.kernel, loader elf, filesys raw, file , options [3] type 1, dev eth0, loader elf, filesys tftp, file , options bmem=192M@64M con sole=ttyS0,1000000 root=/dev/nfs ip=dhcp added 3 sources to autoboot list init fp Initializing USB. CFE initialized. found card in unknown state ca valid! ***** DREAMBOX SSBL ***** Not interrupted yet, trying autoboot or rescue loader, hit any key in the next 5 00ms to cancel Proceeding with autoboot.. You can try aborting with Ctrl+C *** Autoboot: Trying device 'nandflash0.kernel' file (raw,elf) Ethernet link is up: 100 Mbps Full-Duplex Loader:elf Filesys:raw Dev:nandflash0.kernel File: Options: Loading: 0x80001000/7588032 0x8073d8c0/235952 Entry address is 0x805cd1d0 Closing network eth0 Starting program at 0x805cd1d0 ▒[ 0.232000] console [ttyS0] enabled, bootconsole disabled [ 0.232000] console [ttyS0] enabled, bootconsole disabled [ 0.236000] loop: module loaded [ 0.237000] brcmstb_nand: NAND controller driver is loaded [ 0.238000] tun: Universal TUN/TAP device driver, 1.6 [ 0.239000] tun: (C) 1999-2004 Max Krasnyansky [ 0.361000] bcmgenet: configuring instance #0 for internal PHY [ 0.381000] bcmgenet bcmgenet.0: (unregistered net_device): link down [ 0.382000] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver [ 0.383000] ehci-brcm ehci-brcm.0: Broadcom STB EHCI [ 0.383000] ehci-brcm ehci-brcm.0: new USB bus registered, assigned bus numbe r 1 [ 0.415000] ehci-brcm ehci-brcm.0: irq 66, io mem 0x10480300 [ 0.421000] ehci-brcm ehci-brcm.0: USB 0.0 started, EHCI 1.00 [ 0.422000] hub 1-0:1.0: USB hub found [ 0.422000] hub 1-0:1.0: 2 ports detected [ 0.423000] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver [ 0.424000] ohci-brcm ohci-brcm.0: Broadcom STB OHCI [ 0.424000] ohci-brcm ohci-brcm.0: new USB bus registered, assigned bus numbe r 2 [ 0.425000] ohci-brcm ohci-brcm.0: irq 67, io mem 0x10480400 [ 0.491000] hub 2-0:1.0: USB hub found [ 0.491000] hub 2-0:1.0: 2 ports detected [ 0.492000] Initializing USB Mass Storage driver... [ 0.493000] usbcore: registered new interface driver usb-storage [ 0.494000] USB Mass Storage support registered. [ 0.494000] i2c /dev entries driver [ 0.495000] lirc_dev: IR Remote Control driver registered, major 253 [ 0.495000] usbcore: registered new interface driver btusb [ 0.497000] TCP: cubic registered [ 0.497000] NET: Registered protocol family 10 [ 0.499000] NET: Registered protocol family 17 [ 0.499000] Bluetooth: RFCOMM TTY layer initialized [ 0.500000] Bluetooth: RFCOMM socket layer initialized [ 0.501000] Bluetooth: RFCOMM ver 1.11 [ 0.501000] Bluetooth: BNEP (Ethernet Emulation) ver 1.3 [ 0.502000] Bluetooth: BNEP filters: protocol multicast [ 0.502000] Bluetooth: HIDP (Human Interface Emulation) ver 1.2 [ 0.503000] L2TP core driver, V2.0 [ 0.503000] Registering the dns_resolver key type [ 0.504000] PM: CP0 COUNT/COMPARE frequency does not depend on divisor [ 0.506000] EBI CS2: setting up NAND flash (primary) [ 0.507000] NAND device: Manufacturer ID: 0xc8, Chip ID: 0xdc (Unknown NAND 5 12MiB 3,3V 8-bit) [ 0.508000] brcmnand brcmnand.0: 512MiB total, 128KiB blocks, 2KiB pages, 16B OOB, 8-bit, BCH-4 [ 0.508000] [ 0.510000] Bad block table found at page 262080, version 0x01 [ 0.511000] Bad block table found at page 262016, version 0x01 [ 0.512000] nand_read_bbt: bad block at 0x000004e00000 [ 0.513000] nand_read_bbt: bad block at 0x00000f420000 [ 0.513000] nand_read_bbt: bad block at 0x00001ad60000 [ 0.515000] Creating 3 MTD partitions on "brcmnand.0": [ 0.515000] 0x000000000000-0x00001f500000 : "rootfs" [ 0.517000] 0x000000000000-0x000020000000 : "entire_device" [ 0.519000] 0x00001f500000-0x00001fa00000 : "kernel" [ 0.521000] EBI CS0: setting up SPI flash [ 0.521000] spi_brcmstb spi_brcmstb.0: 1-lane output, 3-byte address [ 0.522000] spi_brcmstb spi_brcmstb.0: master is unqueued, this is deprecated [ 0.523000] m25p80 spi0.0: found mx25l12805d, expected w25q128bv [ 0.524000] m25p80 spi0.0: mx25l12805d (16384 Kbytes) [ 0.526000] UBI: attaching mtd0 to ubi0 [ 0.526000] UBI: physical eraseblock size: 131072 bytes (128 KiB) [ 0.527000] UBI: logical eraseblock size: 126976 bytes [ 0.528000] UBI: smallest flash I/O unit: 2048 [ 0.528000] UBI: VID header offset: 2048 (aligned 2048) [ 0.529000] UBI: data offset: 4096 [ 3.669000] UBI: max. sequence number: 12023 [ 3.685000] UBI: attached mtd0 to ubi0 [ 3.686000] UBI: MTD device name: "rootfs" [ 3.686000] UBI: MTD device size: 501 MiB [ 3.687000] UBI: number of good PEBs: 4005 [ 3.688000] UBI: number of bad PEBs: 3 [ 3.688000] UBI: number of corrupted PEBs: 0 [ 3.689000] UBI: max. allowed volumes: 128 [ 3.689000] UBI: wear-leveling threshold: 4096 [ 3.690000] UBI: number of internal volumes: 1 [ 3.690000] UBI: number of user volumes: 1 [ 3.691000] UBI: available PEBs: 0 [ 3.691000] UBI: total number of reserved PEBs: 4005 [ 3.692000] UBI: number of PEBs reserved for bad PEB handling: 40 [ 3.693000] UBI: max/mean erase counter: 7/3 [ 3.693000] UBI: image sequence number: 1978818021 [ 3.694000] UBI: background thread "ubi_bgt0d" started, PID 54 [ 3.696000] ALSA device list: [ 3.696000] No soundcards found. [ 3.795000] UBIFS: mounted UBI device 0, volume 0, name "dreambox-rootfs" [ 3.796000] UBIFS: file system size: 501555200 bytes (489800 KiB, 478 MiB, 3950 LEBs) [ 3.797000] UBIFS: journal size: 9023488 bytes (8812 KiB, 8 MiB, 72 LEB s) [ 3.798000] UBIFS: media format: w4/r0 (latest is w4/r0) [ 3.798000] UBIFS: default compressor: lzo [ 3.799000] UBIFS: reserved for root: 0 bytes (0 KiB) [ 3.801000] VFS: Mounted root (ubifs filesystem) on device 0:12. [ 3.805000] devtmpfs: mounted [ 4.241000] systemd[1]: systemd 230 running in system mode. (-PAM -AUDIT -SEL INUX -IMA -APPARMOR -SMACK +SYSVINIT +UTMP -LIBCRYPTSETUP -GCRYPT +GNUTLS +ACL + XZ -LZ4 -SECCOMP +BLKID -ELFUTILS +KMOD -IDN) [ 4.244000] systemd[1]: Detected architecture mips. Welcome to opendreambox 2.5.0! [ 4.256000] systemd[1]: Set hostname to . [ 4.398000] systemd-fstab-generator[60]: Checking was requested for "rootfs", but it is not a device. [ 4.998000] systemd[1]: Created slice User and Session Slice. [ OK ] Created slice User and Session Slice. [ 5.002000] systemd[1]: Reached target Remote File Systems. [ OK ] Reached target Remote File Systems. [ 5.008000] systemd[1]: Listening on udev Control Socket. [ OK ] Listening on udev Control Socket. [ 5.012000] systemd[1]: Listening on udev Kernel Socket. [ OK ] Listening on udev Kernel Socket. [ 5.015000] systemd[1]: Reached target Swap. [ OK ] Reached target Swap. [ 5.018000] systemd[1]: Listening on Journal Socket. [ OK ] Listening on Journal Socket. [ 5.021000] systemd[1]: Created slice System Slice. [ OK ] Created slice System Slice. [ 5.024000] systemd[1]: Reached target Slices. [ OK ] Reached target Slices. [ 5.048000] systemd[1]: Starting Load Kernel Modules... Starting Load Kernel Modules... [ 5.060000] systemd[1]: Starting Generate environment for enigma2... Starting Generate environment for enigma2... [ 5.072000] systemd[1]: Starting Remount Root and Kernel File Systems... Starting Remount Root and Kernel File Systems... [ 5.084000] systemd[1]: Starting Setup Virtual Console... Starting Setup Virtual Console... [ 5.087000] systemd[1]: Created slice system-serial\x2dgetty.slice. [ OK ] Created slice system-serial\x2dgetty.slice. [ 5.108000] systemd[1]: Starting Create list of required static device nodes for the current kernel... Starting Create list of required st... nodes for the current kernel... [ 5.331000] systemd[1]: Reached target Paths. [ OK ] Reached target Paths. [ 5.351000] systemd[1]: Mounting POSIX Message Queue File System... Mounting POSIX Message Queue File System... [ 5.360000] systemd[1]: Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket (/dev/log). [ 5.379000] systemd[1]: Starting Journal Service... Starting Journal Service... [ 5.382000] stb_core: module license 'Proprietary' taints kernel. [ 5.383000] Disabling lock debugging due to kernel taint [ 5.391000] systemd[1]: Mounted POSIX Message Queue File System. [ OK ] Mounted POSIX Message Queue File System. [ 5.416000] systemd[1]: Started Remount Root and Kernel File Systems. [ OK ] Started Remount Root and Kernel File Systems. [ 5.425000] systemd[1]: Started Setup Virtual Console. [ OK ] Started Setup Virtual Console. [ 5.433000] systemd[1]: Started Create list of required static device nodes f or the current kernel. [ OK ] Started Create list of required sta...ce nodes for the current kernel. [ 5.453000] input: dreambox remote control (native) as /devices/virtual/input /input0 [ 5.455000] input: dreambox advanced remote control (native) as /devices/virt ual/input/input1 [ 5.473000] systemd[1]: Starting Create Static Device Nodes in /dev... Starting Create Static Device Nodes in /dev... [ 5.495000] systemd[1]: Starting udev Coldplug all Devices... Starting udev Coldplug all Devices... [ 5.761000] usb 1-2: new high-speed USB device number 2 using ehci-brcm [ 5.878000] scsi0 : usb-storage 1-2:1.0 [ 6.593000] bcm73625: xvd 20160630, vdc 20160630, rap 20160630, xpt 20160630, driver version 20171021-dm520 [ 6.607000] 6:606975737 SMCA: controller reset timed out [ 6.875000] systemd[1]: Started Create Static Device Nodes in /dev. [ OK ] Started Create Static Device Nodes in /dev. [ 6.894000] systemd[1]: Starting udev Kernel Device Manager... [ 6.896000] scsi 0:0:0:0: Direct-Access Freecom Mobile Drive XXS PQ : 0 ANSI: 2 CCS Starting udev Kernel Device Manager... [ 6.900000] systemd[1]: Reached target Local File Systems (Pre). [ 6.902000] sd 0:0:0:0: [sda] 1465149168 512-byte logical blocks: (750 GB/698 GiB) [ 6.904000] sd 0:0:0:0: [sda] Write Protect is off [ OK ] Reached target Local File Systems (Pre). [ 6.906000] sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, doe sn't support DPO or FUA [ 6.924000] systemd[1]: Mounting /tmp... Mounting /tmp... [ 6.933000] systemd[1]: Mounting /var/volatile... Mounting /var/volatile... [ 6.938000] sda: sda1 [ 6.942000] systemd[1]: Started Journal Service. [ OK ] Started Journal Service. [ 6.946000] sd 0:0:0:0: [sda] Attached SCSI disk Starting Flush Journal to Persistent Storage... [ OK ] Mounted /tmp. [ OK ] Mounted /var/volatile. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. [ OK ] Started udev Coldplug all Devices. [ OK ] Started Load/Save Random Seed. [ OK ] Started Generate environment for enigma2. [ 7.313000] systemd-journald[84]: Received request to flush runtime journal f rom PID 1 [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started udev Kernel Device Manager. Starting Create Volatile Files and Directories... [ OK ] Found device /dev/sci0. [ OK ] Started Create Volatile Files and Directories. Starting Update UTMP about System Boot/Shutdown... [ OK ] Found device /dev/ttyS0. [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Found device /dev/tpm0. [ 8.375000] register adapter [ 8.377000] register adapter [ 8.383000] register adapter [ 8.388000] input: dreambox front panel as /devices/virtual/input/input2 [ 8.405000] OFFSET 1065886607 19589030 [ 8.405000] WKTMR 13 23661496 [ 8.405000] LAST WKTMR 732674083 [ 8.406000] HDMI: CEC start ... state is now 1 [ 8.417000] set input to 0 [ 8.418000] set fb to 2(-1) [ 8.424000] startvideomode '1080p50' read from /etc/videomode [ 8.425000] bcmfb: creating surface with pixel format 0x07e48888 (BPXL_eA8_R8_G8_B8) [ 8.460000] HDMI: now powered down [ 8.486000] HDMI: CEC Physical Addr 1.0.0.0 set from EDID [ 8.487000] HDMI: EDID read ok [ 8.487000] HDMI: initial mode set! [ 8.488000] using 50.00Hz as MonitorRefreshRate [ 8.488000] SET FORMAT: display[0], format='1080p50' [ 8.489000] HDMI mode [ 8.489000] use EDID to detect colorspace [ 8.489000] preferred colorspace: Itu_R_BT_709 [ 8.490000] using 8bit output [ 8.604000] HDMI: CEC use logical address 3 [ 8.605000] HDMI: CEC init completed... state is now 3! [ 8.733000] VIDEO0: enable deinterlacer [ 8.734000] VIDEO0: set fallback framerate to 60000 [ 8.734000] VIDEO0: set progressive override mode to 1 [ 8.735000] VIDEO0: set streamtype! [ 8.735000] -> set MPEG2 [ 8.736000] -> sw rave disabled! [ 8.746000] set ac3 to downmix [ 8.747000] set wmapro to downmix [ 8.747000] set ac3plus to use_hdmi_caps [ 8.748000] set dtshd to force_dts [ 8.748000] set aac to downmix [ 8.749000] DVB: registering new adapter (BCM73625) [ 8.749000] HDMI: CEC send ok! [ OK ] Found device /dev/dvb/adapter0/ca0. [ 8.801000] Initializing adapter 0 (BCM73625 (G3)-DVB-S2) [ 8.802000] bcmast: init frontend core... [ OK ] Found device /dev/dvb/adapter0/demux0. [ 8.830000] DVB: registering adapter 0 frontend 0 (BCM73625 (G3))... [ 8.831000] Initializing adapter 1 (ATBM781x-(null)) [ OK ] Found device /dev/dvb/adapter0/video0. [ 8.891000] DVB: registering adapter 0 frontend 1 (ATBM781X DVB-C)... [ 8.894000] CI stopped... [ 8.895000] init ALSA! Starting Dreambox Bootlogo... [ 8.902000] registered vtuner misc/vtuner0, minor 54 [ 8.903000] registered vtuner misc/vtuner1, minor 53 [ OK ] Reached target Sound Card. [ 8.939000] input: dreambox ir keyboard as /devices/virtual/input/input3 [ 8.941000] input: dreambox ir mouse as /devices/virtual/input/input4 [ OK ] Started Load Kernel Modules. Starting Apply Kernel Variables... [ OK ] Started Dreambox Bootlogo. [ OK ] Started Apply Kernel Variables. [ OK ] Reached target System Initial[ 8.996000] VIDEO0: set blank to 0 ization. [ 8.997000] sync0 is off so we enable it [ 8.997000] sync0: allocated pcroffset 0 [ 8.997000] sync0: playback! [ 8.997000] VIDEO0: total delay: 0 (0 ms) [ OK ] Listening on dbttcd.socket. [ 9.006000] VIDEO0: start decode [ 9.008000] VIDEO0: flush video decoder [ 9.009000] VIDEO0 got pts failed !!!! (ret 0) [ OK ] Listening on busybox-telnetd.socket. [ OK ] Listening on streamproxy.socket. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Listening on dccamd.socket. [ 9.040000] VIDEO0: first pts 0x0 [ 9.040000] VIDEO0: framerate changed 3 [ 9.041000] VIDEO0: resolution changed (1280 720) [ 9.041000] VIDEO0: aspect changed 3 [ 9.043000] set current avs auto aspect to 16:9 [ 9.043000] set current avs auto aspect to 16:9 [ 9.044000] set sb to 3(1) [ OK ] Listening on dropbear.socket. [ OK ] Listening on Avahi mDNS/DNS-SD Stack Activation Socket. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Reached target Timers. [ OK ] Listening on vsftpd.socket. [ OK ] Listening on tpmd.socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. Starting dbttcd.service... Starting dccamd.service... Starting Login Service... [ OK ] Started D-Bus System Message Bus. [ 9.170000] VIDEO0: stop decode [ 9.171000] sync0 1fff no decoder left in sync handling so disable [ 9.171000] sync0: was last pcroffset0 user so free it Starting Connection service... [ OK ] Started Serial Getty on ttyS0. [ OK ] Reached target Login Prompts. Starting enigma2... Starting tpmd.service... Starting Avahi mDNS/DNS-SD Stack... [ OK ] Started dccamd.service. [ OK ] Started Login Service. [ OK ] Started Avahi mDNS/DNS-SD Stack. [ OK ] Started Connection service. Starting Wait for network to be configured by ConnMan... [ OK ] Reached target Network. Starting Samba NMB Daemon... Starting WPA supplicant... [ OK ] Started tpmd.service. Starting Hostname Service... [ 11.339000] ADDRCONF(NETDEV_UP): eth0: link is not ready [ OK ] Started WPA supplicant. [ OK ] Started Hostname Service. [ OK ] Started dbttcd.service. [ 12.893000] bcmgenet bcmgenet.0: eth0: link up, 100 Mbps, full duplex [ 12.894000] ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready [ OK ] Started Wait for network to be configured by ConnMan. [ OK ] Reached target Network is Online. Starting Automounts filesystems on demand... [ OK ] Started Automounts filesystems on demand. [ OK ] Started Samba NMB Daemon. Starting Samba SMB Daemon... opendreambox 2.5.0 dm520 ttyS0 dm520 login: [ 19.422000] bcmast: channel 0 is in normal mode now [ 19.429000] bcmast: channel 0 is sleeping now [ 19.455000] HDMI: CEC device open ... state is 3, flushed 0 old messages from RX fifo [ 19.458000] HDMI: CEC set devtype to 0 [ 19.458000] HDMI: CEC start ... state is now 1 [ 19.460000] HDMI: CEC returned state 1 [ 19.461000] HDMI: CEC state now 801 [ 19.522000] HDMI: CEC use logical address 3 [ 19.522000] HDMI: CEC init completed... state is now 3! [ 19.659000] HDMI: CEC send ok! [ 19.778000] bcmfb: creating surface with pixel format 0x07c68888 (BPXL_eA8_B8_G8_R8) [ 23.922000] systemd-fstab-generator[237]: Checking was requested for "rootfs", but it is not a device. [ 33.487000] set input to 0 [ 33.487000] set fb to 2(-1) [ 33.487000] set current avs auto aspect to 16:9 [ 33.488000] set sb to 3(1) [ 33.490000] set ac3 to downmix [ 33.492000] set ac3plus to use_hdmi_caps [ 40.471000] set current avs auto aspect to 16:9 [ 40.472000] set sb to 3(1) [ 40.478000] set current avs auto aspect to 16:9 [ 40.479000] set sb to 3(1) [ 40.480000] set current avs auto aspect to 16:9 [ 40.481000] set sb to 3(1) [ 40.483000] set current avs auto aspect to 16:9 [ 40.484000] set sb to 3(1) [ 40.485000] set current avs auto aspect to 16:9 [ 40.485000] set sb to 3(1) [ 40.486000] set current avs auto aspect to 16:9 [ 40.486000] set sb to 3(1) [ 40.489000] set current avs auto aspect to 16:9 [ 40.489000] set sb to 3(1) [ 40.490000] set current avs auto aspect to 16:9 [ 40.491000] set sb to 3(1) [ 40.491000] set current avs auto aspect to 16:9 [ 40.492000] set sb to 3(1) [ 40.494000] set current avs auto aspect to 16:9 [ 40.495000] set sb to 3(1) [ 40.495000] set current avs auto aspect to 16:9 [ 40.496000] set sb to 3(1) [ 40.504000] set current avs auto aspect to 16:9 [ 40.505000] set sb to 3(1) [ 40.505000] set current avs auto aspect to 16:9 [ 40.506000] set sb to 3(1) [ 40.507000] set current avs auto aspect to 16:9 [ 40.508000] set sb to 3(1) [ 43.017000] set mute 0 1 [ 43.187000] bcmast: channel 0 is in normal mode now [ 43.214000] al8304: static current limiting is enabled for 11ms only [ 43.260000] start PCR0 on dmx 0 pid:1523 index 3 [ 43.265000] start AUDIO0 on dmx 0 pid:1524 index 1 [ 43.266000] audio ch0: xpt start [ 43.266000] audio ch0: reset GA threshold to 8ms [ 43.267000] sync0 is off so we enable it [ 43.267000] sync0: allocated pcroffset 0 [ 43.268000] sync0: demux-index 0 pcr pid 1523 [ 43.268000] sync0: using 3 as pcr pidchannel [ 43.269000] audio ch0: MPEG [ 43.269000] audio ch0: streamtype set 0 [ 43.270000] audio ch0: streamtype_set: stop rave [ 43.270000] audio ch0: add stereo input to mixer DAC [ 43.271000] audio ch0: add stereo input to mixer SPDIF [ 43.271000] audio ch0: add stereo input to mixer MAI [ 43.272000] audio ch0: mai sample rate changed isr (48000) [ 43.273000] audio ch0: streamtype_set: restart rave [ 43.274000] audio ch0: decoder start [ 43.275000] audio ch0: video delay 0 (0 ms) [ 43.275000] VIDEO0: total delay: 0 (0 ms) [ 43.278000] VIDEO0: set streamtype! [ 43.278000] -> set H264 [ 43.278000] -> sw rave disabled! [ 43.279000] audio ch0 delay 18000 (200 ms) [ 43.279000] VIDEO0: total delay: 18000 (200 ms) [ 43.281000] start VIDEO0 on dmx 0 pid:1523 index 3 [ 43.282000] VIDEO0: total delay: 18000 (200 ms) [ 43.290000] VIDEO0: start decode [ 43.421000] HDMI: CEC returned state 3 [ 43.422000] HDMI: CEC state now 803 [ 43.519000] audio ch0: first pts 0xe751a376, stc 0x2ff330, pcr invalid [ 43.541000] audio ch0: lock [ 43.542000] audio ch0: sample rate changed isr (48000) [ 43.543000] audio ch0: tsm fail [ 43.543000] audio ch0: pts error 0 PTS 0xe751a376, STC 0xe75195b0, type 0 [ 43.644000] sda: sda1 [ 43.851000] audio ch0: status ready isr [ 44.140000] VIDEO0: first pts 0xe753e33a [ 44.140000] VIDEO0: framerate changed 6 [ 44.141000] set current avs auto aspect to 16:9 [ 44.142000] set sb to 3(1) [ 44.307000] VIDEO0: enable tsm [ 46.528000] HDMI: CEC send ok! [ 46.704000] HDMI: CEC send ok! [ 48.639000] HDMI: CEC send ok! [ 49.071000] HDMI: CEC send ok! [ 49.230000] HDMI: CEC send ok! [ 49.328000] HDMI: CEC send ok! [ 49.551000] HDMI: CEC send ok! [ 69.424000] stop PCR0 on dmx 0 pid:1523 [ 69.425000] stop VIDEO0 on dmx 0 pid:1523 [ 69.427000] VIDEO0: stop decode [ 69.428000] VIDEO0: set blank to 1 [ 69.429000] audio ch0: decoder stop [ 69.450000] stop AUDIO0 on dmx 0 pid:1524 [ 69.450000] audio ch0: xpt stop [ 69.451000] sync0 1fff no decoder left in sync handling so disable [ 69.451000] sync0: was last pcroffset0 user so free it [ 69.518000] al8304: static current limiting is enabled for 11ms only [ 69.528000] start PCR0 on dmx 0 pid:17de index 3 [ 69.529000] start AUDIO0 on dmx 0 pid:17e8 index 1 [ 69.530000] audio ch0: xpt start [ 69.530000] sync0 is off so we enable it [ 69.531000] sync0: allocated pcroffset 0 [ 69.531000] sync0: demux-index 0 pcr pid 17de [ 69.532000] sync0: using 3 as pcr pidchannel [ 69.532000] audio ch0: MPEG [ 69.533000] audio ch0: decoder start [ 69.534000] audio ch0 delay 18000 (200 ms) [ 69.534000] VIDEO0: total delay: 18000 (200 ms) [ 69.543000] start VIDEO0 on dmx 0 pid:17de index 3 [ 69.543000] VIDEO0: total delay: 18000 (200 ms) [ 69.551000] VIDEO0: start decode [ 69.829000] audio ch0: first pts 0x1ddd880e0, stc 0x1ddd86d42, pcr valid [ 69.851000] audio ch0: lock [ 69.852000] audio ch0: sample rate changed isr (48000) [ 70.129000] audio ch0: status ready isr [ 70.220000] VIDEO0: first pts 0x1ddd9bcac [ 70.371000] VIDEO0: enable tsm [ 74.732000] set mute 1 1 [ 74.780000] stop PCR0 on dmx 0 pid:17de [ 74.784000] stop VIDEO0 on dmx 0 pid:17de [ 74.786000] VIDEO0: stop decode [ 74.791000] audio ch0: decoder stop [ 74.798000] stop AUDIO0 on dmx 0 pid:17e8 [ 74.799000] audio ch0: xpt stop [ 74.799000] sync0 1fff no decoder left in sync handling so disable [ 74.800000] sync0: was last pcroffset0 user so free it [ 74.811000] set input to 1 [ 74.812000] set fb to 2(0) [ 74.840000] set sb to 3(0) [ 75.003000] HDMI: CEC send ok! [ 75.120000] HDMI: CEC send ok! [ 78.677000] set input to 0 [ 78.677000] set fb to 2(-1) [ 78.678000] using 50.00Hz as MonitorRefreshRate [ 78.678000] SET FORMAT: display[0], format='1080p50' [ 78.679000] HDMI mode [ 78.679000] use EDID to detect colorspace [ 78.680000] preferred colorspace: Itu_R_BT_709 [ 78.680000] using 8bit output [ 78.822000] set current avs auto aspect to 16:9 [ 78.823000] set mute 0 0 [ 78.824000] set sb to 3(1) [ 78.880000] al8304: static current limiting is enabled for 11ms only [ 78.889000] start PCR0 on dmx 0 pid:17de index 3 [ 78.891000] start AUDIO0 on dmx 0 pid:17e8 index 1 [ 78.891000] audio ch0: xpt start [ 78.892000] sync0 is off so we enable it [ 78.892000] sync0: allocated pcroffset 0 [ 78.893000] sync0: demux-index 0 pcr pid 17de [ 78.894000] sync0: using 3 as pcr pidchannel [ 78.894000] audio ch0: MPEG [ 78.895000] audio ch0: decoder start [ 78.896000] audio ch0 delay 18000 (200 ms) [ 78.896000] VIDEO0: total delay: 18000 (200 ms) [ 78.901000] start VIDEO0 on dmx 0 pid:17de index 3 [ 78.902000] VIDEO0: total delay: 18000 (200 ms) [ 78.910000] VIDEO0: start decode [ 79.075000] HDMI: CEC send ok! [ 79.188000] HDMI: CEC send ok! [ 79.189000] audio ch0: first pts 0x1dde55b80, stc 0x1dde547ca, pcr valid [ 79.211000] audio ch0: lock [ 79.212000] audio ch0: sample rate changed isr (48000) [ 79.270000] HDMI: CEC send ok! [ 79.490000] audio ch0: status ready isr [ 79.601000] VIDEO0: first pts 0x1dde69e54 [ 79.751000] VIDEO0: enable tsm