=~=~=~=~=~=~=~=~=~=~=~= PuTTY log 2011.03.08 21:14:42 =~=~=~=~=~=~=~=~=~=~=~= BCM97400 AB FFFFFFFF 1s Sync=0 LLMB=0 L2=0 RAC_I=0 RAC_D=0 Copy Code...34TUCV (c) 2007,2008 Dream Multimedia GmbH. All rights reserved. Dreambox DM8000 FIRST STAGE 1.00 {BO23456}  2ND STAGE OK, build #82 (2011-02-25) 0.000 - BCM board setup Configure MEMC1_2........ Done 0.039 - fp init FP version 7 detected 0.106 - ca init 0.825 - load config 1.404 - config loaded. * press [S] to enter setup OLED found! lcd: BOOT #82 - NAND PROBE: 2c da 80 15 (normal) - read config file: /boot/secondstage.conf detected jffs2 blocksize: 128kb ++++++++++++++++++++++++ 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: /flash/bootlogo.elf bootlogo size: 170039 no HDMI device detected no HDMI mode available, reverting to 720p using 1280x720p (60.00 Hz) create surface 1280 720 Using the Small jpeg decoder library, Copyright (c) 2006, Luc Saillard parsing JPEG header.. Virtual FB Size: 1280x720 lcd: /flash/vmlinux.gz <5>Linux version 2.6.18-7.4-dm8000 (tmbinc@pepe.multimedia-labs.de) (gcc version 4.4.3 (GCC) ) #1 SMP Thu Feb 17 18:32:30 UTC 2011 Fetching vars from bootloader... none present, using defaults. Detected 256 MB on MEMC0 (strap 0x2219c310) <4>WARNING: read invalid MAC address ff:ff:ff:ff:ff:ff from flash @ 0xbffff824 Default command line = root=/dev/mtdblock3 rootfstype=jffs2 rw console=null 2 831ffec0 8302afe8 g_board_RAM_size=256MB Options: sata=1 enet=1 emac_1=0 no_mdio=0 docsis=0 ebi_war=0 pci=1 smp=1 CPU revision is: 0002a042 FPU revision is: 00130001 Determined physical RAM map: memory: 10000000 @ 00000000 (usable) ---> memsize from bootloader: 154 User-defined physical RAM map: node [00000000, 09a00000: RAM] node [09a00000, 06600000: RSVD] <5>Reserving 102 MB upper memory starting at 09a00000 <7>On node 0 totalpages: 65536 <7> DMA zone: 65536 pages, LIFO batch:15 Built 1 zonelists. Total pages: 65536 <5>Kernel command line: console=ttyS0,115200 root=/dev/mtdblock3 rootfstype=jffs2 rw before init RAC 0x01e0701f 0x0000000f 0x2fff0000 after init RAC 0x01e0705f 0x0000000f 0x2fff0000 [4294667.296000] ======> Before RAC_init:$22s5=00000020, $22s6(CBA)=11f0000c [4294667.296000] @B1F0_001C=00000001, @B1F0_0004=2fff0000, @B1F0_0000=01e0701f, @B1F0_0008=0000000f [4294667.296000] CBA = 11f00000 VALUE = 255 PAR_VAL2 = 0fff0000 [4294667.296000] RAC0 = b1f00000 RAC1 = b1f00008 RAC_RANGE = b1f00004 [4294667.296000] RAC_VALUE = 00087000 [4294667.296000] ******* Using CFE setting for RAC [4294667.296000] ******* $22s0=e30e3406, $22s5=00000020, $22s6=11f0000c [4294667.296000] Primary instruction cache 32kB, physically tagged, 2-way, linesize 64 bytes. [4294667.296000] Primary data cache 64kB, 4-way, linesize 64 bytes. [4294667.296000] Synthesized TLB refill handler (23 instructions). [4294667.296000] Synthesized TLB load handler fastpath (37 instructions). [4294667.296000] Synthesized TLB store handler fastpath (37 instructions). [4294667.296000] Synthesized TLB modify handler fastpath (36 instructions). [4294667.296000] PID hash table entries: 2048 (order: 11, 8192 bytes) [4294667.296000] Found MIPS counter frequency: 203 Mhz [4294667.296000] Using 202.501 MHz high precision timer. [4294667.296000] Console: colour dummy device 80x25 [4294667.400000] Dentry cache hash table entries: 32768 (order: 5, 131072 bytes) [4294667.406000] Inode-cache hash table entries: 16384 (order: 4, 65536 bytes) [4294667.425000] Memory: 151680k/262144k available (2623k kernel code, 110252k reserved, 566k data, 136k init, 0k highmem) [4294667.456000] Mount-cache hash table entries: 512 [4294667.460000] Checking for 'wait' instruction... available. [4294667.465000] plat_prepare_cpus: ENABLING 2nd Thread... [4294667.470000] TP0: prom_boot_secondary: Kick off 2nd CPU... [4294667.475000] CPU revision is: 0002a042 [4294667.475000] FPU revision is: 00130001 [4294667.475000] Primary instruction cache 32kB, physically tagged, 2-way, linesize 64 bytes. [4294667.475000] Primary data cache 64kB, 4-way, linesize 64 bytes. [4294667.475000] Synthesized TLB refill handler (23 instructions). [4294667.496000] Brought up 2 CPUs [4294667.568000] migration_cost=1000 [4294667.574000] NET: Registered protocol family 16 [4294667.578000] registering PCI controller with io_map_base unset [4294667.584000] registering PCI controller with io_map_base unset [4294667.589000] SCSI subsystem initialized [4294667.593000] usbcore: registered new driver usbfs [4294667.597000] usbcore: registered new driver hub [4294667.601000] NET: Registered protocol family 2 ÿ[4294667.617000] IP route cache hash table entries: 2048 (order: 1, 8192 bytes) [4294667.624000] TCP established hash table entries: 8192 (order: 4, 65536 bytes) [4294667.630000] TCP bind hash table entries: 4096 (order: 3, 32768 bytes) [4294667.636000] TCP: Hash tables configured (established 8192 bind 4096) [4294667.642000] TCP reno registered [4294667.645000] brcm-pm: disabling power to USB block [4294667.649000] brcm-pm: disabling power to ENET block [4294667.653000] brcm-pm: disabling power to SATA block [4294667.658000] JFFS2 version 2.2. (NAND) (SUMMARY) (C) 2001-2006 Red Hat, Inc. [4294667.665000] Initializing Cryptographic API [4294667.669000] io scheduler noop registered [4294667.672000] io scheduler anticipatory registered [4294667.676000] io scheduler deadline registered [4294667.680000] io scheduler cfq registered (default) [4294667.687000] Serial: 8250/16550 driver $Revision: 1.90 $ 1 ports, IRQ sharing disabled [4294667.695000] serial8250: ttyS0 at MMIO 0x0 (irq = 65) is a 16550A [4294667.700000] brcm-pm: enabling power to ENET block [4294667.704000] Broadcom BCM7400 P42 Ethernet Network Device v2.0 Feb 17 2011 18:31:45 [4294667.710000] SUNDRY revision = 74000032, have IP Hdr Opt=1 [4294667.717000] Config Internal PHY Through MDIO: 100 MB Full-Duplex (auto-neg) [4294669.345000] brcm-pm: enabling power to SATA block [4294669.365000] ata1: SATA max UDMA/133 cmd 0xB0510000 ctl 0xB0510020 bmdma 0xB0510030 irq 42 [4294669.374000] ata2: SATA max UDMA/133 cmd 0xB0510100 ctl 0xB0510120 bmdma 0xB0510130 irq 42 [4294669.381000] scsi0 : sata_svw [4294669.686000] ata1: SATA link down (SStatus 4 SControl 300) [4294669.722000] ATA: abnormal status 0x7F on port 0xb051001c [4294669.728000] scsi1 : sata_svw [4294675.087000] ata2: SATA link up 1.5 Gbps (SStatus 113 SControl F0000) [4294675.100000] ata2.00: ATA-8, max UDMA/133, 3907029168 sectors: LBA48 NCQ (depth 0/32s) [4294675.108000] ata2.00: ata2: dev 0 multi count 0 [4294675.115000] ata2.00: configured for UDMA/133 [4294675.119000] Vendor: ATA Model: WDC WD20EADS-00R Rev: 01.0 [42946M75.124000] Type: Direct-Access ANSI SCSI revision: 05 [4294675.131000] SCSI device sda: 3907029168 512-byte hdwr sectors (2000399 MB) [4294675.137000] sda: Write Protect is off [4294675.140000] SCSI device sda: drive cache: write back [4294675.144000] SCSI device sda: 3907029168 512-byte hdwr sectors (2000399 MB) [4294675.151000] sda: Write Protect is off [4294675.154000] SCSI device sda: drive cache: write back [4294675.158000] sda: sda1 [4294675.190000] sd 1:0:0:0: Attached scsi disk sda [4294675.195000] sd 1:0:0:0: Attached scsi generic sg0 type 0 [4294675.417000] 4587 kb/s [4294675.419000] - NAND PROBE: 2c da 80 15 [4294675.422000] NAND device: Manufacturer ID: 0x2c, Chip ID: 0xda (Unknown NAND 256MiB 3,3V 8-bit) [4294675.430000] Scanning device for bad blocks [4294675.435000] Bad eraseblock 78 at 0x009c0000 [4294675.450000] Bad eraseblock 376 at 0x02f00000 [4294675.480000] Bad eraseblock 1060 at 0x08480000 [4294675.502000] Bad eraseblock 1542 at 0x0c0c0000 [4294675.521000] Bad eraseblock 1930 at 0x0f140000 [4294675.529000] Creating 7 MTD partitions on "NAND 256MiB 3,3V 8-bit": [4294675.536000] 0x0000000000000000-0x0000000010000000 : "complete" [4294675.541000] 0x0000000000000000-0x0000000000100000 : "loader" [4294675.546000] 0x0000000000100000-0x0000000000400000 : "boot partition" [4294675.552000] 0x0000000000400000-0x0000000004000000 : "root partition" [4294675.558000] 0x0000000004000000-0x0000000008000000 : "home partition" [4294675.564000] 0x0000000008000000-0x000000000f800000 : "unused partition" [4294675.570000] 0x000000000f800000-0x0000000010000000 : "preset partition" [4294675.576000] ehci_hcd_brcm_init: Initializing 2 EHCI controller(s) [4294675.581000] brcm-pm: enabling power to USB block [4294675.585000] 10 Dec 2004 USB 2.0 'Enhanced' Host Controler (EHCI) Driver (EHCI-brcm-0) [4294675.592000] drivers/usb/host/ehci-brcm.c: starting brcm EHCI USB Controller [4294675.599000] - USB h/w setup mode [4294675.599000] - USB power enable set for active LOW mode [4294675.607000] brcm-ehci brcm-ehci.0: BRCM EHCI [4294675.610000] brcm-ehci brcm-ehci.0: new USB bus registered, assigned bus number 1 [494675.617000] brcm-ehci brcm-ehci.0: irq 62, io mem 0x10480300 [4294675.622000] brcm-ehci brcm-ehci.0: USB 0.0 started, EHCI 1.00, driver 10 Dec 2004 [4294675.629000] usb usb1: configuration #1 chosen from 1 choice [4294675.634000] hub 1-0:1.0: USB hub found [4294675.637000] hub 1-0:1.0: 2 ports detected [4294675.741000] 10 Dec 2004 USB 2.0 'Enhanced' Host Controller (EHCI) Driver (EHCI-brcm-1) [4294675.749000] drivers/usb/host/ehci-brcm.c: starting brcm EHCI USB Controller [4294675.755000] brcm-ehci-1 brcm-ehci-1.1: BRCM EHCI [4294675.759000] brcm-ehci-1 brcm-ehci-1.1: new USB bus registered, assigned bus number 2 [4294675.766000] brcm-ehci-1 brcm-ehci-1.1: irq 57, io mem 0x10480500 [4294675.771000] brcm-ehci-1 brcm-ehci-1.1: USB 0.0 started, EHCI 1.00, driver 10 Dec 2004 [4294675.779000] usb usb2: configuration #1 chosen from 1 choice [4294675.784000] hub 2-0:1.0: USB hub found [4294675.787000] hub 2-0:1.0: 2 ports detected [4294675.891000] ohci_hcd_brcm_init: Initializing 2 OHCI controller(s) [4294675.897000] 2005 April 22 USB 1.1 'Open' Host Controller (OHCI) Driver (OHCI-brcm-0) [4294675.904000] drivers/usb/host/ohci-brcm.c: starting brcm OHCI USB Controller [4294675.910000] - Resetting at b0480408 [4294675.913000] <-- brcm_start_hc [4294675.915000] brcm-ohci-0 brcm-ohci-0.0: BRCM OHCI [4294675.919000] brcm-ohci-0 brcm-ohci-0.0: new USB bus registered, assigned bus number 3 [4294675.926000] brcm-ohci-0 brcm-ohci-0.0: irq 63, io mem 0x10480400 [4294675.955000] usb 1-1: new high speed USB device using brcm-ehci and address 2 [4294675.986000] usb usb3: configuration #1 chosen from 1 choice [4294675.992000] hub 3-0:1.0: USB hub found [4294675.995000] hub 3-0:1.0: 2 ports detected [4294676.076000] usb 1-1: configuration #1 chosen from 1 choice [4294676.082000] hub 1-1:1.0: USB hub found [4294676.085000] hub 1-1:1.0: 3 ports detected [4294676.099000] 2005 April 22 USB 1.1 'Open' Host Controller (OHCI) Driver (OHCI-brcm-1) [4294676.107000] drivers/usb/host/ohci-brcm.c: starting brcm OHCI USB Controller [4294676.113000] - Resetting at b0480608 [4294676.116000] <-- brcm_start_hc [4294676.118000] brcm-ohci-1 brcm-ohci-1.1: BRCM OHCI [4294676.122000] brcm-ohci-1 brcm-ohci-1.1: new USB bus registered, assigned bus number 4 [4294676.129000] brcm-ohci-1 brcm-ohci-1.1: irq 64, io mem 0x10480600 [4294676.189000] usb usb4: configuration #1 chosen from 1 choice [4294676.195000] hub 4-0:1.0: USB hub found [4294676.198000] hub 4-0:1.0: 2 ports detected [4294676.302000] Initializing USB Mass Storage driver... [4294676.401000] usb 1-2: new high speed USB device using brcm-ehci and address 3 [4294676.524000] usb 1-2: configuration #1 chosen from 1 choice [4294676.530000] scsi2 : SCSI emulation for USB Mass Storage devices [4294676.536000] usbcore: registered new driver usb-storage [4294676.536000] USB Mass Storage support registered. [4294676.536000] i2c /dev entries driver [4294676.549000] TCP bic registered [4294676.553000] NET: Registered protocol family 1 [4294676.556000] NET: Registered protocol family 17 [4294681.549000] isa bounce pool size: 16 pages [4294681.554000] Vendor: Generic Model: USB SD Reader Rev: 1.00 [4294681.562000] Type: Direct-Access ANSI SCSI revision: 00 [4294681.573000] sd 2:0:0:0: Attached scsi removable disk sdb [4294681.579000] sd 2:0:0:0: Attached scsi generic sg1 type 0 [4294681.586000] Vendor: Generic Model: USB CF Reader Rev: 1.01 [4294681.593000] Type: Direct-Access ANSI SCSI revision: 00 [4294681.605000] sd 2:0:0:1: Attached scsi removable disk sdc [4294681.611000] sd 2:0:0:1: Attached scsi generic sg2 type 0 [4294681.618000] Vendor: Generic Model: USB SM Reader Rev: 1.02 [4294681.625000] Type: Direct-Access ANSI SCSI revision: 00 [4294681.637000] sd 2:0:0:2: Attached scsi removable disk sdd [4294681.642000] sd 2:0:0:2: Attached scsi generic sg3 type 0 [4294681.649000] Vendor: Generic Model: USB MS Reader Rev: 1.03 [4294681.656000] Type: Direct-Access ANSI SCSI revision: 00 [4294681.668000] sd 2:0:0:3: Attached scsi removable disk sde [4294681.674000] sd 2:0:0:3: Attached scsi generic sg4 type 0 [4294690.251000] VFS: Mounted root (jffs2 filesystem). [4294690.256000] Freeing unused kernel memory: 136k freed INIT: version 2.86 booting [4294692.122000] stb_core: module license 'Proprietary' taints kernel. [4294692.241000] 4019912 ms: [4294692.290000] 46 ms: 27 Starting /etc/rcS.d/S02sysfs... [4294692.306000] 11 ms: =s Startting the hotplug events dispatcher iSynthesizing the initial hotplug events Waiting for /dev to be fully populated udevd-event[120]: node_symlink: device node '/dev/dbox/lcd0' already exists, link to '/dev/dbox/oled0' will not overwrite it done [4294694.328000] 2020 ms: 29 Starting /etc/rcS.d/S03udev... [4294694.343000] 10 ms: =s [4294694.360000] 14 ms: 31 Starting /etc/rcS.d/S04bootup... [4294694.372000] 6 ms: 10 [4294695.207000] kjournald starting. Commit interval 5 seconds [4294695.207000] EXT3-fs warning: maximal mount count reached, running e2fsck is recommended [4294695.215000] EXT3 FS on sda1, internal journal [4294695.216000] EXT3-fs: recovery complete. [4294695.216000] EXT3-fs: mounted filesystem with ordered data mode. [4294695.237000] 863 ms: 12 [4294695.256000] 16 ms: 14 [4294695.276000] 18 ms: 15 /dev/sda: setting standby[4294695.346000] 68 ms: 16 to 120 (10 minutes) /dev/sda: setting acoustic management to 128 acoustic =[4294695.429000] 80 ms: 17 128 (128=quiet ... 254=fast) [4294695.462000] input: dreambox remote control (native) as /class/input/input0 [4294695.470000] input: dreambox advanced remote control (native) as /class/input/input1 [4294695.491000] input: dreambox ir keyboard as /class/input/input2 [4294695.497000] input: dreambox ir mouse as /class/input/input3 [4294695.975000] fpga init [4294698.267000] done OK 0 [4294701.128000] enter base init, xvd 20100413, vdc 20100413, rap 20090430, xpt 20100825 [4294701.136000] !!! kernMemSize: 154 MB [4294701.142000] smca set muxes [4294701.145000] smcb set muxes [4294701.148000] smcc set muxes [4294701.161000] 33:865012000 SMCA: controller reset timed out [4294701.176000] 33:880015000 SMCB: controller reset timed out [4294703.397000] maskffffffff [4294703.399000] using fpga version 2a [4294703.402000] tsmux set tsports reg 02 to val dc [4294703.406000] tsmux set tsports reg 02 to val dc [4294703.409000] tsmux set tsports reg 03 to val fe [4294703.413000] tsmux set tsports reg 03 to val fe [4294703.417000] tsmux set cisource reg 00 to val 55 [4294703.420000] tsmux set cisource reg 00 to val 55 [4294703.424000] tsmux set cisource reg 01 to val 55 [4294703.428000] tsmux set cisource reg 01 to val 55 [4294703.432000] register adapter [4294703.434000] register adapter [4294703.436000] register adapter [4294703.439000] register adapter [4294703.441000] register adapter [4294703.566000] FP Version 7 [4294703.577000] input: dreambox front panel as /class/input/input4 [4294703.598000] HDMI: no device attached [4294703.602000] set sb to 3(2) [4294703.605000] set fb to 2(-1) [4294703.607000] set input to 0 [4294703.609000] set fb to 2(-1) [4294703.616000] bcm_video_analog_port_setup (fmt=7) [4294703.620000] SET FORMAT: display[1], format=7 [4294703.624000] display 0 uses vbi path 1 [4294703.627000] display 1 uses vbi path 0 [4294703.630000] HDMI: hotplug handler! [4294703.634000] HDMI: no device attached [4294703.637000] using 50.00Hz as MonitorRefreshRate [4294703.641000] SET FORMAT: display[0], format=37 [4294703.644000] set fb to 2(0) [4294703.646000] bcm_video_analog_port_setup (fmt=7) [4294703.650000] SET FORMAT: display[1], format=7 [4294703.653000] Write AVS: 40 40 00 00 00 00 00 03 00 02 00 00 00 ff [4294703.671000] Write AVS: 40 40 00 00 00 00 18 63 00 02 00 00 00 ff [4294703.682000] set sb to 3(2) [4294703.691000] disable wss on display 0 [4294703.694000] disable wss on display 1 [4294703.697000] bcmfb: creating surface with pixel format 0x07e48888 (BPXL_eA8_R8_G8_B8) udevd-event[443]: node_symlink: rename(/dev/fb.udev-tmp, /dev/fb) failed: Is a directory [4294703.856000] enable mad [4294703.907000] VIDEO0: set fallback framerate to 60000 [4294703.912000] VIDEO0: set progressive override mode to 1 [4294703.916000] VIDEO0: set streamtype! [4294703.919000] set MPEG2 [4294703.920000] sw rave disabled! [4294704.052000] enable mad [4294704.055000] VIDEO1: set fallback framerate to 60000 [4294704.059000] VIDEO1: set progressive override mode to 1 [4294704.064000] VIDEO1: set streamtype! [4294704.067000] set MPEG2 [4294704.068000] sw rave disabled! [4294704.074000] sync is off so we enable it [4294704.078000] playback! [4294704.080000] total video delay: 0 (0 ms) [4294704.083000] VIDEO0: start decode [4294704.086000] flush video decoder [4294704.089000] VIDEO0 got pts failed !!!! (ret 0) [4294704.506000] set ac3 to downmix [4294704.509000] set aac to downmix [4294704.529000] VIDEO0: first pts 0x0 [4294704.565000] init ALSA! [4294704.569000] DVB: registering new adapter (BCM7400) [4294704.675000] autodetecting NIMs... [4294704.679000] ret: -145, feid: 00 [4294704.682000] enable tsm0 [4294704.685000] ret: -145, feid: 00 [4294704.688000] Initializing adapter 0 (BCM4501 (internal)-DVB-S2) [4294704.695000] bcm4501: found 4501, revision 2(c0) [4294704.700000] bcm_initAP [4294704.710000] BCM4501: upload & start firmware [4294706.071000] 4501 uCode 0x65 [4294706.077000] DVB: registering adapter 0 frontend 0 (bcm4501)... [4294706.083000] Initializing adapter 1 (BCM4501 (internal)-DVB-S2) [4294706.089000] DVB: registering adapter 0 frontend 0 (bcm4501)... [4294706.095000] CI inserted... [4294706.099000] CI removed... [4294706.102000] CI removed... [4294706.104000] registered vtuner vtuner0, minor 53 [4294706.108000] registered vtuner vtuner1, minor 52 [4294706.113000] CI removed... [4294707.116000] VIDEO0: stop decode [4294707.119000] no decoder left in sync handling so disable xpt_sync [4294707.130000] 11698 ms: 18 [4294707.227000] using 50.00Hz as MonitorRefreshRate [4294707.345000] SET FORMAT: display[0], format=27 [4294707.35000] bcm_video_analog_port_setup (fmt=7) [4294707.504000] SET FORMAT: display[1], format=7 [4294707.623000] set current avs auto aspect to 16:9 [4294707.628000] disable wss on display 0 [4294707.630000] disable wss on display 1 [4294707.633000] set sb to 3(1) [4294707.635000] Write AVS: 40 40505 ms: 19 [4294707.645000] 00 00 00 00 18 61 0012 ms: 20 [4294707.653000] 02 00 00 00 ff [4294707.683000] 31 ms: 21 [4294707.692000] 7 ms: 22 [4294707.696000] 2 ms: 23 [4294707.793000] VIDEO0: set blank to 0 [4294707.798000] sync is off so we enable it [4294707.802000] playback! [4294707.803000] total video delay: 0 (0 ms) [4294707.832000] VIDEO0: start decode [4294707.836000] flush video decoder [4294707.969000] VIDEO0: first pts 0x0 [4294707.972000] VIDEO0: framerate changed 3 [4294707.976000] VIDEO0: resolution changed (1280 720) [4294707.979000] VIDEO0: aspect changed 3 [4294707.982000] set current avs auto aspect to 16:9 [4294707.987000] disable wss on display 0 [4294707.991000] disable wss on display 1 [4294707.995000] set current avs auto aspect to 16:9 [4294708.000000] disable wss on display 0 [4294708.004000] disable wss on display 1 [4294708.004000] [cis] 1d 04 [4294708.004000] [cis] 1c 03 [4294708.004000] [cis] 15 15 [4294708.004000] [cis] 20 04 [4294708.004000] [cis] 1a 15 [4294708.004000] [cis] 1b 11 [4294708.004000] [cis] 1b 25 [4294708.004000] [cis] 14 00 [4294708.004000] [cis] ff 00 [4294708.032000] set sb to 3(1) [4294708.097000] VIDEO0: set blank to 0 [4294708.115000] VIDEO0: stop decode [4294708.119000] VIDEO0: set blank to 0 [4294708.123000] no decoder left in sync handling so disable xpt_sync [4294708.242000] CI buffersize negotiation ok ldconfig: Can't open configuration file /etc/ld.so.conf: No such file or directory [4294708.281000] CI tc_open for 1 is now acknowledged [4294717.203000] 9505 ms: 24 [4294717.221000] 15 ms: 25 [4294717.235000] 11 ms: =s Starting system message bus: dbus. [4294717.647000] 410 ms: 33 Starting /etc/rcS.d/S05dbus-1... [4294717.665000] 13 ms: =s [4294717.721000] 54 ms: 35 Starting /etc/rcS.d/S10tpmd... [4294717.745000] 19 ms: =s [4294717.787000] 39 ms: 37 Starting /etc/rcS.d/S15dccamd... [4294717.824000] 32 ms: =s [4294717.953000] 126 ms: 39 Starting /etc/rcS.d/S20modutils.sh... [4294717.978000] 19 ms: =s * Starting Avahi mDNS/DNS-SD Daemon: avahi-daemon [ ok ] [4294718.552000] 572 ms: 41 Starting /etc/rcS.d/S21avahi-daemon... [4294718.571000] 13 ms: =s [4294718.618000] 45 ms: 43 Starting /etc/rcS.d/S38devpts.sh... [4294718.638000] 14 ms: =s Setting up IP spoofing protection: rp_filter. Configuring network interfaces... eth0 no wireless extensions. udhcpc (v1.15.3) started Sending discover... Sending select for 192.168.11.3... Lease of 192.168.11.3 obtained, lease time 172800 adding dns 192.168.11.1 done. [4294723.207000] 4567 ms: 45 Starting /etc/rcS.d/S40networking... INIT: Entering runlevel: 3 system message bus already started; not starting. Starting system message bus: /usr/bin/dbus-daemon is already running 534 Starting Dropbear SSH server: dropbear. Starting internet superserver: inetd. Starting syslogd/klogd: done * starting FTP Server: vsftpd... done. Starting automounter: done. * Starting Avahi mDNS/DNS-SD Daemon: avahi-daemon [ ok ] [4294725.665000] VIDEO0: set blank to 0 [4294725.670000] sync is off so we enable it [4294725.674000] playback! [4294725.677000] total video delay: 0 (0 ms) [4294725.683000] VIDEO0: start decode [4294725.688000] flush video decoder [4294725.719000] VIDEO0: first pts 0x0 [4294725.847000] VIDEO0: set blank to 0 [4294725.852000] VIDEO0: stop decode [4294725.855000] VIDEO0: set blank to 0 [4294725.859000] no decoder left in sync handling so disable xpt_sync setenv('prefix', '/usr', 0) setenv('exec_prefix', '/usr', 0) setenv('bindir', '/usr/bin', 0) setenv('sbindir', '/usr/sbin', 0) setenv('libexecdir', '/usr/libexec', 0) setenv('datarootdir', '${prefix}/share', 0) setenv('datadir', '/usr/share', 0) setenv('sysconfdir', '/etc', 0) setenv('sharedstatedir', '/com', 0) setenv('localstatedir', '/var', 0) setenv('libdir', '/usr/lib', 0) setenv('localedir', '${datarootdir}/locale', 0) setenv('prefix', '/usr', 1) setenv('exec_[prefix', '/usr', 1) setenv('bindir', '/usr/bin', 1) setenv('sbindir', '/usr/sbin', 1) setenv('libexecdir', '/usr/libexec', 1) setenv('datarootdir', '/usr/share', 1) setenv('datadir', '/usr/share', 1) setenv('sysconfdir', '/etc', 1) setenv('sharedstatedir', '/com', 1) setenv('localstatedir', '/var', 1) setenv('libdir', '/usr/lib', 1) setenv('localedir', '/usr/share/locale', 1) resolve: resolve ${datadir}/enigma2/encoding.conf resolve: -> /usr/share/enigma2/encoding.conf resolve: resolve ${libdir}/enigma2/python resolve: -> /usr/lib/enigma2/python PYTHONPATH: /usr/lib/enigma2/python + (1) Background File Eraser + (8) graphics acceleration manager + (9) GFBDC [4294732.377000] bcmfb: enable manual blit 16384k video mem [4294732.382000] bcmfb: creating surface with pixel format 0x07e48888 (BPXL_eA8_R8_G8_B8) - double buffering available! 13144kB available for acceleration surfaces. resolution: 720 x 576 x 32 (stride: 2880) + (9) gLCDDC found OLED display! lcd buffer 0x6aecd0 8448 bytes, stride 132 + (9) Font Render Class [FONT] initializing lib... [FONT] loading fonts... [FONT] Intializing font cache, using max. 4MB... + (10) gRC RC thread[4294732.625000] tsmux set cisource reg 00 to val 54 created successfully + (15) eW[4294732.630000] tsmux set cisource reg 00 to val 44 indowStyleManager + (20) DVB-CI UI + (20) UHF [4294732.639000] tsmux set cisource reg 01 to val 54 Modulator couldnt open /dev/rfm[4294732.645000] tsmux set cisource reg 01 to val 44 od0!!!! + (20) RC Input layer + (20) misc opti[4294732.653000] tsmux set tsports reg 02 to val dc ons + (20) AVSwitch Driver + ([4294732.659000] tsmux set tsports reg 02 to val dc 21) input device driver Input d[4294732.666000] tsmux set tsports reg 03 to val fe evice "dreambox remote control ([4294732.672000] tsmux set tsports reg 03 to val fe native)" is not a keyboard. Input device "dreambox advanced remote control (native)" is not a keyboard. Input device "dreambox ir keyboard" is a keyboard. Input device "dreambox ir mouse" is not a keyboard. Input device "dreambox front panel" is not a keyboard. Found 5 input devices! + (21) Console RC Driver + (30) eActionMap + (35) CI Slots scanning for common interfaces.. CI Slot 0 setSource(0) CI Slot 1 setSource(0) CI Slot 2 setSource(0) CI Slot 3 setSource(0) eDVBCIInterfaces->setInputSource(0, 0) eDVBCIInterfaces->setInputSource(1, 1) eDVBCIInterfaces->setInputSource(2, 2) eDVBCIInterfaces->setInputSource(3, 3) done, found 4 common interface slots + (40) eServiceCenter settings instance. + (41) eServiceFactoryM2TS + (41) eServiceFactoryMP3 + (41) eServiceFactoryFS + (41) eServiceFactoryDVB reached rl 70 resolve: resolve ${sysconfdir}/enigma2/lamedb resolve: -> /etc/enigma2/lamedb ---- opening lame channel db reading services (version 4) loaded 4140 services scanning for frontends.. openin[4294733.767000] bcm4501 channel 0 is in normale mode now g frontend 0 detected satellite frontend close frontend 0 opening frontend 1 [4294733.782000] bcm4501 channel 1 is in normale mode now detected satellite frontend close frontend 1 found 1 adapter, 2 frontends(2 sim) and 5 demux, boxtype 4 RTC not ready... wait for transponder time [EPGC] Initialized EPGCache (wait for setCacheFile call now) resolve: resolve ${datadir}/enigma2/skin_default/pal.png resolve: -> /usr/share/enigma2/skin_default/pal.png Loading spinners... resolve: resolve ${datadir}/enigma2/skin_default/spinner/wait1.png resolve: -> /usr/share/enigma2/skin_default/spinner/wait1.png resolve: resolve ${datadir}/enigma2/skin_default/spinner/wait2.png resolve: -> /usrshare/enigma2/skin_default/spinner/wait2.png resolve: resolve ${datadir}/enigma2/skin_default/spinner/wait3.png resolve: -> /usr/share/enigma2/skin_default/spinner/wait3.png resolve: resolve ${datadir}/enigma2/skin_default/spinner/wait4.png resolve: -> /usr/share/enigma2/skin_default/spinner/wait4.png resolve: resolve ${datadir}/enigma2/skin_default/spinner/wait5.png resolve: -> /usr/share/enigma2/skin_default/spinner/wait5.png found 4 spinner! executing main setIoPrio best-effort level 3 ok resolve: resolve ${libdir}/enigma2/python/mytest.py resolve: -> /usr/lib/enigma2/python/mytest.py main thread is non-idle! display spinner! resolve: resolve ${sysconfdir}/ resolve: -> /etc/ resolve: resolve ${sysconfdir}/ resolve: -> /etc/ resolve: resolve ${datadir}/fonts/ resolve: -> /usr/share/fonts/ resolve: resolve ${sysconfdir}/enigma2/ resolve: -> /etc/enigma2/ resolve: resolve ${libdir}/enigma2/python/Plugins/ resolve: -> /usr/lib/enigma2/python/Plugins/ resolve: resolve ${datadir}/enigma2/po/ resolve: -> /usr/share/enigma2/po/ resolve: resolve ${datadir}/enigma2/ resolve: -> /usr/share/enigma2/ resolve: resolve ${datadir}/enigma2/ resolve: -> /usr/share/enigma2/ resolve: resolve ${sysconfdir}/enigma2/playlist/ resolve: -> /etc/enigma2/playlist/ resolve: resolve ${datadir}/enigma2/defaults/ resolve: -> /usr/share/enigma2/defaults/ resolve: resolve ${datadir}/enigma2/dealer resolve: -> /usr/share/enigma2/dealer resolve: resolve ${datadir}/meta resolve: -> /usr/share/meta resolve: resolve ${datadir}/enigma2/defaults/ resolve: -> /usr/share/enigma2/defaults/ [4294736.021000] 12808 ms: =s [4294736.036000] 12 ms: 51 [4294736.452000] 413 ms: 51 [4294736.591000] 137 ms: 52 main thread is non-idle! display spinner! [4294736.649000] 56 ms: 52 [4294736.780000] bcm4501 channel 0 is sleeping now [4294736.788000] bcm4501 channel 1 is sleeping now [4294737.436000] 785 ms: 52 /proc/stb/frontend/0/lnb_sense is 0 enumerating block devices... found block device 'sde': no medium found block device 'sdd': no medium found block device 'sdc': no medium found block device 'sdb': no medium found block device 'mtdblock6': blacklisted found block device 'mtdblock5': blacklisted found block device 'mtdblock4': blacklisted found block device 'mtdblock3': blacklisted found block device 'mtdblock2': blacklisted found block device 'mtdblock1': blacklisted found block device 'mtdblock0': blacklisted found block device 'sda': ok, removable=False, cdrom=False, partitions=['sda1'] new Harddisk sda -> /dev/sda -> /dev/sda [ePopen] command: ('hdparm', 'hdparm', '-S0', '/dev/sda') found block device 'sda1': error querying properties [4294737.942000] 503 ms: 54 not loading user skin: [Errno 2] No such file or directory: '/etc/enigma2/skin_user.xml' SKIN ERROR: [Errno 2] No such file or directory: '/usr/share/enigma2/dmm-HD/skin.xml' defaulting to standard skin... [4294737.984000] 39 ms: 55 [4294738.144000] 157 ms: 55 [4294738.1610000] 14 ms: 56 [4294738.166000] 2 ms: 56 Preferred tag editor set to None [4294738.979000] 811 ms: 56 [4294738.989000] 7 ms: 58 [4294739.181000] 189 ms: 58 [4294739.902000] 718 ms: 58 Reading satellites.xml resolve: resolve ${datadir}/enigma2/unicable.xml resolve: -> /usr/share/enigma2/unicable.xml removing internal link on frontend id 1 sec config cleared setSlotInfo for dvb frontend 0 to slotid 0, descr BCM4501 (internal), need rotorworkaround Yes, enabled Yes, DVB-S2 Yes setSlotInfo for dvb frontend 1 to slotid 1, descr BCM4501 (internal), need rotorworkaround Yes, enabled Yes, DVB-S2 Yes slot: 0 configmode: advanced add 130 to 5 add 160 to 4 add 192 to 3 add 284 to 2 add 390 to 1 slot: 1 configmode: simple diseqcmode: single sec config comp[4294741.741000] 1836 ms: 60 leted [4294741.777000] 33 ms: 64 [4294741.780000] 1 ms: 64 [4294741.783000] 0 ms: 64 [4294741.822000] 36 ms: 64 [4294741.856000] 31 ms: 64 resolve: resolve ${datadir}/enigma2/setup.xml resolve: -> /usr/share/enigma2/setup.xml [4294742.076000] 217 ms: 64 [4294742.080000] 1 ms: 65 [4294742.098000] 16 ms: 65 resolve: resolve ${sysconfdir}/enigma2/ resolve: -> /etc/enigma2/ loading bouquet... /etc/enigma2/bouquets.tv resolve: resolve ${sysconfdir}/enigma2/ resolve: -> /etc/enigma2/ loading bouquet... /etc/eni[4294742.119000] 18 ms: 65 gma2/userbouquet.favourites.tv 75 entries in Bouquet userbouquet.favourites.tv 1 entries in Bouquet bouquets.tv resolve: resolve ${sysconfdir}/enigma2/ resolve: -> /etc/enigma2/ loading bouquet... /etc/enigma2/bouquets.radio resolve: resolve ${sysconfdir}/enigma2/ resolve: -> /etc/enigma2/ loading bouquet... /etc/enigma2/userbouquet.favourites.radio 0 entries in Bouquet userbouquet.favourites.radio 1 entries in Bouquet bouquets.radio [4294742.162000] 40 ms: 65 [4294742.232000] 68 ms: 65 [4294742.236000] 1 ms: 65 [4294742.240000] 2 ms: 65 [eDVBLocalTimeHa[4294742.252000] 9 ms: 65 ndler] enable sync local time with transponder time! /usr/lib/python2.6/site-packages/twisted/internet/_sslverify.py:5: DeprecationWarning: the md5 module is deprecated; use hashlib instead import itertools, md5 [4294746.089000] 3834 ms: 65 [4294746.098000] 7 ms: 74 add dreampackage scanner plugin added [4294746.410000] 309 ms: 74 [4294746.420000] 7 ms: 75 [4294746.513000] 91 ms: 75 [4294746.519000] 3 ms: 75 [4294746.522000] 1 ms: 75 [4294746.532000] 7 ms: 75 [4294746.561000] 26 ms: 75 [4294746.591000] 27 ms: 76 [4294746.594000] 1 ms: 76 [FONT] adding font /usr/share/fonts/nmsbd.ttf...OK (Display) [FONT] adding font /usr/share/fonts/nmsbd.ttf...OK (Regular) [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) [4294747.043000] 446 ms: 76 [4294747.123000] 78 ms: 77 [4294747.142000] Write AVS: 40 40 00 00 00 00 00 61 00 02 00 00 00 ff [4294747.185000] set current avs auto aspect to 4:3 [4294747.189000] disable wss on display 0 [4294747.192000] disable wss on display 1 [4294747.195000] set sb to 3(2) [4294747.197000] Write AVS: 40 40 00 00 00 00 00 63 00 02 00 00 00 ff [4294747.281000] set current avs auto aspect to 4:3 [4294747.285000] disable wss on display 0 [4294747.288000] disable wss on display 1 [4294747.291000] set sb to 3(2) [4294747.293000] disable wss on display 0 [4294747.297000] set wss 8 on display 1 [4294747.337000] set input to 0 [4294747.340000] set fb to 2(0) [4294747.343000] set current avs auto aspect to 4:3 [4294747.348000] disable wss on display 0 [4294747.352000] set wss 8 on display 1 [4294747.355000] set sb to 3(2) [4294747.361000] set ac3 to downmix --> setting scaler_sharpness to: 0000000D [4294747.374000] 248 ms: 77 [4294747.388000] 11 ms: 77 couldn't open /proc/stb/misc/12V_output resolve: resolve ${datadir}/enigma2/keymap.xml resolve: -> /usr/share/enigma2/keymap.xml [4294747.460000] 70 ms: 77 [4294747.687000] 225 ms: 78 [ePopen] command: ip -o addr [4294747.748000] 58 ms: 78 setLCDBrightness 0 setLCDBrightness 25 [4294747.799000] 49 ms: 78 resolve: resolve ${datadir}/keymaps/ resolve: -> /usr/share/keymaps/ resolve: resolve ${datadir}/keymaps/ resolve: -> /usr/share/keymaps/ resolve: resolve ${datadir}/keymaps/ resolve: -> /usr/share/keymaps/ Activating keymap: Keyboard English resolve: resolve ${datadir}/keymaps/ resolve: -> /usr/share/keymaps/ [ePopen] command: loadkmap < /usr/share/keymaps/eng.kmap Activating language English language set to en_EN [4294749.131000] 1329 ms: 78 [4294749.150000] 16 ms: 81 [4294749.176000] 23 ms: 82 /usr/lib/enigma2/python/Plugins/Extensions/WebInterface/__init__.py:4: Depre/ahtionWarning: the sha module is deprecated; use the hashlib module instead import os, gettext, sha [WebInterface] set language to en [WebInterface] set language to en [WebInterface] fallback to default translation for Webinterface [WebInterface] fallback to default translation for Configuration for the Webinterface resolve: resolve ${libdir}/enigma2/python/Plugins/SystemPlugins/WirelessLan resolve: -> /usr/lib/enigma2/python/Plugins/SystemPlugins/WirelessLan 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 letterbox bestfit auto [4294753.687000] set current avs auto aspect to 16:9 [4294753.691000] disable wss on display 0 [4294753.694000] set wss 7 on display 1 [4294753.697000] set sb to 3(1) [4294753.699000] Write AVS:set current avs auto aspect to 16:9 [4294753.699000] disable wss on display 0 [4294753.699000] set wss 7 on display 1 [4294753.712000] 40disable wss on display 0 [4294753.712000] set wss 7 on display 1 [4294753.719000] 40set current avs auto aspect to 16:9 [4294753.719000] disable wss on display 0 [4294753.719000] set wss 7 on display 1 [4294753.732000] 00-> setting aspec 00t, policy, polic 00y2, wss 16:9 00letterbox bestfit 00 auto set current avs auto aspect to 16:9 [4294753.740000] 61 00 02 00 00 00 ff [4294753.747000] disable wss on display 0 [4294753.751000] set wss 7 on display 1 [4294753.755000] set sb to 3(1) [4294753.758000] set sb to 3(1) [4294753.762000] set current avs auto aspect to 16:9 [4294753.767000] disable wss on display 0 [4294753.770000] set wss 7 on display 1 [4294753.773000] set sb to 3(1) [4294753.775000] disable wss on display 0 [4294753.779000] set wss 7 on display 1 [4294753.784000] set current avs auto aspect to 16:9 [4294753.789000] disable wss on display 0 [4294753.792000] set wss 7 on display 1 [4294753.796000] set sb to 3(1) -> setting aspect, policy, polic[4294753.803000] set current avs auto aspect to 16:9 y2, wss 16:9 let[4294753.809000] disable wss on display 0 terbox bestfit a[4294753.813000] set wss 7 on display 1 uto [4294753.817000] set sb to 3(1) [4294753.820000] set current avs auto aspect to 16:9 [4294753.825000] disable wss on display 0 [4294753.827000] set wss 7 on display 1 [4294753.830000] set sb to 3(1) [4294753.833000] disable wss on display 0 [4294753.837000] set wss 7 on display 1 [4294753.842000] set current avs auto aspect to 16:9 [4294753.847000] disable wss on display 0 [4294753.851000] set wss 7 on display 1 [4294753.854000] set sb to 3(1) -> setting aspect, policy, polic[4294753.862000] set current avs auto aspect to 16:9 y2, wss 16:9 let[4294753.867000] disable wss on display 0 terbox bestfit a[4294753.872000] set wss 7 on display 1 uto [4294753.876000] set sb to 3(1) [4294753.879000] set current avs auto aspect to 16:9 [4294753.883000] disable wss on display 0 [4294753.887000] set wss 7 on display 1 [4294753.890000] set sb to 3(1) [4294753.892000] disable wss on display 0 [4294753.896000] set wss 7 on display 1 [4294753.899000] set current avs auto aspect to 16:9 [4294753.904000] disable wss on display 0 [4294753.907000] set wss 7 on display 1 [4294753.909000] set sb to 3(1) setMode - port: DVI mode: 1080i[4294753.914000] set current avs auto aspect to 16:9 rate: 50Hz [4294753.920000] disable wss on display 0 [4294753.924000] set wss 7 on display 1 [4294753.928000] set sb to 3(1) [4294753.933000] set current avs auto aspect to 16:9 [4294753.938000] disable wss on display 0 [4294753.941000] set wss 7 on display 1 [4294753.943000] set sb to 3(1) -> setting aspect, policy, polic[4294753.951000] set current avs auto aspect to 16:9 y2, wss 16:9 let[4294753.957000] disable wss on display 0 terbox bestfit a[4294753.961000] set wss 7 on display 1 uto [4294753.965000] set sb to 3(1) [4294753.968000] set current avs auto aspect to 16:9 [4294753.973000] disable wss on display 0 [4294753.977000] set wss 7 on display 1 [4294753.980000] set sb to 3(1) [4294753.985000] disable wss on display 0 [4294753.989000] set wss 7 on display 1 [4294753.992000] set current avs auto aspect to 16:9 [4294753.997000] disable wss on display 0 [4294754.000000] set wss 7 on display 1 [4294754.002000] set sb to 3(1) resolve: resolve ${datadir}/enigma2/ resolve: -> /usr/share/enigma2/ resolve: resolve ${sysconfdir}/enigma2/ resolve: -> /etc/enigma2/ resolve: resolve ${sysconfdir}/enigma2/ resolve: -> /etc/enigma2/ resolve: resolve ${sysconfdir}/enigma2/ resolve: -> /etc/enigma2/ starting hotplug handler add device sde to bdpoll current state: medium removed add device sdd to bdpoll current state: medium removed add device sdc to bdpoll current state: medium removed add device sdb to bdpoll current state: medium rem[4294755.272000] 6094 ms: 82 oved [CI_Assignment] activating ci configs: [CI_Activate] FOUND 4 CI Slots resolve: resolve ${sysconfdir}/enigma2/ci resolve: -> /etc/enigma2/ci resolve: resolve ${sysconfdir}/enigma2/ci resolve: -> /etc/enigma2/ci [CI_Activate_Config_CI1] no config file found [CI_Activate_Config_CI1] error parsing xml... resolve: resolve ${sysconfdir}/enigma2/ci resolve: -> /etc/enigma2/ci [CI_Activate_Config_CI2] no config file found [CI_Activate_Config_CI2] error parsing xml... resolve: resolve ${sysconfdir}/enigma2/ci resolve: -> /etc/enigma2/ci [CI_Activate_Config_CI3] no config file found [CI_Activate_Config_CI3] error parsing xml... [CI_Activate] activate CI0 with following settings: 0 ([], [], []) It's now Sat Jan 1 02:01:27 2000 [timer.py] next activation: 946684987 (in 99010 ms) [TIMER] record time changed, start prepare is now: Sun Aug 15 20:59:40 2010 sanitycheck check checkTimerlist recording service: 1:0:1:10:3:1:1863190:0:0:0: record: 0 [Timer] Record ProcessRepeated It's now Sat Jan 1 02:01:28 2000 next real activation is Sun Aug 15 20:59:40 2010 [timer.py] next activation: 946684988 (in 99992 ms) [TIMER] record time changed, start prepare is now: Wed Mar 9 10:04:40 2011 sanitycheck check checkTimerlist recording service: 1:0:1:10:3:1:1863190:0:0:0: record: 0 [Timer] Record ProcessRepeated localrepeatedbegindate: Wed Mar 9 10:05:00 2011 localbegin: Wed Mar 9 10:05:00 2011 localend: Wed Mar 9 10:05:01 2011 localnow: Sat Jan 1 02:01:29 2000 Day: 0 Day: 1 Day: 2 Day: 3 Day: 4 Day: 5 Day: 6 ProcessRepeated result Wed Mar 9 10:05:00 2011 Wed Mar 9 10:05:01 2011 already passed, skipping shouldSkip: False state == ended False waiting && disabled: 1 [TIMER] record time changed, start prepare is now: Wed Mar 9 10:07:40 2011 sanitycheck check checkTimerlist recording service: 1:0:1:10:3:1:1863190:0:0:0: record: 0 [Timer] Record ProcessRepeated localrepeatedbegindate: Wed Mar 9 10:08:00 2011 localbegin: Wed Mar 9 10:08:00 2011 localend: Wed Mar 9 10:08:01 2011 localnow: Sat Jan 1 02:01:29 2000 Day: 0 Day: 1 Day: 2 Day: 3 Day: 4 Day: 5 Day: 6 ProcessRepeated result Wed Mar 9 10:08:00 2011 Wed Mar 9 10:08:01 2011 already passed, skipping shouldSkip: False state == ended False waiting && disabled: 1 [TIMER] record time changed, start prepare is now: Tue Mar 8 10:10:40 2011 sanitycheck check checkTimerlist[4294755.500000] set mute 0 (000) 1 recording serv[4294755.504000] set mute 1 (000) 1 ice: 1:0:1:10:3:[4294755.508000] set input to 1 1:1863190:0:0:0:[4294755.511000] set fb to 2(2) record: 0 [Timer] Record ProcessRepeated localrepeatedbegindate: Tue Mar 8 10:11:00 2011 localbegin: Tue Mar 8 10:11:00 2011 localend: Wed Mar 9 10:11:01 2011 localnow: Sat Jan [4294755.534000] set sb to 3(0) 1 02:01:29 2000 [4294755.537000] Write AVS: Day: 0 Day: 1 40 Day: 2 Day: 3 41 Day: 4 Day: 5 00 Day: 6 Process 00Repeated result 00 Tue Mar 8 10:1 001:00 2011 Wed M 0aar 9 10:11:01 2 72011 already pas 00sed, skipping s 06houldSkip: False 00 state == ended 00 False waiting 00&& disabled: 1 ff[TIMER] record t ime changed, start prepare is now: Wed Mar 9 10:13:40 2011 sanitycheck check checkTimerlist recording service: 1:0:1:10:3:1:1863190:0:0:0: record: 0 [Timer] Record ProcessRepeated localrepeatedbegindate: Wed Mar 9 10:14:00 2011 localbegin: Wed Mar 9 10:14:00 2011 localend: Wed Mar 9 10:14:01 2011 localnow: Sat Jan 1 02:01:29 2000 Day: 0 Day: 1 Day: 2 Day: 3 Day: 4 Day: 5 Day: 6 ProcessRepeated result Wed Mar 9 10:14:00 2011 Wed Mar 9 10:14:01 2011 already passed, skipping shouldSkip: False state == ended False waiting && disabled: 1 [TIMER] record time changed, start prepare is now: Wed Mar 9 10:16:40 2011 sanitycheck check checkTimerlist recording service: 1:0:1:10:3:1:1863190:0:0:0: record: 0 [Timer] Record ProcessRepeated localrepeatedbegindate: Wed Mar 9 10:17:00 2011 localbegin: Wed Mar 9 10:17:00 2011 localend: Wed Mar 9 10:17:01 2011 localnow: Sat Jan 1 02:01:29 2000 Day: 0 Day: 1 Day: 2 Day: 3 Day: 4 Day: 5 Day: 6 ProcessRepeated result Wed Mar 9 10:17:00 2011 Wed Mar 9 10:17:01 2011 already passed, skipping shouldSkip: False state == ended False waiting && disabled: 1 [TIMER] record time changed, start prepare is now: Wed Mar 9 10:22:40 2011 sanitycheck check checkTimerlist recording service: 1:0:1:10:3:1:1863190:0:0:0: record: 0 [Timer] Record ProcessRepeated localrepeatedbegindate: Wed Mar 9 10:23:00 2011 localbegin: Wed Mar 9 10:23:00 2011 localend: Wed Mar 9 10:23:01 2011 localnow: Sat Jan 1 02:01:29 2000 Day: 0 Day: 1 Day: 2 Day: 3 Day: 4 Day: 5 Day: 6 ProcessRepeated result Wed Mar 9 10:23:00 2011 Wed Mar 9 10:23:01 2011 already passed, skipping shouldSkip: False state == ended False waiting && disabled: 1 [TIMER] record time changed, start prepare is now: Wed Mar 9 10:25:40 2011 sanitycheck check checkTimerlist recording service: 1:0:1:10:3:1:1863190:0:0:0: record: 0 [Timer] Record ProcessRepeated localrepeatedbegindate: Wed Mar 9 10:26:00 2011 localbegin: Wed Mar 9 10:26:00 2011 localend: Wed Mar 9 10:26:01 2011 localnow: Sat Jan 1 02:01:29 2000 Day: 0 Day: 1 Day: 2 Day: 3 Day: 4 Day: 5 Day: 6 ProcessRepeated result Wed Mar 9 10:26:00 2011 Wed Mar 9 10:26:01 2011 already passed, skipping shouldSkip: False state == ended False waiting && disabled: 1 It's now Sat Jan 1 02:01:28 2000 [timer.py] next activation: 946684988 (in 99835 ms) [StartupToStandby] autostart enter standby cannot open /proc/stb/audio/j1_mute(No such file or directory) Setvolume: 100 100 (raw) Setvolume: 0 0 (-1db) cannot open /proc/stb/audio/j1_mute(No such file or directory) allocating new converter! allocating new converter! allocating new converter! setLCDBrightness 0 [StartupToStandby] start timer... [Toplevel.importExternalModules] Could NOT import external module: AutoTimer [Toplevel.importExternalModules] Exception Caught No module named AutoTimer.AutoTimerResource [Toplevel.importExternalModules] Could NOT import external module: EPGRefresh [Toplevel.importExternalModules] Exception Caught No module named EPGRefresh.EPGRefreshResource [Toplevel.importExternalModules] Imported external module: Example [WebInterface] checking for SSL Certificates [Webinterface] started on 0.0.0.0:80 auth=False ssl=False [WebInterface.registerBonjourService] No module named Bonjour.Bonjour [Webinterface] started on 0.0.0.0:443 auth=True [4294756.664000] 1388 ms: 96 ssl=True [WebInterface.registerBonjourService] No module named Bonjour.Bonjour [EPGC] setCacheFile read/write epg data from/to '/hdd/epg.dat' not showing fine-tuning wizard, config variable doesn't exist showtestcard is false Looking for embedded skin [4294756.724000] 57 ms: 99 setValue 50 Setvolume: 50 50[4294756.772000] 45 ms: 99 (raw) Setvolume: 32 32 (-1db) [4294756.787000] 11 ms: 99 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! RemovePopup, id = ZapError allocating new converter! allocating new converter! allocating new converter! resolve: resolve ${datadir}/enigma2/%s/ resolve: -> /usr/share/enigma2/%s/ 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! 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! reused converter! allocating new converter! allocating new converter! reused converter! reused converter! reused converter! allocating new converter! reused converter! allocating new converter! reused converter! allocating new converter! reused converter! reused converter! reused converter! reused converter! allocating new converter! reused converter! reused converter! playing 1:0:1:22:2:1:1863167:0:0:0: not auseable. RemovePopup, id = ZapError allo[4294757.862000] bcm4501 channel 1 is in normale mode now cate channel.. 0002:0001 opening frontend 1 [eDVBCAService] new channel 0x2d5f77c8! (1)tune prepare_sat System 0 Freq 12647000 Pol 0 SR 30000000 INV 2 FEC 5 orbpos 390 system 0 modulation 1 pilot 2, rolloff 0 tuning to 2047 mhz OURSTATE: tuning allocate Channel: res 0 [eDVBCIInterfaces] addPMTHandler 1:0:1:22:2:1:1863167:0:0:0: allocate demux resolve: resolve ${sysconfdir}/scan_tp_valid_check.py resolve: -> /etc/scan_tp_valid_check.py [SEC] set static current limiting [SEC] invalidate current switch params [SEC] setVoltage 2 [SEC] sleep 10ms CI Slot 0: reset requested ci flush ci inserted in slot 0 child has terminated pipes closed child has terminated pipes closed [ePopen] command: route -n | grep eth0 poll: unhandled POLLERR/HUP/NVAL for fd 33(16) child has terminated pipes closed poll: unhandled POLLERR/HUP/NVAL for fd 36(16) poll: unhandled POLLERR/HUP/NVAL for fd 38(16) [SEC] setTone 1 [SEC] sleep 10ms [SEC] update current switch params [SEC] startTuneTimeout 5000 [SEC] setFrontend 1 setting frontend 1 [SEC] sleep 500ms (1)fe event: status 0, inversion off, m_tuning 1 (1)fe event: status 1, inversion off, m_tuning 2 child has terminated pipes closed 192.168 169.254 0.0.0.0 nameservers: [[192, 168, 11, 1]] read configured interface: {'lo': {'dhcp': False}, 'eth0': {'dhcp': True}} self.ifaces after loading: {'eth0': {'preup': False, 'ip': [192, 168, 11, 3], 'up': True, 'mac': '00:09:34:1c:11:83', 'dhcp': True, 'netmask': [255, 255, 255,[4294758.186000] dvb_demux_feed_del: feed not in list (type=0 state=0 pid=ffff) 0], 'gateway': [192, 168, 11, 1[4294758.195000] start pcr0 on dmx 0 pid:32c index 4 ], 'postdown': False}} poll: un[4294758.202000] start audio on dmx 0 pid:32d handled POLLERR/[4294758.208000] audio xpt start HUP/NVAL for fd [4294758.212000] sync is off so we enable it 35(16) (1)fe ev[4294758.218000] demux-index 0 pcr0pid 32c ent: status 1f, [4294758.223000] using 4 as pcr0 pidchannel inversion off, m[4294758.228000] MPEG _tuning 3 OURST[4294758.231000] audio: streamtype set 0 ATE: ok [eDVBCA[4294758.237000] audio: streamtype_set: stop rave Service] channel 0x2d5f77c8 running [eDVBLocalTimerHandler] channel 0x2d5f77c8 running no version filtering 0014: 70 00 00 0[4294758.253000] audio: streamtype_set: restart rave 0 00 00 mask: [4294758.259000] set mute 1 (111) 1 fc 00 00 00 00 0[4294758.264000] audio: decoder start 2 0 mode: 00 00 00 00 00 00 [eEPGCache] channel 0x2d5f77c8 running stop release channel timer no version filtering 0012: 4e 00 22 00 00 00 mask: ff ff ff 00 00 00 mode:[4294758.284000] video delay 0 (0 ms) 00 00 00 00 00[4294758.287000] total video delay: 0 (0 ms) 00 ok ... now we start!! no v[4294758.294000] start video0 on dmx 0 pid:32c ersion filtering[4294758.300000] total video delay: 0 (0 ms) 0000: 00 00 00 00 00 00 mask[4294758.307000] VIDEO0: start decode : ff 00 00 00 00 00 mode: 00 00 00 00 00 00 eventNewProgramInfo 0 0 have 1 video stream(s) (032c), and 1 audio stream(s) (032d), and the pcr pid is 032c, and the text pid is ffffffff allocate demux disable teletext subtitles PES filter: DMX_SET_PES_FILTER - Invalid argument decoder state: play, vpid=812, apid=813 DMX_SET_PES_FILTER(0x32c) - pcr - ok DEMUX_START - pcr - ok DMX_SET_PES_FILTER(0x32d) - audio - ok DEMUX_START - audio - ok AUDIO_SET_BYPASS(1) - ok AUDIO_PAUSE - ok AUDIO_PLAY - ok Video Device: /dev/dvb/adapter0/video0 demux device: /dev/dvb/adapter0/demux0 VIDEO_SET_STREAMTYPE 0 - ok DMX_SET_PES_FILTER(0x32c) - video - ok DEMUX_START - video - ok VIDEO_FREEZE - ok VIDEO_PLAY - ok VIDEO_SLOWMOTION(0) - ok VIDEO_FAST_FORWARD(0) - ok VIDEO_CONTINUE - ok AUDIO_CONTINUE - ok AUDIO_CHANNEL_SELECT(0) - ok not pauseable. + 1/1 TID 00 done! PATready use pmtpid 032e for service_id 0022 no version filtering 032e: 02 00 22 00 00 00 mask: ff ff ff 00 00 00 mode: 00 00 00 00 00 00 doing version filtering 0000: 00 00 00 37 00 00 mask: ff 00 00 3f 00 00 mode: 00 00 00 3e 00 00 VIDEO_GET_EVENT - ok VIDEO_GET_EVENT - ok + 1/1 TID 02 done! eventNewProgramInfo 0 0 have 1 [4294758.530000] dvb_demux_feed_del: feed not in list (type=0 state=0 pid=ffff) video stream(s) (032c), and 1 audio stream(s) (032d), and the pcr pid is 032c, and the text pid is ffffffff PES filter: DMX_SET_PES_FILTER - Invalid argument decoder state: play, vpid=812, apid=813 VIDEO_SLOWMOTION(0) - ok VIDEO_FAST_FORWARD(0) - ok VIDEO_CONTINUE - ok AUDIO_CONTINUE - ok [eDVBCAService] new service 1:0:1:22:2:1:1863167:0:0:0: [eDVBCAService] add demux 0 to slot 0 service 1:0:1:22:2:1:1863167:0:0:0: [eDVBCIInterfaces] got[PMT demux 0 mask 01 prevhash 00000000 [eDVBCAService] send 62 bytes 9f 80 32 3a 03 00 22 11 00 25 01 81 08 01 86 31 67 00 02 00 01 82 02 01 00 84 02 03 2e 09 04 06 04 eb bb 09 04 55 01 ec 20 09 04 55 81 ef 40 02 03 2c 00 00 04 03 2d 00 00 06 0b 9f 00 00 doing version filtering 032e: 02 00 22 11 00 00 mask: ff ff ff 3f 00 00 mode: 00 00 00 3e 00 00 [SEC] set dynamic current limiting [4294758.999000] CI write error [4294759.001000] CI cannot TC_POLL -+ 1/2 TID 4e sdt update done! [4294760.004000] CI inserted... ++ 2/2 TID 4e done! doing version filtering 0012: 4e 00 22 0f 00 00 mask: ff ff ff 3f 00 00 mode: 00 00 00 3e 00 00 [4294761.910000] [cis] 1d 04 [4294761.912000] [cis] 1c 03 [4294761.914000] [cis] 15 15 [4294761.916000] [cis] 20 04 [4294761.917000] [cis] 1a 15 [4294761.920000] [cis] 1b 11 [4294761.922000] [cis] 1b 25 [4294761.924000] [cis] 14 00 [4294761.926000] [cis] ff 00 [4294762.162000] CI buffersize negotiation ok [4294762.201000] CI tc_open for 1 is now acknowledged ci inserted in slot 0 slot: 0x6b1ec0 91 04 00 01 00 41 RESOURCE MANAGER new session nb 1 0x2d5fc160 sendOpenSessionResponse slot: 0x6b1ec0 90 02 00 01 9f 80 11 00 SESSION(1) 9f 80 11: mein cam kann: nichts slot: 0x6b1ec0 90 02 00 01 9f 80 10 00 SESSION(1) 9f 80 10: cam fragt was ich kann. slot: 0x6b1ec0 91 04 00 02 00 41 APPLICATION MANAGER new session nb 2 0x2d59a2b0 sendOpenSessionResponse slot: 0x6b1ec0 90 02 00 02 9f 80 21 10 01 4a 20 4a 20 0a 41 6c 70 68 61 43 72 79 70 74 SESSION(2)/APP 9f 80 21: 01 4a 20 4a 20 0a 41 6c 70 68 61 43 72 79 70 74 application info: len: 16 application_type: 1 application_manufacturer: 20 4a manufacturer_code: 20 4a menu string: AlphaCrypt in final state. slot: 0x6b1ec0 91 04 00 03 00 41 CA MANAGER new session nb 3 0x2d5fef08 sendOpenSessionResponse slot: 0x6b1ec0 90 02 00 03 9f 80 31 1e 06 04 [4294763.702000] tsmux set tsports reg 02 to val 8c 06 48 17 02 17 2[4294763.708000] tsmux set cisource reg 00 to val 45 2 17 62 4a 20 05 00 0b 00 01 00 18 33 18 34 0d 05 0d 22 0d 95 4a fc SESSION(3)/CA 9f 80 31: 06 04 06 48 17 02 17 22 17 62 4a 20 05 00 0b 00 01 00 18 33 18 34 0d 05 0d 22 0d 95 4a fc ca info: 0604 0648 1702 1722 1762 4a20 0500 0b00 0100 1833 1834 0d05 0d22 0d95 4afc Slot 0 plugged The CI in Slot 0 has said it can handle caid 0604... so use it (1)CISlot 0, usecount now 1 eDVBCIInterfaces->setInputSource(1, 4) CI Slot 0 setSource(1) [eDVBCIInterfaces] gotPMT slot: 0x6b1ec0 91 04 00 24 00 41 DATE-TIME new session nb 4 0x2d5fcc68 sendOpenSessionResponse slot: 0x6b1ec0 90 02 00 04 9f 84 40 01 05 SESSION(4)/DATETIME 9f 84 40: 05 [4294764.576000] ch0: lock [4294764.592000] RAP first pts 0x4dcd068e, stc 0x4dccf752, pcr valid [4294764.855000] RAP sampling rate info isr [4294764.859000] eBitRateChange Isr.. set source_changed_event! [4294764.864000] eModeChange Isr.. set source_changed_event! [4294765.066000] VIDEO0: first pts 0x4dceec82 [4294765.070000] VIDEO0: resolution changed (544 576) [4294765.074000] VIDEO0: aspect changed 2 [4294765.077000] set current avs auto aspect to 16:9 [4294765.081000] disable wss on display 0 VIDEO_GET_EVENT [4294765.084000] set wss 7 on display 1 - [4294765.088000] set sb to 3(0) ok [4294765.220000] enable tsm0 [4294765.419000] VIDEO0: progressive changed (0) VIDEO_GET_EVENT - ok enter standby mute already active stopServi[4294765.707000] tsmux set tsports reg 02 to val dc ce [eDVBCAService] free slot 0 demux 0 for service 1:0:1:22:2:1:1863167:0:0:0: [eDVBCAService] free service 1:0:1:22:2:1:1863167:0:0:0: [eDVBCIInterfaces] remove last pmt handler for service 1:0:1:22:2:1:1[4294765.729000] stop pcr0 on dmx 0 pid:32c 863167:0:0:0: se[4294765.734000] stop video0 on dmx 0 pid:32c nd empty capmt eDVBCIInterfaces[4294765.738000] VIDEO0: stop decode ->setInputSource[4294765.743000] VIDEO0: set blank to 1 (1, 1) (3) slot[4294765.747000] audio: decoder stop 0 usecount is now 0 decoder state: play, vpid=-1, apid=-1 DEMUX_STOP - pcr - ok DEMUX_STOP - video - ok VIDEO_STOP - ok AUDIO_STOP - ok DEMUX_STOP - a[4294765.764000] stop audio on dmx 0 pid:32d udio - [4294765.769000] audio: xpt stop(3) [4294765.772000] no decoder left in sync handling so disable xpt_sync [4294765.778000] bcm_audio_channel_stop(3) done ok AUDIO_CONTINUE - ok start rel[4294765.782000] set input to 1 ease channel tim[4294765.786000] set fb to 2(2) er [4294765.790000] set sb to 3(0) reused converter! reused converter! reused converter! setLCDBrightness 0 [eDVBLocalTimerHandler] dont have correction.. set Transponder Diff [eDVBLocalTimerHandler] update RTC [eDVBLocalTimerHandler] time update to 21:16:26 [eDVBLocalTimerHandler] m_time_difference is 352926887 [eDVBLocalTimerHandler] set Linux Time [EPGC] time updated.. start EPG Mainloop before: 1 after: 1 [EPGC] next update in 2 sec release cached channel (timer timeout) [eDVBCAService] remove channel 0x2d5f77c8 [eDVBLocalTimerHandler] remove channel 0x2d5f77c8 [eEPGCache] remove channel 0x2d5f77c8 stop release channel timer close frontend 1 [4294772.182000] bcm4501 channel 1 is sleeping now