BCM74130011 012 K1 K2 K3 K4 K5 Shmoo Version=3.8 DDR Freq=0x0000018C %00000001% RC1=00000005 WC1=FFFFFFEE RC2=00000013 WC2=0000003A RC3=0000000C WC3=FFFFFFEE RC4=0000000C WC4=0000003A NWC=00000014 RC5=00000005 WC5=00000014 RC6=00000012 WC6=00000014 NRC=0000000B RW=0000000E WW=0000004D G=00000000 R=0000000B W=00000014 BL=00000000 RC1=00000002 RC2=00000015 BL=00000001 RC1=00000005 RC2=00000019 BL=00000002 RC1=00000002 RC2=00000010 BL=00000003 RC1=00000005 RC2=00000011 BL=00000004 RC1=00000000 RC2=00000010 BL=00000005 RC1=00000006 RC2=00000018 BL=00000006 RC1=00000004 RC2=00000015 BL=00000007 RC1=00000006 RC2=00000019 3 COPY CODE COMPARE CODE 4567 (c) 2007,2008 Dream Multimedia GmbH. All rights reserved. Dreambox DM7020HD FIRST STAGE 1.1/400 {BO23456} 2ND STAGE OK, build #89 (2015-03-09) 0.000 - BCM board setup 0.002 - fp init FP version 6 detected 0.003 - ca init 0.503 - load config 0.529 - config loaded. * press [S] to enter setup OLED found! lcd: BOOT #89 P23 detected! detected 4K pagesize, 256K blocksize - read config file: /boot/secondstage.conf detected jffs2 blocksize: 256kb +++++++++++++UUUUUUUUUUUUUUU dreamupd initialized Scanning JFFS2 FS: . | / - \ | / - \ | / - \ done. find_inode failed for name=secondstage.conf load: Failed to find inode - failed to open /boot/secondstage.conf - failed to open /cf/autorun.bat lcd: /boot/bootlogo-dm7020hd.elf.gz bootlogo size: 35879 Supported modes: NTSC !NTSC_J !NTSC_443 !PAL_B !PAL_B1 !PAL_D !PAL_D1 PAL_G !PAL_H !PAL_K !PAL_I !PAL_M !PAL_N !PAL_NC !PAL_60 !SECAM_L !SECAM_B !SECAM_G !SECAM_D !SECAM_K !SECAM_H 1080i !1080p 720p 480p 1080i_50Hz !1080p_50Hz !1250i_50Hz 720p_50Hz 576p_50Hz DVI_640x480p !DVI_640x480p_CVT !DVI_1280x720p_50Hz !DVI_1280x720p !DVI_1280x720p_Red !DVI_640x480p_66Hz !DVI_640x480p_70Hz !DVI_640x480p_72Hz !DVI_640x480p_75Hz !DVI_640x480p_85Hz !DVI_1280x720p_70Hz !DVI_1280x720p_72Hz !DVI_1280x720p_75Hz !DVI_1280x720p_85Hz !DVI_1920x1080p_60Hz_Red !Custom0 !Custom1 best supported mode is 25 using 1920x1080i (50.00 Hz) HDMI using colorspace: 1 create surface 1920 1080 Using the Small jpeg decoder library, Copyright (c) 2006, Luc Saillard parsing JPEG header.. Virtual FB Size: 1280x720 lcd: /boot/vmlinux-3.2-dm7020hd.gz [ 0.000000] Linux version 3.2-dm7020hd (newnigma2@nn2build.newnigma2.to) (gcc version 4.6.4 20120303 (prerelease) (GCC) ) #1 SMP Wed Mar 18 08:30:59 CET 2015 [ 0.000000] Fetching vars from bootloader... [ 0.000000] Early serial console at MMIO32 0x10400b00 (options '1000000n8') [ 0.000000] bootconsole [uart0] enabled [ 0.000000] Options: sata=1 enet=1 emac_1=0 no_mdio=0 docsis=0 pci=1 smp=1 moca=0 usb=1 [ 0.000000] Using 512 MB RAM (from straps) [ 0.000000] Fetch var 'memsize' = '64' [ 0.000000] CPU revision is: 0002a044 (Brcm4380) [ 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 58 MB LINUX region at 5 MB (0x03a06000@0x005fa000) [ 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] Zone PFN ranges: [ 0.000000] Normal 0x00000000 -> 0x00030000 [ 0.000000] Movable zone start PFN for each node [ 0.000000] early_node_map[2] active PFN ranges [ 0.000000] 0: 0x00000000 -> 0x00010000 [ 0.000000] 0: 0x00020000 -> 0x00030000 [ 0.000000] On node 0 totalpages: 131072 [ 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 @81407000 s5440 r8192 d15040 u32768 [ 0.000000] pcpu-alloc: s5440 r8192 d15040 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: ubi.mtd=root root=ubi0:rootfs rootfstype=ubifs rw console=ttyS0,115200 debug bmem=192M@64M [ 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: 316976k/524288k available (4755k kernel code, 207312k reserved, 1008k data, 224k init, 0k highmem) [ 0.000000] Hierarchical RCU implementation. [ 0.000000] NR_IRQS:128 [ 0.000000] Measuring MIPS counter frequency... [ 0.000000] Detected MIPS clock frequency: 405 MHz (202.508 MHz counter) [ 0.000000] Console: colour dummy device 80x25 [ 0.000000] Calibrating delay loop... 403.45 BogoMIPS (lpj=201728) [ 0.011000] pid_max: default: 32768 minimum: 301 [ 0.012000] Mount-cache hash table entries: 512 [ 0.014000] SMP: Booting CPU1... [ 0.015000] Primary instruction cache 32kB, VIPT, 2-way, linesize 64 bytes. [ 0.015000] Primary data cache 64kB, 4-way, VIPT, cache aliases, linesize 64 bytes [ 0.016000] CPU revision is: 0002a044 (Brcm4380) [ 0.016000] FPU revision is: 00130001 [ 0.026000] SMP: CPU1 is running [ 0.026000] Brought up 2 CPUs [ 0.027000] devtmpfs: initialized [ 0.031000] NET: Registered protocol family 16 [ 0.033000] PCI2.3->SDRAM window: 512 MB [ 0.040000] bio: create slab at 0 [ 0.042000] SCSI subsystem initialized [ 0.042000] libata version 3.00 loaded. [ 0.043000] usbcore: registered new interface driver usbfs [ 0.043000] usbcore: registered new interface driver hub [ 0.044000] usbcore: registered new device driver usb [ 0.045000] Advanced Linux Sound Architecture Driver Version 1.0.24. [ 0.046000] pci 0000:01:00.0: [14e4:8602] type 0 class 0x000101 [ 0.047000] PCI: found device 14e4:8602 on SATA bus, slot 0 (irq 42) [ 0.047000] pci 0000:01:00.0: reg 24: [mem 0x00000000-0x00000fff] [ 0.048000] pci 0000:01:00.0: reg 30: [mem 0x00000000-0x000007ff pref] [ 0.049000] pci 0000:01:00.0: BAR 5: assigned [mem 0x10510000-0x10510fff] [ 0.050000] pci 0000:01:00.0: BAR 5: set to [mem 0x10510000-0x10510fff] (PCI address [0x10510000-0x10510fff]) [ 0.051000] pci 0000:01:00.0: BAR 6: assigned [mem 0x10511000-0x105117ff pref] [ 0.052000] Switching to clocksource upg [ 0.065000] NET: Registered protocol family 2 [ 0.097000] IP route cache hash table entries: 4096 (order: 2, 16384 bytes) [ 0.099000] TCP established hash table entries: 16384 (order: 5, 131072 bytes) [ 0.101000] TCP bind hash table entries: 16384 (order: 5, 131072 bytes) [ 0.102000] TCP: Hash tables configured (established 16384 bind 16384) [ 0.103000] TCP reno registered [ 0.104000] UDP hash table entries: 256 (order: 1, 8192 bytes) [ 0.105000] UDP-Lite hash table entries: 256 (order: 1, 8192 bytes) [ 0.106000] NET: Registered protocol family 1 [ 0.107000] RPC: Registered named UNIX socket transport module. [ 0.108000] RPC: Registered udp transport module. [ 0.108000] RPC: Registered tcp transport module. [ 0.109000] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.110000] PCI: CLS 0 bytes, default 64 [ 0.114000] JFFS2 version 2.2. (NAND) (SUMMARY) © 2001-2006 Red Hat, Inc. [ 0.115000] SGI XFS with security attributes, large block/inode numbers, no debug enabled [ 0.118000] msgmni has been set to 619 [ 0.119000] io scheduler noop registered [ 0.120000] io scheduler cfq registered (default) [ 0.121000] Serial: 8250/16550 driver, 4 ports, IRQ sharing disabled ÿ[ 0.143000] serial8250.0: ttyS0 at MMIO 0x10400b00 (irq = 22) is a 16550A [ 0.144000] console [ttyS0] enabled, bootconsole disabled [ 0.144000] console [ttyS0] enabled, bootconsole disabled [ 0.166000] serial8250.0: ttyS1 at MMIO 0x10400b80 (irq = 67) is a 16550A [ 0.169000] loop: module loaded [ 0.169000] sata_brcmstb 0000:01:00.0: version 4.0 [ 0.170000] brcm-pm: enabling sata clocks [ 0.171000] PCI: Enabling device 0000:01:00.0 (0000 -> 0003) [ 0.171000] PCI: Setting latency timer of device 0000:01:00.0 to 64 [ 0.369000] scsi0 : sata_brcmstb [ 0.370000] scsi1 : sata_brcmstb [ 0.371000] ata1: SATA max UDMA7 mmio m4096@0x10510000 port 0x10510000 irq 42 [ 0.372000] ata2: SATA max UDMA7 mmio m4096@0x10510000 port 0x10510100 irq 42 [ 0.374000] Broadcom STB NAND controller (BrcmNand Controller) [ 0.374000] bcmemac: Broadcom STB 10/100 EMAC driver v3.0 [ 0.378000] bcmemac: registered interface #0 at 0x10080000 as 'eth0' (00:09:34:2d:c6:b4) [ 0.379000] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver [ 0.380000] brcm-pm: enabling usb clocks [ 0.380000] ehci-brcm ehci-brcm.0: Broadcom STB EHCI [ 0.381000] ehci-brcm ehci-brcm.0: new USB bus registered, assigned bus number 1 [ 0.413000] ehci-brcm ehci-brcm.0: irq 62, io mem 0x10480300 [ 0.419000] ehci-brcm ehci-brcm.0: USB 0.0 started, EHCI 1.00 [ 0.420000] hub 1-0:1.0: USB hub found [ 0.421000] hub 1-0:1.0: 2 ports detected [ 0.421000] ehci-brcm ehci-brcm.1: Broadcom STB EHCI [ 0.422000] ehci-brcm ehci-brcm.1: new USB bus registered, assigned bus number 2 [ 0.454000] ehci-brcm ehci-brcm.1: irq 57, io mem 0x10480500 [ 0.460000] ehci-brcm ehci-brcm.1: USB 0.0 started, EHCI 1.00 [ 0.461000] hub 2-0:1.0: USB hub found [ 0.461000] hub 2-0:1.0: 2 ports detected [ 0.462000] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver [ 0.463000] ohci-brcm ohci-brcm.0: Broadcom STB OHCI [ 0.464000] ohci-brcm ohci-brcm.0: new USB bus registered, assigned bus number 3 [ 0.465000] ohci-brcm ohci-brcm.0: irq 63, io mem 0x10480400 [ 0.530000] hub 3-0:1.0: USB hub found [ 0.531000] hub 3-0:1.0: 2 ports detected [ 0.532000] ohci-brcm ohci-brcm.1: Broadcom STB OHCI [ 0.532000] ohci-brcm ohci-brcm.1: new USB bus registered, assigned bus number 4 [ 0.533000] ohci-brcm ohci-brcm.1: irq 64, io mem 0x10480600 [ 0.599000] hub 4-0:1.0: USB hub found [ 0.600000] hub 4-0:1.0: 2 ports detected [ 0.601000] Initializing USB Mass Storage driver... [ 0.602000] usbcore: registered new interface driver usb-storage [ 0.602000] USB Mass Storage support registered. [ 0.603000] i2c /dev entries driver [ 0.603000] ALSA device list: [ 0.604000] No soundcards found. [ 0.605000] TCP cubic registered [ 0.605000] NET: Registered protocol family 17 [ 0.606000] Registering the dns_resolver key type [ 0.606000] EBI CS1: setting up NAND flash (primary) [ 0.608000] mtd->oobsize=0, mtd->eccOobSize=0 [ 0.608000] NAND_CS_NAND_XOR=00000000 [ 0.609000] B4: NandSelect=40000202, nandConfig=48252300, chipSelect=1 [ 0.609000] brcmnand_read_id: CS1: dev_id=ecd310a6 [ 0.610000] After: NandSelect=40000202, nandConfig=48252300 [ 0.611000] Block size=00040000, erase shift=18 [ 0.611000] NAND Config: Reg=48252300, chipSize=1024 MB, blockSize=256K, erase_shift=12 [ 0.612000] busWidth=1, pageSize=4096B, page_shift=12, page_mask=00000fff [ 0.613000] timing1 not adjusted: 5363444f [ 0.613000] timing2 not adjusted: 00000fc6 [ 0.614000] eccLevel=15, 1Ksector=0, oob=16 [ 0.614000] nbrBitsPerCell=2, cellinfo=0, chip->cellinfo=00000000 [ 0.615000] <--brcmnand_set_acccontrol: acc b4: f7ff1010, after: f7ff1010 [ 0.616000] BrcmNAND mfg ec d3 Samsung K9F8G08U0M 1024MB on CS1 [ 0.617000] [ 0.617000] Found NAND on CS1: ACC=f7ff1010, cfg=48252300, flashId=ecd310a6, tim1=5363444f, tim2=00000fc6 [ 0.618000] BrcmNAND version = 0x0302 1024MB @00000000 [ 0.619000] B4: NandSelect=40000202, nandConfig=48252300, chipSelect=1 [ 0.619000] brcmnand_read_id: CS1: dev_id=ecd310a6 [ 0.620000] After: NandSelect=40000202, nandConfig=48252300 [ 0.621000] Found NAND flash on Chip Select 1, chipSize=1024MB, usable size=1024MB, base=0 [ 0.622000] brcmnand_scan: B4 nand_select = 40000202 [ 0.622000] brcmnand_scan: After nand_select = 40000202 [ 0.623000] page_shift=12, bbt_erase_shift=18, chip_shift=30, phys_erase_shift=18 [ 0.624000] Brcm NAND controller version = 3.2 NAND flash size 1024MB @00000000 [ 0.625000] ECC layout=brcmnand_oob_128 [ 0.625000] brcmnand_scan: mtd->oobsize=128 [ 0.626000] brcmnand_scan: oobavail=102, eccsize=512, writesize=4096 [ 0.626000] brcmnand_scan, eccsize=512, writesize=4096, eccsteps=8, ecclevel=15, eccbytes=3 [ 0.627000] -->brcmnand_default_bbt [ 0.628000] brcmnand_default_bbt: bbt_td = bbt_main_descr [ 0.629000] Bad block table Bbt0 found at page 0003ffc0, version 0x01 for chip on CS1 [ 0.631000] Bad block table 1tbB found at page 0003ff80, version 0x01 for chip on CS1 [ 0.632000] nand_read_bbt: Bad block at 0x03740000 [ 0.633000] nand_read_bbt: Bad block at 0x1e5c0000 [ 0.633000] nand_read_bbt: Bad block at 0x26000000 [ 0.634000] nand_read_bbt: Bad block at 0x2f840000 [ 0.634000] nand_read_bbt: Bad block at 0x3b200000 [ 0.635000] brcmnandCET: Did not find CET, recreating [ 0.641000] brcmnandCET: Status -> Deferred [ 0.642000] Adjust partition complete size from 40000000 to 3ff00000 to avoid overlap with BBT reserved space [ 0.643000] Adjust partition root size from 3f800000 to 3f700000 to avoid overlap with BBT reserved space [ 0.644000] Creating 4 MTD partitions on "brcmnand.0": [ 0.645000] 0x000000000000-0x00003ff00000 : "complete" [ 0.651000] 0x000000000000-0x000000100000 : "loader" [ 0.653000] 0x000000100000-0x000000800000 : "boot" [ 0.655000] 0x000000800000-0x00003ff00000 : "root" [ 0.663000] UBI: attaching mtd3 to ubi0 [ 0.663000] UBI: physical eraseblock size: 262144 bytes (256 KiB) [ 0.664000] UBI: logical eraseblock size: 253952 bytes [ 0.664000] UBI: smallest flash I/O unit: 4096 [ 0.665000] UBI: VID header offset: 4096 (aligned 4096) [ 0.666000] UBI: data offset: 8192 [ 0.679000] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 300) [ 0.738000] usb 1-1: new high-speed USB device number 2 using ehci-brcm [ 0.760000] ata1.00: ATA-8: Hitachi HTS545016B9A300, PBBOC64G, max UDMA/133 [ 0.761000] ata1.00: 312581808 sectors, multi 0: LBA48 NCQ (depth 0/32) [ 0.768000] ata1.00: configured for UDMA/133 [ 0.769000] scsi 0:0:0:0: Direct-Access ATA Hitachi HTS54501 PBBO PQ: 0 ANSI: 5 [ 0.771000] sd 0:0:0:0: [sda] 312581808 512-byte logical blocks: (160 GB/149 GiB) [ 0.773000] sd 0:0:0:0: [sda] Write Protect is off [ 0.773000] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00 [ 0.774000] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA [ 0.803000] sda: sda1 [ 0.806000] sd 0:0:0:0: [sda] Attached SCSI disk [ 1.100000] ata2: SATA link down (SStatus 4 SControl 300) [ 5.029000] UBI: max. sequence number: 7782 [ 5.050000] UBI: attached mtd3 to ubi0 [ 5.051000] UBI: MTD device name: "root" [ 5.051000] UBI: MTD device size: 1015 MiB [ 5.052000] UBI: number of good PEBs: 4055 [ 5.052000] UBI: number of bad PEBs: 5 [ 5.053000] UBI: number of corrupted PEBs: 0 [ 5.054000] UBI: max. allowed volumes: 128 [ 5.054000] UBI: wear-leveling threshold: 4096 [ 5.055000] UBI: number of internal volumes: 1 [ 5.055000] UBI: number of user volumes: 2 [ 5.056000] UBI: available PEBs: 0 [ 5.056000] UBI: total number of reserved PEBs: 4055 [ 5.057000] UBI: number of PEBs reserved for bad PEB handling: 40 [ 5.058000] UBI: max/mean erase counter: 42/2 [ 5.058000] UBI: image sequence number: 687950784 [ 5.059000] UBI: background thread "ubi_bgt0d" started, PID 52 [ 5.194000] UBIFS: mounted UBI device 0, volume 0, name "rootfs" [ 5.195000] UBIFS: file system size: 413941760 bytes (404240 KiB, 394 MiB, 1630 LEBs) [ 5.196000] UBIFS: journal size: 9404416 bytes (9184 KiB, 8 MiB, 38 LEBs) [ 5.197000] UBIFS: media format: w4/r0 (latest is w4/r0) [ 5.197000] UBIFS: default compressor: lzo [ 5.198000] UBIFS: reserved for root: 0 bytes (0 KiB) [ 5.201000] VFS: Mounted root (ubifs filesystem) on device 0:13. [ 5.205000] devtmpfs: mounted [ 5.206000] Freeing unused kernel memory: 224k freed INIT: version 2.88 booting Displaying bootlogo. Starting udev [ 5.940000] udevd[78]: starting version 182 [ 6.365000] stb_core: module license 'Proprietary' taints kernel. [ 6.366000] Disabling lock debugging due to kernel taint [ 8.233000] enter base init, xvd 20100413, vdc 20100413, rap 20100719, xpt 20110228 [ 8.699000] 8:698010801 SMCA: controller reset timed out [ 8.709000] 8:708012643 SMCB: controller reset timed out [ 9.340000] register adapter [ 9.341000] register adapter [ 9.342000] register adapter [ 9.348000] register adapter [ 9.364000] FP Version 6 [ 9.365000] input: dreambox front panel as /devices/virtual/input/input0 [ 9.372000] fpga init [ 9.375000] keys c7 [ 10.696000] done OK 0 [ 10.697000] using fpga version 47 [ 10.697000] tsmux set tsports reg 00 to val 00 [ 10.698000] tsmux set tsports reg 01 to val 01 [ 10.699000] tsmux set tsports reg 02 to val 02 [ 10.699000] tsmux set tsports reg 03 to val 03 [ 10.700000] tsmux set tsports reg 04 to val 04 [ 10.700000] tsmux set tsports reg 05 to val 05 [ 10.701000] tsmux set ciclk reg 34 to val 00 [ 10.701000] tsmux set cisource reg 06 to val 00 [ 10.702000] tsmux set ciclk reg 34 to val 00 [ 10.702000] tsmux set cisource reg 07 to val 01 [ 10.704000] set sb to 3(2) [ 10.704000] set fb to 2(-1) [ 10.705000] set input to 0 [ 10.705000] set fb to 2(-1) [ 10.708000] bcm_video_analog_port_setup (fmt=7) [ 10.709000] SET FORMAT: display[1], format=7 [ 10.710000] display 0 uses vbi path 1 [ 10.710000] display 1 uses vbi path 0 [ 10.711000] HDMI: now powered down [ 10.742000] HDMI: EDID read ok [ 10.743000] HDMI: initial mode set! [ 10.743000] using 50.00Hz as MonitorRefreshRate [ 10.744000] SET FORMAT: display[0], format=37 [ 10.744000] HDMI mode [ 10.744000] use EDID to detect colorspace [ 10.745000] using colorspace: 1 [ 10.777000] HDMI: use logical CEC address 3 [ 10.818000] set fb to 2(0) [ 10.818000] bcm_video_analog_port_setup (fmt=7) [ 10.819000] SET FORMAT: display[1], format=7 [ 10.826000] disable wss on display 0 [ 10.827000] disable wss on display 1 [ 10.827000] bcmfb: creating surface with pixel format 0x07e48888 (BPXL_eA8_R8_G8_B8) [ 10.914000] CEC send ok! [ 10.961000] enable mad [ 11.006000] VIDEO0: set fallback framerate to 60000 [ 11.007000] VIDEO0: set progressive override mode to 1 [ 11.008000] VIDEO0: set streamtype! [ 11.008000] set MPEG2 [ 11.009000] sw rave disabled! [ 11.012000] enable mad [ 11.013000] VIDEO1: set fallback framerate to 60000 [ 11.014000] VIDEO1: set progressive override mode to 1 [ 11.015000] VIDEO1: set streamtype! [ 11.015000] set MPEG2 [ 11.016000] sw rave disabled! [ 11.020000] sync is off so we enable it [ 11.021000] playback! [ 11.021000] total video delay: 0 (0 ms) [ 11.025000] VIDEO0: start decode [ 11.025000] flush video decoder [ 11.025000] VIDEO0 got pts failed !!!! (ret 0) [ 12.418000] set ac3 to downmix [ 12.419000] set ac3plus to use_hdmi_caps [ 12.419000] set aac to downmix [ 12.420000] init ALSA! [ 12.446000] VIDEO0: first pts 0x0 [ 12.477000] DVB: registering new adapter (BCM7405) [ 12.503000] autodetecting NIMs... [ 12.504000] slot 0 [ 12.505000] ret: 2, feid: 13 [ 12.505000] Found: Slot 0, ID: 13 (by CA) [ 12.506000] slot 1 [ 12.508000] ret: 2, feid: 13 [ 12.508000] Found: Slot 1, ID: 13 (by CA) [ 12.509000] Initializing adapter 0 (BCM4505-DVB-S2) [ 12.571000] bcm4506: found 4505, revision 0(b0) [ 12.571000] bcm4506: bcm_initAP [ 12.577000] bcm4506: upload & start firmware [ 12.597000] enable tsm0 [ 14.631000] bcm4506: bcm4506: ap not running - start now [ 14.679000] bcm4506: uCode 0x61 [ 14.696000] bcm4506: no bcm3445 detected [ 14.709000] DVB: registering adapter 0 frontend 0 (bcm4506)... [ 14.728000] Initializing adapter 2 (BCM4505-DVB-S2) [ 14.785000] bcm4506: found 4505, revision 0(b0) [ 14.785000] bcm4506: bcm_initAP [ 14.792000] bcm4506: upload & start firmware [ 16.841000] bcm4506: bcm4506: ap not running - start now [ 16.889000] bcm4506: uCode 0x61 [ 16.906000] bcm4506: no bcm3445 detected [ 16.919000] DVB: registering adapter 0 frontend 1 (bcm4506)... [ 18.168000] VIDEO0: stop decode [ 18.168000] no decoder left in sync handling so disable xpt_sync [ 18.180000] CI stopped... [ 18.182000] registered vtuner misc/vtuner0, minor 54 [ 18.183000] registered vtuner misc/vtuner1, minor 53 [ 18.184000] IR-TX init! [ 18.184000] CI stopped... [ 18.316000] VIDEO0: set blank to 0 [ 18.317000] sync is off so we enable it [ 18.317000] playback! [ 18.317000] total video delay: 0 (0 ms) [ 18.321000] VIDEO0: start decode [ 18.322000] flush video decoder [ 18.366000] VIDEO0: first pts 0x0 [ 18.366000] VIDEO0: framerate changed 3 [ 18.367000] VIDEO0: resolution changed (1280 720) [ 18.367000] VIDEO0: aspect changed 3 [ 18.426000] set current avs auto aspect to 16:9 [ 18.426000] disable wss on display 0 [ 18.427000] disable wss on display 1 [ 18.427000] set current avs auto aspect to 16:9 [ 18.428000] disable wss on display 0 [ 18.428000] disable wss on display 1 [ 18.429000] set sb to 3(1) [ 18.478000] VIDEO0: set blank to 0 [ 18.480000] VIDEO0: stop decode [ 18.480000] VIDEO0: set blank to 0 [ 18.480000] no decoder left in sync handling so disable xpt_sync [ 21.168000] input: dreambox remote control (native) as /devices/virtual/input/input1 [ 21.170000] input: dreambox advanced remote control (native) as /devices/virtual/input/input2 [ 21.179000] input: dreambox ir keyboard as /devices/virtual/input/input3 [ 21.185000] input: dreambox ir mouse as /devices/virtual/input/input4 [ 21.332000] NET: Registered protocol family 10 [ 21.339000] 4016306 ms: [ 21.432000] 93 ms: 12 mkdir /dev/shm..... [ 22.137000] UBIFS: mounted UBI device 0, volume 1, name "data" [ 22.138000] UBIFS: file system size: 599072768 bytes (585032 KiB, 571 MiB, 2359 LEBs) [ 22.139000] UBIFS: journal size: 29966336 bytes (29264 KiB, 28 MiB, 118 LEBs) [ 22.139000] UBIFS: media format: w4/r0 (latest is w4/r0) [ 22.140000] UBIFS: default compressor: lzo [ 22.141000] UBIFS: reserved for root: 4952683 bytes (4836 KiB) [ 22.506000] kjournald starting. Commit interval 5 seconds [ 22.508000] EXT3-fs (sda1): using internal journal [ 22.508000] EXT3-fs (sda1): mounted filesystem with writeback data mode [ 22.522000] 1090 ms: 14 [ 22.996000] 474 ms: 16 [ 23.050000] 54 ms: 18 [ 23.076000] 26 ms: 20 Configuring network interfaces... udhcpc (v1.19.4) started Sending discover... Sending select for 192.168.1.10... Lease of 192.168.1.10 obtained, lease time 43200 /etc/udhcpc.d/50default: Adding DNS 192.168.1.1 done. [ 24.976000] 1900 ms: 22 starting rdate timesync...: Done (time.fu-berlin.de) [ 25.169000] 193 ms: 24 [ 25.194000] 24 ms: 26 net.ipv4.conf.default.rp_filter = 1 net.ipv4.conf.all.rp_filter = 1 [ 25.243000] 48 ms: 28 Starting tpmd. [ 25.280000] 37 ms: 30 INIT: Entering runlevel: 3 Starting system message bus: dbus. [ 25.674000] 394 ms: 33 Starting Dropbear SSH server: dropbear. [ 25.742000] 68 ms: 34 Starting automounter: loading autofs4 kernel module, done. [ 26.659000] 917 ms: 35 starting Busybox Periodic Command Scheduler: crond... done. [ 26.712000] 53 ms: 36 crond[586]: crond: crond (busybox 1.19.4) started, log level 8 [ 26.771000] 59 ms: 37 Starting dbttcd. [ 26.798000] 27 ms: 38 [ 26.905000] 107 ms: 39 Starting default emu. [ 26.930000] 24 ms: 40 creating NFS state directory: done NFSD Modul loaded.... NFSD Mounted.... starting 8 nfsd kernel threads: [ 27.440000] Installing knfsd (copyright (C) 1996 okir@monad.swb.de). [ 27.531000] NFSD: Using /var/lib/nfs/v4recovery as the NFSv4 state recovery directory [ 27.533000] NFSD: starting 90-second grace period done starting mountd: done starting statd: done [ 28.016000] 1086 ms: 41 Starting syslogd/klogd: done [ 28.069000] 53 ms: 42 Starting internet superserver: xinetd. [ 28.140000] 71 ms: 43 * Starting Avahi mDNS/DNS-SD Daemon: avahi-daemon ...done. [ 28.490000] 350 ms: 44 cat: can't open '/proc/stb/lcd/xres': No such file or directory [ 32.851000] ------------[ cut here ]------------ [ 32.852000] WARNING: at fs/proc/generic.c:586 proc_register+0x1f0/0x2ac() [ 32.853000] proc_dir_entry 'stb/lcd' already registered [ 32.854000] Modules linked in: tft_dm7020hd(O+) nfsd autofs4 ipv6 dreambox_keyboard(P) dreambox_rc2(P) bcm7405(P) alps_bsbe2(P) snd_pcm snd_timer snd_page_alloc tu1216(O) cu1216mk3(P) alps_bsbe1_c01a(P) si2169c(P) si2166b(P) bcm4506(P) helper(O) cxd1981(P) alps_bsbe1(O) lnb_ctrl(P) stv0299 lcd(P) stb_core(P) [ 32.857000] Call Trace: [ 32.858000] [<8049d4b4>] dump_stack+0x8/0x34 [ 32.858000] [<8002eddc>] warn_slowpath_common+0x78/0xa4 [ 32.859000] [<8002ee90>] warn_slowpath_fmt+0x2c/0x38 [ 32.859000] [<80112ca0>] proc_register+0x1f0/0x2ac [ 32.860000] [<80113200>] proc_mkdir_mode+0x40/0x6c [ 32.861000] [] init_module+0x38/0x204 [tft_dm7020hd] [ 32.861000] [<80001570>] do_one_initcall+0x160/0x1f0 [ 32.862000] [<8006b2f8>] sys_init_module+0x1148/0x1e98 [ 32.863000] [<8000c85c>] stack_done+0x20/0x40 [ 32.863000] [ 32.863000] ---[ end trace 20518b0b3ce9dce9 ]--- [ 32.886000] 4395 ms: 45 * starting eDaemon: eDaemon... done. [ 32.939000] 53 ms: 46 [ 32.957000] procfile_read_xres called [ 32.958000] procfile_read_xres called [ 32.959000] procfile_read_yres called [ 32.960000] procfile_read_yres called [ 32.961000] procfile_read_bpp called [ 32.962000] procfile_read_bpp called [ 32.971000] eDaemon uses obsolete (PF_INET,SOCK_PACKET) [SCRIPT] start: OScam unstable #11234 [ 33.087000] 148 ms: 47 [ 33.098000] 10 ms: 48 [ 33.221000] VIDEO0: set blank to 0 [ 33.222000] sync is off so we enable it [ 33.223000] playback! [ 33.223000] total video delay: 0 (0 ms) [ 33.228000] VIDEO0: start decode [ 33.228000] flush video decoder [ 33.266000] VIDEO0: first pts 0x0 [ 33.385000] VIDEO0: set blank to 0 [ 33.387000] VIDEO0: stop decode [ 33.388000] VIDEO0: set blank to 0 [ 33.388000] no decoder left in sync handling so disable xpt_sync enigma2 is the main pvr application... adjust oom score! Enigma2 v4.0.0 (revision: tarball-20160616-0-g724eb6a, date: 2016-06-16) Enigma2 is property of Dream Multimedia GmbH. All copyrights with regard to Enigma2 belong to Dream Multimedia GmbH only. The full license text is available at /usr/share/doc/enigma2/LICENSE. PYTHONPATH: /usr/lib/enigma2/python default pipe size of 65536 bytes detected ---- opening lame channel db reading services (version 4) loaded 1490 services scanning for frontends.. opening frontend 0 [ 40.018000] bcm4506: channel 0 is in normale mode now detected satellite frontend (0)setTone Off (0)setVoltage 0V close frontend 0 opening frontend 1 [ 40.032000] bcm4506: channel 0 is sleeping now [ 40.032000] bcm4506: channel 0 is in normale mode now detected satellite frontend (1)setTone Off (1)setVoltage 0V close frontend 1 scanning for demux.. scanning for decoders.. scanning for descramblers.. [ 40.045000] bcm4506: channel 0 is sleeping now found 1 adapter, 2 frontends(2 sim) and 5 demux, boxtype 5 + (8) graphics acceleration manager + (9) GFBDC 16384k video mem [ 40.075000] bcmfb: enable manual blit [ 40.098000] bcmfb: creating surface with pixel format 0x07e48888 (BPXL_eA8_R8_G8_B8) - double buffering not available. 14764kB available for acceleration surfaces. resolution: 720 x 576 x 32 (stride: 2880) + (9) gLCDDC found OLED display! [ 40.173000] procfile_read_xres called [ 40.173000] procfile_read_xres called [ 40.175000] procfile_read_yres called [ 40.175000] procfile_read_yres called [ 40.176000] procfile_read_bpp called [ 40.177000] procfile_read_bpp called lcd buffer 0x73fd5008 192000 bytes, stride 800 + (9) Font Render Class [FONT] initializing lib... [FONT] loading fonts... [FONT] Intializing font cache, using max. 4MB... + (10) gRC RC thread created successfully + (15) eWindowStyleManager + (20) DVB-CI UI + (20) UHF Modulator couldnt open /dev/rfmod0!!!! + (20) RC Input layer + (20) misc options + (20) HDMI CEC Subsystem attached: hdmi attached attached: hdmi cec address acknowledged my physical: 1.1.0.0 logical 3 + (20) AVSwitch Driver couldnt open /dev/dbox/fp0 to monitor vcr scart slow blanking changed! + (21) input device driver Input device "dreambox front panel" is a remotecontrol Input device "dreambox remote control (native)" is a remotecontrol Input device "dreambox advanced remote control (native)" is a remotecontrol Input device "dreambox ir keyboard" is a keyboard Input device "dreambox ir mouse" is a mouse Found 5 input devices! + (21) Console RC Driver + (30) eActionMap + (35) CI Slots scanning for common interfaces.. CI Slot 0 has fd 25 CI Slot 1 has fd 26 done, found 2 common interface slots + (40) eServiceCenter settings instance. + (41) eHbbtv + (41) eServiceFactoryBludisc + (41) eServiceFactoryM2TS + (41) eServiceFactoryMP3 + (41) eServiceFactoryFS + (41) eServiceFactoryDVB reached rl 70 [eDVBLocalTimeHandler] Use valid Linux Time 'Thu Oct 6 17:39:50 2016' :) (RTC?) [EPGC] Initialized EPGCache (wait for setCacheFile call now) before: 1 after: 1 setIoPrio best-effort level 7 ok before: 1 after: 1 [eFileMonitor] File Watcher starting setIoPrio best-effort level 7 ok Loading spinners... found 12 spinners! executing main setIoPrio best-effort level 6 ok [ 41.134000] 8036 ms: 49 [ 41.148000] 13 ms: 50 [ 41.168000] 19 ms: 50 [ 41.183000] 15 ms: 50 Reading satellites.xml main thread is non-idle! display spinner! sec config cleared [ 42.377000] tsmux set cisource reg 06 to val 00 CI Slot 0 setSource(0) [ 42.379000] tsmux set cisource reg 07 to val 00 CI Slot 1 setSource(0) setSlotInfo for dvb frontend 0 to slotid 0, fe_descr BCM4505, input_name A need rotorworkaround No, enabled Yes, DVB-S2 Yes setSlotInfo for dvb frontend 1 to slotid 1, fe_descr BCM4505, input_name B need rotorworkaround No, enabled Yes, DVB-S2 Yes slot: 0 configmode: simple diseqcmode: single slot: 1 configmode: simple diseqcmode: single sec config completed [ 42.432000] 1249 ms: 50 /proc/stb/frontend/0/lnb_sense is 19 /proc/stb/frontend/1/lnb_sense is 0 [setupConfigEntries] initial_call for stored uuid: 7eea2b9d-84d7-4e8a-bd06-61d8dde1b27c {'mountpoint': '/media/hdd', 'device_info': 'eSATA', 'device_description': 'ATA-Hitachi HTS54501 (160.041 GB)', 'enabled': 'true'} Invalid output format export. Choose from value, device, list, or full Invalid output format export. Choose from value, device, list, or full Invalid output format export. Choose from value, device, list, or full Invalid output format export. Choose from value, device, list, or full Invalid output format export. Choose from value, device, list, or full Invalid output format export. Choose from value, device, list, or full enumerating block devices... found block device 'loop0': blacklisted found block device 'loop1': blacklisted found block device 'mtdblock0': blacklisted found block device 'mtdblock1': blacklisted found block device 'mtdblock2': blacklisted found block device 'mtdblock3': blacklisted found block device 'sda': ok, removable=False, cdrom=False, partitions=['sda1'] Unable to determine structure of /dev fallback to udev new Harddisk sda -> /dev/sda -> /dev/sda [ePopen] command: ('hdparm', 'hdparm', '-S0', '/dev/sda') Invalid output format export. Choose from value, device, list, or full [addDevicePartition] device:'sda' with UUID:'None' Invalid output format export. Choose from value, device, list, or full [ 43.530000] cdfs 2.6.27 loaded. [ 43.655000] fuse init (API version 7.17) Harddisk.py: failed to read /sys/block/sda1/removable found block device 'sda1': error querying properties Invalid output format export. Choose from value, device, list, or full [addDevicePartition] device:'sda1' with UUID:'7eea2b9d-84d7-4e8a-bd06-61d8dde1b27c' Invalid output format export. Choose from value, device, list, or full [enumerateBlockDevices] devices_scanned_on_init: [('sda', False, False, True)] verifyInstalledStorageDevices [ 43.939000] 1506 ms: 52 [ 43.947000] 8 ms: 54 [ 44.003000] 55 ms: 54 [ 44.034000] 30 ms: 54 [ 44.036000] 2 ms: 54 [ 44.094000] 58 ms: 54 main thread is non-idle! display spinner! [ 44.580000] 485 ms: 54 [ 44.719000] 139 ms: 55 [ 44.729000] 10 ms: 55 [ 44.731000] 2 ms: 55 [ 44.733000] 2 ms: 55 Preferred tag editor set to None [ 45.814000] 1081 ms: 55 [ 45.822000] 7 ms: 56 [ 45.949000] 127 ms: 56 [ 46.293000] 344 ms: 56 [ 46.295000] 2 ms: 57 [ 46.317000] 22 ms: 57 [ 46.318000] 1 ms: 57 [ 46.320000] 1 ms: 57 [ 46.340000] 19 ms: 57 [ 46.380000] 40 ms: 57 [ 47.426000] 1045 ms: 57 [ 47.435000] 8 ms: 58 [ 47.451000] 16 ms: 58 loading bouquet... /etc/enigma2/bouquets.tv loading bouquet... /etc/enigma2/userbouquet.favourites.tv 50 entries in Bouquet userbouquet.favourites.tv loading bouquet... /etc/enigma2/userbouquet.sky__tv_.tv 9 entries in Bouquet userbouquet.sky__tv_.tv 2 entries in Bouquet bouquets.tv loading bouquet... /etc/enigma2/bouquets.radio loading bouquet... /etc/enigma2/userbouquet.favourites.radio 7 entries in Bouquet userbouquet.favourites.radio 1 entries in Bouquet bouquets.radio [ 47.475000] 24 ms: 58 [ 47.504000] 28 ms: 58 [ 47.528000] 24 ms: 58 [ 47.530000] 2 ms: 58 [ 47.533000] 3 ms: 58 [eDVBLocalTimeHandler] enable sync local time with transponder time! [ 47.552000] 19 ms: 58 [ 49.660000] 2108 ms: 59 APIs: ['api', 'api.enigma2', 'api.enigma2.systeminfo', 'api.enigma2.servicedata'] Functions: ['api._API__needsSession', 'api._API__session', 'api.enigma2._API__needsSession', 'api.enigma2._API__session', '[ 49.737000] 76 ms: 62 api.enigma2.systeminfo._API__needsSession', 'api.enigma2.systeminfo._API__session', 'api.enigma2.systeminfo.test', 'api.enigma2.servicedata.getAllTransponderList', 'api.enigma2.servicedata.getRadioProviders', 'api.enigma2.servicedata.getServicePIDs', 'api.enigma2.servicedata.convertTransponderInfoHumanReadable', 'api.enigma2.servicedata.getRadioServices', 'api.enigma2.servicedata.getTVProviders', 'api.enigma2.servicedata._API__needsSession', 'api.enigma2.servicedata.getTransponderList', 'api.enigma2.servicedata.getTransponderInfo', 'api.enigma2.servicedata.getSatList', 'api.enigma2.servicedata.getServiceList', 'api.enigma2.servicedata.getServiceName', 'api.enigma2.servicedata.getTVServices', 'api.enigma2.servicedata.getAllServices', 'api.enigma2.servicedata._API__session', 'api.enigma2.servicedata.getCurrentService', 'api.enigma2.servicedata.playService', 'api.enigma2.servicedata.getTVBouquets', 'api.enigma2.servicedata.getRadioBouquets'] [ePopen] command: ip -o addr show dev eth0 [ResourceManager] adding: iNetwork [ 49.968000] 230 ms: 62 add dreampackage scanner plugin added [ 50.121000] 153 ms: 62 [ 50.123000] 2 ms: 63 [ 50.199000] 76 ms: 63 [ 50.202000] 3 ms: 63 [ 50.204000] 2 ms: 63 [ 50.214000] 10 ms: 63 [ 50.237000] 23 ms: 63 [ 50.269000] 32 ms: 63 [ 50.271000] 2 ms: 63 [FONT] adding font /usr/share/fonts/nmsbd.ttf...OK (Display) [ 50.329000] bcmfb: creating surface with pixel format 0x07e48888 (BPXL_eA8_R8_G8_B8) - double buffering not available. 12784kB available for acceleration surfaces. resolution: 1280 x 720 x 32 (stride: 5120) [FONT] adding font /usr/share/fonts/lcd.ttf...OK (LCD) [FONT] adding font /usr/share/fonts/ae_AlMateen.ttf...OK (Replacement) [FONT] adding font /usr/share/fonts/tuxtxt.ttf...OK (Console) [FONT] adding font /usr/share/fonts/nmsbd.ttf...OK (Arial) [FONT] adding font /usr/share/enigma2/DMConcinnity-HD/fonts/HandelGotDBol.ttf...OK (Regular) [FONT] adding font /usr/share/fonts/tcm.ttf...OK (Regular_LCD) [FONT] adding font /usr/share/fonts/tcm.ttf...OK (Regular) [ 50.636000] 365 ms: 63 [ 50.747000] 111 ms: 63 [ 50.806000] set current avs auto aspect to 4:3 [ 50.807000] disable wss on display 0 [ 50.807000] disable wss on display 1 [ 50.808000] set sb to 3(2) [ 50.846000] set current avs auto aspect to 4:3 [ 50.847000] disable wss on display 0 [ 50.847000] disable wss on display 1 [ 50.848000] set sb to 3(2) [ 50.850000] disable wss on display 0 [ 50.850000] set wss 8 on display 1 [ 50.887000] set input to 0 [ 50.887000] set fb to 2(0) [ 50.888000] set current avs auto aspect to 4:3 [ 50.888000] disable wss on display 0 [ 50.889000] set wss 8 on display 1 [ 50.890000] set sb to 3(2) [ 50.894000] set ac3 to passthrough [ 50.898000] set ac3plus to use_hdmi_caps --> setting scaler_sharpness to: 0000000E [ 50.947000] 199 ms: 64 [ 50.959000] 11 ms: 64 [ 50.979000] 19 ms: 64 [ 51.246000] 267 ms: 64 [ 51.253000] 6 ms: 64 setLCDBrightness 76 setLCDBrightness 102 [ 51.273000] 20 ms: 64 Activating keymap: Dreambox Keyboard Deutsch [ePopen] command: loadkmap < /usr/share/keymaps/dream-de.kmap Activating language German language set to de_DE [WebInterface] set language to de [WebInterface] set language to de [WebInterface] set language to de It's now Thu Oct 6 17:40:11 2016 [timer.py] next activation: 1475768511 (in 99860 ms) [WebInterface] fallback to default translation for Webinterface [TeleText] set language to [de] [TeleText] set language to [de] [TeleText] fallback to default translation for bilinear [TeleText] plugins [AudioSync] set language to de [AudioSync] set language to de getModeList for port DVI-PC getModeList for port YPbPr getModeList for port Scart getModeList for port DVI-PC getModeList for port DVI hotplug on dvi -> setting aspect, policy, policy2, wss 16:9 panscan letterbox auto [ 68.506000] set current avs auto aspect to 16:9 [ 68.507000] disable wss on display 0 [ 68.507000] set wss 7 on display 1 [ 68.508000] set sb to 3(1) [ 68.546000] set current avs auto aspect to 16:9 [ 68.547000] disable wss on display 0 [ 68.547000] set wss 7 on display 1 [ 68.548000] set sb to 3(1) [ 68.550000] disable wss on display 0 [ 68.550000] set wss 7 on display 1 [ 68.552000] set current avs auto aspect to 16:9 [ 68.552000] disable wss on display 0 [ 68.553000] set wss 7 on display 1 [ 68.553000] set sb to 3(1) -> setting aspect, policy, policy2, wss 16:9 panscan letterbox auto [ 68.560000] set current avs auto aspect to 16:9 [ 68.561000] disable wss on display 0 [ 68.561000] set wss 7 on display 1 [ 68.562000] set sb to 3(1) [ 68.564000] set current avs auto aspect to 16:9 [ 68.564000] disable wss on display 0 [ 68.565000] set wss 7 on display 1 [ 68.565000] set sb to 3(1) [ 68.567000] disable wss on display 0 [ 68.568000] set wss 7 on display 1 [ 68.570000] set current avs auto aspect to 16:9 [ 68.570000] disable wss on display 0 [ 68.571000] set wss 7 on display 1 [ 68.571000] set sb to 3(1) -> setting aspect, policy, policy2, wss 16:9 panscan letterbox auto [ 68.576000] set current avs auto aspect to 16:9 [ 68.577000] disable wss on display 0 [ 68.577000] set wss 7 on display 1 [ 68.578000] set sb to 3(1) [ 68.580000] set current avs auto aspect to 16:9 [ 68.580000] disable wss on display 0 [ 68.581000] set wss 7 on display 1 [ 68.581000] set sb to 3(1) [ 68.583000] disable wss on display 0 [ 68.583000] set wss 7 on display 1 [ 68.585000] set current avs auto aspect to 16:9 [ 68.586000] disable wss on display 0 [ 68.586000] set wss 7 on display 1 [ 68.587000] set sb to 3(1) -> setting aspect, policy, policy2, wss 16:9 panscan letterbox auto [ 68.592000] set current avs auto aspect to 16:9 [ 68.593000] disable wss on display 0 [ 68.593000] set wss 7 on display 1 [ 68.594000] set sb to 3(1) [ 68.596000] set current avs auto aspect to 16:9 [ 68.596000] disable wss on display 0 [ 68.597000] set wss 7 on display 1 [ 68.597000] set sb to 3(1) [ 68.599000] disable wss on display 0 [ 68.599000] set wss 7 on display 1 [ 68.601000] set current avs auto aspect to 16:9 [ 68.602000] disable wss on display 0 [ 68.602000] set wss 7 on display 1 [ 68.602000] set sb to 3(1) setMode - port: DVI mode: 720p rate: multi [ 68.608000] set current avs auto aspect to 16:9 [ 68.608000] disable wss on display 0 [ 68.609000] set wss 7 on display 1 [ 68.609000] set sb to 3(1) [ 68.611000] set current avs auto aspect to 16:9 [ 68.612000] disable wss on display 0 [ 68.612000] set wss 7 on display 1 [ 68.613000] set sb to 3(1) -> setting aspect, policy, policy2, wss 16:9 panscan letterbox auto [ 68.622000] set current avs auto aspect to 16:9 [ 68.623000] disable wss on display 0 [ 68.623000] set wss 7 on display 1 [ 68.624000] set sb to 3(1) [ 68.626000] set current avs auto aspect to 16:9 [ 68.627000] disable wss on display 0 [ 68.627000] set wss 7 on display 1 [ 68.628000] set sb to 3(1) [ 68.629000] disable wss on display 0 [ 68.630000] set wss 7 on display 1 [ 68.632000] set current avs auto aspect to 16:9 [ 68.632000] disable wss on display 0 [ 68.633000] set wss 7 on display 1 [ 68.633000] set sb to 3(1) sh: fpupgrade: not found /usr/share/enigma2/ [ePopen] command: find /usr/lib/enigma2/python/Plugins/newnigma2/ -iname "*.png" -maxdepth 2 -mindepth 1 [ePopen] command: find /usr/share/enigma2/DMConcinnity-HD/newnigma2/ -iname "*.png" -maxdepth 2 -mindepth 1 [OSD preference] did not found: /usr/share/enigma2/DMConcinnity-HD/icons/beta_g.png [FONT] adding font /usr/share/fonts/nmsbd.ttf...OK (LCD_NN) [eCamdCtrl] starting cmd 5 [eCamdCtrl] executing /usr/bin/camdctrl current [TeleText] autostart(0, {}) [CrashlogAutoSubmit] - autostart [CI_Assignment] activating ci configs: [CI_Activate] FOUND 2 CI Slots [CI_Activate_Config_CI0] no config file found [CI_Activate_Config_CI0] error parsing xml... [CI_Activate_Config_CI1] no config file found [CI_Activate_Config_CI1] error parsing xml... [ResourceManager] adding: DiseqcTester starting hotplug handler couldn't open /proc/stb/misc/12V_output [WebInterface] set language to de [WebInterface] set language to de [WebInterface] set language to de [TeleText] set language to [de] [TeleText] set language to [de] EMC: Setting EPG language: de_DE [AudioSync] set language to de [AudioSync] set language to de [ 70.487000] 19214 ms: 64 [ 70.502000] 15 ms: 95 [ 70.510000] tsmux set ciclk reg 34 to val 00 [ 70.515000] tsmux set ciclk reg 34 to val 00 FIXME: request for unknown slot FIXME: request for unknown slot [ 70.529000] 26 ms: 95 It's now Thu Oct 6 17:40:20 2016 [timer.py] next activation: 1475768520 (in 99627 ms) [TIMER] record time changed, start prepare is now: Fri Oct 7 07:36:40 2016 [Timer] Record ProcessRepeated It's now Thu Oct 6 17:40:20 2016 next real activation is Fri Oct 7 07:36:40 2016 [timer.py] next activation: 1475768520 (in 99583 ms) [TIMER] record time changed, start prepare is now: Fri Oct 7 12:11:40 2016 [Timer] Record ProcessRepeated It's now Thu Oct 6 17:40:20 2016 next real activation is Fri Oct 7 07:36:40 2016 [timer.py] next activation: 1475768520 (in 99560 ms) [TIMER] record time changed, start prepare is now: Fri Oct 7 18:51:40 2016 [Timer] Record ProcessRepeated It's now Thu Oct 6 17:40:20 2016 next real activation is Fri Oct 7 07:36:40 2016 [timer.py] next activation: 1475768520 (in 99532 ms) [TIMER] record time changed, start prepare is now: Sat Oct 8 01:06:40 2016 [Timer] Record ProcessRepeated It's now Thu Oct 6 17:40:20 2016 next real activation is Fri Oct 7 07:36:40 2016 [timer.py] next activation: 1475768520 (in 99509 ms) [TIMER] record time changed, start prepare is now: Sat Oct 8 06:49:29 2016 [Timer] Record ProcessRepeated It's now Thu Oct 6 17:40:20 2016 next real activation is Fri Oct 7 07:36:40 2016 [timer.py] next activation: 1475768520 (in 99486 ms) [TIMER] record time changed, start prepare is now: Sat Oct 8 07:01:40 2016 [Timer] Record ProcessRepeated It's now Thu Oct 6 17:40:20 2016 next real activation is Fri Oct 7 07:36:40 2016 [timer.py] next activation: 1475768520 (in 99460 ms) [TIMER] record time changed, start prepare is now: Sat Oct 8 09:16:40 2016 [Timer] Record ProcessRepeated It's now Thu Oct 6 17:40:20 2016 next real activation is Fri Oct 7 07:36:40 2016 [timer.py] next activation: 1475768520 (in 99442 ms) [TIMER] record time changed, start prepare is now: Sat Oct 8 18:51:40 2016 [Timer] Record ProcessRepeated It's now Thu Oct 6 17:40:20 2016 next real activation is Fri Oct 7 07:36:40 2016 [timer.py] next activation: 1475768520 (in 99432 ms) [TIMER] record time changed, start prepare is now: Mon Oct 3 20:07:05 2016 [Timer] Record ProcessRepeated already passed, skipping shouldSkip: True state == ended False waiting && disabled: 0 [TIMER] record time changed, start prepare is now: Wed Oct 5 15:21:40 2016 [Timer] Record ProcessRepeated already passed, skipping shouldSkip: True state == ended False waiting && disabled: 0 It's now Thu Oct 6 17:40:20 2016 [timer.py] next activation: 1475768520 (in 99396 ms) InfoBarTunerState start [IBTS Plugins]: Files: ['Unknown', 'Records', 'StreamWebIf', 'StreamServer', 'Live', 'StreamOpenWebIf', 'Timers'] [IBTS Plugins]: {'StreamWebIf': , 'StreamServer': , 'Unknown': , 'StreamOpenWebIf': , 'Records': , 'Live': , 'Timers': } Looking for embedded skin Repeated: 1 Repeated: 3 Repeated: 7 Repeated: 15 Repeated: 31 Repeated: 63 Repeated: 127 ProcessRepeated localrepeatedbegindate: Thu Oct 6 05:45:00 2016 localbegin: Thu Oct 6 05:45:00 2016 localend: Thu Oct 6 05:45:00 2016 localnow: Thu Oct 6 17:40:22 2016 Day: 0 Day: 1 Day: 2 Day: 3 Day: 4 Day: 5 Day: 6 localbegin after addOneDay: Fri Oct 7 05:45:00 2016 localend after addOneDay: Fri Oct 7 05:45:00 2016 ProcessRepeated result Fri Oct 7 05:45:00 2016 Fri Oct 7 05:45:01 2016 It's now Thu Oct 6 17:40:21 2016 [EPGRefreshTimer] next real activation is Fri Oct 7 05:45:01 2016 [timer.py] next activation: 1475768521 (in 99820 ms) [TeleText] ----- sessionstart(<__main__.Session instance at 0x20344e0>) ----- [TeleText] [favorites] reading accel 496000 bytes accel memstat: used=0 kB, free 12784 kB, s 0 kB accel memory: 0 Looking for embedded skin [TeleText] __layoutFinished EMC: +++ EMC git20160821 startup [EMC] checkImg is not Vti cannot import name BT_FIXRATIO [EMC] checkImg is not newPiconRenderer cannot import name getPiconName [ResourceManager] adding: NetworkAdapterSelection [ResourceManager] adding: AdapterSetup [CrashlogAutoSubmit] - autostart [CrashlogAutoSubmit] - not starting CrashlogAutoSubmit [Cec] power on [OSD preference] did not found: /usr/share/enigma2/DMConcinnity-HD/icons/beta_g.png [InstallerUpdateCheck] timer changed [InstallerUpdateCheck] is offline [CamdresetEveryDay] next reset: Thu Oct 6 23:45:21 2016 No skin to read... [Toplevel.importExternalModules] Imported external module: Example [Toplevel.importExternalModules] Imported external module: EPGRefresh [HTTPRootResource}.__init__ [Webinterface] started on 0.0.0.0:80 auth=False ssl=False [WebInterface.registerBonjourService] No module named Bonjour.Bonjour [HTTPRootResource}.__init__ [Webinterface] started on 0.0.0.0:443 auth=True ssl=True [WebInterface.registerBonjourService] No module named Bonjour.Bonjour [ 73.236000] 2707 ms: 95 [EPGC] set outdated epg timespan to 0 hours... [EPGC] set cache timespan to 28 days! [EPGC] setCacheFile read/write epg data from/to '/media/hdd/epg.dat' [EPGC] time updated.. start EPG Mainloop before: 1 after: 1 not showing fine-tuning wizard, config variable doesn't exist showtestcard is false Looking for embedded skin [ 73.301000] 64 ms: 99 setValue 50 [ 73.353000] set mute 0 (000) 1 cannot open /proc/stb/audio/j1_mute(No such file or directory) Setvolume: 100 100 (raw) Setvolume: 0 0 (-1db) Setvolume: 50 50 (raw) Setvolume: 32 32 (-1db) [ 73.355000] 54 ms: 99 [ 73.374000] 19 ms: 99 EMC: Setting EPG language: de_DE libpagecache exists... drop caches now! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! [Picon Renderer] Exception caught here warning, skin is missing element UnhandledKeyPixmap in allocating new converter! allocating new converter! allocating new converter! reused converter! RemovePopup, id = ZapError allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! reused converter! allocating new converter! allocating new converter! reused converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! reused converter! allocating new converter! allocating new converter! reused converter! allocating new converter! allocating new converter! reused converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! reused converter! allocating new converter! allocating new converter! reused converter! allocating new converter! allocating new converter! reused converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! reused converter! reused converter! allocating new converter! allocating new converter! [Picon Renderer] not found: /picons/piconHD/.png [Picon Renderer] not found: /picons/piconHD/.png [Picon Renderer] not found: /picons/piconHD/.png [Picon Renderer] Sorry, i give up! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! reused converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! reused converter! allocating new converter! allocating new converter! main thread is non-idle! display spinner! reused converter! allocating new converter! reused converter! allocating new converter! reused converter! allocating new converter! allocating new converter! cec event! attached: hdmi attached attached: hdmi cec address acknowledged my physical: 1.1.0.0 logical 3 CISlot 0 what 2 CISlot 1 what 2 child has terminated pipes closed child has terminated pipes closed [EPGC] 42355 events read from /media/hdd/epg.dat [EPGC] load 30602 descriptors...[ePopen] command: route -n | grep eth0 poll: unhandled POLLERR/HUP/NVAL for fd 45(16) child has terminated pipes closed poll: unhandled POLLERR/HUP/NVAL for fd 49(16) poll: unhandled POLLERR/HUP/NVAL for fd 52(16) child has terminated pipes closed child has terminated pipes closed poll: unhandled POLLERR/HUP/NVAL for fd 59(16) poll: unhandled POLLERR/HUP/NVAL for fd 62(17) child has terminated pipes closed poll: unhandled POLLERR/HUP/NVAL for fd 69(16) delaying playService request until the ChannelSelection is finally initialized deferred playService ... ChannelSelection is finally initialized now playing 1:0:19:132F:3EF:1:C00000:0:0:0: realpath /etc/enigma2/virtsubmapping.txt: No such file or directory can't open /etc/enigma2/virtsubmapping.txt: No such file or directory [Picon Renderer] not found: /picons/piconHD/ORF1 HD.png finished [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 5815991 bytes for cache used [TeleText] service started not pauseable. RemovePopup, id = ZapError allocate channel.. 03ef:0001:00c00000 opening frontend 0 [ 77.676000] bcm4506: channel 0 is in normale mode now [eDVBCAService] new channel 0x227fbd0! (0)tune RotorCmd ffffffff, lastRotorCmd ffffffff prepare_sat System 1 Freq 11302750 Pol 0 SR 22000000 INV 2 FEC 2 orbpos 192 system 1 modulation 2 pilot 2, rolloff 0 tuning to 1552 mhz OURSTATE: tuning allocate Channel: res 0 allocate demux setInputSource i[ 77.696000] tsmux set tsports reg 00 to val 00 nput0 to A cec event! send 3 -> f : 7d child has terminated pipes closed 0.0.0.0 169.254 192.168 nameservers: [[192, 168, 1, 1]] read configured interface: {'lo': {'dhcp': False}, 'eth0': {'dhcp': True}} self.ifaces after loading: {'eth0': {'preup': False, 'predown': False, 'ip': [192, 168, 1, 10], 'up': True, 'dns-nameserver[ 77.810000] CEC send ok! s': False, 'broadcast': False, 'mac': '00:09:34:2d:c6:b4', 'dhcp': True, 'bcast': [192, 168, 1, 255], 'netmask': [255, 255, 255, 0], 'gateway': [192, 168, 1, 1]}} poll: unhandled POLLERR/HUP/NVAL for fd 45(16) [SEC] lock 0 cec event! send 3 -> 0 : 04 [SEC] set static current limiting [SEC] invalidate current switch params [SEC] setVoltage 2 (0)setVoltage 18V [SEC] sleep 10ms [SEC] update current switch params [SEC] startTuneTimeout 5000 [SEC] setFrontend 1 (0)setting frontend (0)fe event: status 0, freq 1552750, inversion off, m_tuning 1 F [SEC] unlock [SEC] sleep 500ms [ 77.900000] CEC send ok! cec event! send 3 -> f : 82 11 00 (0)fe event: status 1, freq 1552750, inversion off, m_tuning 1 N [ 78.015000] CEC send ok! (0)fe event: status 0, freq 1552750, inversion off, m_tuning 2 N [SEC] set dynamic current limiting (0)fe event: status 1f, freq 1553041, inversion off, m_tuning 3 N OURSTATE: ok [eDVBCAService] channel 0x227fbd0 running [eDVBLocalTimeHandler] channel 0x227fbd0 running no version filtering 0014: 70 00 00 00 00 00 mask: fc 00 00 00 00 00 mode: 00 00 00 00 00 00 [eEPGCache] channel 0x227fbd0 running [EPGC] next update in 2 sec stop release channel timer no version filtering 0012: 4e 13 2f 00 00 00 mask: ff ff ff 00 00 00 mode: 00 00 00 00 00 00 ok ... now we start!! no version filtering 0000: 00 00 00 00 00 00 mask: ff 00 00 00 00 00 mode: 00 00 00 00 00 00 eventNewProgramInfo 0 0 have 1 video stream(s) (0780), and 1 audio stream(s) (0781), and the pcr pid is 0780, and the text pid is 0785 allocate demux disable teletext subtitles page ffffffffffffffff (und) decoder state: play, vpid=1920, apid=1921 DMX_SET_PES_FILTER(0x780) - pcr - ok[ 78.447000] start pcr0 on dmx 3 pid:780 index 5 DEMUX_START - pcr - ok DMX_SET_PES_FILTER(0x781) - audio - ok DEMUX_START - audio - ok AUDIO_SET_BYPASS(0) - [ 78.450000] start audio on dmx 3 pid:781 [ 78.450000] audio xpt start [ 78.451000] sync is off so we enable it [ 78.451000] demux-index 3 pcr0pid 780 [ 78.452000] using 5 as pcr0 pidchannel [ 78.453000] AC3 [ 78.453000] audio: streamtype set 1 [ 78.453000] audio: streamtype_set: stop rave [ 78.454000] passthru [ 78.455000] audio: streamtype_set: restart rave [ 78.455000] set mute 0 (011) 1 ok AUDIO_PAUSE - ok AUDIO_PLAY - [ 78.456000] audio: decoder start 2 [ 78.459000] video delay 0 (0 ms) [ 78.459000] total video delay: 0 (0 ms) ok Video Device: /dev/dvb/adapter0/video0 demux de[ 78.461000] VIDEO0: set streamtype! vice: /dev/dvb/a[ 78.461000] set H264 dapter0/demux3 [ 78.462000] sw rave disabled! VIDEO_SET_STREAM[ 78.462000] audio delay 18000 (200 ms) TYPE 1 - [ 78.463000] total video delay: 18000 (200 ms) ok DMX_SET_PES_FILTER(0x780) - video - ok DEMUX_START - video - [ 78.464000] start video0 on dmx 3 pid:780 [ 78.465000] total video delay: 18000 (200 ms) ok VIDEO_FREEZE - ok VIDEO_PLAY - [ 78.469000] VIDEO0: start decode ok DMX_SET_PES_FILTER(0x785) - ttx - ok DEMUX_START - ttx - ok VIDEO_SLOWMOTION(0) - ok VIDEO_FAST_FORWARD(0) - ok VIDEO_CONTINUE - ok AUDIO_CONTINUE - ok AUDIO_CHANNEL_SELECT(0) - ok not pauseable. [TeleText] serviceInfoChanged [TeleText] TXT PID 1925 DEMUX 3 VIDEO_GET_EVENT - ok [eDVBLocalTimeHandler] Receiver time is 'Thu Oct 6 17:40:28 2016' [eDVBLocalTimeHandler] Transponder time is 'Thu Oct 6 17:40:28 2016' [eDVBLocalTimeHandler] diff is 0 [eDVBLocalTimeHandler] diff < 120 .. use Transponder Time [eDVBLocalTimeHandler] not changed VIDEO_GET_EVENT - ok + 1/1 TID 00 done! PATready use pmtpid 006b for service_id 132f no version filtering 006b: 02 13 2f 00 00 00 mask: ff ff ff 00 00 00 mode: 00 00 00 00 00 00 doing version filtering 0000: 00 00 00 31 00 00 mask: ff 00 00 3f 00 00 mode: 00 00 00 3e 00 00 [ 78.695000] CEC recv ok! cec event! handle message 0 -> f : 84 00 00 00 Report Physical Address 0.0.0.0 device type 0 (TV) [ 78.872000] CEC recv ok! cec event! handle message 0 -> f : 87 08 00 46 Device Vendor ID 080046 + 1/1 TID 02 done! [eFileWatch] Now watching /tmp [eFileWatch] Now watching /var/run [eDVBCAService] new service 1:0:19:132F:3EF:1:C00000:0:0:0: [eDVBCAService] add demux 3 to slot 0 service 1:0:19:132F:3EF:1:C00000:0:0:0: [eDVBCAService] demux 3 mask 08 [eDVBCAServiceConn] send 116 bytes to '/tmp/camd.socket' 9f 80 32 70 03 13 2f 11 00 4c 01 81 08 00 c0 00 00 03 ef 00 01 82 02 08 03 84 02 00 6b 85 02 00 41 09 04 06 48 e0 78 09 04 06 50 e0 7a 09 04 0d 95 e1 0e 09 04 0d 98 e1 10 09 0f 05 00 e1 cd 10 01 00 13 01 20 14 03 05 19 00 09 04 09 c4 e1 e0 09 04 09 8c e1 ea 1b 07 80 00 00 06 07 81 00 00 06 07 82 00 00 06 07 85 00 00 05 1c 8e 00 00 0b 1c 8f 00 00 [eDVBCIInterfaces] addPMTHandler 1:0:19:132F:3EF:1:C00000:0:0:0: recheckPMTHAndlers() recheck 0x222392c 1:0:19:132F:3EF:1:C00000:0:0:0: HBBTV broadcast data on pid 1c8f check Slot 0 check Slot 1 recheckPMTHAndlers() recheck 0x222392c 1:0:19:132F:3EF:1:C00000:0:0:0: check Slot 0 check Slot 1 [eDVBCIInterfaces] gotPMT eventNewProgramInfo 0 0 start ait on pid 1c8e no version filtering 1c8e: 74 00 00 00 00 00 mask: ff 00 00 00 00 00 mode: 00 00 00 00 00 00 have 1 video stream(s) (0780), and 2 audio stream(s) (0781, 0782), and the pcr pid is 0780, and the text pid is 0785 decoder state: play, vpid=1920, apid=1921 HBBTV broadcast data on pid 1c8f [TeleText] serviceInfoChanged [TeleText] TXT PID 1925 DEMUX 3 doing version filtering 006b: 02 13 2f 11 00 00 mask: ff ff ff 3f 00 00 mode: 00 00 00 3e 00 00 -+ 1/2 TID 4e [ 79.140000] CEC recv ok! cec event! handle message 0 -> f : a0 08 00 46 00 04 00 01 Vendor Command with ID [eFileMonitor] 1 events queued [eFileMonitor] File {/tmp/ecm.info} has been created [ 79.312000] RAP first pts 0x5794f166, stc 0x5794db4c, pcr valid [ 79.312000] audio_cdb_itb_error_isr! underflow [ 79.317000] audio_cdb_itb_error_isr! underflow [ 79.321000] audio_cdb_itb_error_isr! underflow [ 79.325000] audio_cdb_itb_error_isr! underflow [ 79.329000] audio_cdb_itb_error_isr! underflow [ 79.334000] audio_cdb_itb_error_isr! underflow [ 79.338000] audio_cdb_itb_error_isr! underflow [ 79.342000] audio_cdb_itb_error_isr! underflow [ 79.344000] ch0: lock [ 79.344000] RAP sampling rate info isr (48000) [ 79.344000] eModeChange Isr.. set source_changed_event! + 1/1 TID 74 done! [eHbbtv] aitChanged pid=0x1c8e [eOipfApplication] Profile 0000, Version 1.1.1 [eOipfApplication] deu:ORF HbbTV - SAT HD [eHbbtv] New Application: ORF HbbTV - SAT HD (orgid=13, appid=2, urlBase=http://orfhbbtv.orf.apa.net/orf/, initalPath=newsportal/index.html, controlCode=1, visibility=2) [eHbbtv] Resolving Application Locator: dvb://current.ait/13.2 [eHbbtv] We have 1 app(s) for service 1.1007.4911 [eHbbtv] 'dvb://current.ait/13.2' resolved to 'http://orfhbbtv.orf.apa.net/orf/newsportal/index.html' doing version filtering 1c8e: 74 00 00 01 00 00 mask: ff 00 00 3f 00 00 mode: 00 00 00 3e 00 00 [ 79.739000] CEC recv ok! cec event! handle message 5 -> f : 72 01 ++ 2/2 TID 4e done! doing version filtering 0012: 4e 13 2f 2f 00 00 mask: ff ff ff 3f 00 00 mode: 00 00 00 3e 00 00 [ 79.906000] VIDEO0: first pts 0x57973c7e [ 79.907000] VIDEO0: framerate changed 6 VIDEO_GET_EVENT - ok [ 79.908000] set current avs auto aspect to 16:9 [ 79.909000] disable wss on display 0 [ 79.909000] set wss 7 on display 1 [ 79.910000] set sb to 3(1) [ 80.060000] enable tsm0 [ 80.204000] CEC recv ok! cec event! handle message 0 -> f : a0 08 00 46 00 13 00 10 00 00 01 00 00 00 00 Vendor Command with ID sdt update done! [ 80.329000] HDMI: force disconnect! [ 80.329000] HDMI: now disconnected [ 80.329000] HDMI: stop CEC RX cec event! nothing attached! [ 80.347000] CEC recv ok! cec event! cec event! handle message 8 -> f : 84 10 00 04 Report Physical Address 1.0.0.0 device type 4 (Playback Device) [EPGC] start caching events(1475768430) [ 80.554000] CEC recv ok! cec event! handle message 5 -> f : 84 10 00 05 Report Physical Address 1.0.0.0 device type 5 (Audio System) [ 80.917000] CEC recv ok! cec event! handle message 0 -> f : a0 08 00 46 00 08 00 00 Vendor Command with ID [ 81.060000] CEC recv ok! cec event! handle message 8 -> f : 87 00 00 f0 Device Vendor ID 0000f0 [ 81.424000] CEC recv ok! cec event! handle message 5 -> f : 80 10 00 11 00 Routing Change from 1.0.0.0 to 1.1.0.0 now we are *on air* !!! [ 81.591000] CEC recv ok! cec event! handle message 5 -> f : 72 01 [ 81.724000] CEC recv ok! cec event! handle message 0 -> f : 32 67 65 72 Set Menu Language: ger [ 82.207000] HDMI: now connected [ 82.236000] HDMI: EDID read ok [ 82.246000] using 50.00Hz as MonitorRefreshRate [ 82.366000] SET FORMAT: display[0], format=37 [ 82.367000] HDMI mode [ 82.367000] use EDID to detect colorspace [ 82.368000] using colorspace: 1 [ 82.481000] bcm_video_analog_port_setup (fmt=7) [ 82.586000] SET FORMAT: display[1], format=7 IBTS hide IBTS timerHide IBTS tunerHide [ 82.706000] set current avs auto aspect to 16:9 [ 82.707000] disable wss on display 0 [ 82.707000] set wss 7 on display 1 [ 82.708000] HDMI: reset audio streamtype after EDID read [ 82.709000] audio: streamtype set 1 [ 82.709000] audio: streamtype_set: stop decoder [ 82.710000] audio: decoder stop [ 82.710000] set sb to 3(1) [ 82.711000] audio: streamtype_set: stop rave [ 82.712000] passthru [ 82.713000] audio: streamtype_set: restart rave [ 82.713000] set mute 0 (011) 0 [ 82.714000] audio: streamtype_set: restart decoder [ 82.714000] audio: decoder start 0 [ 82.716000] audio delay 18000 (200 ms) [ 82.717000] total video delay: 18000 (200 ms) [ 82.718000] HDMI: start CEC [ 82.718000] CEC recv ok! cec event! attached: hdmi attached cec event! attached: hdmi attached cec event! handle message 0 -> f : 87 08 00 46 Device Vendor ID 080046 [ 82.769000] ch0: lock [ 82.769000] RAP first pts 0x5799b066, stc 0x57999a84, pcr valid [ 82.796000] HDMI: use logical CEC address 3 cec event! attached: hdmi attached attached: hdmi cec address acknowledged my physical: 1.1.0.0 logical 3 [ 82.939000] CEC recv ok! cec event! attached: hdmi attached attached: hdmi cec address acknowledged my physical: 1.1.0.0 logical 3 cec event! handle message 8 -> f : 87 00 00 f0 Device Vendor ID 0000f0 [ 83.074000] CEC send ok! [ 83.561000] CEC recv ok! cec event! handle message 5 -> f : 87 00 00 f0 Device Vendor ID 0000f0 [ 84.267000] CEC recv ok! cec event! handle message 0 -> 3 : 8f Give Device Power Status cec event! send 3 -> 0 : 90 00 [ 84.354000] CEC send ok! [ 84.416000] CEC recv ok! cec event! handle message 0 -> 3 : 46 Give OSD Name cec event! send 3 -> 0 : 47 44 72 65 61 6d 62 6f 78 [ 84.672000] CEC send ok! [ 84.734000] CEC recv ok! cec event! handle message 0 -> 3 : 8c Give Device Vendor ID cec event! send 3 -> f : 87 00 09 34 [ 84.869000] CEC send ok! [ 84.961000] CEC recv ok! cec event! handle message 5 -> f : 72 01 [ 85.153000] CEC recv ok! cec event! handle message 0 -> f : 86 11 00 Set Stream Path to 1.1.0.0 cec event! send 3 -> f : 82 11 00 [ 85.264000] CEC send ok! [ 85.846000] CEC recv ok! cec event! handle message 4 -> f : 84 12 00 04 Report Physical Address 1.2.0.0 device type 4 (Playback Device) [ 86.508000] CEC recv ok! cec event! handle message 4 -> f : 87 08 00 46 Device Vendor ID 080046 [EPGC] abort non avail viasat reading [EPGC] nownext finished(1475768438) [ 91.713000] CEC recv ok! cec event! handle message 0 -> 3 : action -> InfobarMenuActions mainMenu loading mainmenu XML... TimerEdit TimerEditList PluginBrowser PluginBrowser reused converter! reused converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! action -> OkCancelActions ok okbuttonClick stopService [eDVBCAService] free slot 0 demux 3 for service 1:0:19:132F:3EF:1:C00000:0:0:0: [eDVBCAService] free service 1:0:19:132F:3EF:1:C00000:0:0:0: recheckPMTHAndlers() [eFileMonitor] 1 events queued [TeleText] service stopped [TeleText] send array('B', [1, 0, 0, 0]) decoder state: p[ 96.266000] stop pcr0 on dmx 3 pid:780 lay, vpid=-1, apid=-1 DEMUX_STOP - pcr - ok DEMUX_STOP - video - [ 96.268000] stop video0 on dmx 3 pid:780 [ 96.278000] VIDEO0: stop decode ok VIDEO_STOP - [ 96.279000] VIDEO0: set blank to 1 ok AUDIO_STOP - [ 96.280000] audio: decoder stop ok DEMUX_STOP - audio - [ 96.282000] stop audio on dmx 3 pid:781 [ 96.282000] audio: xpt stop(3) [ 96.282000] no decoder left in sync handling so disable xpt_sync [ 96.283000] bcm_audio_channel_stop(3) done ok AUDIO_CONTINUE - ok DEMUX_STOP - ttx - ok start release channel timer reused converter! reused converter! reused converter! reused converter! reused converter! set seek position: 0 set instance. Using MediaPlayerLCDScreen Skin reused converter! reused converter! reused converter! warning, skin is missing element text3 in warning, skin is missing element text4 in [Picload] setPara max-X=250 max-Y=250 aspect_ratio=1.000000 cache=0 resize=1 bg=#FF000000 [eFileMonitor] File {/tmp/ecm.info} has been deleted release cached channel (timer timeout) [eDVBCAService] remove channel 0x227fbd0 [eDVBCAService] write leave transponder to '/tmp/camd.socket' ok [eDVBLocalTimeHandler] remove channel 0x227fbd0 [eEPGCache] remove channel 0x227fbd0 [EPGC] abort caching events !! stop release channel timer (0) preClose action -> DirectionActions down action -> DirectionActions downUp nr_read 1653 nr_write 8 sum 1661 prev_sum 0 hdd was accessed since previous check! [IDLE] 0 300 False action -> OkCancelActions ok action -> OkCancelActions ok [prepareClose] running mainloop [SEC] lock 0 [SEC] sendDiseqc: e01002(DiSEqC peripherial power off) [SEC] sleep 150ms [SEC] delayed close frontend close frontend 0 (0)setVoltage 0V [SEC] unlock [ 102.516000] bcm4506: channel 0 is sleeping now action -> DirectionActions down action -> DirectionActions downUp action -> DirectionActions down action -> DirectionActions downUp action -> DirectionActions down action -> DirectionActions downUp action -> OkCancelActions ok action -> DirectionActions down action -> DirectionActions downUp [ 106.723000] CEC recv ok! cec event! handle message 0 -> 3 : action -> DirectionActions down action -> DirectionActions downUp action -> DirectionActions down action -> DirectionActions downUp action -> OkCancelActions ok action -> DirectionActions down action -> DirectionActions downUp action -> DirectionActions down action -> DirectionActions downUp action -> DirectionActions down action -> DirectionActions downUp action -> DirectionActions down action -> DirectionActions downUp action -> DirectionActions down action -> DirectionActions downUp action -> DirectionActions down action -> DirectionActions downUp action -> DirectionActions down action -> DirectionActions downUp action -> DirectionActions down action -> DirectionActions downUp action -> OkCancelActions ok action -> DirectionActions down action -> DirectionActions downUp action -> OkCancelActions ok playing 4097:0:0:0:0:0:0:0:0:0:/media/net/NAS_Aufnahmen/movie/Kinderserien/Oktonauten_MKV/Oktonauten.001.mkv eServiceMP3::construct! eServiceMP3::playbin2 uri=file:///media/net/NAS_Aufnahmen/movie/Kinderserien/Oktonauten_MKV/Oktonauten.001.mkv eServiceMP3::starting pipeline find old format eServiceReference string [Picon Renderer] not found: /picons/piconHD/.png [Picon Renderer] not found: /picons/piconHD/N/.png find old format eServiceReference string [Picon Renderer] not found: /picons/piconHD/.png [Picon Renderer] Sorry, i give up! [TeleText] service started new service started! trying to download cuts! download failed, no cuesheet interface resolved to PLAY 0 eServiceMP3::state transition NULL -> READY [TeleText] serviceInfoChanged [TeleText] TXT PID -1 DEMUX None [__evUpdatedInfo] title 0 of 0 () [TeleText] serviceInfoChanged [TeleText] TXT PID -1 DEMUX None [__evUpdatedInfo] title 0 of 0 () [TeleText] serviceInfoChanged [TeleText] TXT PID -1 DEMUX None [__evUpdatedInfo] title 0 of 0 () [ 116.944000] VIDEO0: set blank to 0 [ 116.945000] sync is off so we enable it [ 116.945000] playback! [ 116.945000] total video delay: 0 (0 ms) [ 117.169000] audio xpt start [ 117.169000] audio: decoder start 0 [ 117.172000] video delay 0 (0 ms) [ 117.172000] total video delay: 0 (0 ms) eServiceMP3::audioresample quality set to 1! [ 117.195000] VIDEO0: set fallback framerate to 25000 [ 117.199000] VIDEO0: start decode [ 117.220000] AC3 eServiceMP3::state transition READY -> PAUSED eServiceMP3::async-done - 1 video, 1 audio, 0 subtitle eServiceMP3::audio stream=0 codec=AC-3 audio language=de getBouquet failed.. no path given! getBouquet failed.. no path given! [ 117.263000] RAP first pts 0x0, stc 0x732b54, pcr invalid [ 117.263000] video_stc is not valid yet.. so ignore audio first pts [ 117.264000] ch0: lock [ 117.264000] audio_sampling_rate_info_isr: 31 callbacks suppressed [ 117.265000] RAP sampling rate info isr (48000) [ 117.266000] eModeChange Isr.. set source_changed_event! [ 117.306000] VIDEO0: first pts 0x0 [ 117.307000] VIDEO0: framerate changed 3 [ 117.307000] VIDEO0: resolution changed (720 406) [ 117.309000] set current avs auto aspect to 16:9 [ 117.310000] disable wss on display 0 [ 117.310000] set wss 7 on display 1 [ 117.311000] set sb to 3(1) eServiceMP3::state transition PAUSED -> PLAYING [ 117.461000] enable tsm0 [ 117.591000] usb 1-1: usbfs: process 699 (usbtftdisplay) did not claim interface 0 before use [ 121.724000] CEC recv ok! cec event! handle message 0 -> 3 : action -> MediaPlayerActions stop playing None stopService eServiceMP3::stop /media/net/NAS_Aufnahmen/movie/Kinderserien/Oktonauten_MKV/Oktonauten.001.mkv [ 122.989000] VIDEO0: set blank to 0 [ 122.991000] VIDEO0: stop decode [ 122.992000] VIDEO0: set blank to 0 [ 122.993000] VIDEO0: set fallback framerate to 60000 [ 122.996000] audio: decoder stop [ 122.997000] audio: xpt stop(1) [ 122.998000] unpause [ 122.998000] no decoder left in sync handling so disable xpt_sync [ 122.999000] bcm_audio_channel_stop(1) done [ 123.005000] ignore pts error when no video is running [TeleText] service stopped [TeleText] send array('B', [1, 0, 0, 0]) eServiceMP3::destruct! [Picload] setPara max-X=250 max-Y=250 aspect_ratio=1.000000 cache=0 resize=1 bg=#FF000000 action -> DirectionActions left action -> DirectionActions leftUp action -> OkCancelActions ok action -> DirectionActions up action -> DirectionActions upUp action -> DirectionActions up action -> DirectionActions upUp action -> OkCancelActions ok action -> DirectionActions down action -> DirectionActions downUp action -> OkCancelActions ok nr_read 1653 nr_write 11 sum 1664 prev_sum 1661 hdd was accessed since previous check! [IDLE] 0 300 False action -> DirectionActions down action -> DirectionActions downUp action -> OkCancelActions ok [ 136.722000] CEC recv ok! cec event! handle message 0 -> 3 : action -> DirectionActions right action -> DirectionActions rightUp [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 5815991 bytes for cache used action -> MediaPlayerActions menu reused converter! action -> WizardActions down action -> DirectionActions downUp unknown action DirectionActions/downUp! typo in keymap? action -> WizardActions down action -> DirectionActions downUp unknown action DirectionActions/downUp! typo in keymap? action -> WizardActions ok [Picload] setPara max-X=250 max-Y=250 aspect_ratio=1.000000 cache=0 resize=1 bg=#FF000000 playing None stopService action -> DirectionActions down action -> DirectionActions downUp action -> OkCancelActions ok playing 1:0:0:0:0:0:0:0:0:0:/media/net/NAS_Aufnahmen/movie/Kinderserien/My_little_Pony/20151028 1622 - Junior - My little Pony - Freundschaft ist Magie S02E05 Das Hufschwestern Festival.ts realpath /media/net/NAS_Aufnahmen/movie/Kinderserien/My_little_Pony/20151028 1622 - Junior - My little Pony - Freundschaft ist Magie S02E05 Das Hufschwestern Festival.ts.meta: No such file or directory realpath /media/net/NAS_Aufnahmen/movie/Kinderserien/My_little_Pony/recordings.epl: No such file or directory realpath /media/net/NAS_Aufnahmen/movie/Kinderserien/My_little_Pony/20151028 1622 - Junior - My little Pony - Freundschaft ist Magie S02E05 Das Hufschwestern Festival.ts.meta: No such file or directory realpath /media/net/NAS_Aufnahmen/movie/Kinderserien/My_little_Pony/recordings.epl: No such file or directory no .meta file found, trying to find PMT pid PMT pid found on pid 1000, service id 1 alloc PVR allocate channel.. 0000:0000:00000000 (/media/net/NAS_Aufnahmen/movie/Kinderserien/My_little_Pony/20151028 1622 - Junior - My little Pony - Freundschaft ist Magie S02E05 Das Hufschwestern Festival.ts) allocate pvr demux loading streaminfo for /media/net/NAS_Aufnahmen/movie/Kinderserien/My_little_Pony/20151028 1622 - Junior - My little Pony - Freundschaft ist Magie S02E05 Das Hufschwestern Festival.ts cutfile not found! PCR 11831 found at 564 pid 100 (47 41 00 30 07 50 00 00 8c 18 fe) PTS 6c1a4c0 found at 338506972 pid 101 stream: c0 before: 1 after: 1 setIoPrio best-effort level 5 ok FILEPUSH THREAD START no version filtering getNextSourceSpan, current offset is 0, m_skipmode_m = 0! 0012: 4e 00 00 00 00 00 NO CUESHEET. (0, 10485700) mask: ff ff ff 00 00 00 mode: 00 00 00 00 00 00 ok ... now we start!! no version filtering 1000: 02 00 01 00 00 00 mask: ff ff ff 00 00 00 mode: 00 00 00 00 00 00 realpath /media/net/NAS_Aufnahmen/movie/Kinderserien/My_little_Pony/20151028 1622 - Junior - My little Pony - Freundschaft ist Magie S02E05 Das Hufschwestern Festival.ts.meta: No such file or directory realpath /media/net/NAS_Aufnahmen/movie/Kinderserien/My_little_Pony/recordings.epl: No such file or directory realpath /media/net/NAS_Aufnahmen/movie/Kinderserien/My_little_Pony/20151028 1622 - Junior - My little Pony - Freundschaft ist Magie S02E05 Das Hufschwestern Festival.ts.meta: No such file or directory realpath /media/net/NAS_Aufnahmen/movie/Kinderserien/My_little_Pony/recordings.epl: No such file or directory [Picon Renderer] not found: /picons/piconHD/20151028 1622 - JUNIOR - MY LITTLE PONY - FREUNDSCHAFT IST MAGIE S02E05 DAS HUFSCHWESTERN FESTIVAL.TS.png [Picon Renderer] not found: /picons/piconHD/1_0_0_0_0_0_0_0_0_0.png realpath /media/net/NAS_Aufnahmen/movie/Kinderserien/My_little_Pony/20151028 1622 - Junior - My little Pony - Freundschaft ist Magie S02E05 Das Hufschwestern Festival.ts.meta: No such file or directory realpath /media/net/NAS_Aufnahmen/movie/Kinderserien/My_little_Pony/recordings.epl: No such file or directory [Picon Renderer] not found: /picons/piconHD/20151028 1622 - Junior - My little Pony - Freundschaft ist Magie S02E05 Das Hufschwestern Festival.ts.png [Picon Renderer] Sorry, i give up! [TeleText] service started new service started! trying to download cuts! eDVBServicePlay::unpause resolved to PLAY -1 realpath /media/net/NAS_Aufnahmen/movie/Kinderserien/My_little_Pony/20151028 1622 - Junior - My little Pony - Freundschaft ist Magie S02E05 Das Hufschwestern Festival.ts.meta: No such file or directory realpath /media/net/NAS_Aufnahmen/movie/Kinderserien/My_little_Pony/recordings.epl: No such file or directory eDVBServicePlay::unpause resolved to PLAY -1 realpath /media/net/NAS_Aufnahmen/movie/Kinderserien/My_little_Pony/20151028 1622 - Junior - My little Pony - Freundschaft ist Magie S02E05 Das Hufschwestern Festival.ts.meta: No such file or directory realpath /media/net/NAS_Aufnahmen/movie/Kinderserien/My_little_Pony/recordings.epl: No such file or directory getNextSourceSpan, current offset is 10485700, m_skipmode_m = 0! NO CUESHEET. (10485700, 10485700) getNextSourceSpan, current offset is 20971400, m_skipmode_m = 0! NO CUESHEET. (20971400, 10485700) getNextSourceSpan, current offset is 31457100, m_skipmode_m = 0! NO CUESHEET. (31457100, 10485700) timeout 0012! getNextSourceSpan, current offset is 41942800, m_skipmode_m = 0! NO CUESHEET. (41942800, 10485700) getNextSourceSpan, current offset is 52428500, m_skipmode_m = 0! NO CUESHEET. (52428500, 10485700) getNextSourceSpan, current offset is 62914200, m_skipmode_m = 0! NO CUESHEET. (62914200, 10485700) [ 151.723000] CEC recv ok! cec event! handle message 0 -> 3 : getNextSourceSpan, current offset is 73399900, m_skipmode_m = 0! NO CUESHEET. (73399900, 10485700) getNextSourceSpan, current offset is 83885600, m_skipmode_m = 0! NO CUESHEET. (83885600, 10485700) getNextSourceSpan, current offset is 94371300, m_skipmode_m = 0! NO CUESHEET. (94371300, 10485700) getNextSourceSpan, current offset is 104857000, m_skipmode_m = 0! NO CUESHEET. (104857000, 10485700) getNextSourceSpan, current offset is 115342700, m_skipmode_m = 0! NO CUESHEET. (115342700, 10485700) getNextSourceSpan, current offset is 125828400, m_skipmode_m = 0! NO CUESHEET. (125828400, 10485700) nr_read 1653 nr_write 11 sum 1664 prev_sum 1664 hdd IDLE! [IDLE] 30.0001158714 300 False getNextSourceSpan, current offset is 136314100, m_skipmode_m = 0! NO CUESHEET. (136314100, 10485700) getNextSourceSpan, current offset is 146799800, m_skipmode_m = 0! NO CUESHEET. (146799800, 10485700) getNextSourceSpan, current offset is 157285500, m_skipmode_m = 0! NO CUESHEET. (157285500, 10485700) getNextSourceSpan, current offset is 167771200, m_skipmode_m = 0! NO CUESHEET. (167771200, 10485700) timeout 1000! DVB service failed to tune - error 4 getNextSourceSpan, current offset is 178256900, m_skipmode_m = 0! NO CUESHEET. (178256900, 10485700) getNextSourceSpan, current offset is 188742600, m_skipmode_m = 0! NO CUESHEET. (188742600, 10485700) [ 166.725000] CEC recv ok! cec event! handle message 0 -> 3 : getNextSourceSpan, current offset is 199228300, m_skipmode_m = 0! NO CUESHEET. (199228300, 10485700) getNextSourceSpan, current offset is 209714000, m_skipmode_m = 0! NO CUESHEET. (209714000, 10485700) getNextSourceSpan, current offset is 220199700, m_skipmode_m = 0! NO CUESHEET. (220199700, 10485700) It's now Thu Oct 6 17:42:00 2016 next real activation is Fri Oct 7 07:36:40 2016 [timer.py] next activation: 1475768620 (in 99996 ms) It's now Thu Oct 6 17:42:00 2016 [timer.py] next activation: 1475768620 (in 99989 ms) getNextSourceSpan, current offset is 230685400, m_skipmode_m = 0! NO CUESHEET. (230685400, 10485700) It's now Thu Oct 6 17:42:01 2016 [EPGRefreshTimer] next real activation is Fri Oct 7 05:45:01 2016 [timer.py] next activation: 1475768621 (in 99997 ms) getNextSourceSpan, current offset is 241171100, m_skipmode_m = 0! NO CUESHEET. (241171100, 10485700) getNextSourceSpan, current offset is 251656800, m_skipmode_m = 0! NO CUESHEET. (251656800, 10485700) getNextSourceSpan, current offset is 262142500, m_skipmode_m = 0! NO CUESHEET. (262142500, 10485700) getNextSourceSpan, current offset is 272628200, m_skipmode_m = 0! NO CUESHEET. (272628200, 10485700) getNextSourceSpan, current offset is 283113900, m_skipmode_m = 0! NO CUESHEET. (283113900, 10485700) getNextSourceSpan, current offset is 293599600, m_skipmode_m = 0! NO CUESHEET. (293599600, 10485700) getNextSourceSpan, current offset is 304085300, m_skipmode_m = 0! NO CUESHEET. (304085300, 10485700) getNextSourceSpan, current offset is 314571000, m_skipmode_m = 0! NO CUESHEET. (314571000, 10485700) getNextSourceSpan, current offset is 325056700, m_skipmode_m = 0! NO CUESHEET. (325056700, 10485700) [ 181.723000] CEC recv ok! cec event! handle message 0 -> 3 : getNextSourceSpan, current offset is 335542400, m_skipmode_m = 0! NO CUESHEET. (335542400, 10485700) wait for driver eof ok reached EOF, but we are in stream mode. delaying 1 second. eDVBChannel: End of file! eDVBServicePlay::unpause resolved to PLAY -1 wait for driver eof ok reached EOF, but we are in stream mode. delaying 1 second. eDVBChannel: End of file! eDVBServicePlay::unpause resolved to PLAY -1 wait for driver eof ok reached EOF, but we are in stream mode. delaying 1 second. eDVBChannel: End of file! eDVBServicePlay::unpause resolved to PLAY -1 wait for driver eof ok reached EOF, but we are in stream mode. delaying 1 second. eDVBChannel: End of file! eDVBServicePlay::unpause resolved to PLAY -1 wait for driver eof ok reached EOF, but we are in stream mode. delaying 1 second. eDVBChannel: End of file! eDVBServicePlay::unpause resolved to PLAY -1 wait for driver eof ok reached EOF, but we are in stream mode. delaying 1 second. eDVBChannel: End of file! eDVBServicePlay::unpause resolved to PLAY -1 wait for driver eof ok reached EOF, but we are in stream mode. delaying 1 second. eDVBChannel: End of file! eDVBServicePlay::unpause resolved to PLAY -1 wait for driver eof ok reached EOF, but we are in stream mode. delaying 1 second. eDVBChannel: End of file! eDVBServicePlay::unpause resolved to PLAY -1 nr_read 1653 nr_write 11 sum 1664 prev_sum 1664 hdd IDLE! [IDLE] 59.9998660088 300 False wait for driver eof ok reached EOF, but we are in stream mode. delaying 1 second. eDVBChannel: End of file! eDVBServicePlay::unpause resolved to PLAY -1 wait for driver eof ok reached EOF, but we are in stream mode. delaying 1 second. eDVBChannel: End of file! eDVBServicePlay::unpause resolved to PLAY -1 wait for driver eof ok reached EOF, but we are in stream mode. delaying 1 second. eDVBChannel: End of file! eDVBServicePlay::unpause resolved to PLAY -1 wait for driver eof ok reached EOF, but we are in stream mode. delaying 1 second. eDVBChannel: End of file! eDVBServicePlay::unpause resolved to PLAY -1 wait for driver eof ok reached EOF, but we are in stream mode. delaying 1 second. eDVBChannel: End of file! eDVBServicePlay::unpause resolved to PLAY -1 wait for driver eof ok reached EOF, but we are in stream mode. delaying 1 second. eDVBChannel: End of file! eDVBServicePlay::unpause resolved to PLAY -1 [ 196.723000] CEC recv ok! cec event! handle message 0 -> 3 : wait for driver eof ok reached EOF, but we are in stream mode. delaying 1 second. eDVBChannel: End of file! eDVBServicePlay::unpause resolved to PLAY -1 [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 5815991 bytes for cache used wait for driver eof ok reached EOF, but we are in stream mode. delaying 1 second. eDVBChannel: End of file! eDVBServicePlay::unpause resolved to PLAY -1 wait for driver eof ok reached EOF, but we are in stream mode. delaying 1 second. eDVBChannel: End of file! eDVBServicePlay::unpause resolved to PLAY -1 wait for driver eof ok reached EOF, but we are in stream mode. delaying 1 second. eDVBChannel: End of file! eDVBServicePlay::unpause resolved to PLAY -1 wait for driver eof ok reached EOF, but we are in stream mode. delaying 1 second. eDVBChannel: End of file! eDVBServicePlay::unpause resolved to PLAY -1 wait for driver eof ok reached EOF, but we are in stream mode. delaying 1 second. eDVBChannel: End of file! eDVBServicePlay::unpause resolved to PLAY -1 wait for driver eof ok reached EOF, but we are in stream mode. delaying 1 second. eDVBChannel: End of file! eDVBServicePlay::unpause resolved to PLAY -1 wait for driver eof ok reached EOF, but we are in stream mode. delaying 1 second. eDVBChannel: End of file! eDVBServicePlay::unpause resolved to PLAY -1 wait for driver eof ok reached EOF, but we are in stream mode. delaying 1 second. eDVBChannel: End of file! eDVBServicePlay::unpause resolved to PLAY -1 wait for driver eof ok reached EOF, but we are in stream mode. delaying 1 second. eDVBChannel: End of file! eDVBServicePlay::unpause resolved to PLAY -1 wait for driver eof ok reached EOF, but we are in stream mode. delaying 1 second. eDVBChannel: End of file! eDVBServicePlay::unpause resolved to PLAY -1 wait for driver eof ok reached EOF, but we are in stream mode. delaying 1 second. eDVBChannel: End of file! eDVBServicePlay::unpause resolved to PLAY -1 wait for driver eof ok reached EOF, but we are in stream mode. delaying 1 second. eDVBChannel: End of file! eDVBServicePlay::unpause resolved to PLAY -1 wait for driver eof ok reached EOF, but we are in stream mode. delaying 1 second. eDVBChannel: End of file! eDVBServicePlay::unpause resolved to PLAY -1 wait for driver eof ok reached EOF, but we are in stream mode. delaying 1 second. eDVBChannel: End of file! eDVBServicePlay::unpause resolved to PLAY -1 [ 211.721000] CEC recv ok! cec event! handle message 0 -> 3 : wait for driver eof ok reached EOF, but we are in stream mode. delaying 1 second. eDVBChannel: End of file! eDVBServicePlay::unpause resolved to PLAY -1 wait for driver eof ok reached EOF, but we are in stream mode. delaying 1 second. eDVBChannel: End of file! eDVBServicePlay::unpause resolved to PLAY -1 wait for driver eof ok reached EOF, but we are in stream mode. delaying 1 second. eDVBChannel: End of file! eDVBServicePlay::unpause resolved to PLAY -1 wait for driver eof ok reached EOF, but we are in stream mode. delaying 1 second. eDVBChannel: End of file! eDVBServicePlay::unpause resolved to PLAY -1 wait for driver eof ok reached EOF, but we are in stream mode. delaying 1 second. eDVBChannel: End of file! eDVBServicePlay::unpause resolved to PLAY -1 wait for driver eof ok reached EOF, but we are in stream mode. delaying 1 second. eDVBChannel: End of file! eDVBServicePlay::unpause resolved to PLAY -1 wait for driver eof ok reached EOF, but we are in stream mode. delaying 1 second. eDVBChannel: End of file! eDVBServicePlay::unpause resolved to PLAY -1 wait for driver eof ok reached EOF, but we are in stream mode. delaying 1 second. eDVBChannel: End of file! eDVBServicePlay::unpause resolved to PLAY -1 wait for driver eof ok reached EOF, but we are in stream mode. delaying 1 second. eDVBChannel: End of file! eDVBServicePlay::unpause resolved to PLAY -1 nr_read 1653 nr_write 11 sum 1664 prev_sum 1664 hdd IDLE! [IDLE] 90.0000629425 300 False wait for driver eof ok reached EOF, but we are in stream mode. delaying 1 second. eDVBChannel: End of file! eDVBServicePlay::unpause resolved to PLAY -1 wait for driver eof ok reached EOF, but we are in stream mode. delaying 1 second. eDVBChannel: End of file! eDVBServicePlay::unpause resolved to PLAY -1 wait for driver eof ok reached EOF, but we are in stream mode. delaying 1 second. eDVBChannel: End of file! eDVBServicePlay::unpause resolved to PLAY -1 wait for driver eof ok reached EOF, but we are in stream mode. delaying 1 second. eDVBChannel: End of file! eDVBServicePlay::unpause resolved to PLAY -1 wait for driver eof ok reached EOF, but we are in stream mode. delaying 1 second. eDVBChannel: End of file! eDVBServicePlay::unpause resolved to PLAY -1 wait for driver eof ok reached EOF, but we are in stream mode. delaying 1 second. eDVBChannel: End of file! eDVBServicePlay::unpause resolved to PLAY -1 [ 226.721000] CEC recv ok! cec event! handle message 0 -> 3 : wait for driver eof ok reached EOF, but we are in stream mode. delaying 1 second. eDVBChannel: End of file! eDVBServicePlay::unpause resolved to PLAY -1 wait for driver eof ok reached EOF, but we are in stream mode. delaying 1 second. eDVBChannel: End of file! eDVBServicePlay::unpause resolved to PLAY -1 wait for driver eof ok reached EOF, but we are in stream mode. delaying 1 second. eDVBChannel: End of file! eDVBServicePlay::unpause resolved to PLAY -1 wait for driver eof ok reached EOF, but we are in stream mode. delaying 1 second. eDVBChannel: End of file! eDVBServicePlay::unpause resolved to PLAY -1 wait for driver eof ok reached EOF, but we are in stream mode. delaying 1 second. eDVBChannel: End of file! eDVBServicePlay::unpause resolved to PLAY -1 wait for driver eof ok reached EOF, but we are in stream mode. delaying 1 second. eDVBChannel: End of file! eDVBServicePlay::unpause resolved to PLAY -1 wait for driver eof ok reached EOF, but we are in stream mode. delaying 1 second. eDVBChannel: End of file! eDVBServicePlay::unpause resolved to PLAY -1 wait for driver eof ok reached EOF, but we are in stream mode. delaying 1 second. eDVBChannel: End of file! eDVBServicePlay::unpause resolved to PLAY -1 wait for driver eof ok reached EOF, but we are in stream mode. delaying 1 second. eDVBChannel: End of file! eDVBServicePlay::unpause resolved to PLAY -1 wait for driver eof ok reached EOF, but we are in stream mode. delaying 1 second. eDVBChannel: End of file! eDVBServicePlay::unpause resolved to PLAY -1 wait for driver eof ok reached EOF, but we are in stream mode. delaying 1 second. eDVBChannel: End of file! eDVBServicePlay::unpause resolved to PLAY -1 wait for driver eof ok reached EOF, but we are in stream mode. delaying 1 second. eDVBChannel: End of file! eDVBServicePlay::unpause resolved to PLAY -1 wait for driver eof ok reached EOF, but we are in stream mode. delaying 1 second. eDVBChannel: End of file! eDVBServicePlay::unpause resolved to PLAY -1 wait for driver eof ok reached EOF, but we are in stream mode. delaying 1 second. eDVBChannel: End of file! eDVBServicePlay::unpause resolved to PLAY -1 wait for driver eof ok reached EOF, but we are in stream mode. delaying 1 second. eDVBChannel: End of file! eDVBServicePlay::unpause resolved to PLAY -1 action -> MediaPlayerActions stop playing None stopService stopping thread. FILEPUSH THREAD STOP thread joined 0 [TeleText] service stopped [TeleText] send array('B', [1, 0, 0, 0]) realpath /media/net/NAS_Aufnahmen/movie/Kinderserien/My_little_Pony/20151028 1622 - Junior - My little Pony - Freundschaft ist Magie S02E05 Das Hufschwestern Festival.ts.meta: No such file or directory realpath /media/net/NAS_Aufnahmen/movie/Kinderserien/My_little_Pony/recordings.epl: No such file or directory [Picload] setPara max-X=250 max-Y=250 aspect_ratio=1.000000 cache=0 resize=1 bg=#FF000000 [ 241.722000] CEC recv ok! cec event! handle message 0 -> 3 : action -> DirectionActions down action -> DirectionActions downUp action -> OkCancelActions ok action -> DirectionActions right action -> DirectionActions rightUp action -> DirectionActions right action -> DirectionActions rightUp nr_read 1653 nr_write 11 sum 1664 prev_sum 1664 hdd IDLE! [IDLE] 119.999748945 300 False action -> OkCancelActions cancel Writing playlist into file /etc/enigma2/playlist.e2pls playing 1:0:19:132F:3EF:1:C00000:0:0:0: [TeleText] service started not pauseable. RemovePopup, id = ZapError allocate channel.. 03ef:0001:00c00000 opening frontend 0 [ 250.818000] bcm4506: channel 0 is in normale mode now [eDVBCAService] new channel 0x25b93f8! (0)tune RotorCmd ffffffff, lastRotorCmd ffffffff prepare_sat System 1 Freq 11302750 Pol 0 SR 22000000 INV 2 FEC 2 orbpos 192 system 1 modulation 2 pilo[ 250.830000] tsmux set tsports reg 00 to val 00 t 2, rolloff 0 tuning to 1552 mhz OURSTATE: tuning allocate Channel: res 0 allocate demux setInputSource input0 to A [SEC] lock 0 [SEC] set static current limiting [SEC] invalidate current switch params [SEC] setVoltage 2 (0)setVoltage 18V [SEC] sleep 10ms [SEC] update current switch params [SEC] startTuneTimeout 5000 [SEC] setFrontend 1 (0)setting frontend (0)fe event: status 0, freq 1552750, inversion off, m_tuning 1 F [SEC] unlock [SEC] sleep 500ms (0)fe event: status 1, freq 1552750, inversion off, m_tuning 1 N (0)fe event: status 0, freq 1552750, inversion off, m_tuning 2 N (0)fe event: status 1f, freq 1553040, inversion off, m_tuning 3 N OURSTATE: ok [eDVBCAService] channel 0x25b93f8 running [eDVBLocalTimeHandler] channel 0x25b93f8 running no version filtering 0014: 70 00 00 00 00 00 mask: fc 00 00 00 00 00 mode: 00 00 00 00 00 00 [eEPGCache] channel 0x25b93f8 running stop release channel timer [EPGC] next update in 2 sec no version filtering 0012: 4e 13 2f 00 00 00 mask: ff ff ff 00 00 00 mode: 00 00 00 00 00 00 ok ... now we start!! no version filtering 0000: 00 00 00 00 00 00 mask: ff 00 00 00 00 00 mode: 00 00 00 00 00 00 eventNewProgramInfo 0 0 have 1 video stream(s) (0780), and 1 audio stream(s) (0781), and the pcr pid is 0780, and the text pid is 0785 allocate demux disable teletext subtitles page ffffffffffffffff (und) decoder state: play, vpid=1920, apid=1921 DMX_SET_PES_FILTER(0x780) - pcr - ok[ 251.267000] start pcr0 on dmx 3 pid:780 index 5 DEMUX_START - pcr - ok DMX_SET_PES_FILTER(0x781) - audio - ok DEMUX_START - audio - ok AUDIO_SET_BYPASS(0) - [ 251.269000] start audio on dmx 3 pid:781 [ 251.269000] audio xpt start [ 251.270000] sync is off so we enable it [ 251.270000] demux-index 3 pcr0pid 780 [ 251.271000] using 5 as pcr0 pidchannel [ 251.272000] AC3 ok AUDIO_PAUSE - ok AUDIO_PLAY - [ 251.273000] audio: decoder start 0 [ 251.275000] audio delay 18000 (200 ms) [ 251.275000] total video delay: 18000 (200 ms) ok Video Device: /dev/dvb/adapter0/video0 demux device: /dev/dvb/adapter0/demux3 VIDEO_SET_STREAMTYPE 1 - ok DMX[ 251.278000] start video0 on dmx 3 pid:780 _SET_PES_FILTER([ 251.278000] total video delay: 18000 (200 ms) 0x780) - video - ok DEMUX_START - video - ok VIDEO_FREEZE - ok VIDEO_PLAY - [ 251.283000] VIDEO0: start decode ok DMX_SET_PES_FILTER(0x785) - ttx - ok DEMUX_START - ttx - ok VIDEO_SLOWMOTION(0) - ok VIDEO_FAST_FORWARD(0) - ok VIDEO_CONTINUE - ok AUDIO_CONTINUE - ok not pauseable. [TeleText] serviceInfoChanged [TeleText] TXT PID 1925 DEMUX 3 +- 1/2 TID 4e [ 251.342000] RAP first pts 0x185cfa370, stc 0x58811b4c, pcr valid [ 251.345000] audio_cdb_itb_error_isr! underflow [ 251.349000] audio_cdb_itb_error_isr! underflow [ 251.353000] audio_cdb_itb_error_isr! underflow [ 251.357000] audio_cdb_itb_error_isr! underflow [ 251.361000] audio_cdb_itb_error_isr! underflow [ 251.365000] audio_cdb_itb_error_isr! underflow [ 251.369000] audio_cdb_itb_error_isr! underflow [ 251.373000] audio_cdb_itb_error_isr! underflow [ 251.377000] audio_cdb_itb_error_isr! underflow [ 251.381000] audio_cdb_itb_error_isr! underflow [SEC] set dynamic current limiting + 1/1 TID 00 done! PATready use pmtpid 006b for service_id 132f no version filtering 006b: 02 13 2f 00 00 00 mask: ff ff ff 00 00 00 mode: 00 00 00 00 00 00 doing version filtering 0000: 00 00 00 31 00 00 mask: ff 00 00 3f 00 00 mode: 00 00 00 3e 00 00 + 1/1 TID 02 done! [eDVBCAService] new service 1:0:19:132F:3EF:1:C00000:0:0:0: [eDVBCAService] add demux 3 to slot 0 service 1:0:19:132F:3EF:1:C00000:0:0:0: [eDVBCAService] demux 3 mask 08 [eDVBCAServiceConn] send 116 bytes to '/tmp/camd.socket' 9f 80 32 70 03 13 2f 11 00 4c 01 81 08 00 c0 00 00 03 ef 00 01 82 02 08 03 84 02 00 6b 85 02 00 41 09 04 06 48 e0 78 09 04 06 50 e0 7a 09 04 0d 95 e1 0e 09 04 0d 98 e1 10 09 0f 05 00 e1 cd 10 01 00 13 01 20 14 03 05 19 00 09 04 09 c4 e1 e0 09 04 09 8c e1 ea 1b 07 80 00 00 06 07 81 00 00 06 07 82 00 00 06 07 85 00 00 05 1c 8e 00 00 0b 1c 8f 00 00 [eDVBCIInterfaces] addPMTHandler 1:0:19:132F:3EF:1:C00000:0:0:0: recheckPMTHAndlers() recheck 0x22b14c4 1:0:19:132F:3EF:1:C00000:0:0:0: HBBTV broadcast data on pid 1c8f check Slot 0 check Slot 1 recheckPMTHAndlers() recheck 0x22b14c4 1:0:19:132F:3EF:1:C00000:0:0:0: check Slot 0 check Slot 1 [eDVBCIInterfaces] gotPMT eventNewProgramInfo 0 0 start ait on pid 1c8e no version filtering 1c8e: 74 00 00 00 00 00 mask: ff 00 00 00 00 00 mode: 00 00 00 00 00 00 have 1 video stream(s) (0780), and 2 audio stream(s) (0781, 0782), and the pcr pid is 0780, and the text pid is 0785 decoder state: play, vpid=1920, apid=1921 HBBTV broadcast data on pid 1c8f [TeleText] serviceInfoChanged [TeleText] TXT PID 1925 DEMUX 3 doing version filtering 006b: 02 13 2f 11 00 00 mask: ff ff ff 3f 00 00 mode: 00 00 00 3e 00 00 [eFileMonitor] 1 events queued [eFileMonitor] File {/tmp/ecm.info} has been created [ 252.014000] ch0: lock sdt update done! ++ 2/2 TID 4e done! doing version filtering 0012: 4e 13 2f 2f 00 00 mask: ff ff ff 3f 00 00 mode: 00 00 00 3e 00 00 + 1/1 TID 74 done! [eHbbtv] aitChanged pid=0x1c8e [eOipfApplication] Profile 0000, Version 1.1.1 [eOipfApplication] deu:ORF HbbTV - SAT HD [eHbbtv] New Application: ORF HbbTV - SAT HD (orgid=13, appid=2, urlBase=http://orfhbbtv.orf.apa.net/orf/, initalPath=newsportal/index.html, controlCode=1, visibility=2) [eHbbtv] Resolving Application Locator: dvb://current.ait/13.2 [eHbbtv] We have 1 app(s) for service 1.1007.4911 [eHbbtv] 'dvb://current.ait/13.2' resolved to 'http://orfhbbtv.orf.apa.net/orf/newsportal/index.html' doing version filtering 1c8e: 74 00 00 01 00 00 mask: ff 00 00 3f 00 00 mode: 00 00 00 3e 00 00 [ 252.523000] VIDEO0: first pts 0x5884442e [ 252.523000] VIDEO0: framerate changed 6 [ 252.523000] VIDEO0: resolution changed (1280 720) VIDEO_GET_EVENT - ok [ 252.526000] set current avs auto aspect to 16:9 [ 252.526000] disable wss on display 0 [ 252.527000] set wss 7 on display 1 [ 252.529000] set sb to 3(1) VIDEO_GET_EVENT - ok [ 252.677000] enable tsm0 [EPGC] start caching events(1475768603) action -> OkCancelActions ok okbuttonClick SleepTimerEdit SleepTimerEdit Standby Standby Standby TryQuitMainloop Standby TryQuitMainloop Standby TryQuitMainloop reused converter! reused converter! allocating new converter! allocating new converter! [ 256.725000] CEC recv ok! cec event! handle message 0 -> 3 : action -> OkCancelActions ok okbuttonClick Looking for embedded skin set wakeup time to 2016/10/07 05:41 stopService [eDVBCAService] free slot 0 demux 3 for service 1:0:19:132F:3EF:1:C00000:0:0:0: [eDVBCAService] free service 1:0:19:132F:3EF:1:C00000:0:0:0: recheckPMTHAndlers() [eFileMonitor] 1 events queued [TeleText] service stopped [TeleText] send array('B', [1, 0, 0, 0]) decoder state: play, vpid=-1, ap[ 257.803000] stop pcr0 on dmx 3 pid:780 id=-1 DEMUX_STOP - pcr - ok DE[ 257.804000] stop video0 on dmx 3 pid:780 MUX_STOP - video - [ 257.813000] VIDEO0: stop decode ok VIDEO_STOP - [ 257.813000] VIDEO0: set blank to 1 ok AUDIO_STOP - [ 257.815000] audio: decoder stop ok DEMUX_STOP - aud[ 257.816000] stop audio on dmx 3 pid:781 io - [ 257.817000] audio: xpt stop(3) [ 257.817000] no decoder left in sync handling so disable xpt_sync [ 257.818000] bcm_audio_channel_stop(3) done ok AUDIO_CONTINUE - ok DEMUX_STOP - ttx - ok start release channel timer [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 5815991 bytes for cache used [EPGRefresh] Stopping Timer [TeleText] autostart(1, {}) [CrashlogAutoSubmit] - autostart [ResourceManager] adding: DiseqcTester [Cec] power off [eFileWatch] Not watching /tmp anymore [eFileWatch] Not watching /var/run anymore [eFileMonitor] File Watcher stopped thread joined 0 eBackgroundFileEraser got quit message thread joined 0 ---- saving lame channel db saved 107 channels and 1490 services! release cached channel (timer timeout) [eDVBCAService] remove channel 0x25b93f8 [eDVBCAService] write leave transponder to '/tmp/camd.socket' ok [eDVBLocalTimeHandler] remove channel 0x25b93f8 [eEPGCache] remove channel 0x25b93f8 [EPGC] abort caching events !! stop release channel timer (0) preClose [EPGC] store epg to realpath '/media/hdd/epg.dat' [EPGC] 42345 events written to /media/hdd/epg.dat thread joined 0 main thread is non-idle! display spinner! set RTC to previous valid time - (41) eHbbtv - (41) eServiceFactoryBludisc - (41) eServiceFactoryM2TS - (41) eServiceFactoryMP3 - (41) eServiceFactoryFS - (41) eServiceFactoryDVB - (41) eServiceFactoryDVD - (40) eServiceCenter - (35) CI Slots - (30) eActionMap - (21) input device driver - (21) Console RC Driver - (20) DVB-CI UI - (20) UHF Modulator - (20) RC Input layer - (20) misc options - (20) HDMI CEC Subsystem cec event! send 3 -> f : 36 [ 262.934000] CEC send ok! - (20) AVSwitch Driver - (15) eWindowStyleManager - (10) gRC waiting for gRC thread shutdown gRC thread has finished - (9) GFBDC [ 262.939000] bcmfb: creating surface with pixel format 0x07e48888 (BPXL_eA8_R8_G8_B8) [ 262.946000] bcmfb: disable manual blit - (9) gLCDDC - (9) Font Render Class - (8) graphics acceleration manager reached rl -1 [ 263.322000] CEC recv ok! [ 263.590000] CEC recv ok! [prepareClose] no mainloop [SEC] lock 0 [SEC] sendDiseqc: e01002(DiSEqC peripherial power off) [SEC] sleep 150ms [SEC] delayed close frontend close frontend 0 (0)setVoltage 0V [SEC] unlock [ 264.030000] bcm4506: channel 0 is sleeping now INIT: Switching to runlevel: 0 INIT: Sending processes the TERM signal Stopping Dropbear SSH server: no /bin/true found; none killed [SCRIPT] stop: CCcam 2.3.0 CCcam2.3.0 not running... Unmounting remote filesystems... * Stopping Avahi mDNS/DNS-SD Daemon: avahi-daemon ...done. Stopping automounter: done. stopping Busybox Periodic Command Scheduler: crond... stopped crond (pid 586) done. Stopping dbttcd. Stopping system message bus: dbus. Stopping default emu. stopping statd: done stopping mountd: done stopping nfsd: [ 266.096000] nfsd: last server has exited, flushing export cache done Stopping syslogd/klogd: stopped syslogd (pid 641) stopped klogd (pid 643) done Stopping internet superserver: xinetd. Unmounting remote filesystems... Deconfiguring network interfaces... smbd: no process found nmbd: no process found Sending all processes the TERM signal... [ 270.064000] HDMI: now powered down Sending all processes the KILL signal... Deactivating swap... Unmounting local filesystems... [ 273.176000] UBIFS: un-mount UBI device 0, volume 1 [ 275.345000] sd 0:0:0:0: [sda] Synchronizing SCSI cache [ 275.346000] sd 0:0:0:0: [sda] Stopping disk [ 275.348000] Power down. [ 275.349000] fp_power_o