Debug Meldungen machen die vuduo4kse unendlich langsam

Antworten
Benutzeravatar
muffpotter
Beiträge: 3
Registriert: Mo 11. Sep 2017, 22:12
Box: VU+ DUO 4K SE

Debug Meldungen machen die vuduo4kse unendlich langsam

Beitrag von muffpotter »

Der Test des folgenden Image verlief kurz und schmerzhaft:

distro=NI
imagename=NI \o/ Neutrino-Image
imagedescription=VU+ Duo 4K SE [arm-vuduo4kse] Selfmade
imageversion=4.00.192
version=9400202106102256
describe=NI-4.00.192-g1f649f4
builddate=Thu 10 Jun 2021 10:56:49 PM CEST
box_model=vuduo4kse
creator=NI-Buildsystem, engr
imagedir=vuplus/duo4kse

Direkt nach dem Hochlauf von Neutrino war die Box praktisch nicht mehr zu bedienen, da die Flut an Debug Meldungen ([HAL:ae4005b8:demux ] Read #0: not open!) auf der Console nicht enden wollte.

Code: Alles auswählen

[HAL:ae4005b8:demux ] Read #0: not open!
[HAL:ae4005b8:demux ] Read #0: not open!
[HAL:ae4005b8:demux ] Read #0: not open!
[HAL:ae4005b8:demux ] Read #0: not open!
[HAL:ae4005b8:demux ] Read #0: not open!
[HAL:ae4005b8:demux ] Read #0: not open!
[HAL:ae4005b8:demux ] Read #0: not open!
[HAL:ae4005b8:demux ] Read #0: not open!
[HAL:ae4005b8:demux ] Read #0: not open!
[HAL:ae4005b8:demux ] Read #0: not open!
[HAL:ae4005b8:demux ] Read #0: not open!
[HAL:ae4005b8:demux ] Read #0: not open!
[HAL:ae4005b8:demux ] Read #0: not open!
[HAL:ae4005b8:demux ] Read #0: not open!
[HAL:ae4005b8:demux ] Read #0: not open!
[HAL:ae4005b8:demux ] TuxTxt stopping subtitle thread ...
Read #0: not open!
[HAL:ae4005b8:demux ] [dvb-sub] paused
Read #0: not open!
[HAL:ae4005b8:demux ] Stop #0: not open!
cutNeutrino: last mode 1
cutNeutrino: this mode 7
cutNeutrino: save mode 1
CNeutrinoApp::handleMsg: CHANGEMODE to 7 rezap 0
IS FILE PLAYER: true
[HAL:00651200:play  ] Open - player output name: Output PlayMode: PLAYMODE_FILE
[HAL:00651200:play  ] Start - filename=/media/sda1/movies/Irgendeine Sendung_20210611_102500.ts vpid=511 vtype=1 apid=515 ac3=0
minimal Probe: 1
key: 0160 value 0, translate: 0160 -ok-
Mit sehr viel Geduld war es möglich den Video Player zu starten oder auf einen FTV Sender zu wechseln und damit die Meldungen zu stoppen. Sobald man aber z.B. auf einen HD+ Sender wechselt, startet die Meldungsflut erneut.
Das gleiche passiert allerdings auch beim nightly Image vom 10.06.2021.
Sofern man sich mit Putty über Netzwerk anmeldet und die Console umleitet, wird die Box einigermaßen benutzbar, da dann die Meldungen schneller verarbeitet werden können, aber es ist nicht wirklich schön.

Das wollte ich mal berichten, mit der Hoffnung auf eine Lösung :nerd:
Life is never fair. But the root password helps in any case......
Vu+ duo 4k se mit CI+ Modul und HD+ Smartcard, BP rev19250-ddt
Dm800seV2,
Zee²,
Neo²
Benutzeravatar
Miky
NI - Team
Beiträge: 1209
Registriert: Di 5. Apr 2016, 17:17
Box: Tank,Trinity,Neo 1,Neo2,Neo²,HD51
Been thanked: 1 time

Re: Debug Meldungen machen die vuduo4kse unendlich langsam

Beitrag von Miky »

Denke die Debug Meldungen sind den Versuchen von FlatTV geschuldet.
Boxen: Neo 1, Neo2 , Neo², Trinity, Tank, HD 51 alle SAT
Kein PN Support!
Benutzeravatar
FlatTV
Developer
Beiträge: 109
Registriert: Fr 8. Apr 2016, 23:10

Re: Debug Meldungen machen die vuduo4kse unendlich langsam

Beitrag von FlatTV »

Nö? :sunglasses:

Hast du ein vielleicht ein Log vom booten?
Benutzeravatar
BPanther
NI - VIP
Beiträge: 736
Registriert: So 29. Sep 2019, 18:37
Kontaktdaten:

Re: Debug Meldungen machen die vuduo4kse unendlich langsam

Beitrag von BPanther »

Sowas ähnliches hatte ich beim NI bei den VU+ Images generell schon länger mal berichtet. Ob das an den vielen Meldungen liegt kann ich nicht beurteilen, aber die FB ist hier zumindest recht lahm, leider. Manchmal gibt sich das nach einem Neutrino Neustart via Menü, manchmal auch nicht oder kommt dann im laufe der Zeit wieder. Am Speicherverbrauch ist dabei nichts auffälliges oder Unterschiede zu beobachten wenn sie mal normal funktioniert oder eben nicht.
Bild
Benutzeravatar
FlatTV
Developer
Beiträge: 109
Registriert: Fr 8. Apr 2016, 23:10

Re: Debug Meldungen machen die vuduo4kse unendlich langsam

Beitrag von FlatTV »

Mir persönlich war das nicht aufgefallen, womöglich weil ich ARD als Startkanal habe.
Kommt auf die Liste, auch wenn ich gerade keine Lust auf die VU+ Zicke habe.
Wir haben zur Zeit ein angespanntes Verhältnis.
Benutzeravatar
muffpotter
Beiträge: 3
Registriert: Mo 11. Sep 2017, 22:12
Box: VU+ DUO 4K SE

Re: Debug Meldungen machen die vuduo4kse unendlich langsam

Beitrag von muffpotter »

Ein Log, kein Problem, hier ist es:

Code: Alles auswählen

=~=~=~=~=~=~=~=~=~=~=~= PuTTY log 2021.06.11 21:52:12 =~=~=~=~=~=~=~=~=~=~=~=
cc^C[14630.793474] reboot: Restarting system
CPU 0123
BCM74450040
PRID74440040
v1.40
RR:00000200
BFW
seclib-1.15
BBL v1.0.1
AVS init...
AVS init OK
AVS load:select_image: addr_offset: 00051000 part_offset: 00000000 bootStatus: 10252b25
AVS load_code offset =00051000
LOADED

single board
select_image: addr_offset: 00055000 part_offset: 00000000 bootStatus: 302b2525
MEMSYS-ALT
SHMOO 03040100 BLD:exported HW:hpf0_b1p3 shmoo_ver:3.4.1.0
MCB: FLEX
AVS: overtemp mon ON
AVS start:status=000000ff
STB: Current voltage=00000370(880)
 temperature=00011f33(73523)
 PV=00000338(824)
 MV=00000370(880)
AVS FW rev=0x30333278
OK
DDR0: !@ ffe0e780 <= ffe0da04 MEMSYS-0 @ f1100000 OK
DDR1: =@ ffe0e780 <= ffe0da04 MEMSYS-1 @ f1180000 OK
DDR2: =@ ffe0e780 <= ffe0da04 MEMSYS-2 @ f1200000 OK
ARCH: 32@992 failed
Sdram Scramble OTP = 0x00000001 
Sdram Scramble OK
select_image: addr_offset: 00020000 part_offset: 00000000 bootStatus: 2025252b
BFW load mode OTP = 0x0000000f 
bfw_hash_locked = 0x00000000 
BFW key3 OK, BFW load OK
BFW v4.1.5
disable rpmb failed!
INFO v00000003
COPY CODE... DONE
MICH: disable success
SSBL
SEC: jtag debug status: 000000af
PINMUX
CACHE FLUSH OK
MMU ON
CACHE FLUSH OK
_fbss  0707dd60
_ebss  070a66d8
_end   070a66f8
HEAP @ 07100000
STACK @ 09200000
ARCH: CONFIG OK
CLR BSS 0707dd60 to 070a66d8 OK
CLR SRAM  OK
CACHE FLUSH OK
CPU CLKSET OK
GO!

[33;1m    ,/   [0m 
[33;1m  ,'/___,[0m BOLT v1.40 v1.40 LOCAL BUILD
[33;1m.'__  ,' [0m (2020-06-16 17:24:26 Vuplus team)
[33;1m   /,'   [0m arm-linux-gcc (Broadcom stbgcc-4.8-1.5) 4.8.5
[33;1m  /'     [0m Copyright (C) 2020 Broadcom
[0m
Board: DUO4KSE (7444 of 7445e0 family)
strap=000005bc,00000009:
otp @ 0xf0404030 = 0x822000e0: en_cr(0x00000060) en_testport(0x00000080) macrovision_disable(0x02000000) moca_disable(0x80000000) rv9_disable(0x00200000) 
otp @ 0xf0404034 = 0x00000001: moca2_disable(0x00000001) 
bond option: 0x03
RESET CAUSE: 0x00000200 software_master (1 of 22 possible causes)
******************************************
Automatic startup canceled via Ctrl-C
******************************************

CPU 4x B15 [420f00f3] 1503 MHz, SCB 432 MHz
SPLASH: starting
SPLASH BMEM init @ 3dffffff
SPLASH BMEM init @ 7fffffff
SPLASH BMEM init @ bfffffff
SPLASH: Old format file in flash. Use splash_create_flash_file to create newer format.
Loaded BMP: W=720 H=480
SPLASH: audio not present
AVS: park check
AVS: STB: V=0.886V, T=+74.010C, PV=0.824V, MV=0.880V, FW=[0.3.2.x]
LCD_Init Complete
boot device name [flash1]
waiting for usb...
usb: resetting device on bus 5 hub 1 port 1[J


USB: New high speed device connected to bus 5 hub 1 port 1[J


usb: resetting device on bus 5 hub 2 port 1[J


USB: New high speed device connected to bus 5 hub 2 port 1[J

usb: no driver found for 0a5c:bd27

usb: resetting device on bus 5 hub 2 port 2[J


USB: New full speed device connected to bus 5 hub 2 port 2[J

usb: no driver found for 0a5c:2045
done
checking usb
No Priority Usb
checking front key
readFrontKey 0 0
Check ignore.update file
Checking usbdisk0:/vuplus/duo4kse/ignore.update......NO
Check force.update file
Checking usbdisk0:/vuplus/duo4kse/force.update......NO
bolt update ...Checking usbdisk0:/vuplus/duo4kse/bolt_auto.bin......NO
Checking usbdisk0:/vuplus/duo4kse/rootfs.tar.bz2......NO
....NO
Checking usbdisk0:/vuplus/duo4kse/splash_auto.bin......NO
Checking usbdisk0:/vuplus/duo4kse/kernel_auto.bin......NO
BOLT> ^C
BOLT> ^C
BOLT> ^C
BOLT> ^C
BOLT> ^C
BOLT> ^C
BOLT> ^C
BOLT> ^C
BOLT> ^C
BOLT> ^C
BOLT> ^C
BOLT> ^C
BOLT> ^C
BOLT> ^C
BOLT> ^C
BOLT> ^C
BOLT> ^C
BOLT> ^C
BOLT> ^C
BOLT> ^C
BOLT> ^C
BOLT> ^C
BOLT> 
USB: Device disconnected from bus 5 hub 2 port 1[J


USB: Device disconnected from bus 5 hub 2 port 2[J

BOLT> ^C
BOLT> 
usb: resetting device on bus 5 hub 2 port 1[J


USB: New high speed device connected to bus 5 hub 2 port 1[J

usb: no driver found for 0a5c:bd27

usb: resetting device on bus 5 hub 2 port 2[J


USB: New full speed device connected to bus 5 hub 2 port 2[J

usb: no driver found for 0a5c:2045
BOLT> ^C
BOLT> ^C
BOLT> ^C
BOLT> printenv
Variable Name        Value
-------------------- --------------------------------------------------
        BOOT_CONSOLE uart0
              SPLASH ENABLE
             STARTUP batch -fatfs flash0.startup:STARTUP
         ETH0_HWADDR 00:1D:EC:16:D6:15
             VERSION 1.40
            BUILDTAG v1.40
      CHIP_FAMILY_ID 74450040
           BOARDNAME DUO4KSE
            NODENAME DUO4KSE
          MEMORYSIZE 3072
          DT_ADDRESS 7618000
             DT_SIZE bef0
*** command status = 0
BOLT> batch -fatfs flash0.startup:STARTUP_4
Loader:raw Filesys:fat Dev:flash0.startup File:STARTUP_4 Options:(null)
Loading: .
 206 bytes read
Loader:zimg Filesys:raw Dev:flash0.kernel_4 File:(null) Options:libata.force=1:3.0G,2:3.0G,3:3.0G root=/dev/mmcblk0p16 rootfstype=ext4 rootflags=data=journal rootwait rw coherent_pool=2M vmalloc=622m bmem=630m@394m bmem=383m@1665m bmem=443m@2625m
Reading 4555952 bytes from zImage...........
Starting program at 0x8000 (DTB @ 0x7618000)

32 bit direct boot...
[    0.000000] Booting Linux on physical CPU 0x0
[    0.000000] Linux version 4.1.45-1.17 (engr@vmdebian10) (gcc version 6.5.0 (crosstool-NG UNKNOWN) ) #1 SMP Thu Jun 10 18:13:25 CEST 2021
[    0.000000] CPU: ARMv7 Processor [420f00f3] revision 3 (ARMv7), cr=30c5387d
[    0.000000] CPU: PIPT / VIPT nonaliasing data cache, PIPT instruction cache
[    0.000000] Machine model: DUO4KSE
[    0.000000] moving dtb from 0x0000000007618000 to 0x0000000000952bcc
[    0.000000] bmem: Reserved 630 MiB at 0x0000000018a00000
[    0.000000] bmem: Reserved 383 MiB at 0x0000000068100000
[    0.000000] bmem: Reserved 443 MiB at 0x00000000a4100000
[    0.000000] cma: Reserved 16 MiB at 0x00000000a3000000
[    0.000000] Forcing write-allocate cache policy for SMP
[    0.000000] Memory policy: Data cache writealloc
[    0.000000] PERCPU: Embedded 12 pages/cpu @d6998000 s16972 r8192 d23988 u49152
[    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 777468
[    0.000000] Kernel command line: libata.force=1:3.0G,2:3.0G,3:3.0G root=/dev/mmcblk0p16 rootfstype=ext4 rootflags=data=journal rootwait rw coherent_pool=2M vmalloc=622m bmem=630m@394m bmem=383m@1665m bmem=443m@2625m
[    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] Memory: 1602172K/3112960K available (6649K kernel code, 299K rwdata, 2044K rodata, 312K init, 198K bss, 1494404K reserved, 16384K cma-reserved, 1241620K highmem)
[    0.000000] Virtual kernel memory layout:
[    0.000000]     vector  : 0xffff0000 - 0xffff1000   (   4 kB)
[    0.000000]     fixmap  : 0xffc00000 - 0xfff00000   (3072 kB)
[    0.000000]     vmalloc : 0xd8800000 - 0xff000000   ( 616 MB)
[    0.000000]     lowmem  : 0xc0000000 - 0xd8200000   ( 386 MB)
[    0.000000]     pkmap   : 0xbfe00000 - 0xc0000000   (   2 MB)
[    0.000000]     modules : 0xbf000000 - 0xbfe00000   (  14 MB)
[    0.000000]       .text : 0xc0008000 - 0xc088596c   (8695 kB)
[    0.000000]       .init : 0xc0886000 - 0xc08d4000   ( 312 kB)
[    0.000000]       .data : 0xc08d4000 - 0xc091eca8   ( 300 kB)
[    0.000000]        .bss : 0xc0921000 - 0xc0952bcc   ( 199 kB)
[    0.000000] Hierarchical RCU implementation.
[    0.000000] NR_IRQS:16 nr_irqs:16 16
[    0.000000] irq_brcmstb_l2: registered L2 intc (mem: 0xd8802000, parent irq: 94)
[    0.000000] irq_brcmstb_l2: registered L2 intc (mem: 0xd8808000, parent irq: 64)
[    0.000000] irq_brcmstb_l2: registered L2 intc (mem: 0xd880aa00, parent irq: 65)
[    0.000000] irq_brcmstb_l2: registered L2 intc (mem: 0xd880c640, parent irq: 96)
[    0.000000] irq_brcmstb_l2: registered L2 intc (mem: 0xd880e200, parent irq: 48)
[    0.000000] irq_bcm7120_l2: registered BCM7120 L2 intc (mem: 0xfc40a780, parent IRQ(s): 2)
[    0.000000] irq_bcm7120_l2: registered BCM7120 L2 intc (mem: 0xd8810280, parent IRQ(s): 3)
[    0.000000] Architected cp15 timer(s) running at 27.00MHz (virt).
[    0.000000] clocksource arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x63a1e71a3, max_idle_ns: 440795203123 ns
[    0.000003] sched_clock: 56 bits at 27MHz, resolution 37ns, wraps every 4398046511093ns
[    0.000012] Switching to timer-based delay loop, resolution 37ns
[    0.000389] Calibrating delay loop (skipped), value calculated using timer frequency.. 54.00 BogoMIPS (lpj=27000)
[    0.000399] pid_max: default: 32768 minimum: 301
[    0.000516] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.000522] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.001051] CPU: Testing write buffer coherency: ok
[    0.001244] CPU0: update cpu_capacity 1024
[    0.001250] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
[    0.001425] Setting up static identity map for 0x84c0 - 0x8518
[    0.003247] MCP: Enabling write pairing
[    0.004095] CPU1: update cpu_capacity 1024
[    0.004098] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001
[    0.004719] CPU2: update cpu_capacity 1024
[    0.004722] CPU2: thread -1, cpu 2, socket 0, mpidr 80000002
[    0.005339] CPU3: update cpu_capacity 1024
[    0.005342] CPU3: thread -1, cpu 3, socket 0, mpidr 80000003
[    0.005383] Brought up 4 CPUs
[    0.005398] SMP: Total of 4 processors activated (216.00 BogoMIPS).
[    0.005402] CPU: All CPU(s) started in SVC mode.
[    0.005783] devtmpfs: initialized
[    0.015692] VFP support v0.3: implementor 42 architecture 4 part 00 variant 0 rev 0
[    0.015908] clocksource jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns
[    0.015926] futex hash table entries: 1024 (order: 4, 65536 bytes)
[    0.017247] NET: Registered protocol family 16
[    0.019427] DMA: preallocated 2048 KiB pool for atomic coherent allocations
[    0.024136] cpuidle: using governor ladder
[    0.030163] cpuidle: using governor menu
[    0.037942] No ATAGs?
[    0.037964] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers.
[    0.037970] hw-breakpoint: maximum watchpoint size is 8 bytes.
[    0.038122] Broadcom Brahma-B15 readahead cache at: 0xd8ac2478
[    0.047262] brcmstb-gpio f040a700.gpio: Registered 4 banks (GPIO(s): 0-127)
[    0.047459] brcmstb-gpio f04172c0.gpio: Registered 2 banks (GPIO(s): 128-191)
[    0.047872] SCSI subsystem initialized
[    0.048138] usbcore: registered new interface driver usbfs
[    0.048173] usbcore: registered new interface driver hub
[    0.048213] usbcore: registered new device driver usb
[    0.048270] Linux video capture interface: v2.00
[    0.048340] pps_core: LinuxPPS API ver. 1 registered
[    0.048344] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    0.048361] PTP clock support registered
[    0.048674] Advanced Linux Sound Architecture Driver Initialized.
[    0.049337] Switched to clocksource arch_sys_counter
[    0.049573] FS-Cache: Loaded
[    0.050545] thermal thermal_zone0: failed to read out thermal zone (-22)
[    0.050641] NET: Registered protocol family 2
[    0.051023] TCP established hash table entries: 4096 (order: 2, 16384 bytes)
[    0.051048] TCP bind hash table entries: 4096 (order: 3, 32768 bytes)
[    0.051093] TCP: Hash tables configured (established 4096 bind 4096)
[    0.051160] UDP hash table entries: 256 (order: 1, 8192 bytes)
[    0.051173] UDP-Lite hash table entries: 256 (order: 1, 8192 bytes)
[    0.051308] NET: Registered protocol family 1
[    0.051518] RPC: Registered named UNIX socket transport module.
[    0.051523] RPC: Registered udp transport module.
[    0.051527] RPC: Registered tcp transport module.
[    0.051530] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    0.051844] CPU PMU: Failed to parse /pmu/interrupt-affinity[0]
[    0.051874] hw perfevents: enabled with armv7_cortex_a15_on_a53 PMU driver, 7 counters available
[    0.053601] NFS: Registering the id_resolver key type
[    0.053622] Key type id_resolver registered
[    0.053627] Key type id_legacy registered
[    0.053642] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[    0.053651] Installing knfsd (copyright (C) 1996 okir@monad.swb.de).
[    0.053835] FS-Cache: Netfs 'cifs' registered for caching
[    0.053897] Key type cifs.spnego registered
[    0.054013] fuse init (API version 7.23)
[    0.055025] bounce: pool size: 64 pages
[    0.055054] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 252)
[    0.055063] io scheduler noop registered
[    0.055069] io scheduler deadline registered
[    0.055079] io scheduler cfq registered (default)
[    0.055298] brcm-gisb-arb f0400000.gisb-arb: registered mem: d8adc000, irqs: 261, 262
[    0.056147] brcmstb-sata-phy f0458100.sata_phy: registered 2 port(s)
[    0.056411] brcm-pci f0460000.pcie: fixing incorrect interrupt-map in DT node
[    0.056465] PCI host bridge /pcie@f0460000 ranges:
[    0.056479]   MEM 0xc0000000..0xcfffffff -> 0xc0000000
[    0.157570] brcm-pci f0460000.pcie: link down
[    0.157631] brcm-pci f0490000.pcie: fixing incorrect interrupt-map in DT node
[    0.157676] PCI host bridge /pcie@f0490000 ranges:
[    0.157685]   MEM 0xd0000000..0xdfffffff -> 0xd0000000
[    0.258717] brcm-pci f0490000.pcie: link down
[    0.260345] brcmstb_memc f1102000.memc-ddr: registered
[    0.260388] brcmstb_memc f1182000.memc-ddr: registered
[    0.260431] brcmstb_memc f1202000.memc-ddr: registered
[    0.260630] brcm-waketimer f0417580.waketimer: registered, with irq 263
[    0.260825] Serial: 8250/16550 driver, 4 ports, IRQ sharing disabled
[    0.261949] console [ttyS0] disabled
[    0.261970] f040ab00.serial: ttyS0 at MMIO 0xf040ab00 (irq = 107, base_baud = 5062500) is a 16550A
[    1.037715] console [ttyS0] enabled
[    1.041525] f040ab40.serial: ttyS1 at MMIO 0xf040ab40 (irq = 108, base_baud = 5062500) is a 16550A
[    1.050783] f040ab80.serial: ttyS2 at MMIO 0xf040ab80 (irq = 109, base_baud = 5062500) is a 16550A
[    1.063688] loop: module loaded
[    1.067640] brcm-ahci f045a000.sata: controller can do ALPM, turning on CAP_ALPM
[    1.075057] brcm-ahci f045a000.sata: masking port_map 0x3 -> 0x3
[    1.081116] brcm-ahci f045a000.sata: SSS flag set, parallel bus scan disabled
[    1.098357] brcm-ahci f045a000.sata: AHCI 0001.0300 32 slots 2 ports 6 Gbps 0x3 impl platform mode
[    1.107339] brcm-ahci f045a000.sata: flags: 64bit ncq sntf stag pm clo pmp slum part 
[    1.117116] scsi host0: brcm-ahci
[    1.120695] scsi host1: brcm-ahci
[    1.124170] ata1: FORCE: PHY spd limit set to 3.0Gbps
[    1.129240] ata1: SATA max UDMA/133 mmio [mem 0xf045a000-0xf045aa9b] port 0x100 irq 62
[    1.137175] ata2: FORCE: PHY spd limit set to 3.0Gbps
[    1.142240] ata2: SATA max UDMA/133 mmio [mem 0xf045a000-0xf045aa9b] port 0x180 irq 62
[    1.150180] brcm-ahci f045a000.sata: Broadcom AHCI SATA3 registered
[    1.157803] libphy: Fixed MDIO Bus: probed
[    1.164166] libphy: unimac MII bus: probed
[    1.172637] unimac-mdio f0b403c0.mdio: Broadcom UniMAC MDIO bus at 0xd8ba43c0
[    1.180525] brcm-systemport f04a0000.ethernet: Broadcom SYSTEMPORTv 1.00 at 0xd8ba8000 (irqs: 54, 55, TXQs: 32, RXQs: 1)
[    1.192786] xhci-brcm: xHCI BRCM driver
[    1.211799] xhci-brcm f0471000.xhci_v2: xHCI Host Controller
[    1.217577] xhci-brcm f0471000.xhci_v2: new USB bus registered, assigned bus number 1
[    1.225721] xhci-brcm f0471000.xhci_v2: hcc params 0x0250f1fd hci version 0x100 quirks 0x00010090
[    1.234629] xhci-brcm f0471000.xhci_v2: irq 115, io mem 0xf0471000
[    1.241271] hub 1-0:1.0: USB hub found
[    1.245059] hub 1-0:1.0: config failed, hub doesn't have any ports! (err -19)
[    1.252347] xhci-brcm f0471000.xhci_v2: xHCI Host Controller
[    1.258121] xhci-brcm f0471000.xhci_v2: new USB bus registered, assigned bus number 2
[    1.266015] usb usb2: We don't know the algorithms for LPM for this host, disabling LPM.
[    1.274506] hub 2-0:1.0: USB hub found
[    1.278293] hub 2-0:1.0: 2 ports detected
[    1.282753] ehci-brcm: EHCI BRCM driver
[    1.286696] ehci-brcm f0470300.ehci_v2: EHCI Host Controller
[    1.292492] ehci-brcm f0470300.ehci_v2: new USB bus registered, assigned bus number 3
[    1.310480] ehci-brcm f0470300.ehci_v2: irq 111, io mem 0xf0470300
[    1.322347] ehci-brcm f0470300.ehci_v2: USB 2.0 started, EHCI 1.00
[    1.328948] hub 3-0:1.0: USB hub found
[    1.332737] hub 3-0:1.0: 1 port detected
[    1.336918] ehci-brcm f0470500.ehci_v2: EHCI Host Controller
[    1.342696] ehci-brcm f0470500.ehci_v2: new USB bus registered, assigned bus number 4
[    1.360664] ehci-brcm f0470500.ehci_v2: irq 112, io mem 0xf0470500
[    1.372347] ehci-brcm f0470500.ehci_v2: USB 2.0 started, EHCI 1.00
[    1.378934] hub 4-0:1.0: USB hub found
[    1.382721] hub 4-0:1.0: 1 port detected
[    1.401399] ehci-brcm f0480300.ehci_v2: EHCI Host Controller
[    1.407171] ehci-brcm f0480300.ehci_v2: new USB bus registered, assigned bus number 5
[    1.425140] ehci-brcm f0480300.ehci_v2: irq 117, io mem 0xf0480300
[    1.425357] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 320)
[    1.426982] ata1.00: ATA-8: WDC WD5000BUDT-63G8FY0, 01.01A01, max UDMA/133
[    1.426986] ata1.00: 976773168 sectors, multi 0: LBA48 NCQ (depth 31/32)
[    1.428621] ata1.00: configured for UDMA/133
[    1.428825] scsi 0:0:0:0: Direct-Access     ATA      WDC WD5000BUDT-6 1A01 PQ: 0 ANSI: 5
[    1.429306] sd 0:0:0:0: [sda] 976773168 512-byte logical blocks: (500 GB/466 GiB)
[    1.429310] sd 0:0:0:0: [sda] 4096-byte physical blocks
[    1.429463] sd 0:0:0:0: [sda] Write Protect is off
[    1.429528] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[    1.431425] sd 0:0:0:0: Attached scsi generic sg0 type 0
[    1.501348] ehci-brcm f0480300.ehci_v2: USB 2.0 started, EHCI 1.00
[    1.507982] hub 5-0:1.0: USB hub found
[    1.511776] hub 5-0:1.0: 1 port detected
[    1.515999] ehci-brcm f0480500.ehci_v2: EHCI Host Controller
[    1.521789] ehci-brcm f0480500.ehci_v2: new USB bus registered, assigned bus number 6
[    1.539827] ehci-brcm f0480500.ehci_v2: irq 118, io mem 0xf0480500
[    1.551347] ehci-brcm f0480500.ehci_v2: USB 2.0 started, EHCI 1.00
[    1.557940] hub 6-0:1.0: USB hub found
[    1.561731] hub 6-0:1.0: 1 port detected
[    1.566006] ohci-brcm: OHCI BRCM driver
[    1.566351] ata2: SATA link down (SStatus 0 SControl 320)
[    1.575373] ohci-brcm f0470400.ohci_v2: BRCM OHCI controller
[    1.581149] ohci-brcm f0470400.ohci_v2: new USB bus registered, assigned bus number 7
[    1.589067] ohci-brcm f0470400.ohci_v2: irq 113, io mem 0xf0470400
[    1.649741] hub 7-0:1.0: USB hub found
[    1.653529] hub 7-0:1.0: 1 port detected
[    1.657700] ohci-brcm f0470600.ohci_v2: BRCM OHCI controller
[    1.663477] ohci-brcm f0470600.ohci_v2: new USB bus registered, assigned bus number 8
[    1.671437] ohci-brcm f0470600.ohci_v2: irq 114, io mem 0xf0470600
[    1.732744] hub 8-0:1.0: USB hub found
[    1.736533] hub 8-0:1.0: 1 port detected
[    1.740730] ohci-brcm f0480400.ohci_v2: BRCM OHCI controller
[    1.746519] ohci-brcm f0480400.ohci_v2: new USB bus registered, assigned bus number 9
[    1.754437] ohci-brcm f0480400.ohci_v2: irq 119, io mem 0xf0480400
[    1.815743] hub 9-0:1.0: USB hub found
[    1.819530] hub 9-0:1.0: 1 port detected
[    1.823709] ohci-brcm f0480600.ohci_v2: BRCM OHCI controller
[    1.829504] ohci-brcm f0480600.ohci_v2: new USB bus registered, assigned bus number 10
[    1.837504] ohci-brcm f0480600.ohci_v2: irq 120, io mem 0xf0480600
[    1.898726] hub 10-0:1.0: USB hub found
[    1.902604] hub 10-0:1.0: 1 port detected
[    1.906935] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    1.913495] ehci-pci: EHCI PCI platform driver
[    1.917997] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[    1.924200] ohci-pci: OHCI PCI platform driver
[    1.928755] usbcore: registered new interface driver uas
[    1.934187] usbcore: registered new interface driver usb-storage
[    1.940368] mousedev: PS/2 mouse device common for all mice
[    1.946114] i2c /dev entries driver
[    1.950095] IR NEC protocol handler initialized
[    1.954647] IR RC5(x/sz) protocol handler initialized
[    1.959715] IR RC6 protocol handler initialized
[    1.964266] IR JVC protocol handler initialized
[    1.968810] IR Sony protocol handler initialized
[    1.973446] IR SANYO protocol handler initialized
[    1.978164] IR Sharp protocol handler initialized
[    1.982888] IR MCE Keyboard/mouse protocol handler initialized
[    1.988740] IR XMP protocol handler initialized
[    1.993579] brcmstb_thermal f04d1500.thermal: registered AVS TMON of-sensor driver
[    2.001726] brcmstb-cpufreq f03e257c.cpu_clk_div: registered
[    2.007603] sdhci: Secure Digital Host Controller Interface driver
[    2.013801] sdhci: Copyright(c) Pierre Ossman
[    2.018178] sdhci-pltfm: SDHCI platform and OF driver helper
[    2.025083] sdhci-brcmstb f03e0200.sdhci: No vmmc regulator found
[    2.031201] sdhci-brcmstb f03e0200.sdhci: No vqmmc regulator found
[    2.067348] mmc0: SDHCI controller on f03e0200.sdhci [f03e0200.sdhci] using ADMA
[    2.075016] usbcore: registered new interface driver usbhid
[    2.080605] usbhid: USB HID core driver
[    2.084966] NET: Registered protocol family 17
[    2.089581] Distributed Switch Architecture driver version 0.1
[    2.095460] brcm-systemport f04a0000.ethernet sys0: [0]: detected a Broadcom Starfighter 2 switch
[    2.104707] Starfighter 2 top: 4.00, core: 2.00 base: 0xd8c00000, IRQs: 56, 57
[    2.111996] libphy: dsa slave smi: probed
[    2.135106] mmc0: MAN_BKOPS_EN bit is not set
[    2.146186] mmc0: new high speed MMC card at address 0001
[    2.151813] mmcblk0: mmc0:0001 4FTE4R 3.64 GiB 
[    2.156440] mmcblk0boot0: mmc0:0001 4FTE4R partition 1 4.00 MiB
[    2.162450] mmcblk0boot1: mmc0:0001 4FTE4R partition 2 4.00 MiB
[    2.168448] mmcblk0rpmb: mmc0:0001 4FTE4R partition 3 512 KiB
[    2.172348] f0b403c0.mdio--1:05: Broadcom BCM7445 PHY revision: 0x10, patch: 3
[    2.184122]  mmcblk0: p1 p2 p3 p4 p5 p6 p7 p8 p9 p10 p11 p12 p13 p14 p15 p16
[    2.405706] dsa f0b00000.ethernet_switch eth0 (uninitialized): attached PHY at address 5 [Broadcom BCM7445]
[    2.415969] brcm-systemport f04a0000.ethernet sys0: [1]: detected a Broadcom Starfighter 2 switch
[    2.424868] brcm-systemport f04a0000.ethernet sys0: [1]: couldn't create dsa switch instance (error -22)
[    2.434487] 8021q: 802.1Q VLAN Support v1.8
[    2.438721] Key type dns_resolver registered
[    2.443112] Registering SWP/SWPB emulation handler
[    2.451896] ALSA device list:
[    2.454879]   No soundcards found.
[    2.818350] usb 5-1: new high-speed USB device number 2 using ehci-brcm
[    2.939444] hub 5-1:1.0: USB hub found
[    2.943362] hub 5-1:1.0: 4 ports detected
[    3.681911]  sda: sda1
[    3.684875] sd 0:0:0:0: [sda] Attached SCSI disk
[    3.689955] EXT4-fs: Warning: mounting with data=journal disables delayed allocation and O_DIRECT support!
[    3.792802] EXT4-fs (mmcblk0p16): recovery complete
[    3.797942] EXT4-fs (mmcblk0p16): mounted filesystem with journalled data mode. Opts: data=journal
[    3.806958] VFS: Mounted root (ext4 filesystem) on device 179:16.
[    3.813668] devtmpfs: mounted
[    3.816895] Freeing unused kernel memory: 312K

INIT: version  booting

Jan 01 01:00:03 (none) user.info rcS[88]: start
Jan 01 01:00:03 (none) user.info rcS[88]: creating and mounting system directories ...
Fri Jan  1 00:00:00 CET 2021
Jan  1 00:00:00 vuduo4kse syslog.info syslogd started: BusyBox v1.31.1
Jan  1 00:00:00 vuduo4kse user.info service[143]: running /etc/init.d/sysctl start 
* Applying /etc/sysctl.conf ...
Jan  1 00:00:00 vuduo4kse user.info service[149]: running /etc/init.d/vuplus-platform-util start 
Starting vuplus-platform-utilOSD-RES: 1280 x 720
.
Jan  1 00:00:06 vuduo4kse user.info service[253]: running /etc/init.d/mdev start 
Jan  1 00:00:06 vuduo4kse user.info mdev[256]: mdev coldplug ...
Jan  1 00:00:07 vuduo4kse user.info mdev-mount[427]: drive has partitions sda1, not trying to mount sda
Jan  1 00:00:07 vuduo4kse user.info mdev-mount[432]: [add] mounting /dev/sda1 to /media/sda1
/lib/mdev/fs/mdev-mount: line 89: can't create /proc/stb/lcd/symbol_hdd: nonexistent directory
Jan  1 00:00:09 vuduo4kse user.info mdev-mmcblk[451]: [add] processing mmcblk0p1
Jan  1 00:00:09 vuduo4kse user.info mdev-mmcblk[451]: [add] mounting /dev/mmcblk0p1 to /mnt/mmcblk0p1
NTFS signature is missing.
Failed to mount '/dev/mmcblk0p1': Invalid argument
The device '/dev/mmcblk0p1' doesn't seem to have a valid NTFS.
Maybe the wrong device is used? Or the whole disk instead of a
partition (e.g. /dev/sda, not /dev/sda1)? Or the other way around?
NTFS signature is missing.
Failed to mount '/dev/mmcblk0p1': Invalid argument
The device '/dev/mmcblk0p1' doesn't seem to have a valid NTFS.
Maybe the wrong device is used? Or the whole disk instead of a
partition (e.g. /dev/sda, not /dev/sda1)? Or the other way around?
ERROR: exFAT file system is not found.
mount: mounting /dev/mmcblk0p1 on /mnt/mmcblk0p1 failed: Invalid argument
Jan  1 00:00:09 vuduo4kse user.warn mdev-mmcblk[451]: mount /dev/mmcblk0p1 /mnt/mmcblk0p1 failed with 255
Jan  1 00:00:09 vuduo4kse user.warn mdev-mmcblk[451]:       
Jan  1 00:00:09 vuduo4kse user.info mdev-mmcblk[540]: [add] processing mmcblk0p2
Jan  1 00:00:09 vuduo4kse user.info mdev-mmcblk[540]: [add] mounting /dev/mmcblk0p2 to /mnt/mmcblk0p2
NTFS signature is missing.
Failed to mount '/dev/mmcblk0p2': Invalid argument
The device '/dev/mmcblk0p2' doesn't seem to have a valid NTFS.
Maybe the wrong device is used? Or the whole disk instead of a
partition (e.g. /dev/sda, not /dev/sda1)? Or the other way around?
NTFS signature is missing.
Failed to mount '/dev/mmcblk0p2': Invalid argument
The device '/dev/mmcblk0p2' doesn't seem to have a valid NTFS.
Maybe the wrong device is used? Or the whole disk instead of a
partition (e.g. /dev/sda, not /dev/sda1)? Or the other way around?
ERROR: exFAT file system is not found.
mount: mounting /dev/mmcblk0p2 on /mnt/mmcblk0p2 failed: Invalid argument
Jan  1 00:00:09 vuduo4kse user.warn mdev-mmcblk[540]: mount /dev/mmcblk0p2 /mnt/mmcblk0p2 failed with 255
Jan  1 00:00:09 vuduo4kse user.warn mdev-mmcblk[540]:       
Jan  1 00:00:09 vuduo4kse user.info mdev-mmcblk[674]: [add] processing mmcblk0p3
Jan  1 00:00:09 vuduo4kse user.info mdev-mmcblk[674]: [add] mounting /dev/mmcblk0p3 to /mnt/mmcblk0p3
NTFS signature is missing.
Failed to mount '/dev/mmcblk0p3': Invalid argument
The device '/dev/mmcblk0p3' doesn't seem to have a valid NTFS.
Maybe the wrong device is used? Or the whole disk instead of a
partition (e.g. /dev/sda, not /dev/sda1)? Or the other way around?
NTFS signature is missing.
Failed to mount '/dev/mmcblk0p3': Invalid argument
The device '/dev/mmcblk0p3' doesn't seem to have a valid NTFS.
Maybe the wrong device is used? Or the whole disk instead of a
partition (e.g. /dev/sda, not /dev/sda1)? Or the other way around?
ERROR: exFAT file system is not found.
mount: mounting /dev/mmcblk0p3 on /mnt/mmcblk0p3 failed: Invalid argument
Jan  1 00:00:10 vuduo4kse user.warn mdev-mmcblk[674]: mount /dev/mmcblk0p3 /mnt/mmcblk0p3 failed with 255
Jan  1 00:00:10 vuduo4kse user.warn mdev-mmcblk[674]:       
Jan  1 00:00:10 vuduo4kse user.info mdev-mmcblk[816]: [add] processing mmcblk0p4
Jan  1 00:00:10 vuduo4kse user.info mdev-mmcblk[816]: [add] mounting /dev/mmcblk0p4 to /mnt/mmcblk0p4
NTFS signature is missing.
Failed to mount '/dev/mmcblk0p4': Invalid argument
The device '/dev/mmcblk0p4' doesn't seem to have a valid NTFS.
Maybe the wrong device is used? Or the whole disk instead of a
partition (e.g. /dev/sda, not /dev/sda1)? Or the other way around?
NTFS signature is missing.
Failed to mount '/dev/mmcblk0p4': Invalid argument
The device '/dev/mmcblk0p4' doesn't seem to have a valid NTFS.
Maybe the wrong device is used? Or the whole disk instead of a
partition (e.g. /dev/sda, not /dev/sda1)? Or the other way around?
ERROR: exFAT file system is not found.
mount: mounting /dev/mmcblk0p4 on /mnt/mmcblk0p4 failed: Invalid argument
Jan  1 00:00:10 vuduo4kse user.warn mdev-mmcblk[816]: mount /dev/mmcblk0p4 /mnt/mmcblk0p4 failed with 255
Jan  1 00:00:10 vuduo4kse user.warn mdev-mmcblk[816]:       
Jan  1 00:00:10 vuduo4kse user.info mdev-mmcblk[966]: [add] processing mmcblk0p5
Jan  1 00:00:10 vuduo4kse user.info mdev-mmcblk[966]: [add] mounting /dev/mmcblk0p5 to /mnt/mmcblk0p5
NTFS signature is missing.
Failed to mount '/dev/mmcblk0p5': Invalid argument
The device '/dev/mmcblk0p5' doesn't seem to have a valid NTFS.
Maybe the wrong device is used? Or the whole disk instead of a
partition (e.g. /dev/sda, not /dev/sda1)? Or the other way around?
NTFS signature is missing.
Failed to mount '/dev/mmcblk0p5': Invalid argument
The device '/dev/mmcblk0p5' doesn't seem to have a valid NTFS.
Maybe the wrong device is used? Or the whole disk instead of a
partition (e.g. /dev/sda, not /dev/sda1)? Or the other way around?
ERROR: exFAT file system is not found.
mount: mounting /dev/mmcblk0p5 on /mnt/mmcblk0p5 failed: Invalid argument
Jan  1 00:00:10 vuduo4kse user.warn mdev-mmcblk[966]: mount /dev/mmcblk0p5 /mnt/mmcblk0p5 failed with 255
Jan  1 00:00:10 vuduo4kse user.warn mdev-mmcblk[966]:       
Jan  1 00:00:10 vuduo4kse user.info mdev-mmcblk[1125]: [add] processing mmcblk0p7
Jan  1 00:00:10 vuduo4kse user.info mdev-mmcblk[1125]: [add] mounting /dev/mmcblk0p7 to /mnt/mmcblk0p7
NTFS signature is missing.
Failed to mount '/dev/mmcblk0p7': Invalid argument
The device '/dev/mmcblk0p7' doesn't seem to have a valid NTFS.
Maybe the wrong device is used? Or the whole disk instead of a
partition (e.g. /dev/sda, not /dev/sda1)? Or the other way around?
NTFS signature is missing.
Failed to mount '/dev/mmcblk0p7': Invalid argument
The device '/dev/mmcblk0p7' doesn't seem to have a valid NTFS.
Maybe the wrong device is used? Or the whole disk instead of a
partition (e.g. /dev/sda, not /dev/sda1)? Or the other way around?
ERROR: exFAT file system is not found.
mount: mounting /dev/mmcblk0p7 on /mnt/mmcblk0p7 failed: Invalid argument
Jan  1 00:00:10 vuduo4kse user.warn mdev-mmcblk[1125]: mount /dev/mmcblk0p7 /mnt/mmcblk0p7 failed with 255
Jan  1 00:00:10 vuduo4kse user.warn mdev-mmcblk[1125]:       
Jan  1 00:00:10 vuduo4kse user.info mdev-mmcblk[1299]: [add] processing mmcblk0p8
Jan  1 00:00:11 vuduo4kse user.info mdev-mmcblk[1299]: [add] mounting /dev/mmcblk0p8 to /mnt/mmcblk0p8
NTFS signature is missing.
Failed to mount '/dev/mmcblk0p8': Invalid argument
The device '/dev/mmcblk0p8' doesn't seem to have a valid NTFS.
Maybe the wrong device is used? Or the whole disk instead of a
partition (e.g. /dev/sda, not /dev/sda1)? Or the other way around?
NTFS signature is missing.
Failed to mount '/dev/mmcblk0p8': Invalid argument
The device '/dev/mmcblk0p8' doesn't seem to have a valid NTFS.
Maybe the wrong device is used? Or the whole disk instead of a
partition (e.g. /dev/sda, not /dev/sda1)? Or the other way around?
ERROR: exFAT file system is not found.
mount: mounting /dev/mmcblk0p8 on /mnt/mmcblk0p8 failed: Invalid argument
Jan  1 00:00:11 vuduo4kse user.warn mdev-mmcblk[1299]: mount /dev/mmcblk0p8 /mnt/mmcblk0p8 failed with 255
Jan  1 00:00:11 vuduo4kse user.warn mdev-mmcblk[1299]:       
Jan  1 00:00:11 vuduo4kse user.info mdev-mmcblk[1481]: [add] processing mmcblk0p9
Jan  1 00:00:11 vuduo4kse user.info mdev-mmcblk[1481]: [add] /dev/mmcblk0p9 is a kernel partition [] - not mounting.
Jan  1 00:00:11 vuduo4kse user.info mdev-mmcblk[1624]: [add] processing mmcblk0p10
Jan  1 00:00:11 vuduo4kse user.info mdev-mmcblk[1624]: [add] mounting /dev/mmcblk0p10 to /mnt/rootfs1
Jan  1 00:00:11 vuduo4kse user.info mdev-mmcblk[1661]: [add] processing mmcblk0p11
Jan  1 00:00:11 vuduo4kse user.info mdev-mmcblk[1661]: [add] /dev/mmcblk0p11 is a kernel partition [] - not mounting.
Jan  1 00:00:11 vuduo4kse user.info mdev-mmcblk[1700]: [add] processing mmcblk0p12
Jan  1 00:00:11 vuduo4kse user.info mdev-mmcblk[1700]: [add] mounting /dev/mmcblk0p12 to /mnt/rootfs2
Jan  1 00:00:11 vuduo4kse user.info mdev-mmcblk[1753]: [add] processing mmcblk0p13
Jan  1 00:00:11 vuduo4kse user.info mdev-mmcblk[1753]: [add] /dev/mmcblk0p13 is a kernel partition [] - not mounting.
Jan  1 00:00:11 vuduo4kse user.info mdev-mmcblk[1808]: [add] processing mmcblk0p14
Jan  1 00:00:11 vuduo4kse user.info mdev-mmcblk[1808]: [add] mounting /dev/mmcblk0p14 to /mnt/rootfs3
Jan  1 00:00:11 vuduo4kse user.info mdev-mmcblk[1879]: [add] processing mmcblk0p15
Jan  1 00:00:12 vuduo4kse user.info mdev-mmcblk[1879]: [add] /dev/mmcblk0p15 is a kernel partition [] - not mounting.
Jan  1 00:00:12 vuduo4kse user.info mdev-mmcblk[1950]: [add] processing mmcblk0p16
Jan  1 00:00:12 vuduo4kse user.info mdev-mmcblk[1950]: /dev/mmcblk0p16 already mounted - not mounting again
Jan  1 00:00:12 vuduo4kse user.info mdev[256]: scanning /sys/bus/usb/devices/ to help mdev with usb-coldplug
Jan  1 00:00:12 vuduo4kse user.info mdev[256]: usb device /sys/bus/usb/devices/1-0:1.0 found
Jan  1 00:00:12 vuduo4kse user.info mdev[256]: usb device /sys/bus/usb/devices/10-0:1.0 found
Jan  1 00:00:12 vuduo4kse user.info mdev[256]: usb device /sys/bus/usb/devices/2-0:1.0 found
Jan  1 00:00:12 vuduo4kse user.info mdev[256]: usb device /sys/bus/usb/devices/3-0:1.0 found
Jan  1 00:00:12 vuduo4kse user.info mdev[256]: usb device /sys/bus/usb/devices/4-0:1.0 found
Jan  1 00:00:12 vuduo4kse user.info mdev[256]: usb device /sys/bus/usb/devices/5-0:1.0 found
Jan  1 00:00:12 vuduo4kse user.info mdev[256]: usb device /sys/bus/usb/devices/5-1.1:1.0 found
Jan  1 00:00:12 vuduo4kse user.info mdev[256]: usb device /sys/bus/usb/devices/5-1.2:1.0 found
Jan  1 00:00:12 vuduo4kse user.info mdev[256]: usb device /sys/bus/usb/devices/5-1:1.0 found
Jan  1 00:00:12 vuduo4kse user.info mdev[256]: usb device /sys/bus/usb/devices/6-0:1.0 found
Jan  1 00:00:12 vuduo4kse user.info mdev[256]: usb device /sys/bus/usb/devices/7-0:1.0 found
Jan  1 00:00:12 vuduo4kse user.info mdev[256]: usb device /sys/bus/usb/devices/8-0:1.0 found
Jan  1 00:00:12 vuduo4kse user.info mdev-usb[2038]: add 1-0 Linux 4.1.45-1.17 xhci-hcd xHCI Host Controller
Jan  1 00:00:12 vuduo4kse user.info mdev[256]: usb device /sys/bus/usb/devices/9-0:1.0 found
Jan  1 00:00:12 vuduo4kse user.info mdev-usb[2038]: type 9/0/1
Jan  1 00:00:12 vuduo4kse user.info mdev-usb[2038]: 1-0 HUB Device
Jan  1 00:00:12 vuduo4kse user.info service[2076]: running /etc/init.d/network start 
Jan 01 00:00:12 vuduo4kse user.info network[2082]: Configuring netrk ... 
Jan  1 00:00:12 vuduo4kse user.info mdev-usb[2094]: add 2-0 Linux 4.1.45-1.17 xhci-hcd xHCI Host Controller
Jan  1 00:00:12 vuduo4kse user.info mdev-usb[2094]: type 9/0/3
Jan  1 00:00:12 vuduo4kse user.info mdev-usb[2094]: 2-0 HUB Device
Jan  1 00:00:12 vuduo4kse user.info mdev-usb[2117]: add 3-0 Linux 4.1.45-1.17 ehci_hcd EHCI Host Controller
Jan  1 00:00:12 vuduo4kse user.info mdev-usb[2117]: type 9/0/0
Jan  1 00:00:12 vuduo4kse user.info mdev-usb[2117]: 3-0 HUB Device
udhcpc: started, v1.31.1
Setting IP address 0.0.0.0 on eth0
udhcpc: sending discover
udhcpc: sending discover
udhcpc: sending discover
udhcpc: sending discover
udhcpc: sending discover
udhcpc: sending select for 192.168.2.74
udhcpc: lease of 192.168.2.74 obtained, lease time 345600
Setting IP address 192.168.2.74 on eth0
Deleting routers
route: SIOCDELRT: No such process
Adding router 192.168.2.1
Recreating /etc/resolv.conf
 Adding DNS server 192.168.2.98
 Adding DNS server 192.168.2.1
Jan  1 00:00:16 vuduo4kse user.info service[2161]: running /etc/init.d/ntpdate start 
Jan  1 00:00:16 vuduo4kse user.info rc[88]: running /etc/rcS.d/S40coredump start
Jan  1 00:00:16 vuduo4kse user.info rc[88]: running /etc/rcS.d/S90proc start

INIT: Entering runlevel: 3

Jan  1 00:00:16 vuduo4kse daemon.info init: Entering runlevel: 3
Jan  1 00:00:16 vuduo4kse user.info rc[2188]: running /etc/rc3.d/S01fstab start
Jan 01 00:00:16 vuduo4kse user.info S01fstab[2193]: mount all local stuff from /etc/fstab
Jan 01 00:00:16 vuduo4kse user.info S01fstab[2193]: mount all netfs stuff from /var/etc/fstab
Jan  1 00:00:16 vuduo4kse user.info rc[2188]: running /etc/rc3.d/S50crond start
Jan  1 00:00:16 vuduo4kse cron.info crond[2202]: crond (busybox 1.31.1) started, log level 8
Jan  1 00:00:16 vuduo4kse user.info rc[2188]: running /etc/rc3.d/S50inetd start
Jan  1 00:00:16 vuduo4kse user.info rc[2188]: running /etc/rc3.d/S75autofs start
Jan 01 00:00:17 vuduo4kse user.info S75autofs[2209]: starting
Jan  1 00:00:17 vuduo4kse user.info rc[2188]: running /etc/rc3.d/S75djmount start
Jan  1 00:00:17 vuduo4kse user.info rc[2188]: running /etc/rc3.d/S75dropbear start
Jan  1 00:00:18 vuduo4kse authpriv.info dropbear[2236]: Running in background
Jan  1 00:00:18 vuduo4kse user.info rc[2188]: running /etc/rc3.d/S75inadyn start
Jan  1 00:00:18 vuduo4kse user.info rc[2188]: running /etc/rc3.d/S75minidlnad start
Jan  1 00:00:18 vuduo4kse user.info rc[2188]: running /etc/rc3.d/S75minisatip start
Jan  1 00:00:18 vuduo4kse user.info rc[2188]: running /etc/rc3.d/S75nfsd start
Jan  1 00:00:18 vuduo4kse user.info rc[2188]: running /etc/rc3.d/S75samba start
Jan  1 00:00:18 vuduo4kse user.info rc[2188]: running /etc/rc3.d/S75ushare start
Jan  1 00:00:18 vuduo4kse daemon.err uShare[2260]: Interface eth0 is down.
Jan  1 00:00:18 vuduo4kse daemon.err uShare[2260]: Recheck uShare's configuration and try again !
Jan  1 00:00:18 vuduo4kse daemon.notice uShare[2261]: Listening on telnet port 1337
Jan  1 00:00:18 vuduo4kse daemon.notice uShare[2261]: Initializing UPnP subsystem ...
Jan  1 00:00:18 vuduo4kse daemon.notice uShare[2261]: UPnP MediaServer listening on 192.168.2.74:49152
Jan  1 00:00:18 vuduo4kse user.info rc[2188]: running /etc/rc3.d/S75vsftpd start
Jan  1 00:00:18 vuduo4kse user.info rc[2188]: running /etc/rc3.d/S75xupnpd start
Jan  1 00:00:18 vuduo4kse daemon.notice uShare[2261]: Sending UPnP advertisement for device ...
Jan  1 00:00:18 vuduo4kse daemon.notice uShare[2261]: Listening for control point connections ...
Jan  1 00:00:18 vuduo4kse daemon.notice uShare[2261]: Building Metadata List ...
Jan  1 00:00:18 vuduo4kse daemon.notice uShare[2261]: Looking for files in content directory : /media/sda1
Jan  1 00:00:19 vuduo4kse daemon.notice uShare[2261]: Looking for files in content directory : /media/sdb1
Jan  1 00:00:19 vuduo4kse daemon.notice uShare[2261]: Found 2730 files and subdirectories.
Jan  1 00:00:28 vuduo4kse user.info rc[2188]: running /etc/rc3.d/S80emmrd start
Jan  1 00:00:28 vuduo4kse user.info rc[2188]: running /etc/rc3.d/S80fritzcallmonitor start
Jan  1 00:00:28 vuduo4kse user.info rc[2188]: running /etc/rc3.d/S80openvpn start
Jan  1 00:00:28 vuduo4kse user.info rc[2188]: running /etc/rc3.d/S80rcu_switcher start
Jan  1 00:00:28 vuduo4kse user.info rc[2188]: running /etc/rc3.d/S80tuxcald start
Jan  1 00:00:28 vuduo4kse user.info rc[2188]: running /etc/rc3.d/S80tuxmaild start
Jan  1 00:00:28 vuduo4kse user.info rc[2188]: running /etc/rc3.d/S98camd start
Jan 01 00:00:28 vuduo4kse user.info S98camd[2314]: start camd_datefix
Jan 01 00:00:28 vuduo4kse user.info camd_datefix[2317]: manipulating date ... 
Jun 11 21:52:00 vuduo4kse user.info camd_datefix[2317]: done (202106112152)
Jun 11 21:52:00 vuduo4kse user.info S98camd[2314]: start oscam
Jun 11 21:52:00 vuduo4kse user.info rc[2188]: running /etc/rc3.d/S98user-initscripts start
Jun 11 21:52:00 vuduo4kse user.info S98user-initscripts[2360]: run users initscripts start ...
Jun 11 21:52:00 vuduo4kse user.info rc[2188]: running /etc/rc3.d/S99rc.local start
[HAL:00000000:init  ] [32m[CEC] new instance created 
[0m[neutrino] create CA Instance
[ca_ci] -> GetInstance
[ca_ci] -> cCA 2
set input0 to tuner A
set input1 to tuner B
set input2 to tuner C
set input3 to tuner D
set input4 to tuner E
set input5 to tuner F
set input6 to tuner G
set input7 to tuner H
set input8 to tuner I
set input9 to tuner J
set input10 to tuner K
set input11 to tuner L
set input12 to tuner M
set input13 to tuner N
set input14 to tuner O
set input15 to tuner P
[CA] Slot0: Waiting for zapit
[CA] Slot1: Waiting for zapit
libstb-hal debug options can be set by exporting HAL_DEBUG.
The following values (or bitwise OR combinations) are valid:
	component: audio   0x01
	component: video   0x02
	component: demux   0x04
	component: play    0x08
	component: power   0x10
	component: init    0x20
	component: ca      0x40
	component: record  0x80
	all components:    0xff
[HAL:00000000:init  ] hal_api_init begin, initialized=0, debug=0x00
[HAL:bec04c74:power ] SetCpuFreq(0) => set standby = false
[HAL:00000000:init  ] hal_api_init end
[fb_accel_arm] run start
[neutrino] armbox framebuffer Instance created
[fb_generic] [bcmfb] framebuffer 21600k video mem
[fb_accel_arm] SetMode: 32bits, red 8:16 green 8:8 blue 8:0 transp 8:24
[fb_accel_arm] 1280x720x32 line length 7680. using bcmfb graphics accelerator.
[neutrino] executing /usr/share/tuxbox/neutrino/control/neutrino.start
[HAL:00000000:init  ] hal_api_init begin, initialized=1, debug=0x00
[HAL:00000000:init  ] hal_api_init end
[ca_ci] -> GetNumberCISlots
[neutrino] recording dir: /media/sda1/movies
[neutrino] timeshift dir: /media/sda1/timeshift
[CWebChannelsSetup] loading: /var/tuxbox/webtv/webtv_usr.xml
[CWebChannelsSetup] loading: /usr/share/tuxbox/neutrino/webtv/filmon.xml
[CWebChannelsSetup] loading: /usr/share/tuxbox/neutrino/webtv/yt_live.xml
[CWebChannelsSetup] skipping: /var/tuxbox/webradio/webradio_usr.xml
[CWebChannelsSetup] loading: /usr/share/tuxbox/neutrino/webradio/80s80s.xml
[CWebChannelsSetup] loading: /usr/share/tuxbox/neutrino/webradio/90s90s.xml
[CWebChannelsSetup] loading: /usr/share/tuxbox/neutrino/webradio/stompfm.xml
[neutrino] [CNeutrinoFonts] [SetupNeutrinoFonts - 171] font file: /usr/share/fonts/neutrino.ttf
[neutrino] [CNeutrinoFonts] [SetupNeutrinoFonts - 193] standard font family: Roboto
[neutrino] [CNeutrinoFonts] [SetupDynamicFonts - 153] dynamic font family: Roboto
ShowText ''
ShowText ' '
ShowText 'NI \o/'
CLCD::setScrollMode scroll_repeats:1
[analogclock.cpp:InitAnalogClock] found file: /usr/share/tuxbox/neutrino/icons/oled/clock/analog_clock.png
[analogclock.cpp:InitAnalogClock] found file: /usr/share/tuxbox/neutrino/icons/oled/clock/analog_hour.png
[analogclock.cpp:InitAnalogClock] found file: /usr/share/tuxbox/neutrino/icons/oled/clock/analog_min.png
[analogclock.cpp:InitAnalogClock] finish initialization
[digitalclock.cpp:InitDigitalClock] found file: /usr/share/tuxbox/neutrino/icons/oled/clock/time_zero.png
[digitalclock.cpp:InitDigitalClock] found file: /usr/share/tuxbox/neutrino/icons/oled/clock/time_one.png
[digitalclock.cpp:InitDigitalClock] found file: /usr/share/tuxbox/neutrino/icons/oled/clock/time_two.png
[digitalclock.cpp:InitDigitalClock] found file: /usr/share/tuxbox/neutrino/icons/oled/clock/time_three.png
[digitalclock.cpp:InitDigitalClock] found file: /usr/share/tuxbox/neutrino/icons/oled/clock/time_four.png
[digitalclock.cpp:InitDigitalClock] found file: /usr/share/tuxbox/neutrino/icons/oled/clock/time_five.png
[digitalclock.cpp:InitDigitalClock] found file: /usr/share/tuxbox/neutrino/icons/oled/clock/time_six.png
[digitalclock.cpp:InitDigitalClock] found file: /usr/share/tuxbox/neutrino/icons/oled/clock/time_seven.png
[digitalclock.cpp:InitDigitalClock] found file: /usr/share/tuxbox/neutrino/icons/oled/clock/time_eight.png
[digitalclock.cpp:InitDigitalClock] found file: /usr/share/tuxbox/neutrino/icons/oled/clock/time_nine.png
[digitalclock.cpp:InitDigitalClock] found file: /usr/share/tuxbox/neutrino/icons/oled/clock/time_dots.png
[digitalclock.cpp:InitDigitalClock] finish initialization
[simpleclock.cpp:InitSimpleClock] finish initialization
[weather.cpp:InitWeather] found file: /usr/share/tuxbox/neutrino/icons/oled/weather/clear-day.png
[weather.cpp:InitWeather] found file: /usr/share/tuxbox/neutrino/icons/oled/weather/clear-night.png
[weather.cpp:InitWeather] found file: /usr/share/tuxbox/neutrino/icons/oled/weather/cloudy.png
[weather.cpp:InitWeather] found file: /usr/share/tuxbox/neutrino/icons/oled/weather/fog.png
[weather.cpp:InitWeather] found file: /usr/share/tuxbox/neutrino/icons/oled/weather/partly-cloudy-day.png
[weather.cpp:InitWeather] found file: /usr/share/tuxbox/neutrino/icons/oled/weather/partly-cloudy-night.png
[weather.cpp:InitWeather] found file: /usr/share/tuxbox/neutrino/icons/oled/weather/rain.png
[weather.cpp:InitWeather] found file: /usr/share/tuxbox/neutrino/icons/oled/weather/sleet.png
[weather.cpp:InitWeather] found file: /usr/share/tuxbox/neutrino/icons/oled/weather/snow.png
[weather.cpp:InitWeather] found file: /usr/share/tuxbox/neutrino/icons/oled/weather/wind.png
[weather.cpp:InitWeather] found file: /usr/share/tuxbox/neutrino/icons/oled/weather/unknown.png
[weather.cpp:InitWeather] finish initialization
Jun 11 21:52:00 vuduo4kse user.info neutrino: vuplus4k: current lcd is 480x320, 32bpp, vuplus4k lcd device was opened successfully
Jun 11 21:52:00 vuduo4kse user.info neutrino: vuplus4k: VUPLUS4K initialized.
[fe0/0] frontend fd 12 type 0
[fe0/0] add delivery system DVB-S (delivery_system: 5)
[fe0/0] add delivery system DVB-S2 (delivery_system: 6 / Multistream: yes)
[fe0/0] add delivery system DVB-S2X (delivery_system: 21 / Multistream: yes)
[femanager.cpp:Init:102] add fe 0
[fe0/1] frontend fd 15 type 0
[fe0/1] add delivery system DVB-S (delivery_system: 5)
[fe0/1] add delivery system DVB-S2 (delivery_system: 6 / Multistream: yes)
[fe0/1] add delivery system DVB-S2X (delivery_system: 21 / Multistream: yes)
[femanager.cpp:Init:102] add fe 1
[fe0/2] frontend fd 16 type 0
[fe0/2] add delivery system DVB-S (delivery_system: 5)
[fe0/2] add delivery system DVB-S2 (delivery_system: 6 / Multistream: yes)
[fe0/2] add delivery system DVB-S2X (delivery_system: 21 / Multistream: yes)
[femanager.cpp:Init:102] add fe 2
[fe0/3] frontend fd 17 type 0
[fe0/3] add delivery system DVB-S (delivery_system: 5)
[fe0/3] add delivery system DVB-S2 (delivery_system: 6 / Multistream: yes)
[fe0/3] add delivery system DVB-S2X (delivery_system: 21 / Multistream: yes)
[femanager.cpp:Init:102] add fe 3
[fe0/4] frontend fd 18 type 0
[fe0/4] add delivery system DVB-S (delivery_system: 5)
[fe0/4] add delivery system DVB-S2 (delivery_system: 6 / Multistream: yes)
[fe0/4] add delivery system DVB-S2X (delivery_system: 21 / Multistream: yes)
[femanager.cpp:Init:102] add fe 4
[fe0/5] frontend fd 19 type 0
[fe0/5] add delivery system DVB-S (delivery_system: 5)
[fe0/5] add delivery system DVB-S2 (delivery_system: 6 / Multistream: yes)
[fe0/5] add delivery system DVB-S2X (delivery_system: 21 / Multistream: yes)
[femanager.cpp:Init:102] add fe 5
[fe0/6] frontend fd 20 type 0
[fe0/6] add delivery system DVB-S (delivery_system: 5)
[fe0/6] add delivery system DVB-S2 (delivery_system: 6 / Multistream: yes)
[fe0/6] add delivery system DVB-S2X (delivery_system: 21 / Multistream: yes)
[femanager.cpp:Init:102] add fe 6
[fe0/7] frontend fd 27 type 0
[fe0/7] add delivery system DVB-S (delivery_system: 5)
[fe0/7] add delivery system DVB-S2 (delivery_system: 6 / Multistream: yes)
[fe0/7] add delivery system DVB-S2X (delivery_system: 21 / Multistream: yes)
[femanager.cpp:Init:102] add fe 7
[frontend.cpp:Open:198] /dev/dvb/adapter0/frontend8: No such file or directory
[frontend.cpp:Open:198] /dev/dvb/adapter0/frontend9: No such file or directory
[frontend.cpp:Open:198] /dev/dvb/adapter0/frontend10: No such file or directory
[frontend.cpp:Open:198] /dev/dvb/adapter0/frontend11: No such file or directory
[frontend.cpp:Open:198] /dev/dvb/adapter0/frontend12: No such file or directory
[frontend.cpp:Open:198] /dev/dvb/adapter0/frontend13: No such file or directory
[frontend.cpp:Open:198] /dev/dvb/adapter0/frontend14: No such file or directory
[frontend.cpp:Open:198] /dev/dvb/adapter0/frontend15: No such file or directory
[frontend.cpp:Open:198] /dev/dvb/adapter1/frontend0: No such file or directory
[frontend.cpp:Open:198] /dev/dvb/adapter1/frontend1: No such file or directory
[frontend.cpp:Open:198] /dev/dvb/adapter1/frontend2: No such file or directory
[frontend.cpp:Open:198] /dev/dvb/adapter1/frontend3: No such file or directory
[frontend.cpp:Open:198] /dev/dvb/adapter1/frontend4: No such file or directory
[frontend.cpp:Open:198] /dev/dvb/adapter1/frontend5: No such file or directory
[frontend.cpp:Open:198] /dev/dvb/adapter1/frontend6: No such file or directory
[frontend.cpp:Open:198] /dev/dvb/adapter1/frontend7: No such file or directory
[frontend.cpp:Open:198] /dev/dvb/adapter1/frontend8: No such file or directory
[frontend.cpp:Open:198] /dev/dvb/adapter1/frontend9: No such file or directory
[frontend.cpp:Open:198] /dev/dvb/adapter1/frontend10: No such file or directory
[frontend.cpp:Open:198] /dev/dvb/adapter1/frontend11: No such file or directory
[frontend.cpp:Open:198] /dev/dvb/adapter1/frontend12: No such file or directory
[frontend.cpp:Open:198] /dev/dvb/adapter1/frontend13: No such file or directory
[frontend.cpp:Open:198] /dev/dvb/adapter1/frontend14: No such file or directory
[frontend.cpp:Open:198] /dev/dvb/adapter1/frontend15: No such file or directory
[frontend.cpp:Open:198] /dev/dvb/adapter2/frontend0: No such file or directory
[frontend.cpp:Open:198] /dev/dvb/adapter2/frontend1: No such file or directory
[frontend.cpp:Open:198] /dev/dvb/adapter2/frontend2: No such file or directory
[frontend.cpp:Open:198] /dev/dvb/adapter2/frontend3: No such file or directory
[frontend.cpp:Open:198] /dev/dvb/adapter2/frontend4: No such file or directory
[frontend.cpp:Open:198] /dev/dvb/adapter2/frontend5: No such file or directory
[frontend.cpp:Open:198] /dev/dvb/adapter2/frontend6: No such file or directory
[frontend.cpp:Open:198] /dev/dvb/adapter2/frontend7: No such file or directory
[frontend.cpp:Open:198] /dev/dvb/adapter2/frontend8: No such file or directory
[frontend.cpp:Open:198] /dev/dvb/adapter2/frontend9: No such file or directory
[frontend.cpp:Open:198] /dev/dvb/adapter2/frontend10: No such file or directory
[frontend.cpp:Open:198] /dev/dvb/adapter2/frontend11: No such file or directory
[frontend.cpp:Open:198] /dev/dvb/adapter2/frontend12: No such file or directory
[frontend.cpp:Open:198] /dev/dvb/adapter2/frontend13: No such file or directory
[frontend.cpp:Open:198] /dev/dvb/adapter2/frontend14: No such file or directory
[frontend.cpp:Open:198] /dev/dvb/adapter2/frontend15: No such file or directory
[frontend.cpp:Open:198] /dev/dvb/adapter3/frontend0: No such file or directory
[frontend.cpp:Open:198] /dev/dvb/adapter3/frontend1: No such file or directory
[frontend.cpp:Open:198] /dev/dvb/adapter3/frontend2: No such file or directory
[frontend.cpp:Open:198] /dev/dvb/adapter3/frontend3: No such file or directory
[frontend.cpp:Open:198] /dev/dvb/adapter3/frontend4: No such file or directory
[frontend.cpp:Open:198] /dev/dvb/adapter3/frontend5: No such file or directory
[frontend.cpp:Open:198] /dev/dvb/adapter3/frontend6: No such file or directory
[frontend.cpp:Open:198] /dev/dvb/adapter3/frontend7: No such file or directory
[frontend.cpp:Open:198] /dev/dvb/adapter3/frontend8: No such file or directory
[frontend.cpp:Open:198] /dev/dvb/adapter3/frontend9: No such file or directory
[frontend.cpp:Open:198] /dev/dvb/adapter3/frontend10: No such file or directory
[frontend.cpp:Open:198] /dev/dvb/adapter3/frontend11: No such file or directory
[frontend.cpp:Open:198] /dev/dvb/adapter3/frontend12: No such file or directory
[frontend.cpp:Open:198] /dev/dvb/adapter3/frontend13: No such file or directory
[frontend.cpp:Open:198] /dev/dvb/adapter3/frontend14: No such file or directory
[frontend.cpp:Open:198] /dev/dvb/adapter3/frontend15: No such file or directory
[femanager.cpp:Init:118] found 8 frontends, 16 demuxes
[HAL:0062b690:audio ] cAudio::setVolume(75, 75)
[zapit] Loading services, channel size 480 ..
[getservices.cpp:LoadServices:915] Loading satellites...
[getservices.cpp:FindTransponder:623] going to parse dvb-s provider 19.2E Ku-band Astra 1KR/1L/1M/1N
[getservices.cpp:FindTransponder:623] going to parse dvb-s provider 19.2E (Sky) Ku-band Astra 1KR/1L/1M/1N
[getservices.cpp:FindTransponder:623] going to parse dvb-s provider 19.2E (HD+) Ku-band Astra 1KR/1L/1M/1N
[zapit] 3876 services loaded (3876)...
[zapit] service loading took: 85 msec
[getservices.cpp:FindTransponder:623] going to parse dvb-s provider 19.2E Ku-band Astra 1KR/1L/1M/1N
[zapit.cpp:PrepareChannels:1149] LoadServices: success
[bouquets.cpp:parseBouquetsXml:415] reading bouquets from /var/tuxbox/config/zapit/bouquets.xml
[bouquets.cpp:parseBouquetsXml:517] total: 117 bouquets
[bouquets.cpp:loadWebchannels:890] Loading webtv from /var/tuxbox/webtv/webtv_usr.xml ...
[bouquets.cpp:loadWebchannels:890] Loading webtv from /usr/share/tuxbox/neutrino/webtv/filmon.xml ...
[bouquets.cpp:loadWebchannels:941] * auto epg_id found for Die neue Zeit TV: b33403f700011269
[bouquets.cpp:loadWebchannels:941] * auto epg_id found for R9 Oesterreich: b0e403ed00013400
[bouquets.cpp:loadWebchannels:941] * auto epg_id found for SPORT1: a988041f00011581
[bouquets.cpp:loadWebchannels:941] * auto epg_id found for ZDF: b20c03f200012ba2
[bouquets.cpp:loadWebchannels:941] * auto epg_id found for BBC World News: b03403ea00011389
[bouquets.cpp:loadWebchannels:941] * auto epg_id found for Bloomberg: b5f4040200012753
[bouquets.cpp:loadWebchannels:890] Loading webtv from /usr/share/tuxbox/neutrino/webtv/yt_live.xml ...
[bouquets.cpp:loadWebchannels:890] Loading webradio from /var/tuxbox/webradio/webradio_usr.xml ...
[bouquets.cpp:loadWebchannels:890] Loading webradio from /usr/share/tuxbox/neutrino/webradio/80s80s.xml ...
[bouquets.cpp:loadWebchannels:890] Loading webradio from /usr/share/tuxbox/neutrino/webradio/90s90s.xml ...
[bouquets.cpp:loadWebchannels:890] Loading webradio from /usr/share/tuxbox/neutrino/webradio/stompfm.xml ...
[bouquets.cpp:parseBouquetsXml:415] reading bouquets from /var/tuxbox/config/zapit/ubouquets.xml
[bouquets.cpp:parseBouquetsXml:517] total: 178 bouquets
############## CBouquetManager::makeRemainingChannelsBouquet: numbers start at: tv 1 radio 1 ############
[zapit] bouquet loading took: 288 msec
CServiceManager::SaveServices: total channels: 4571
CServiceManager::SaveServices: processed channels: 3876
[zapit.cpp:Start:2641] channels have been loaded succesfully
[zapit.cpp] diseqc type = 0
[femanager.cpp:loadSettings:247] load config for fe0
[femanager.cpp:loadSettings:247] load config for fe1
[femanager.cpp:loadSettings:247] load config for fe2
[femanager.cpp:loadSettings:247] load config for fe3
[femanager.cpp:loadSettings:247] load config for fe4
[femanager.cpp:loadSettings:247] load config for fe5
[femanager.cpp:loadSettings:247] load config for fe6
[femanager.cpp:loadSettings:247] load config for fe7
[femanager.cpp:linkFrontends:427] linking..
[femanager.cpp:linkFrontends:478] Frontend #0: is independent
[frontend.cpp:setDiseqcType:1679] fe0: NO_DISEQC
[femanager.cpp:linkFrontends:478] Frontend #1: is independent
[frontend.cpp:setDiseqcType:1679] fe1: NO_DISEQC
[frontend.cpp:Close:362] [fe0/2] close frontend fd 16
[frontend.cpp:Close:362] [fe0/3] close frontend fd 17
[frontend.cpp:Close:362] [fe0/4] close frontend fd 18
[frontend.cpp:Close:362] [fe0/5] close frontend fd 19
[frontend.cpp:Close:362] [fe0/6] close frontend fd 20
[frontend.cpp:Close:362] [fe0/7] close frontend fd 27
[femanager.cpp:linkFrontends:495] pip demux: 3
[ca_ci] -> SetInitMask param:2
[ca_ci] -> GetNumberCISlots
[ca_ci] -> SetTSClock for Slot0 to: high
[ca_ci] -> GetNumberCISlots
[ca_ci] -> SetTSClock for Slot1 to: normal
[ca_ci] -> GetNumberCISlots
[ca_ci] -> SetCIDelay for all Slots to: 256
[ca_ci] -> GetNumberCISlots
[ca_ci] -> SetCIRelevantPidsRouting for Slot0 to: 0
[ca_ci] -> GetNumberCISlots
[ca_ci] -> SetCIRelevantPidsRouting for Slot1 to: 9
[ca_ci] -> GetNumberCISlots
[ca_ci] -> Start
[zapit] GetConfig...
[zapit] starting... tid 2432
[zapit] sdt monitor started
[neutrino VideoSettings] setupVideoSystem setup videosystem...
>>>>>[src/gui/osd_helpers.cpp - setVideoSystem:216] before SetVideoSystem:
                var.xres        : 1280, var.yres    :  720, var.yres_virtual: 1440
                fix.line_length : 7680, fix.smem_len: 22118400 Byte
[HAL:0062d768:video ] SetVideoSystem: old: '720p50' new: '1080i60'
>>>>>[src/gui/osd_helpers.cpp - setVideoSystem:229] after SetVideoSystem:
                var.xres        : 1280, var.yres    :  720, var.yres_virtual: 1440
                fix.line_length : 7680, fix.smem_len: 22118400 Byte
>>>>>[changeOsdResolution:75] osd mode: OSDMODE_720 => OSDMODE_720, automode: true, forceOsdReset: false
[fb_accel_arm] SetMode: 32bits, red 8:16 green 8:8 blue 8:0 transp 8:24
[fb_accel_arm] 1280x720x32 line length 7680. using bcmfb graphics accelerator.
[neutrino VideoSettings] setVideoSettings init video settings...
[1;34m[swscaler @ 0x645de0] [0m[0;33mdeprecated pixel format used, make sure you did set range correctly
[0m[rcinput:checkdev] /dev/input mtime changed
[rcinput:open] opened /dev/input/event0 (fd 32) ev 0xb
[zapit.cpp:leaveStandby:2460] standby 1 recording 0
[zapit.cpp:ZapIt:518] [zapit] zap to Das Erste HD (b45603fb0001283d tp 2ce500c203fb0001)
[zapit] GetConfig...
[femanager.cpp:allocateFE:751] pip demux: 3
[zapit.cpp:StopPlayBack:2394] standby 0 playing 0 forced 0 send_pmt 1
[fe0/0] tune to 11493000 DVB-S2 8PSK 2/3 H/L srate 22000000 pli -1 plc 0 plm 1 (tuner 1743000 offset 9750000 timeout 4000)
[frontend.cpp:setFrontend:1540] [fe0/0] tuner pilot 2 (feparams 2) streamid (-1/0/1)

[fe0/0] FE_HAS_SIGNAL
[fe0/0] FE_HAS_CARRIER
[fe0/0] FE_HAS_VITERBI
[frontend.cpp:getEvent:804] [fe0/0] ******** FE_HAS_LOCK: freq 1742774
[fe0/0] tuning took: 255 msec (min 255 max 255)
[HAL:afe014b8:demux ] _open: setting /dev/dvb/adapter0/demux0 to source 0
Jun 11 21:52:03 vuduo4kse local0.info xupnpd[2434]: ./plugins/xupnpd_youtube.lua:168: bad argument #1 to 'decode' (string expected, got nil)
[zapit] parsing pmt pid 0x13EC (Das Erste HD)
[subtitles] DVBSub: PID=0x13f1, lang=deu, cpageid=0001, apageid=0001
[zapit] channel found, audio pid 13f2, subtitle pid 0 mode 0 volume 75
[capmt.cpp:SetMode:256] PLAY: fe_num 0 dmx_src 0
[capmt.cpp:SetMode:309] channel b45603fb0001283d [Das Erste HD] mode 0 START src 0 mask 0 -> 1 update 0 rmode 0 mp 0
[capmt.cpp:SetMode:337] [33m socket only[0m
[CBasicClient] connect failed.
/tmp/camd.socket: No such file or directory
[capmt.cpp:SetMode:483] [33m (oldmask != newmask) || force_update)[0m
[ca_ci] -> SendCAPMT
TP: B45603FB0001
SID: 283D
SOURCE: 0
CA_MASK: 1
CALEN: 65
Scrambled: 0
Mode: 0
Enabled: START
[ca_ci] -> FindFreeSlot
No free ci-slot
CaMap Empty
[pmt] set update filter, sid 0x283d pid 0x13ec version 2
[timerd] wakeup from standby: no
[neutrino] executing /usr/share/tuxbox/neutrino/control/deepstandby.off
[neutrino CEC Settings] setCECSettings init CEC settings...
[HAL:005b4d58:init  ] [32m[CEC] switch off SetCECMode
[0mtimerd startup, tid 2436
CStreamManager::Listen: on 31339, fd 31
[HAL:adc005b8:demux ] Stop #0: not open!
[yhttpd] Webserver nhttpd/3.4.1 (yhttpd_core/1.3.2) tid 2439
2021-06-11 21:52:03.770 [sectionsd] start
2021-06-11 21:52:03.770 [sectionsd] Caching: 3 days, 3 hours Extended Text, max 20000 events, Events are old 3 hours after end time
2021-06-11 21:52:03.770 [sectionsd] NTP: enabled, command /usr/sbin/ntpdate 0.pool.ntp.org
Starting STREAM thread keeper, tid 2440
2021-06-11 21:52:03.771 [sectionsd] starting
2021-06-11 21:52:03.771 [sectionsd] SIevent size: 92
2021-06-11 21:52:03.771 [sectionsd] ====> USING SLOW ADDEVENT. export 'NO_SLOW_ADDEVENT=1' to avoid <===
2021-06-11 21:52:03.777 [sectionsd] timeThread::run:: starting, pid 2382 (2883568720)
2021-06-11 21:52:03.777 [sectionsd] eitThread::run:: starting, pid 2382 (2875176016)
2021-06-11 21:52:03.778 [sectionsd] cnThread::run:: starting, pid 2382 (2866783312)
2021-06-11 21:52:03.778 [sectionsd] freeSatThread::run:: starting, pid 2382 (2858390608)
[yhttpd] Webserver starting...
CInfoIcons::Init() reports 5 entries
[ca_ci] -> GetNumberCISlots
CI: CAM not found
CI: CAM not found
[ca_ci] -> GetNumberCISlots
CI: CAM not found
CI: CAM not found
[neutrino] menue setup
[neutrino] init menus...
[neutrino] mediaplayer menu instance created
[ca_ci] -> GetNumberCISlots
[network config] Instance created
[neutrino] [CNetworkSetup]	[getInstance - 90],  Instance created
[neutrino] ScanSetup Instance created
[HAL:00732b40:play  ] cPlayback
[mb]->refreshBrowserList
[mb]->refreshFilterList 20
[neutrino CMoviePlayerGui] Instance created...
[neutrino] integratePlugins: add Multiboot
[neutrino] integratePlugins: add Favoriten sichern
[neutrino] integratePlugins: add Vollständiges Image-Backup
[neutrino] integratePlugins: add Benutzer-Bouquets
[neutrino] registering as event client
[neutrino] had to wait 0 ms for timerd start...
CLCD::setMuted 0
ShowText 'Vol:  0%'
ShowText 'Vol: 75%'
[ca_ci] -> GetNumberCISlots
CI: CAM not found
CI: CAM not found
[neutrino] Creating channels lists...
[neutrino] got 4076 TV and 483 RADIO channels
[neutrino] all channels took: 1 msec
[neutrino] created 19.2E Ku-band Astra 1KR/1L/1M/1N (192) bouquet with 1135 TV and 153 RADIO channels
[neutrino] sat took: 6 msec
[neutrino] created 19.2E (Sky) Ku-band Astra 1KR/1L/1M/1N (193) bouquet with 1135 TV and 153 RADIO channels
[neutrino] sat took: 7 msec
[neutrino] created 19.2E (HD+) Ku-band Astra 1KR/1L/1M/1N (194) bouquet with 1135 TV and 153 RADIO channels
[neutrino] sat took: 7 msec
[neutrino] got 671 WebTV channels
[neutrino] got 24 WebRadio channels
[neutrino] got 21 new TV channels
[neutrino] got 15 removed TV channels
[neutrino] sats took: 15 msec
[neutrino] got 142 TV and 57 RADIO bouquets
[neutrino] took: 17 msec
CNeutrinoApp::SetChannelMode 0 [tv]
[neutrino.cpp:SetChannelMode:2454] newmode 0 sort old -1 new 0
[neutrino.cpp:tvMode:4807] rezap 1 current mode 1
ShowText ''
CRemoteControl::tvMode
CNeutrinoApp::SetChannelMode 0 [tv]
[neutrino.cpp:SetChannelMode:2454] newmode 0 sort old -1 new 0
**************************** CChannelList::zapTo_ChannelID b45603fb0001283d
**************************** CChannelList::zapToChannel me 0x8076b8 Alle Kanäle tuned 268435455 new Das Erste HD -> b45603fb0001283d
2021-06-11 21:52:07.032 [sectionsd] commandserviceStopped
2021-06-11 21:52:07.032 [sectionsd] cnThread: stop eit update filter (not active)
2021-06-11 21:52:07.032 [sectionsd] commandserviceStopped done
stopPlayBack: stopping...
stopPlayBack: stopped
CChannelList::adjustToChannelID me 0x8076b8 [Alle Kanäle] list size 4076 channel_id b45603fb0001283d
[zapit.cpp:ZapIt:518] [zapit] zap to Das Erste HD (b45603fb0001283d tp 2ce500c203fb0001)
[zapit] saving channel, apid 13f2 sub pid 0 mode 0 volume 75
[zapit] GetConfig...
[femanager.cpp:allocateFE:751] pip demux: 3
[zapit.cpp:StopPlayBack:2394] standby 0 playing 0 forced 0 send_pmt 1
[capmt.cpp:SetMode:256] PLAY: fe_num 0 dmx_src 0
[capmt.cpp:SetMode:309] channel b45603fb0001283d [Das Erste HD] mode 0 STOP src 0 mask 1 -> 0 update 0 rmode 0 mp 0
[capmt.cpp:SetMode:385] [33m (newmask == 0)[0m
[ca_ci] -> SendCAPMT
TP: B45603FB0001
SID: 283D
SOURCE: 0
CA_MASK: 0
CALEN: 0
Scrambled: 0
Mode: 0
Enabled: STOP
2021-06-11 21:52:07.052 [sectionsd] commandserviceChanged: Service change to b45603fb0001283d demux #0
2021-06-11 21:52:07.052 [sectionsd] changeDMX: for 0x4e not ignored! even though real_pauseCounter> 0 (1)
2021-06-11 21:52:07.052 [sectionsd] changeDMX: for 0x50 not ignored! even though real_pauseCounter> 0 (1)
2021-06-11 21:52:07.052 [sectionsd] changeDMX: for 0x60 not ignored! even though real_pauseCounter> 0 (1)
CHDDDestExec::exec: hd-idle = /usr/sbin/hd-idle
sh: you need to specify whom to kill
[ca_ci] -> Ready param:1
[CWeather]: GetWeatherDetails
################################## after all ##################################: 6376 msec
[neutrino] initialized everything
[CPlugins] could not find startup
[ca_ci] -> GetNumberCISlots
CI: CAM not found
CI: CAM not found
[zapit] parsing pmt pid 0x13EC (Das Erste HD)
[subtitles] DVBSub: PID=0x13f1, lang=deu, cpageid=0001, apageid=0001
[zapit] channel found, audio pid 13f2, subtitle pid 0 mode 0 volume 75
[zapit.cpp:StartPlayBack:2310] standby 0 playing 0 forced 0
[zapit] vpid 13ED apid 13F2 pcr 13ED
[HAL:0062b690:audio ] SetStreamType 0 (0x0)
[capmt.cpp:SetMode:256] PLAY: fe_num 0 dmx_src 0
[capmt.cpp:SetMode:309] channel b45603fb0001283d [Das Erste HD] mode 0 START src 0 mask 0 -> 1 update 0 rmode 0 mp 0
[capmt.cpp:SetMode:337] [33m socket only[0m
[CBasicClient] connect failed.
/tmp/camd.socket: No such file or directory
[capmt.cpp:SetMode:483] [33m (oldmask != newmask) || force_update)[0m
[ca_ci] -> SendCAPMT
TP: B45603FB0001
SID: 283D
SOURCE: 0
CA_MASK: 1
CALEN: 65
Scrambled: 0
Mode: 0
Enabled: START
[ca_ci] -> FindFreeSlot
No free ci-slot
CaMap Empty
[pmt] set update filter, sid 0x283d pid 0x13ec version 2
CRemoteControl::handleMsg: EVT_TUNE_COMPLETE (b45603fb0001283d)
2021-06-11 21:52:07.399 [sectionsd] commandserviceChanged: Service change to b45603fb0001283d demux #0
CRemoteControl::handleMsg: EVT_TUNE_COMPLETE (b45603fb0001283d)
2021-06-11 21:52:07.419 [sectionsd] commandserviceChanged: Service change to b45603fb0001283d demux #0
ShowText 'Das Erste HD'
[zapit.cpp:ZapIt:518] [zapit] zap to Das Erste HD (b45603fb0001283d tp 2ce500c203fb0001)
[zapit] saving channel, apid 13f2 sub pid 0 mode 0 volume 75
[zapit] GetConfig...
[femanager.cpp:allocateFE:751] pip demux: 3
[zapit.cpp:StopPlayBack:2394] standby 0 playing 1 forced 0 send_pmt 1
[capmt.cpp:SetMode:256] PLAY: fe_num 0 dmx_src 0
[capmt.cpp:SetMode:309] channel b45603fb0001283d [Das Erste HD] mode 0 STOP src 0 mask 1 -> 0 update 0 rmode 0 mp 0
[capmt.cpp:SetMode:385] [33m (newmask == 0)[0m
[ca_ci] -> SendCAPMT
TP: B45603FB0001
SID: 283D
SOURCE: 0
CA_MASK: 0
CALEN: 0
Scrambled: 0
Mode: 0
Enabled: STOP
TuxTxt stopping subtitle thread ...
[dvb-sub] paused
CRemoteControl::handleMsg: EVT_TUNE_COMPLETE (b45603fb0001283d)
2021-06-11 21:52:10.292 [sectionsd] commandserviceChanged: Service change to b45603fb0001283d demux #0
[zapit] parsing pmt pid 0x13EC (Das Erste HD)
[subtitles] DVBSub: PID=0x13f1, lang=deu, cpageid=0001, apageid=0001
[zapit] channel found, audio pid 13f2, subtitle pid 0 mode 0 volume 75
[zapit.cpp:StartPlayBack:2310] standby 0 playing 0 forced 0
[zapit] vpid 13ED apid 13F2 pcr 13ED
[HAL:0062b690:audio ] SetStreamType 0 (0x0)
[capmt.cpp:SetMode:256] PLAY: fe_num 0 dmx_src 0
[capmt.cpp:SetMode:309] channel b45603fb0001283d [Das Erste HD] mode 0 START src 0 mask 0 -> 1 update 0 rmode 0 mp 0
[capmt.cpp:SetMode:337] [33m socket only[0m
[capmt.cpp:SetMode:483] [33m (oldmask != newmask) || force_update)[0m
[ca_ci] -> SendCAPMT
TP: B45603FB0001
SID: 283D
SOURCE: 0
CA_MASK: 1
CALEN: 65
Scrambled: 0
Mode: 0
Enabled: START
[ca_ci] -> FindFreeSlot
No free ci-slot
CaMap Empty
[pmt] set update filter, sid 0x283d pid 0x13ec version 2
11 Jun 21:53:42 ntpdate[2448]: step time server 131.188.3.223 offset +92.079687 sec
2021-06-11 21:53:42.696 [sectionsd] timeThread: Time set via NTP, going to sleep for 1800 seconds.
2021-06-11 21:53:42.696 [sectionsd] eitThread::run:: time set: Fri Jun 11 21:53:42 2021

2021-06-11 21:53:42.696 [sectionsd] cnThread::run:: time set: Fri Jun 11 21:53:42 2021

2021-06-11 21:53:42.696 [sectionsd] freeSatThread::run:: time set: Fri Jun 11 21:53:42 2021

2021-06-11 21:53:43.199 [sectionsd] addEvent: ch 03fb0001283d running 4 (curr) got_CN 0
[neutrino] CSectionsdClient::EVT_GOT_CN_EPG
2021-06-11 21:53:44.030 [sectionsd] addEvent: ch 03fb0001283d running 1 (next) got_CN 1
[neutrino] CSectionsdClient::EVT_GOT_CN_EPG
2021-06-11 21:53:45.569 [sectionsd] cnThread: eit update filter, ch 0x03fb0001283d, current ver 0x1a  got events 3 (not active)
[ConfigFile] saving /var/tuxbox/config/timerd.conf
[CA] Slot0: waiting timeout!
[CA] Slot0: start pollthread
[CA] Slot1: waiting timeout!
[CA] Slot1: start pollthread
1. cam (0) status changed ->cam now present
[CI SESS] receiveData slot: 0x5b7308 <- 
[CI SESS] -> use session_nb = 1
[CI SESS] Tag: 00010041 >
[CI SESS] RESOURCE MANAGER
[CI SESS] new session nb 1 0xad6014a8
[CI SESS] sendOpenSessionResponse
[ca_ci] -> transmitData len(9) -> 
CCAMMenuHandler::handleCamMsg: CA msg 0 from neutrino
CCAMMenuHandler::handleCamMsg: CAM im Slot 1
send: 9 len: 9
[ca_ci] -> transmitData len(8) -> 
send: 8 len: 8
[ca_ci] set input0 to ci0
[CI SESS] receiveData slot: 0x5b7308 <- 
[CI RM] SESSION(1)RES 9f 80 11 (len = 0): 
[CI RM] -> my cam can do: nothing
[ca_ci] -> transmitData len(8) -> 
send: 8 len: 8
[CI SESS] receiveData slot: 0x5b7308 <- 
[CI RM] SESSION(1)RES 9f 80 10 (len = 0): 
[CI RM] cam profile inquiry
[ca_ci] -> transmitData len(44) -> 
send: 44 len: 44
[CI SESS] receiveData slot: 0x5b7308 <- 
[CI SESS] -> use session_nb = 2
[CI SESS] Tag: 00020043 >
[CI SESS] APPLICATION MANAGER
[CI SESS] new session nb 2 0xad603a08
[CI SESS] sendOpenSessionResponse
[ca_ci] -> transmitData len(9) -> 
send: 9 len: 9
[ca_ci] -> transmitData len(8) -> 
send: 8 len: 8
[CI SESS] receiveData slot: 0x5b7308 <- 
[CI AM] SESSION(2)/APP 9f 80 21: 01 ca fe ba be 15 53 6b 79 20 4e 44 53 20 43 49 20 50 6c 75 73 20 4d 6f 64 75 6c 
[CI AM] application info:
[CI AM]   len: 27
[CI AM]   application_type: 1
[CI AM]   application_manufacturer: fe ca
[CI AM]   manufacturer_code: be ba
  menu string: Sky NDS CI Plus Modul
[CI AM] set cam name Sky NDS CI Plus Modul on slot(0)
[CI AM] in final state.
[CI SESS] receiveData slot: 0x5b7308 <- 
[CI SESS] -> use session_nb = 3
[CI SESS] Tag: 00240041 >
[CI SESS] DATE-TIME
[CI SESS] new session nb 3 0xad603878
[CI SESS] sendOpenSessionResponse
[ca_ci] -> transmitData len(9) -> 
send: 9 len: 9
[CI SESS] receiveData slot: 0x5b7308 <- 
[CI DT] SESSION(3)/DATETIME 9f 84 40: 00 
[CI DT] -> sendDateTime
[ca_ci] -> transmitData len(15) -> 
send: 15 len: 15
Jun 11 21:54:04 vuduo4kse cron.info crond[2202]: time disparity of 233093 minutes detected
[CI SESS] receiveData slot: 0x5b7308 <- 
[CI SESS] -> use session_nb = 4
[CI SESS] Tag: 008c1001 >
[CI SESS] CC MANAGER
[descrambler] -> descrambler_init 1
[CI SESS] new session nb 4 0xad603810
[CI SESS] sendOpenSessionResponse
[ca_ci] -> transmitData len(9) -> 
send: 9 len: 9
[dvbci_ccmgr] > doAction
[CI SESS] receiveData slot: 0x5b7308 <- 
SESSION(4)/CC 9f 90 01: 
[dvbci_ccmgr] -> ci_ccmgr_cc_open_cnf
[dvbci_ccmgr] -> data_initialize
[dvbci_ccmgr] -> get_authdata
[ca_ci] -> transmitData len(9) -> 
send: 9 len: 9
[CI SESS] receiveData slot: 0x5b7308 <- 
SESSION(4)/CC 9f 90 03: 
[dvbci_ccmgr] -> ci_ccmgr_cc_data_req
[dvbci_ccmgr] -> get_authdata
[ca_ci] -> transmitData len(45) -> 
send: 45 len: 45
[CI SESS] receiveData slot: 0x5b7308 <- 
SESSION(4)/CC 9f 90 03: 
[dvbci_ccmgr] -> ci_ccmgr_cc_data_req
[ca_ci] -> transmitData len(32) -> 
send: 32 len: 32
[CI SESS] receiveData slot: 0x5b7308 <- 
SESSION(4)/CC 9f 90 05: 
[dvbci_ccmgr] -> ci_ccmgr_cc_sync_req
[ca_ci] -> transmitData len(9) -> 
send: 9 len: 9
[CI SESS] receiveData slot: 0x5b7308 <- 
SESSION(4)/CC 9f 90 07: 
[dvbci_ccmgr] -> ci_ccmgr_cc_sac_data_req
[dvbci_ccmgr] -> ci_ccmgr_cc_sac_send (9F9008) 
[ca_ci] -> transmitData len(80) -> 
send: 80 len: 80
[CI SESS] receiveData slot: 0x5b7308 <- 
SESSION(4)/CC 9f 90 07: 
[dvbci_ccmgr] -> ci_ccmgr_cc_sac_data_req
[dvbci_ccmgr] -> ci_ccmgr_cc_sac_send (9F9008) 
[ca_ci] -> transmitData len(64) -> 
send: 64 len: 64
[CI SESS] receiveData slot: 0x5b7308 <- 
[CI SESS] -> use session_nb = 5
[CI SESS] Tag: 00030041 >
[CI SESS] CA MANAGER
[CI SESS] new session nb 5 0xad601920
[CI SESS] sendOpenSessionResponse
[ca_ci] -> transmitData len(9) -> 
send: 9 len: 9
[ca_ci] -> transmitData len(8) -> 
send: 8 len: 8
[CI SESS] receiveData slot: 0x5b7308 <- 
[CI DT] SESSION(3)/DATETIME 9f 84 40: 00 
[CI DT] -> sendDateTime
[ca_ci] -> transmitData len(15) -> 
send: 15 len: 15
[CI SESS] receiveData slot: 0x5b7308 <- 
SESSION(4)/CC 9f 90 09: 
[dvbci_ccmgr] -> ci_ccmgr_cc_sac_sync_req
[dvbci_ccmgr] -> ci_ccmgr_cc_sac_send (9F9010) 
[ca_ci] -> transmitData len(48) -> 
send: 48 len: 48
[CI SESS] receiveData slot: 0x5b7308 <- 
[CI SESS] recvCloseSessionRequest
[CI SESS] close Session Request
[CI SESS] handleClose
[ca_ci] -> transmitData len(5) -> 
send: 5 len: 5
[CI SESS] receiveData slot: 0x5b7308 <- 
[CI SESS] -> use session_nb = 3
[CI SESS] Tag: 00240041 >
[CI SESS] DATE-TIME
[CI SESS] new session nb 3 0xad601968
[CI SESS] sendOpenSessionResponse
[ca_ci] -> transmitData len(9) -> 
send: 9 len: 9
[CI SESS] receiveData slot: 0x5b7308 <- 
[CI CA] SESSION(5)/CA 9f 80 31: 09 8c 
[CI CA] ca info:
098c 
Anzahl Caids: 1 Slot: 0 > 098c 
CCAMMenuHandler::handleCamMsg: CA msg 2 from neutrino
CCAMMenuHandler::handleCamMsg: CAM-Init beendet 1: Sky NDS CI Plus Modul
[capmt.cpp:SetMode:256] PLAY: fe_num 0 dmx_src 0
[capmt.cpp:SetMode:309] channel b45603fb0001283d [Das Erste HD] mode 0 START src 0 mask 1 -> 1 update 1 rmode 0 mp 0
[capmt.cpp:SetMode:337] [33m socket only[0m
[capmt.cpp:SetMode:360] [33m (oldmask == newmask)[0m
[capmt.cpp:SetMode:483] [33m (oldmask != newmask) || force_update)[0m
[ca_ci] -> SendCAPMT
TP: B45603FB0001
SID: 283D
SOURCE: 0
CA_MASK: 1
CALEN: 65
Scrambled: 0
Mode: 0
Enabled: START
[ca_ci] -> FindFreeSlot
[ca_ci] set input0 to tuner0
No free ci-slot
CaMap Empty
[CI SESS] receiveData slot: 0x5b7308 <- 
[CI DT] SESSION(3)/DATETIME 9f 84 40: 00 
[CI DT] -> sendDateTime
[ca_ci] -> transmitData len(15) -> 
send: 15 len: 15
key: 0160 value 1, translate: 0160 -ok-
CNeutrinoApp::showChannelList: bouquetList 0x783208 size 13 old_b 0
CChannelList::paint() after paint head: 2 msec
CChannelList::paint() after paint body: 39 msec
ShowText 'Das Erste HD - Tagesthemen'
CChannelList::paint() paint total: 99 msec
key: 0160 value 0, translate: 0160 -ok-
key: 006c value 1, translate: 006c -cursor down-
ShowText 'ZDF HD'
key: 006c value 0, translate: 006c -cursor down-
key: 0160 value 1, translate: 0160 -ok-
**************************** CChannelList::zapToChannel me 0x8076b8 Alle Kanäle tuned 1280 new ZDF HD -> b24603f300012b66
StopAutoRecord: autoshift 0
2021-06-11 21:54:39.582 [sectionsd] commandserviceStopped
stopPlayBack: stopping...
stopPlayBack: stopped
CChannelList::adjustToChannelID me 0x8076b8 [Alle Kanäle] list size 4076 channel_id b24603f300012b66
CNeutrinoApp::showChannelList: nNewChannel 1
ShowText 'Das Erste HD'
[zapit.cpp:ZapIt:518] [zapit] zap to ZDF HD (b24603f300012b66 tp 2c6100c203f30001)
[zapit] saving channel, apid 13f2 sub pid 0 mode 0 volume 75
[zapit] GetConfig...
[femanager.cpp:allocateFE:751] pip demux: 3
[zapit.cpp:StopPlayBack:2394] standby 0 playing 1 forced 0 send_pmt 1
[capmt.cpp:SetMode:256] PLAY: fe_num 0 dmx_src 0
[capmt.cpp:SetMode:309] channel b45603fb0001283d [Das Erste HD] mode 0 STOP src 0 mask 1 -> 0 update 0 rmode 0 mp 0
[capmt.cpp:SetMode:385] [33m (newmask == 0)[0m
[ca_ci] -> SendCAPMT
TP: B45603FB0001
SID: 283D
SOURCE: 0
CA_MASK: 0
CALEN: 0
Scrambled: 0
Mode: 0
Enabled: STOP
2021-06-11 21:54:39.607 [sectionsd] cnThread: stop eit update filter (active)
2021-06-11 21:54:39.608 [sectionsd] commandserviceStopped done
TuxTxt stopping subtitle thread ...
[dvb-sub] paused
[fe0/0] tune to 11361000 DVB-S2 8PSK 2/3 H/L srate 22000000 pli -1 plc 0 plm 1 (tuner 1611000 offset 9750000 timeout 4000)
[frontend.cpp:setFrontend:1540] [fe0/0] tuner pilot 2 (feparams 2) streamid (-1/0/1)

[ca_ci] -> GetNumberCISlots
CI: CAM found in Slot 0
CI: CAM not found
[frontend.cpp:getEvent:804] [fe0/0] ******** FE_HAS_LOCK: freq 1610784
[fe0/0] tuning took: 119 msec (min 119 max 255)
[zapit] parsing pmt pid 0x17D4 (ZDF HD)
[subtitles] DVBSub: PID=0x17f3, lang=deu, cpageid=0001, apageid=0001
[zapit] channel found, audio pid 17ea, subtitle pid 0 mode 0 volume 75
[zapit.cpp:StartPlayBack:2310] standby 0 playing 0 forced 0
[zapit] vpid 17DE apid 17EA pcr 17DE
[HAL:0062b690:audio ] SetStreamType 0 (0x0)
[capmt.cpp:SetMode:256] PLAY: fe_num 0 dmx_src 0
[capmt.cpp:SetMode:309] channel b24603f300012b66 [ZDF HD] mode 0 START src 0 mask 0 -> 1 update 0 rmode 0 mp 0
[capmt.cpp:SetMode:337] [33m socket only[0m
[capmt.cpp:SetMode:483] [33m (oldmask != newmask) || force_update)[0m
[ca_ci] -> SendCAPMT
TP: B24603F30001
SID: 2B66
SOURCE: 0
CA_MASK: 1
CALEN: 55
Scrambled: 0
Mode: 0
Enabled: START
[ca_ci] -> FindFreeSlot
[ca_ci] set input0 to tuner0
No free ci-slot
CaMap Empty
[pmt] set update filter, sid 0x2b66 pid 0x17d4 version 0
CRemoteControl::handleMsg: EVT_TUNE_COMPLETE (b24603f300012b66)
2021-06-11 21:54:39.910 [sectionsd] commandserviceChanged: Service change to b24603f300012b66 demux #0
2021-06-11 21:54:39.912 [sectionsd] cnThread: stop eit update filter (not active)
key: 0160 value 0, translate: 0160 -ok-
2021-06-11 21:54:40.739 [sectionsd] addEvent: ch 03f300012b66 running 1 (next) got_CN 0
[neutrino] CSectionsdClient::EVT_GOT_CN_EPG
2021-06-11 21:54:40.782 [sectionsd] addEvent: ch 03f300012b66 running 4 (curr) got_CN 2
[neutrino] CSectionsdClient::EVT_GOT_CN_EPG
ShowText 'ZDF HD'
2021-06-11 21:54:42.167 [sectionsd] cnThread: eit update filter, ch 0x03f300012b66, current ver 0x11  got events 3 (not active)
11 Jun 21:54:46 ntpdate[2571]: adjust time server 131.188.3.223 offset +0.000522 sec
2021-06-11 21:54:46.699 [sectionsd] timeThread: Time set via NTP, going to sleep for 1800 seconds.
key: 0160 value 1, translate: 0160 -ok-
CNeutrinoApp::showChannelList: bouquetList 0x783208 size 13 old_b 0
CChannelList::paint() after paint head: 15 msec
CChannelList::paint() after paint body: 30 msec
ShowText 'ZDF HD - SOKO Leipzig'
CChannelList::paint() paint total: 85 msec
key: 0160 value 0, translate: 0160 -ok-
key: 006a value 1, translate: 006a -cursor right-
CChannelList::paint() after paint head: 4 msec
CChannelList::paint() after paint body: 39 msec
ShowText 'Sky One HD'
CChannelList::paint() paint total: 95 msec
key: 006a value 0, translate: 006a -cursor right-
key: 0069 value 1, translate: 0069 -cursor left-
CChannelList::paint() after paint head: 4 msec
CChannelList::paint() after paint body: 17 msec
ShowText 'ZDF HD - SOKO Leipzig'
CChannelList::paint() paint total: 73 msec
key: 0069 value 0, translate: 0069 -cursor left-
key: 006c value 1, translate: 006c -cursor down-
ShowText 'RTL HD'
key: 006c value 0, translate: 006c -cursor down-
key: 0160 value 1, translate: 0160 -ok-
**************************** CChannelList::zapToChannel me 0x8076b8 Alle Kanäle tuned 1126 new RTL HD -> aa0204210001ef10
StopAutoRecord: autoshift 0
2021-06-11 21:54:52.965 [sectionsd] commandserviceStopped
stopPlayBack: stopping...
stopPlayBack: stopped
CChannelList::adjustToChannelID me 0x8076b8 [Alle Kanäle] list size 4076 channel_id aa0204210001ef10
2021-06-11 21:54:52.971 [sectionsd] cnThread: stop eit update filter (active)
2021-06-11 21:54:52.971 [sectionsd] commandserviceStopped done
[zapit.cpp:ZapIt:518] [zapit] zap to RTL HD (aa0204210001ef10 tp 2a5000c204210001)
[zapit] saving channel, apid 17ea sub pid 0 mode 0 volume 75
[zapit] GetConfig...
[femanager.cpp:allocateFE:751] pip demux: 3
[zapit.cpp:StopPlayBack:2394] standby 0 playing 1 forced 0 send_pmt 1
[capmt.cpp:SetMode:256] PLAY: fe_num 0 dmx_src 0
[capmt.cpp:SetMode:309] channel b24603f300012b66 [ZDF HD] mode 0 STOP src 0 mask 1 -> 0 update 0 rmode 0 mp 0
[capmt.cpp:SetMode:385] [33m (newmask == 0)[0m
[ca_ci] -> SendCAPMT
TP: B24603F30001
SID: 2B66
SOURCE: 0
CA_MASK: 0
CALEN: 0
Scrambled: 0
Mode: 0
Enabled: STOP
TuxTxt stopping subtitle thread ...
[dvb-sub] paused
[fe0/0] tune to 10832000 DVB-S2 8PSK 2/3 H/L srate 22000000 pli -1 plc 0 plm 1 (tuner 1082000 offset 9750000 timeout 4000)
[frontend.cpp:setFrontend:1540] [fe0/0] tuner pilot 2 (feparams 2) streamid (-1/0/1)

[fe0/0] FE_HAS_SIGNAL
[fe0/0] FE_HAS_CARRIER
[fe0/0] FE_HAS_VITERBI
CNeutrinoApp::showChannelList: nNewChannel -2
ShowText 'ZDF HD'
[ca_ci] -> GetNumberCISlots
CI: CAM found in Slot 0
CI: CAM not found
[frontend.cpp:getEvent:804] [fe0/0] ******** FE_HAS_LOCK: freq 1081269
[fe0/0] tuning took: 155 msec (min 119 max 255)
CRemoteControl::handleMsg: EVT_TUNE_COMPLETE (aa0204210001ef10)
2021-06-11 21:54:53.397 [sectionsd] commandserviceChanged: Service change to aa0204210001ef10 demux #0
2021-06-11 21:54:53.398 [sectionsd] cnThread: stop eit update filter (not active)
key: 0160 value 0, translate: 0160 -ok-
[zapit] parsing pmt pid 0x60 (RTL HD)
2021-06-11 21:54:53.537 [sectionsd] addEvent: ch 04210001ef10 running 4 (curr) got_CN 0
[neutrino] CSectionsdClient::EVT_GOT_CN_EPG
[subtitles] DVBSub: PID=0x0030, lang=deu, cpageid=0001, apageid=0001
[zapit] channel found, audio pid 103, subtitle pid ffffffff mode 0 volume 75
[zapit.cpp:StartPlayBack:2310] standby 0 playing 0 forced 0
[zapit] vpid FF apid 103 pcr FF
[HAL:adc005b8:demux ] Stop #0: not open!
[HAL:adc005b8:demux ] Start #0: not open!
[HAL:adc005b8:demux ] Read #0: not open!
[HAL:adc005b8:demux ] Read #0: not open!
[HAL:adc005b8:demux ] Read #0: not open!
[HAL:0062b690:audio ] SetStreamType 0 (0x0)
Diesmal ist die Meldungsflut nicht direkt nach dem Hochlauf entstanden, sondern erst als ich auf einen Sender wechselte.
Der Hochlauf wurde kurz unterbrochen, damit ich die Partition 4 manuell starten konnte.

Ich hoffe das hilft weiter.
Life is never fair. But the root password helps in any case......
Vu+ duo 4k se mit CI+ Modul und HD+ Smartcard, BP rev19250-ddt
Dm800seV2,
Zee²,
Neo²
Benutzeravatar
Janus
NI - VIP
Beiträge: 1136
Registriert: Di 12. Apr 2016, 19:41
Box: HD1, Zee, Neo, Tank, HD51, Duo4K

Re: Debug Meldungen machen die vuduo4kse unendlich langsam

Beitrag von Janus »

Ich hoffe nicht, dass das "ansteckend" ist.

Heute habe ich das mit einem Selbstbau vom 03.06. überhaupt zum ersten Mal gemerkt.
Nach dem Start aus dem Standby (ca. 2 Stunden war ich weg) war das NI - wie oben beschreiben - über einen längeren Zeitraum nicht bedienbar. Mit Kenntnis dieses Verhaltens natürlich doch. Man muss natürlich ewig warten, bis man ein Ergebnis sieht und darf dann erst den nächsten FB-Code absenden.

Ein dabei villeicht interessantes Verhalten kam beim esten Einschalten über die Lautsprecher:
Ein überlautes, schmerzerzeugendes Krächzen, dass sich auch per Box-Steuerung nicht abstellen ließ. ("Lautsprecher aus" ging natürlich)
Nach Kaltstart der Box über den Netzschalter war der Ton wieder in Ordnug, aber die Anfangs-Probleme mit der Bedienbarkeit blieben weiterhin.

So nach ca. 10 Minuten ist im Moment Alles wieder im gewohnten Zeitrahmen: Taste drücken > Box reagiert sofort.
Benutzeravatar
Gorcon
NI - VIP
Beiträge: 2699
Registriert: Mi 13. Apr 2016, 10:55
Box: E2HD, VU+ Uno4kSE, VU+ Ultimate4k
Has thanked: 1 time

Re: Debug Meldungen machen die vuduo4kse unendlich langsam

Beitrag von Gorcon »

Sowas kenne ich beim TV. Aber der hat eine Watchdog der den TV dann nach ein paar sekunden neustarten lässt.
Benutzeravatar
FlatTV
Developer
Beiträge: 109
Registriert: Fr 8. Apr 2016, 23:10

Re: Debug Meldungen machen die vuduo4kse unendlich langsam

Beitrag von FlatTV »

Ich habe heute von @muffpotter mehr Infos bekommen, nur kommt es bei mir nicht zu dem Verhalten.
Ich bin da noch etwas ratlos, da meine Box sich anders verhält.
Benutzeravatar
muffpotter
Beiträge: 3
Registriert: Mo 11. Sep 2017, 22:12
Box: VU+ DUO 4K SE

Re: Debug Meldungen machen die vuduo4kse unendlich langsam

Beitrag von muffpotter »

Welcher Prozess könnte denn für die Endlos-Read Schleife [ [HAL-Info:ae0005b8:demux ] Read #0: not open! ] verantwortlich sein?


Der Read wird dermaßen oft ausgeführt, dass neutrino 100% Last auf einem der 4 Kerne verursacht bzw. dropbear und neutrino teilen sich in etwa die Last, wenn die Console auf Putty über LAN umgeleitet wurde.
Und müsste der Prozess nicht vorher prüfen ob der Kanal #0 offen ist?
Life is never fair. But the root password helps in any case......
Vu+ duo 4k se mit CI+ Modul und HD+ Smartcard, BP rev19250-ddt
Dm800seV2,
Zee²,
Neo²
Benutzeravatar
Janus
NI - VIP
Beiträge: 1136
Registriert: Di 12. Apr 2016, 19:41
Box: HD1, Zee, Neo, Tank, HD51, Duo4K

Re: Debug Meldungen machen die vuduo4kse unendlich langsam

Beitrag von Janus »

vuDuo4K - Mal die üblichen Verdächtigen abgeklopft:
( "=>" bedeutet Power OFF Power ON)

-- Ich habe die Speicherung der EPG-Daten abgestellt => keine Besserung
-- ich habe nur noch die lokalen Settings im RootFS verwendet => keine Besserung

++ Ich habe /etc/ci_auth_slot_0.bin gelöscht, da eh kein CAM in der Box ist => Seitdem (24 h) kein "Warten" mehr...

Abklopfen erstmal abgebrochen.
Vielleicht hilft es Jemandem - auch ohne Log - weiter.
satbaby
NI - VIP
Beiträge: 540
Registriert: Di 12. Apr 2016, 13:02

Re: Debug Meldungen machen die vuduo4kse unendlich langsam

Beitrag von satbaby »

Normal kommt in log das:
[zapit] vpid FF apid 103 pcr FF
[HAL: 005686d8:audio ] SetStreamType 0 (0x0)
[capmt.cpp:SetMode:257] PLAY: fe_num 0 dmx_src 0
[capmt.cpp:SetMode:310] channel aa0004210001ef10 [RTL HD] mode 0 START src 0 mask 0 -> 1 update 0 rmode 0 mp 0
[capmt.cpp:SetMode:338] socket only
[capmt.cpp:SetMode:473] CI: use CI for [RTL HD]
[capmt.cpp:SetMode:484] (oldmask != newmask) || force_update)
[ca_ci] -> SendCAP

Vielleicht hängt mit ca_cam Änderungen in https://github.com/neutrino-images/ni-l ... 39e5c34f14

Ich habe hier kein CI und keine probleme.

gruß
Benutzeravatar
BPanther
NI - VIP
Beiträge: 736
Registriert: So 29. Sep 2019, 18:37
Kontaktdaten:

Re: Debug Meldungen machen die vuduo4kse unendlich langsam

Beitrag von BPanther »

Ich habe bei meinen NI Tests nie ein CI Modul benutzt und dennoch die Probleme mit der FB.
Bild
Benutzeravatar
vanhofen
Administrator
Beiträge: 2908
Registriert: Di 5. Apr 2016, 00:05
Has thanked: 1 time
Been thanked: 3 times

Re: Debug Meldungen machen die vuduo4kse unendlich langsam

Beitrag von vanhofen »

Das kommt vom GraphLCD-Thread.
Benutzeravatar
BPanther
NI - VIP
Beiträge: 736
Registriert: So 29. Sep 2019, 18:37
Kontaktdaten:

Re: Debug Meldungen machen die vuduo4kse unendlich langsam

Beitrag von BPanther »

Hmm, durchaus möglich. Da es hier im DDT problemlos läuft - allerdings ist im NI mehr "Klimbim" im Gegensatz zum DDT verbaut (nicht abwertend gemeint!) - wäre ich darauf nicht gekommen.
Bild
Antworten

Zurück zu „Allgemein“