openHDF Enigma2 crash log crashdate=Mon Jan 8 08:16:52 2018 compiledate=Jan 2 2018 skin=XionHDF/skin.xml sourcedate=2018-01-02 branch=master rev=493bb8d component=enigma2, signal stbmodel=ultimo stbmodel=dm900 kernelcmdline=bmem=640M@384M bmem=384M@2048M console=ttyS0,1000000 root=/dev/mmcblk0p2 rootwait rootfstype=ext4 coherent_pool=2M nimsockets=NIM Socket 0: imageversion=box_type=dm900 imageissue=Welcome to OpenEmbedded Linux OE-Alliance for %h 8:13:31.9282 { } Plugins/Extensions/Volume_adjust/plugin.py:447 loadXML [Volume Adjust] load xml... 08:13:31.9323 [ ] dvb_ci/dvbci.cpp:937 gotPMT [eDVBCIInterfaces] gotPMT 08:13:31.9323 [ ] dvb/cahandler.cpp:624 buildCAPMT [eDVBCAService] don't build/send the same CA PMT twice 08:13:31.9325 [ ] dvb/demux.cpp:90 openDemux [eDVBDemux] open demux /dev/dvb/adapter0/demux0 08:13:31.9326 [ ] dvb/demux.cpp:286 start [eDVBSectionReader] DMX_SET_FILTER pid=1106 08:13:31.9455 [ ] dvb/demux.cpp:90 openDemux [eDVBDemux] open demux /dev/dvb/adapter0/demux0 08:13:31.9456 [ ] dvb/demux.cpp:286 start [eDVBSectionReader] DMX_SET_FILTER pid=7001 08:13:32.0836 { } Plugins/Extensions/Volume_adjust/plugin.py:469 __evUpdatedInfo [Volume Adjust] Update Info 08:13:32.0838 { } Plugins/Extensions/Volume_adjust/plugin.py:473 __evUpdatedInfo [Volume Adjust] Volume = 100 08:13:32.0838 { } Plugins/Extensions/Volume_adjust/plugin.py:447 loadXML [Volume Adjust] load xml... 08:13:32.1898 [ ] dvb/dvbtime.cpp:469 updateTime [eDVBLocalTimerHandler] Transponder time is 08.01.2018 08:13:09 08:13:32.1898 [ ] dvb/dvbtime.cpp:482 updateTime [eDVBLocalTimerHandler] diff is -23 08:13:32.1899 [ ] dvb/dvbtime.cpp:485 updateTime [eDVBLocalTimerHandler] diff < 120 .. use Transponder Time 08:13:32.1899 [ ] dvb/dvbtime.cpp:49 setRTC [eDVBLocalTimerHandler] set RTC Time 08:13:32.1900 [ ] dvb/dvbtime.cpp:555 updateTime [eDVBLocalTimerHandler] update RTC 08:13:32.1901 [ ] dvb/dvbtime.cpp:575 updateTime [eDVBLocalTimerHandler] time update to 08:13:09 08:13:32.1901 [ ] dvb/dvbtime.cpp:578 updateTime [eDVBLocalTimerHandler] m_time_difference is -23 08:13:09.1901 [ ] dvb/dvbtime.cpp:604 updateTime [eDVBLocalTimerHandler] stepped Linux Time to 08:13:09 08:13:09.1904 [ ] dvb/dvb.cpp:2415 getDemux [eDVBChannel] getDemux cap=00 08:13:32.2076 [ ] dvb/frontend.cpp:2260 tuneLoopInt [SEC] set dynamic current limiting 08:13:32.3743 { } Plugins/Extensions/Volume_adjust/plugin.py:469 __evUpdatedInfo [Volume Adjust] Update Info 08:13:32.3744 { } Plugins/Extensions/Volume_adjust/plugin.py:473 __evUpdatedInfo [Volume Adjust] Volume = 100 08:13:32.3744 { } Plugins/Extensions/Volume_adjust/plugin.py:447 loadXML [Volume Adjust] load xml... 08:13:32.6937 [ ] dvb/pmt.cpp:950 SDTScanEvent [eDVBServicePMTHandler] sdt update done! 08:13:32.7740 < > dvb/decoder.cpp:543 video_event [eDVBVideo0] VIDEO_GET_EVENT SIZE_CHANGED 720x576 aspect 3 08:13:32.7778 < > dvb/decoder.cpp:543 video_event [eDVBVideo0] VIDEO_GET_EVENT unhandled DVBAPI Video Event 32 08:13:32.7783 [ ] service/servicedvb.cpp:1728 timeshift [eDVBServicePlay] timeshift 08:13:32.8751 { } Plugins/Extensions/Volume_adjust/plugin.py:469 __evUpdatedInfo [Volume Adjust] Update Info 08:13:32.8753 { } Plugins/Extensions/Volume_adjust/plugin.py:473 __evUpdatedInfo [Volume Adjust] Volume = 100 08:13:32.8753 { } Plugins/Extensions/Volume_adjust/plugin.py:447 loadXML [Volume Adjust] load xml... 08:13:33.1791 { } Components/AVSwitch.py:328 setPolicy43 [VideoMode] setting policy: panscan 08:13:33.1795 { } Components/AVSwitch.py:345 setPolicy169 [VideoMode] setting policy2: scale 08:13:33.5860 { } Plugins/Extensions/Volume_adjust/plugin.py:469 __evUpdatedInfo [Volume Adjust] Update Info 08:13:33.5861 { } Plugins/Extensions/Volume_adjust/plugin.py:473 __evUpdatedInfo [Volume Adjust] Volume = 100 08:13:33.5862 { } Plugins/Extensions/Volume_adjust/plugin.py:447 loadXML [Volume Adjust] load xml... 08:13:33.8856 { } Plugins/Extensions/Volume_adjust/plugin.py:469 __evUpdatedInfo [Volume Adjust] Update Info 08:13:33.8858 { } Plugins/Extensions/Volume_adjust/plugin.py:473 __evUpdatedInfo [Volume Adjust] Volume = 100 08:13:33.8858 { } Plugins/Extensions/Volume_adjust/plugin.py:447 loadXML [Volume Adjust] load xml... 08:13:35.5936 [ ] dvb/demux.cpp:90 openDemux [eDVBDemux] open demux /dev/dvb/adapter0/demux0 08:13:35.5937 [ ] dvb/demux.cpp:286 start [eDVBSectionReader] DMX_SET_FILTER pid=18 08:13:36.5104 [ ] dvb/pmt.cpp:293 AITready [eDVBServicePMTHandler] AITready 08:13:36.5105 [ ] dvb/pmt.cpp:312 AITready [eDVBServicePMTHandler] Section Length : 524, Total Section Length : 527 08:13:36.5106 [ ] dvb/pmt.cpp:323 AITready [eDVBServicePMTHandler] found applicaions ids >> pid : 0, orgid : 1, appid : 9 08:13:36.5106 [ ] dvb/pmt.cpp:323 AITready [eDVBServicePMTHandler] found applicaions ids >> pid : 0, orgid : 1, appid : 12 08:13:36.5106 [ ] dvb/pmt.cpp:323 AITready [eDVBServicePMTHandler] found applicaions ids >> pid : 0, orgid : 1, appid : 3 08:13:36.5106 [ ] dvb/pmt.cpp:323 AITready [eDVBServicePMTHandler] found applicaions ids >> pid : 0, orgid : 1, appid : 1 08:13:36.5108 [ ] dvb/pmt.cpp:287 saveData [eDVBServicePMTHandler] Save Data Len : [527] 08:13:36.5109 [ ] dvb/demux.cpp:90 openDemux [eDVBDemux] open demux /dev/dvb/adapter0/demux0 08:13:36.5110 [ ] dvb/demux.cpp:286 start [eDVBSectionReader] DMX_SET_FILTER pid=3010 08:13:36.5112 [ ] dvb/pmt.cpp:460 AITready [eDVBServicePMTHandler] No found anything. 08:13:41.5510 [ ] dvb/pmt.cpp:468 OCready [eDVBServicePMTHandler] OCready 08:16:50.8542 [ ] driver/rcinput.cpp:38 handleCode 1 192 1 08:16:50.8631 [ ] service/servicedvb.cpp:1728 timeshift [eDVBServicePlay] timeshift 08:16:50.8635 { } Navigation.py:247 playService playing 1:0:1:2144:3070:13E:820000:0:0:0: 08:16:50.8638 [ ] dvb/cahandler.cpp:333 unregisterService [eDVBCAService] free slot 0 demux 0 for service 1:0:1:2141:3070:13E:820000:0:0:0: 08:16:50.8639 [ ] dvb/cahandler.cpp:497 ~eDVBCAService [eDVBCAService] free service 1:0:1:2141:3070:13E:820000:0:0:0: 08:16:50.8674 [ ] service/servicedvb.cpp:1728 timeshift [eDVBServicePlay] timeshift 08:16:50.8677 [ ] service/servicedvb.cpp:1728 timeshift [eDVBServicePlay] timeshift 08:16:50.8685 [ ] dvb/decoder.cpp:836 setState [eTSMPEGDecoder] decoder state: play, vpid=ffffffff, apid=ffffffff 08:16:50.8686 < > dvb/decoder.cpp:728 stop [eDVBPCR0] DEMUX_STOP ok 08:16:50.8687 [ ] dvb/decoder.cpp:739 ~eDVBPCR [eDVBPCR0] destroy 08:16:50.8687 < > dvb/decoder.cpp:427 stop [eDVBVideo0] DEMUX_STOP ok 08:16:50.8695 < > dvb/decoder.cpp:436 stop [eDVBVideo0] VIDEO_STOP ok 08:16:50.8697 [ ] dvb/decoder.cpp:528 ~eDVBVideo [eDVBVideo0] destroy 08:16:50.8697 < > dvb/decoder.cpp:159 stop [eDVBAudio0] AUDIO_STOP ok 08:16:50.8902 < > dvb/decoder.cpp:167 stop [eDVBAudio0] DEMUX_STOP ok 08:16:50.8903 < > dvb/decoder.cpp:203 unfreeze [eDVBAudio0] AUDIO_CONTINUE ok 08:16:50.8904 [ ] dvb/decoder.cpp:247 ~eDVBAudio [eDVBAudio0] destroy 08:16:50.8904 < > dvb/decoder.cpp:806 stop [eDVBText0] DEMUX_STOP ok 08:16:50.8909 [ ] dvb/decoder.cpp:817 ~eDVBTText [eDVBText0] destroy 08:16:50.8942 [ ] dvb/dvb.cpp:1495 DVBChannelStateChanged [eDVBResourceManager] start release channel timer 08:16:50.9001 [ ] service/servicedvb.cpp:1728 timeshift [eDVBServicePlay] timeshift 08:16:50.9038 [ ] service/servicedvb.cpp:1728 timeshift [eDVBServicePlay] timeshift 08:16:50.9041 [ ] service/servicedvb.cpp:1728 timeshift [eDVBServicePlay] timeshift 08:16:50.9046 [ ] service/servicedvb.cpp:1728 timeshift [eDVBServicePlay] timeshift 08:16:50.9050 [ ] service/servicedvb.cpp:1728 timeshift [eDVBServicePlay] timeshift 08:16:50.9053 [ ] service/servicedvb.cpp:1728 timeshift [eDVBServicePlay] timeshift 08:16:50.9058 { } Screens/InfoBarGenerics.py:5592 __serviceStarted new service started! trying to download cuts! 08:16:50.9060 [ ] dvb/dvb.cpp:1489 DVBChannelStateChanged [eDVBResourceManager] stop release channel timer 08:16:50.9061 [ ] dvb/dvb.cpp:1495 DVBChannelStateChanged [eDVBResourceManager] start release channel timer 08:16:50.9061 [ ] dvb/dvb.cpp:1396 allocateChannel [eDVBResourceManager] use cached_channel, frontend=0 08:16:50.9061 [ ] dvb/dvb.cpp:1489 DVBChannelStateChanged [eDVBResourceManager] stop release channel timer 08:16:50.9062 [ ] dvb/pmt.cpp:1000 tuneExt [eDVBServicePMTHandler] allocate Channel: res 0 08:16:50.9062 [ ] dvb_ci/dvbci.cpp:850 addPMTHandler [eDVBCIInterfaces] addPMTHandler 1:0:1:2144:3070:13E:820000:0:0:0: 08:16:50.9062 [ ] dvb/dvb.cpp:2415 getDemux [eDVBChannel] getDemux cap=01 08:16:50.9063 [ ] dvb/pmt.cpp:77 channelStateChanged [eDVBServicePMTHandler] ok ... now we start!! 08:16:50.9063 [ ] service/servicedvb.cpp:1203 serviceEvent [eDVBServicePlay] eventNewProgramInfo timeshift_enabled=0 timeshift_active=0 08:16:50.9063 < > service/servicedvb.cpp:2872 updateDecoder [eDVBServicePlay] have 1 video stream(s) (0208), and 1 audio stream(s) (02b2), and the pcr pid is 0208, and the text pid is 0257 08:16:50.9064 [ ] dvb/dvb.cpp:2415 getDemux [eDVBChannel] getDemux cap=01 08:16:50.9078 [ ] service/servicedvb.cpp:3556 setAC3Delay [eDVBServicePlay] Setting audio delay: setAC3Delay, 0 + 0 08:16:50.9080 [ ] service/servicedvb.cpp:3567 setPCMDelay [eDVBServicePlay] Setting audio delay: setPCMDelay, 0 + 0 08:16:50.9080 [ ] dvb/decoder.cpp:836 setState [eTSMPEGDecoder] decoder state: play, vpid=0208, apid=02b2 08:16:50.9081 < > dvb/decoder.cpp:707 startPid [eDVBPCR0] DMX_SET_PES_FILTER pid=0x0208 ok 08:16:50.9082 < > dvb/decoder.cpp:715 startPid [eDVBPCR0] DEMUX_START ok 08:16:50.9083 < > dvb/decoder.cpp:84 startPid [eDVBAudio0] DMX_SET_PES_FILTER pid=0x02b2 ok 08:16:50.9084 < > dvb/decoder.cpp:92 startPid [eDVBAudio0] DEMUX_START ok 08:16:50.9084 < > dvb/decoder.cpp:138 startPid [eDVBAudio0] AUDIO_SET_BYPASS bypass=1 ok 08:16:50.9089 < > dvb/decoder.cpp:191 freeze [eDVBAudio0] AUDIO_PAUSE ok 08:16:50.9090 < > dvb/decoder.cpp:146 startPid [eDVBAudio0] AUDIO_PLAY ok 08:16:50.9095 [ ] dvb/decoder.cpp:265 eDVBVideo [eDVBVideo] Video Device: /dev/dvb/adapter0/video0 08:16:50.9096 [ ] dvb/decoder.cpp:276 eDVBVideo [eDVBVideo] demux device: /dev/dvb/adapter0/demux0 08:16:50.9097 < > dvb/decoder.cpp:356 startPid [eDVBVideo0] VIDEO_SET_STREAMTYPE 0 - ok 08:16:50.9097 < > dvb/decoder.cpp:391 startPid [eDVBVideo0] DMX_SET_PES_FILTER pid=0x0208 ok 08:16:50.9100 < > dvb/decoder.cpp:399 startPid [eDVBVideo0] DEMUX_START ok 08:16:50.9101 < > dvb/decoder.cpp:460 freeze [eDVBVideo0] VIDEO_FREEZE ok 08:16:50.9101 < > dvb/decoder.cpp:414 startPid [eDVBVideo0] VIDEO_PLAY ok 08:16:50.9152 < > dvb/decoder.cpp:785 startPid [eDVBText0] DMX_SET_PES_FILTER pid=0x0257 ok 08:16:50.9153 < > dvb/decoder.cpp:793 startPid [eDVBText0] DEMUX_START ok 08:16:50.9168 < > dvb/decoder.cpp:484 setSlowMotion [eDVBVideo0] VIDEO_SLOWMOTION 0 ok 08:16:50.9169 < > dvb/decoder.cpp:499 setFastForward [eDVBVideo0] VIDEO_FAST_FORWARD 0 ok 08:16:50.9170 < > dvb/decoder.cpp:472 unfreeze [eDVBVideo0] VIDEO_CONTINUE ok 08:16:50.9170 < > dvb/decoder.cpp:203 unfreeze [eDVBAudio0] AUDIO_CONTINUE ok 08:16:50.9171 [ ] dvb/teletext.cpp:222 eDVBTeletextParser [eDVBTeletextParser] Starting! 08:16:50.9171 [ ] dvb/teletext.cpp:645 setPageAndMagazine [eDVBTeletextParser] disable teletext subtitles page ffffffffffffffff (und) 08:16:50.9171 [!W!] dvb/demux.cpp:361 eDVBPESReader [eDVBPESReader] Created. Opening demux 08:16:50.9171 [ ] dvb/demux.cpp:90 openDemux [eDVBDemux] open demux /dev/dvb/adapter0/demux0 08:16:50.9172 [ ] dvb/teletext.cpp:230 eDVBTeletextParser [eDVBTeletextParser] created teletext subtitle PES reader! 08:16:50.9173 [!W!] dvb/demux.cpp:361 eDVBPESReader [eDVBPESReader] Created. Opening demux 08:16:50.9173 [ ] dvb/demux.cpp:90 openDemux [eDVBDemux] open demux /dev/dvb/adapter0/demux0 08:16:50.9174 [ ] dvb/teletext.cpp:463 start [eDVBTeletextParser] starting PES reader on pid=0257 08:16:50.9174 [ ] dvb/demux.cpp:399 start [eDVBPESReader] DMX_SET_PES_FILTER pid=0257 08:16:50.9179 [ ] service/servicedvb.cpp:1728 timeshift [eDVBServicePlay] timeshift 08:16:50.9182 [ ] service/servicedvb.cpp:1728 timeshift [eDVBServicePlay] timeshift 08:16:50.9184 [ ] service/servicedvb.cpp:1728 timeshift [eDVBServicePlay] timeshift 08:16:50.9189 [ ] service/servicedvb.cpp:1728 timeshift [eDVBServicePlay] timeshift 08:16:50.9192 [ ] service/servicedvb.cpp:1728 timeshift [eDVBServicePlay] timeshift 08:16:50.9239 { } Plugins/Extensions/Volume_adjust/plugin.py:469 __evUpdatedInfo [Volume Adjust] Update Info 08:16:50.9240 { } Plugins/Extensions/Volume_adjust/plugin.py:473 __evUpdatedInfo [Volume Adjust] Volume = 100 08:16:50.9241 { } Plugins/Extensions/Volume_adjust/plugin.py:447 loadXML [Volume Adjust] load xml... 08:16:50.9242 { } Plugins/Extensions/Volume_adjust/plugin.py:484 __evUpdatedInfo [Volume Adjust] New Channel 08:16:50.9243 { } Plugins/Extensions/Volume_adjust/plugin.py:489 __evUpdatedInfo [Volume Adjust] oldvol = 0 08:16:50.9300 [ ] dvb/cahandler.cpp:267 registerService [eDVBCAService] new service 1:0:1:2144:3070:13E:820000:0:0:0: 08:16:50.9301 [ ] dvb/cahandler.cpp:285 registerService [eDVBCAService] add demux 0 to slot 0 service 1:0:1:2144:3070:13E:820000:0:0:0: 08:16:50.9307 [ ] dvb/demux.cpp:90 openDemux [eDVBDemux] open demux /dev/dvb/adapter0/demux0 08:16:50.9308 [ ] dvb/demux.cpp:286 start [eDVBSectionReader] DMX_SET_FILTER pid=0 08:16:50.9360 [ ] service/servicedvb.cpp:1728 timeshift [eDVBServicePlay] timeshift 08:16:50.9438 [ ] dvb/demux.cpp:90 openDemux [eDVBDemux] open demux /dev/dvb/adapter0/demux0 08:16:50.9439 [ ] dvb/demux.cpp:286 start [eDVBSectionReader] DMX_SET_FILTER pid=18 08:16:50.9452 [ ] dvb/dvb.cpp:2415 getDemux [eDVBChannel] getDemux cap=00 08:16:50.9660 [ ] dvb/pmt.cpp:207 PATready [eDVBServicePMTHandler] PATready 08:16:50.9661 [ ] dvb/pmt.cpp:218 PATready [eDVBServicePMTHandler] PAT TSID: 0x3070 (12400) 08:16:50.9661 [ ] dvb/pmt.cpp:247 PATready [eDVBServicePMTHandler] use pmtpid 0453 for service_id 2144 08:16:50.9662 [ ] dvb/demux.cpp:90 openDemux [eDVBDemux] open demux /dev/dvb/adapter0/demux0 08:16:50.9663 [ ] dvb/demux.cpp:286 start [eDVBSectionReader] DMX_SET_FILTER pid=1107 08:16:50.9667 [ ] dvb/demux.cpp:90 openDemux [eDVBDemux] open demux /dev/dvb/adapter0/demux0 08:16:50.9668 [ ] dvb/demux.cpp:286 start [eDVBSectionReader] DMX_SET_FILTER pid=0 08:16:51.0192 [ ] service/servicedvb.cpp:1203 serviceEvent [eDVBServicePlay] eventNewProgramInfo timeshift_enabled=0 timeshift_active=0 08:16:51.0197 [ ] dvb/demux.cpp:90 openDemux [eDVBDemux] open demux /dev/dvb/adapter0/demux0 08:16:51.0198 [ ] dvb/demux.cpp:286 start [eDVBSectionReader] DMX_SET_FILTER pid=6006 08:16:51.0203 < > service/servicedvb.cpp:2872 updateDecoder [eDVBServicePlay] have 1 video stream(s) (0208), and 1 audio stream(s) (02b2), and the pcr pid is 0208, and the text pid is 0257 08:16:51.0205 [ ] service/servicedvb.cpp:3556 setAC3Delay [eDVBServicePlay] Setting audio delay: setAC3Delay, 0 + 0 08:16:51.0207 [ ] service/servicedvb.cpp:3567 setPCMDelay [eDVBServicePlay] Setting audio delay: setPCMDelay, 0 + 0 08:16:51.0207 [ ] dvb/decoder.cpp:836 setState [eTSMPEGDecoder] decoder state: play, vpid=0208, apid=02b2 08:16:51.0219 [ ] dvb/demux.cpp:90 openDemux [eDVBDemux] open demux /dev/dvb/adapter0/demux0 08:16:51.0220 [ ] dvb/demux.cpp:286 start [eDVBSectionReader] DMX_SET_FILTER pid=6006 08:16:51.0274 { } Plugins/Extensions/Volume_adjust/plugin.py:469 __evUpdatedInfo [Volume Adjust] Update Info 08:16:51.0276 { } Plugins/Extensions/Volume_adjust/plugin.py:473 __evUpdatedInfo [Volume Adjust] Volume = 100 08:16:51.0277 { } Plugins/Extensions/Volume_adjust/plugin.py:447 loadXML [Volume Adjust] load xml... 08:16:51.0320 [ ] dvb_ci/dvbci.cpp:937 gotPMT [eDVBCIInterfaces] gotPMT 08:16:51.0320 [ ] dvb/cahandler.cpp:624 buildCAPMT [eDVBCAService] don't build/send the same CA PMT twice 08:16:51.0322 [ ] dvb/demux.cpp:90 openDemux [eDVBDemux] open demux /dev/dvb/adapter0/demux0 08:16:51.0323 [ ] dvb/demux.cpp:286 start [eDVBSectionReader] DMX_SET_FILTER pid=1107 08:16:51.0973 [ ] driver/rcinput.cpp:38 handleCode 0 192 1 08:16:51.9357 < > dvb/decoder.cpp:543 video_event [eDVBVideo0] VIDEO_GET_EVENT unhandled DVBAPI Video Event 32 08:16:51.9362 [ ] service/servicedvb.cpp:1728 timeshift [eDVBServicePlay] timeshift 08:16:52.5836 [ ] bsod.cpp:328 print_backtrace Backtrace: 08:16:52.5838 [ ] bsod.cpp:336 print_backtrace /usr/bin/enigma2(_Z17handleFatalSignaliP9siginfo_tPv) [0x786C0] 08:16:52.5839 [ ] bsod.cpp:336 print_backtrace /lib/libc.so.6(__default_rt_sa_restorer) [0xB613AE60] 08:16:52.5839 [ ] bsod.cpp:348 handleFatalSignal -------FATAL SIGNAL dmesg <6>[ 0.000000] Booting Linux on physical CPU 0x0 <6>[ 0.000000] Initializing cgroup subsys cpuset <6>[ 0.000000] Initializing cgroup subsys cpu <6>[ 0.000000] Initializing cgroup subsys cpuacct <5>[ 0.000000] Linux version 3.14-1.17-dm900 (jenkins@6.2) (gcc version 6.3.0 (GCC) ) #1 SMP Wed Nov 15 13:31:24 UTC 2017 <6>[ 0.000000] CPU: ARMv7 Processor [420f00f3] revision 3 (ARMv7), cr=30c7387d <6>[ 0.000000] CPU: PIPT / VIPT nonaliasing data cache, PIPT instruction cache <6>[ 0.000000] Machine model: BCM97252SSFF <6>[ 0.000000] moving dtb from 0x0000000007728000 to 0x0000000000855b88 <6>[ 0.000000] bmem: Reserved 640 MiB at 0x0000000018000000 <6>[ 0.000000] bmem: Reserved 384 MiB at 0x0000000080000000 <6>[ 0.000000] cma: Reserved 16 MiB at 0x000000000f000000 <6>[ 0.000000] Memory policy: Data cache writealloc <7>[ 0.000000] On node 0 totalpages: 524288 <7>[ 0.000000] Normal zone: 512 pages used for memmap <7>[ 0.000000] Normal zone: 0 pages reserved <7>[ 0.000000] Normal zone: 65536 pages, LIFO batch:15 <7>[ 0.000000] HighMem zone: 3584 pages used for memmap <7>[ 0.000000] HighMem zone: 458752 pages, LIFO batch:31 <6>[ 0.000000] MCP: Enabling write pairing <6>[ 0.000000] PERCPU: Embedded 8 pages/cpu @cdfc0000 s8384 r8192 d16192 u32768 <7>[ 0.000000] pcpu-alloc: s8384 r8192 d16192 u32768 alloc=8*4096 <7>[ 0.000000] pcpu-alloc: [0] 0 [0] 1 <4>[ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. Total pages: 523776 <5>[ 0.000000] Kernel command line: bmem=640M@384M bmem=384M@2048M console=ttyS0,1000000 root=/dev/mmcblk0p2 rootwait rootfstype=ext4 coherent_pool=2M <6>[ 0.000000] PID hash table entries: 1024 (order: 0, 4096 bytes) <6>[ 0.000000] Dentry cache hash table entries: 32768 (order: 5, 131072 bytes) <6>[ 0.000000] Inode-cache hash table entries: 16384 (order: 4, 65536 bytes) <4>[ 0.000000] Memory: 1006784K/2097152K available (6197K kernel code, 230K rwdata, 1616K rodata, 244K init, 208K bss, 1073984K reserved, 16384K cma-reserved, 786428K highmem) <5>[ 0.000000] Virtual kernel memory layout: <5>[ 0.000000] vector : 0xffff0000 - 0xffff1000 ( 4 kB) <5>[ 0.000000] fixmap : 0xfff00000 - 0xfffe0000 ( 896 kB) <5>[ 0.000000] vmalloc : 0xd0800000 - 0xff000000 ( 744 MB) <5>[ 0.000000] lowmem : 0xc0000000 - 0xd0000000 ( 256 MB) <5>[ 0.000000] pkmap : 0xbfe00000 - 0xc0000000 ( 2 MB) <5>[ 0.000000] modules : 0xbf000000 - 0xbfe00000 ( 14 MB) <5>[ 0.000000] .text : 0xc0008000 - 0xc07a9758 (7814 kB) <5>[ 0.000000] .init : 0xc07aa000 - 0xc07e70c0 ( 245 kB) <5>[ 0.000000] .data : 0xc07e8000 - 0xc0821ae0 ( 231 kB) <5>[ 0.000000] .bss : 0xc0821ae8 - 0xc0855b88 ( 209 kB) <6>[ 0.000000] Hierarchical RCU implementation. <6>[ 0.000000] Additional per-CPU info printed with stalls. <6>[ 0.000000] RCU restricting CPUs from NR_CPUS=4 to nr_cpu_ids=2. <6>[ 0.000000] RCU: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 <6>[ 0.000000] NR_IRQS:16 nr_irqs:16 16 <6>[ 0.000000] irq_brcmstb_l2: registered L2 intc (mem: 0xfc403000, parent irq: 93) <6>[ 0.000000] irq_brcmstb_l2: registered L2 intc (mem: 0xfc3e1000, parent irq: 64) <6>[ 0.000000] irq_brcmstb_l2: registered L2 intc (mem: 0xfc410640, parent irq: 95) <6>[ 0.000000] irq_brcmstb_l2: registered L2 intc (mem: 0xfc4d1200, parent irq: 58) <6>[ 0.000000] Architected cp15 timer(s) running at 27.00MHz (virt). <6>[ 0.000004] sched_clock: 56 bits at 27MHz, resolution 37ns, wraps every 2545165795328ns <6>[ 0.000010] Switching to timer-based delay loop <6>[ 0.000226] Console: colour dummy device 80x30 <6>[ 0.000247] Calibrating delay loop (skipped), value calculated using timer frequency.. 54.00 BogoMIPS (lpj=27000) <6>[ 0.000255] pid_max: default: 32768 minimum: 301 <6>[ 0.000350] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes) <6>[ 0.000355] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes) <6>[ 0.005833] Initializing cgroup subsys devices <6>[ 0.005847] Initializing cgroup subsys freezer <6>[ 0.005855] Initializing cgroup subsys net_cls <6>[ 0.005881] CPU: Testing write buffer coherency: ok <6>[ 0.006098] CPU0: update cpu_power 1024 <6>[ 0.006104] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000 <6>[ 0.006153] Setting up static identity map for 0x5ea228 - 0x5ea280 <4>[ 0.007363] CPU1: Booted secondary processor <6>[ 0.007380] CPU1: update cpu_power 1024 <6>[ 0.007383] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001 <6>[ 0.007452] Brought up 2 CPUs <6>[ 0.007464] SMP: Total of 2 processors activated (108.00 BogoMIPS). <6>[ 0.007468] CPU: All CPU(s) started in SVC mode. <6>[ 0.007854] devtmpfs: initialized <6>[ 0.011282] VFP support v0.3: implementor 42 architecture 4 part 00 variant 0 rev 0 <6>[ 0.011828] regulator-dummy: no parameters <6>[ 0.011996] NET: Registered protocol family 16 <6>[ 0.012959] DMA: preallocated 2048 KiB pool for atomic coherent allocations <6>[ 0.013160] cpuidle: using governor ladder <6>[ 0.013164] cpuidle: using governor menu <4>[ 0.014304] irq: no irq domain found for /rdb/interrupt-controller@f040a700 ! <4>[ 0.014387] irq: no irq domain found for /rdb/interrupt-controller@f0417380 ! <6>[ 0.016063] syscon f0404000.syscon: regmap [mem 0xf0404000-0xf0404523] registered <6>[ 0.016147] syscon f03e2400.syscon: regmap [mem 0xf03e2400-0xf03e27ff] registered <6>[ 0.016213] syscon f0452000.syscon: regmap [mem 0xf0452000-0xf04520ff] registered <6>[ 0.016277] syscon f0404100.syscon: regmap [mem 0xf0404100-0xf040415b] registered <6>[ 0.016341] syscon f040415c.syscon: regmap [mem 0xf040415c-0xf040419b] registered <6>[ 0.016407] syscon f0410700.syscon: regmap [mem 0xf0410700-0xf041070b] registered <6>[ 0.016474] syscon f041070c.syscon: regmap [mem 0xf041070c-0xf0410713] registered <6>[ 0.016536] syscon f1101004.syscon: regmap [mem 0xf1101004-0xf1101403] registered <6>[ 0.016598] syscon f1181004.syscon: regmap [mem 0xf1181004-0xf1181403] registered <6>[ 0.016666] syscon f0404080.syscon: regmap [mem 0xf0404080-0xf0404083] registered <6>[ 0.016733] syscon f04040a4.syscon: regmap [mem 0xf04040a4-0xf04040a7] registered <6>[ 0.016795] syscon f03e0154.syscon: regmap [mem 0xf03e0154-0xf03e0157] registered <6>[ 0.016858] syscon f03e0354.syscon: regmap [mem 0xf03e0354-0xf03e0357] registered <6>[ 0.016923] syscon f03e0400.syscon: regmap [mem 0xf03e0400-0xf03e0403] registered <4>[ 0.017229] irq: no irq domain found for /rdb/interrupt-controller@f0417380 ! <6>[ 0.017455] Broadcom Brahma-B15 readahead cache at: 0xfc3e2478 <6>[ 0.021866] bio: create slab at 0 <4>[ 0.022175] brcmstb-gpio f040a600.gpio: Couldn't get IRQ. Enable CONFIG_BCM7120_L2_IRQ if you want GPIO interrupt support <6>[ 0.022203] brcmstb-gpio f040a600.gpio: Registered 5 banks (GPIO(s): 0-159) <4>[ 0.022234] brcmstb-gpio f0417400.gpio: Couldn't get IRQ. Enable CONFIG_BCM7120_L2_IRQ if you want GPIO interrupt support <6>[ 0.022247] brcmstb-gpio f0417400.gpio: Registered 2 banks (GPIO(s): 160-223) <5>[ 0.022598] SCSI subsystem initialized <7>[ 0.022691] libata version 3.00 loaded. <6>[ 0.022812] usbcore: registered new interface driver usbfs <6>[ 0.022842] usbcore: registered new interface driver hub <6>[ 0.022901] usbcore: registered new device driver usb <6>[ 0.022940] media: Linux media interface: v0.10 <6>[ 0.022962] Linux video capture interface: v2.00 <6>[ 0.022999] pps_core: LinuxPPS API ver. 1 registered <6>[ 0.023002] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <6>[ 0.023012] PTP clock support registered <6>[ 0.023251] Advanced Linux Sound Architecture Driver Initialized. <6>[ 0.023538] Bluetooth: Core ver 2.18 <6>[ 0.023552] NET: Registered protocol family 31 <6>[ 0.023555] Bluetooth: HCI device and connection manager initialized <6>[ 0.023563] Bluetooth: HCI socket layer initialized <6>[ 0.023570] Bluetooth: L2CAP socket layer initialized <6>[ 0.023577] Bluetooth: SCO socket layer initialized <6>[ 0.023856] cfg80211: Calling CRDA to update world regulatory domain <6>[ 0.024115] Switched to clocksource arch_sys_counter <4>[ 0.028487] thermal thermal_zone0: failed to read out thermal zone 0 <6>[ 0.028570] NET: Registered protocol family 2 <6>[ 0.028989] TCP established hash table entries: 2048 (order: 1, 8192 bytes) <6>[ 0.029005] TCP bind hash table entries: 2048 (order: 2, 16384 bytes) <6>[ 0.029027] TCP: Hash tables configured (established 2048 bind 2048) <6>[ 0.029061] TCP: reno registered <6>[ 0.029067] UDP hash table entries: 256 (order: 1, 8192 bytes) <6>[ 0.029080] UDP-Lite hash table entries: 256 (order: 1, 8192 bytes) <6>[ 0.029211] NET: Registered protocol family 1 <6>[ 0.029441] RPC: Registered named UNIX socket transport module. <6>[ 0.029445] RPC: Registered udp transport module. <6>[ 0.029448] RPC: Registered tcp transport module. <6>[ 0.029452] RPC: Registered tcp NFSv4.1 backchannel transport module. <7>[ 0.029459] PCI: CLS 0 bytes, default 64 <6>[ 0.030060] futex hash table entries: 512 (order: 3, 32768 bytes) <4>[ 0.030404] bounce pool size: 64 pages <5>[ 0.030931] NFS: Registering the id_resolver key type <5>[ 0.030964] Key type id_resolver registered <5>[ 0.030968] Key type id_legacy registered <6>[ 0.030978] nfs4filelayout_init: NFSv4 File Layout Driver Registering... <6>[ 0.031103] SGI XFS with ACLs, security attributes, realtime, large block/inode numbers, no debug enabled <6>[ 0.031413] msgmni has been set to 462 <6>[ 0.032210] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) <6>[ 0.032218] io scheduler noop registered <6>[ 0.032234] io scheduler cfq registered (default) <6>[ 0.032446] brcm-gisb-arb f0400000.gisb-arb: registered mem: fc400000, irqs: 258, 259 <6>[ 0.032727] brcm-usb-phy f0470200.usb-phy: USB3.0 clock not found in Device Tree <6>[ 0.098395] brcmstb_memc f1102000.memc-ddr: registered <6>[ 0.098422] brcmstb_memc f1182000.memc-ddr: registered <6>[ 0.098597] Serial: 8250/16550 driver, 4 ports, IRQ sharing disabled <6>[ 0.099366] f040a900.serial: ttyS0 at MMIO 0xf040a900 (irq = 107, base_baud = 5062500) is a 16550A <6>[ 0.100195] loop: module loaded <3>[ 0.100480] ahci brcmstb-ahci.0: can't get clock <6>[ 0.100556] ahci brcmstb-ahci.0: SSS flag set, parallel bus scan disabled <6>[ 0.111128] ahci brcmstb-ahci.0: AHCI 0001.0300 32 slots 2 ports 6 Gbps 0x3 impl platform mode <6>[ 0.111135] ahci brcmstb-ahci.0: flags: 64bit ncq sntf stag pm clo pmp slum part <6>[ 0.112910] scsi0 : ahci_platform <6>[ 0.113160] scsi1 : ahci_platform <6>[ 0.113286] ata1: SATA max UDMA/133 mmio [mem 0xf045a000-0xf045aa9b] port 0x100 irq 91 <6>[ 0.113291] ata2: SATA max UDMA/133 mmio [mem 0xf045a000-0xf045aa9b] port 0x180 irq 91 <6>[ 0.113787] spi_brcmstb f03e3400.spi: 1-lane output, 3-byte address <6>[ 0.113842] spi_brcmstb f03e3400.spi: master is unqueued, this is deprecated <4>[ 0.114230] m25p80 spi32766.0: found mx25l25635e, expected m25p80 <6>[ 0.114247] spi_brcmstb f03e3400.spi: 1-lane output, 4-byte address <6>[ 0.114265] m25p80 spi32766.0: mx25l25635e (32768 Kbytes) <5>[ 0.114291] 5 ofpart partitions found on MTD device spi32766.0 <5>[ 0.114295] Creating 5 MTD partitions on "spi32766.0": <5>[ 0.114301] 0x000000000000-0x000000200000 : "flash0.bolt" <5>[ 0.114791] 0x000000200000-0x000000220000 : "flash0.nvram" <5>[ 0.115221] 0x000000220000-0x000000240000 : "flash0.devtree" <5>[ 0.115624] 0x000000240000-0x000001000000 : "flash0.recovery" <5>[ 0.116026] 0x000000000000-0x000002000000 : "flash0" <6>[ 0.116734] libphy: Fixed MDIO Bus: probed <6>[ 0.117100] bcmgenet f0b00000.ethernet: GENET 4.5 EPHY: 0x1001 <6>[ 0.130123] libphy: bcmgenet MII bus: probed <6>[ 0.150998] xhci-brcm: xHCI BRCM driver <6>[ 0.151069] xhci-brcm f0471000.xhci_v2: xHCI Host Controller <6>[ 0.151089] xhci-brcm f0471000.xhci_v2: new USB bus registered, assigned bus number 1 <6>[ 0.151422] xhci-brcm f0471000.xhci_v2: irq 149, io mem 0xf0471000 <6>[ 0.151551] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002 <6>[ 0.151557] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 <6>[ 0.151562] usb usb1: Product: xHCI Host Controller <6>[ 0.151566] usb usb1: Manufacturer: Linux 3.14-1.17-dm900 xhci-hcd <6>[ 0.151570] usb usb1: SerialNumber: f0471000.xhci_v2 <6>[ 0.151897] hub 1-0:1.0: USB hub found <3>[ 0.151923] hub 1-0:1.0: config failed, hub doesn't have any ports! (err -19) <6>[ 0.152036] xhci-brcm f0471000.xhci_v2: xHCI Host Controller <6>[ 0.152047] xhci-brcm f0471000.xhci_v2: new USB bus registered, assigned bus number 2 <6>[ 0.152186] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003 <6>[ 0.152192] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 <6>[ 0.152196] usb usb2: Product: xHCI Host Controller <6>[ 0.152200] usb usb2: Manufacturer: Linux 3.14-1.17-dm900 xhci-hcd <6>[ 0.152205] usb usb2: SerialNumber: f0471000.xhci_v2 <6>[ 0.152474] hub 2-0:1.0: USB hub found <6>[ 0.152495] hub 2-0:1.0: 2 ports detected <6>[ 0.152801] ehci-brcm: EHCI BRCM driver <6>[ 0.152874] ehci-brcm f0470300.ehci_v2: EHCI Host Controller <6>[ 0.152886] ehci-brcm f0470300.ehci_v2: new USB bus registered, assigned bus number 3 <6>[ 0.162993] ehci-brcm f0470300.ehci_v2: irq 145, io mem 0xf0470300 <6>[ 0.168125] ehci-brcm f0470300.ehci_v2: USB 2.0 started, EHCI 1.00 <6>[ 0.168231] usb usb3: New USB device found, idVendor=1d6b, idProduct=0002 <6>[ 0.168236] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1 <6>[ 0.168241] usb usb3: Product: EHCI Host Controller <6>[ 0.168245] usb usb3: Manufacturer: Linux 3.14-1.17-dm900 ehci_hcd <6>[ 0.168250] usb usb3: SerialNumber: f0470300.ehci_v2 <6>[ 0.168531] hub 3-0:1.0: USB hub found <6>[ 0.168553] hub 3-0:1.0: 1 port detected <6>[ 0.168751] ehci-brcm f0470500.ehci_v2: EHCI Host Controller <6>[ 0.168767] ehci-brcm f0470500.ehci_v2: new USB bus registered, assigned bus number 4 <6>[ 0.178900] ehci-brcm f0470500.ehci_v2: irq 146, io mem 0xf0470500 <6>[ 0.184122] ehci-brcm f0470500.ehci_v2: USB 2.0 started, EHCI 1.00 <6>[ 0.184226] usb usb4: New USB device found, idVendor=1d6b, idProduct=0002 <6>[ 0.184231] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1 <6>[ 0.184236] usb usb4: Product: EHCI Host Controller <6>[ 0.184240] usb usb4: Manufacturer: Linux 3.14-1.17-dm900 ehci_hcd <6>[ 0.184245] usb usb4: SerialNumber: f0470500.ehci_v2 <6>[ 0.184504] hub 4-0:1.0: USB hub found <6>[ 0.184526] hub 4-0:1.0: 1 port detected <6>[ 0.184775] ohci-brcm: OHCI BRCM driver <6>[ 0.184841] ohci-brcm f0470400.ohci_v2: BRCM OHCI controller <6>[ 0.184855] ohci-brcm f0470400.ohci_v2: new USB bus registered, assigned bus number 5 <6>[ 0.184877] ohci-brcm f0470400.ohci_v2: irq 147, io mem 0xf0470400 <6>[ 0.239224] usb usb5: New USB device found, idVendor=1d6b, idProduct=0001 <6>[ 0.239230] usb usb5: New USB device strings: Mfr=3, Product=2, SerialNumber=1 <6>[ 0.239234] usb usb5: Product: BRCM OHCI controller <6>[ 0.239238] usb usb5: Manufacturer: Linux 3.14-1.17-dm900 ohci_hcd <6>[ 0.239242] usb usb5: SerialNumber: f0470400.ohci_v2 <6>[ 0.239496] hub 5-0:1.0: USB hub found <6>[ 0.239516] hub 5-0:1.0: 1 port detected <6>[ 0.239710] ohci-brcm f0470600.ohci_v2: BRCM OHCI controller <6>[ 0.239723] ohci-brcm f0470600.ohci_v2: new USB bus registered, assigned bus number 6 <6>[ 0.239742] ohci-brcm f0470600.ohci_v2: irq 148, io mem 0xf0470600 <6>[ 0.294220] usb usb6: New USB device found, idVendor=1d6b, idProduct=0001 <6>[ 0.294226] usb usb6: New USB device strings: Mfr=3, Product=2, SerialNumber=1 <6>[ 0.294231] usb usb6: Product: BRCM OHCI controller <6>[ 0.294235] usb usb6: Manufacturer: Linux 3.14-1.17-dm900 ohci_hcd <6>[ 0.294239] usb usb6: SerialNumber: f0470600.ohci_v2 <6>[ 0.294498] hub 6-0:1.0: USB hub found <6>[ 0.294519] hub 6-0:1.0: 1 port detected <6>[ 0.294759] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver <6>[ 0.294764] ehci-pci: EHCI PCI platform driver <6>[ 0.294801] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver <6>[ 0.294805] ohci-pci: OHCI PCI platform driver <6>[ 0.294918] usbcore: registered new interface driver usb-storage <6>[ 0.294956] i2c /dev entries driver <6>[ 0.295003] lirc_dev: IR Remote Control driver registered, major 250 <6>[ 0.295346] brcmstb_thermal f04d1500.thermal: registered AVS TMON of-sensor driver <6>[ 0.295453] usbcore: registered new interface driver btusb <4>[ 0.295509] cpufreq_cpu0: failed to get cpu0 regulator: -19 <4>[ 0.295683] brcmstb-cpufreq: probe of f03e257c.cpu-clk-div failed with error -17 <6>[ 0.295761] sdhci: Secure Digital Host Controller Interface driver <6>[ 0.295764] sdhci: Copyright(c) Pierre Ossman <6>[ 0.295767] sdhci-pltfm: SDHCI platform and OF driver helper <6>[ 0.296863] mmc0: no vqmmc regulator found <6>[ 0.296868] mmc0: no vmmc regulator found <6>[ 0.327124] mmc0: SDHCI controller on f03e0000.sdhci [f03e0000.sdhci] using ADMA <6>[ 0.328193] mmc1: no vqmmc regulator found <6>[ 0.328199] mmc1: no vmmc regulator found <6>[ 0.358120] mmc1: SDHCI controller on f03e0200.sdhci [f03e0200.sdhci] using ADMA <6>[ 0.358233] hidraw: raw HID events driver (C) Jiri Kosina <6>[ 0.358320] usbcore: registered new interface driver usbhid <6>[ 0.358323] usbhid: USB HID core driver <6>[ 0.360549] TCP: cubic registered <6>[ 0.360603] NET: Registered protocol family 10 <6>[ 0.363033] NET: Registered protocol family 17 <6>[ 0.363940] Bluetooth: RFCOMM TTY layer initialized <6>[ 0.363950] Bluetooth: RFCOMM socket layer initialized <6>[ 0.363953] Bluetooth: RFCOMM ver 1.11 <6>[ 0.363957] Bluetooth: BNEP (Ethernet Emulation) ver 1.3 <6>[ 0.363960] Bluetooth: BNEP filters: protocol multicast <6>[ 0.363967] Bluetooth: BNEP socket layer initialized <6>[ 0.363971] Bluetooth: HIDP (Human Interface Emulation) ver 1.2 <6>[ 0.363977] Bluetooth: HIDP socket layer initialized <6>[ 0.364020] l2tp_core: L2TP core driver, V2.0 <5>[ 0.364042] Key type dns_resolver registered <5>[ 0.364135] Registering SWP/SWPB emulation handler <6>[ 0.364407] regulator-dummy: disabling <6>[ 0.364667] ALSA device list: <6>[ 0.364673] No soundcards found. <3>[ 0.364722] Warning: unable to open an initial console. <6>[ 0.364779] Waiting for root device /dev/mmcblk0p2... <6>[ 0.425761] mmc1: BKOPS_EN bit is not set <6>[ 0.432129] mmc1: new high speed MMC card at address 0001 <6>[ 0.432354] mmcblk0: mmc1:0001 4FPD3R 3.64 GiB <6>[ 0.432451] mmcblk0boot0: mmc1:0001 4FPD3R partition 1 4.00 MiB <6>[ 0.432550] mmcblk0boot1: mmc1:0001 4FPD3R partition 2 4.00 MiB <6>[ 0.432637] mmcblk0rpmb: mmc1:0001 4FPD3R partition 3 512 KiB <6>[ 0.436261] mmcblk0: p1 p2 p3 <6>[ 0.437270] mmcblk0boot1: unknown partition table <6>[ 0.437753] mmcblk0boot0: unknown partition table <6>[ 0.470156] usb 3-1: new high-speed USB device number 2 using ehci-brcm <6>[ 0.470878] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null) <6>[ 0.470903] VFS: Mounted root (ext4 filesystem) readonly on device 179:2. <6>[ 0.471638] devtmpfs: mounted <6>[ 0.669518] fuse init (API version 7.22) <4>[ 0.934199] stb_core: module license 'Proprietary' taints kernel. <4>[ 0.934208] Disabling lock debugging due to kernel taint <6>[ 0.989310] input: dreambox remote control (native) as /devices/virtual/input/input0 <6>[ 0.992678] input: dreambox advanced remote control (native) as /devices/virtual/input/input1 <5>[ 0.995562] random: nonblocking pool is initialized <6>[ 1.113783] usb 3-1: New USB device found, idVendor=05dc, idProduct=a811 <6>[ 1.113792] usb 3-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 <6>[ 1.113797] usb 3-1: Product: USB Flash Drive <6>[ 1.113802] usb 3-1: Manufacturer: UFD <6>[ 1.113806] usb 3-1: SerialNumber: AACYBKAXDQP09WGH <6>[ 1.114923] usb-storage 3-1:1.0: USB Mass Storage device detected <6>[ 1.116745] scsi2 : usb-storage 3-1:1.0 <6>[ 1.145164] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300) <6>[ 1.151478] ata1.00: ATA-8: ST1000LM024 HN-M101MBB, 2AR10002, max UDMA/133 <6>[ 1.151486] ata1.00: 1953525168 sectors, multi 0: LBA48 NCQ (depth 31/32) <6>[ 1.157833] ata1.00: configured for UDMA/133 <5>[ 1.158055] scsi 0:0:0:0: Direct-Access ATA ST1000LM024 HN-M 2AR1 PQ: 0 ANSI: 5 <5>[ 1.161564] sd 0:0:0:0: [sda] 1953525168 512-byte logical blocks: (1.00 TB/931 GiB) <5>[ 1.161574] sd 0:0:0:0: [sda] 4096-byte physical blocks <5>[ 1.161738] sd 0:0:0:0: [sda] Write Protect is off <7>[ 1.161747] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00 <5>[ 1.161845] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA <6>[ 1.199383] sda: sda1 <6>[ 1.210231] bcm7439: xvd 20160630, vdc 20160630, rap 20160630, xpt 20160630, driver version 20171216-dm900 <5>[ 1.219069] sd 0:0:0:0: [sda] Attached SCSI disk <6>[ 1.221312] usb 4-1: new high-speed USB device number 2 using ehci-brcm <6>[ 1.280136] ata2: SATA link down (SStatus 0 SControl 300) <6>[ 1.335811] usb 4-1: New USB device found, idVendor=1a40, idProduct=0101 <6>[ 1.335820] usb 4-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0 <6>[ 1.335825] usb 4-1: Product: USB 2.0 Hub <6>[ 1.341249] hub 4-1:1.0: USB hub found <6>[ 1.341450] hub 4-1:1.0: 4 ports detected <6>[ 1.615328] usb 4-1.1: new high-speed USB device number 3 using ehci-brcm <6>[ 1.620454] EXT4-fs (sda1): mounted filesystem with ordered data mode. Opts: (null) <6>[ 1.716441] usb 4-1.1: New USB device found, idVendor=1d19, idProduct=1101 <6>[ 1.716449] usb 4-1.1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 <6>[ 1.716454] usb 4-1.1: Product: DVB-T Dongle <6>[ 1.716459] usb 4-1.1: Manufacturer: Realtek <6>[ 1.716463] usb 4-1.1: SerialNumber: 00000991 <6>[ 1.740487] usb 4-1.1: dvb_usb_v2: found a 'Dexatek DK DVB-T Dongle' in warm state <6>[ 1.781325] usb 4-1.1: dvb_usb_v2: will pass the complete MPEG2 transport stream to the software demuxer <6>[ 1.781448] DVB: registering new adapter (Dexatek DK DVB-T Dongle) <6>[ 1.791706] usb 4-1.1: DVB: registering adapter 1 frontend 0 (Realtek RTL2832 (DVB-T))... <6>[ 1.796406] fc0013: Fitipower FC0013 successfully attached. <6>[ 1.797328] usb 4-1.3: new high-speed USB device number 4 using ehci-brcm <6>[ 1.815139] usb 4-1.1: dvb_usb_v2: 'Dexatek DK DVB-T Dongle' successfully initialized and connected <6>[ 1.815433] usbcore: registered new interface driver dvb_usb_rtl28xxu <3>[ 1.824655] 1:823541534 SMCB: controller reset timed out <6>[ 1.888941] usb 4-1.3: New USB device found, idVendor=0bda, idProduct=8176 <6>[ 1.888953] usb 4-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=3 <6>[ 1.888959] usb 4-1.3: Product: 802.11n WLAN Adapter <6>[ 1.888963] usb 4-1.3: Manufacturer: Realtek <6>[ 1.888968] usb 4-1.3: SerialNumber: 00e04c000001 <6>[ 2.068032] usbcore: registered new interface driver rtl8192cu <4>[ 2.199029] register adapter <4>[ 2.200962] register adapter <4>[ 2.203861] register adapter <4>[ 2.206826] register adapter <4>[ 2.209389] register adapter <4>[ 2.212716] FP did not respond <6>[ 2.214356] input: dreambox front panel as /devices/virtual/input/input2 <6>[ 2.217526] OFFSET 1515227415 9421926 <6>[ 2.217535] WKTMR 7 10322072 <6>[ 2.217539] LAST WKTMR 58959 <4>[ 2.217759] HDMI: CEC start ... state is now 1 <4>[ 2.218239] set input to 0 <4>[ 2.218248] set fb to 2(-1) <4>[ 2.219344] fallback to startvideomode '720p50' <6>[ 2.221512] bcmfb: creating surface with pixel format 0x07e48888 (BPXL_eA8_R8_G8_B8) <4>[ 2.255278] HDMI: now connected <4>[ 2.284162] HDMI: CEC Physical Addr 2.0.0.0 set from EDID <4>[ 2.284169] HDMI: EDID read ok <4>[ 2.284175] HDMI: initial mode set! <4>[ 2.284182] using 50.00Hz as MonitorRefreshRate <4>[ 2.284187] SET FORMAT: display[0], format='720p50' <4>[ 2.284195] HDMI mode <4>[ 2.284199] use EDID to detect colorspace <4>[ 2.284203] preferred colorspace: Itu_R_BT_709 <4>[ 2.284206] using 8bit output <4>[ 2.417242] HDMI: CEC use logical address 3 <4>[ 2.417264] HDMI: CEC init completed... state is now 3! <4>[ 2.556542] HDMI: CEC send ok! <5>[ 2.629597] scsi 2:0:0:0: Direct-Access UFD USB Flash Drive 1100 PQ: 0 ANSI: 0 CCS <4>[ 2.722179] VIDEO0: enable deinterlacer <4>[ 2.722285] VIDEO0: set fallback framerate to 60000 <4>[ 2.722294] VIDEO0: set progressive override mode to 1 <4>[ 2.722298] VIDEO0: set streamtype! <4>[ 2.722305] -> set MPEG2 <4>[ 2.722308] -> sw rave disabled! <4>[ 2.806681] VIDEO1: set fallback framerate to 60000 <4>[ 2.806693] VIDEO1: set progressive override mode to 1 <4>[ 2.806697] VIDEO1: set streamtype! <4>[ 2.806702] -> set MPEG2 <4>[ 2.806705] -> sw rave disabled! <4>[ 2.842239] VIDEO2: set fallback framerate to 60000 <4>[ 2.842248] VIDEO2: set progressive override mode to 1 <4>[ 2.842251] VIDEO2: set streamtype! <4>[ 2.842256] -> set MPEG2 <4>[ 2.842259] -> sw rave disabled! <4>[ 2.855436] set ac3 to downmix <4>[ 2.855450] set wmapro to downmix <4>[ 2.855458] set ac3plus to use_hdmi_caps <4>[ 2.855465] set dtshd to force_dts <4>[ 2.855474] set aac to downmix <4>[ 2.855813] audio ch2: add stereo (background decoder) input to mixer DUMMY2 <4>[ 2.855852] audio ch1: add stereo (hdmi-rx) input to mixer DUMMY <6>[ 2.856393] DVB: registering new adapter (BCM7439) <4>[ 2.975879] autodetecting NIMs... <5>[ 3.075086] sd 2:0:0:0: [sdb] 31326208 512-byte logical blocks: (16.0 GB/14.9 GiB) <5>[ 3.076450] sd 2:0:0:0: [sdb] Write Protect is off <7>[ 3.076462] sd 2:0:0:0: [sdb] Mode Sense: 43 00 00 00 <3>[ 3.077690] sd 2:0:0:0: [sdb] No Caching mode page found <3>[ 3.077700] sd 2:0:0:0: [sdb] Assuming drive cache: write through <3>[ 3.082853] sd 2:0:0:0: [sdb] No Caching mode page found <3>[ 3.082865] sd 2:0:0:0: [sdb] Assuming drive cache: write through <6>[ 3.083976] sdb: sdb1 <3>[ 3.091350] sd 2:0:0:0: [sdb] No Caching mode page found <3>[ 3.091360] sd 2:0:0:0: [sdb] Assuming drive cache: write through <5>[ 3.091369] sd 2:0:0:0: [sdb] Attached SCSI removable disk <4>[ 3.486122] Found: Slot 0, ID: 19 (by CA) <4>[ 3.486132] Initializing adapter 0 (Si2166B-DVB-S2) <6>[ 3.488592] (NULL device *): DVB: registering adapter 0 frontend 0 (Si2166B)... <4>[ 3.488945] Initializing adapter 1 (Si2166B-DVB-S2) <6>[ 3.500761] (NULL device *): DVB: registering adapter 0 frontend 1 (Si2166B)... <4>[ 3.501574] CI stopped... <4>[ 3.501709] init ALSA! <4>[ 3.519029] registered vtuner misc/vtuner0, minor 49 <4>[ 3.520534] registered vtuner misc/vtuner1, minor 48 <4>[ 3.522079] registered vtuner misc/vtuner2, minor 47 <4>[ 3.523519] registered vtuner misc/vtuner3, minor 46 <4>[ 3.523556] HDMI-RX: init <4>[ 3.524376] HDMI-RX: no device attached <4>[ 3.524428] HDMI-RX: video format change event <4>[ 3.524433] HDMI-RX: hdmi packet status stopped <4>[ 3.524439] HDMI-RX: hdmiInput0 Receiving DVI format <4>[ 3.524445] HDMI-RX: hdmiInput0 Color Space change from '' to '' <4>[ 3.524478] HDMI-RX: init done <4>[ 3.524484] bcm_vce_init() <4>[ 3.524489] bcm_vce_init() 0 <4>[ 3.548063] vice0: allocate output buffers <6>[ 3.577200] input: dreambox ir keyboard as /devices/virtual/input/input3 <6>[ 3.587499] input: dreambox ir mouse as /devices/virtual/input/input4 <6>[ 3.621077] Installing knfsd (copyright (C) 1996 okir@monad.swb.de). <4>[ 3.665945] 0 ms: <4>[ 3.894778] 229 ms: 10 <4>[ 3.899957] 5 ms: 12 <4>[ 3.913774] 14 ms: 13 <4>[ 3.916303] VIDEO0: set blank to 0 <4>[ 3.916521] sync0 is off so we enable it <4>[ 3.916530] sync0: allocated pcroffset 0 <4>[ 3.916539] sync0: playback! <4>[ 3.916566] VIDEO0: total delay: 0 (0 ms) <4>[ 3.921396] VIDEO0: start decode <4>[ 3.921418] VIDEO0: flush video decoder <4>[ 3.921425] VIDEO0 got pts failed !!!! (ret 0) <6>[ 3.930197] EXT4-fs (mmcblk0p2): re-mounted. Opts: data=ordered <4>[ 3.934599] 21 ms: 15 <4>[ 3.950926] 16 ms: 16 <4>[ 3.955106] VIDEO0: first pts 0x0 <4>[ 3.955143] VIDEO0: EOTF changed to SDR <4>[ 3.955151] VIDEO0: framerate changed 3 <4>[ 3.955156] VIDEO0: resolution changed (1280 720) <4>[ 3.955161] VIDEO0: aspect changed 3 <4>[ 3.955413] set current avs auto aspect to 16:9 <4>[ 3.955430] set current avs auto aspect to 16:9 <4>[ 3.955471] set sb to 3(1) <4>[ 3.956674] 6 ms: 18 <4>[ 3.962685] 6 ms: 19 <4>[ 4.105175] VIDEO0: enable tsm <4>[ 4.192664] VIDEO0: stop decode <4>[ 4.192681] sync0 1fff no decoder left in sync handling so disable <4>[ 4.192686] sync0: was last pcroffset0 user so free it <4>[ 4.247642] 285 ms: 21 <4>[ 4.262914] 15 ms: 22 <4>[ 4.302792] 40 ms: 24 <4>[ 4.313748] 11 ms: 25 <4>[ 4.359961] 46 ms: 27 <4>[ 10.055755] 5696 ms: 28 <4>[ 10.068079] 12 ms: 30 <4>[ 10.079948] 12 ms: 31 <4>[ 10.146713] 67 ms: 33 <4>[ 10.171228] 25 ms: 33 <6>[ 10.812741] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready <6>[ 18.871250] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready <6>[ 20.120132] f0b00000.etherne:01: Broadcom BCM7439 (2) PHY revision: 0x10, patch: 1 <6>[ 20.122270] f0b00000.ethernet: configuring instance for internal PHY <6>[ 20.122356] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready <6>[ 22.127223] libphy: f0b00000.etherne:01 - Link is Down <6>[ 23.301686] libphy: f0b00000.etherne:01 - Link is Up - 1000/Full <6>[ 23.301710] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready <4>[ 26.316457] 16145 ms: 34 <4>[ 26.347657] 31 ms: 35 <4>[ 26.367669] 20 ms: 36 <4>[ 26.400555] NFSD: Using /var/lib/nfs/v4recovery as the NFSv4 state recovery directory <6>[ 26.400848] NFSD: starting 90-second grace period (net c0819500) <4>[ 26.430408] 63 ms: 36 <4>[ 26.437294] 7 ms: 37 <4>[ 27.532612] 1095 ms: 38 <4>[ 27.570355] 38 ms: 39 <4>[ 27.580779] 10 ms: 39 <4>[ 27.610002] 29 ms: 40 <4>[ 27.643958] 34 ms: 41 <4>[ 27.662925] 19 ms: 42 <4>[ 28.368599] 706 ms: 42 <4>[ 28.388528] 20 ms: 43 <4>[ 28.410024] 21 ms: 44 <4>[ 28.430778] 21 ms: 45 <4>[ 28.472138] 42 ms: 45 <4>[ 28.488934] 16 ms: 46 <4>[ 28.505910] 17 ms: 47 <4>[ 28.518201] 13 ms: 48 <4>[ 28.522028] 3 ms: 48 <4>[ 29.570371] sync0 is off so we enable it <4>[ 29.570385] sync0: allocated pcroffset 0 <4>[ 29.570397] sync0: playback! <4>[ 29.570419] VIDEO0: total delay: 0 (0 ms) <4>[ 29.575383] VIDEO0: start decode <4>[ 29.575407] VIDEO0: flush video decoder <4>[ 29.611702] VIDEO0: first pts 0x0 <4>[ 29.776150] VIDEO0: enable tsm <4>[ 29.846900] VIDEO0: stop decode <4>[ 29.846912] sync0 1fff no decoder left in sync handling so disable <4>[ 29.846917] sync0: was last pcroffset0 user so free it <3>[ 29.927810] bcm_accel: invalid opcode! 00000080 <6>[ 29.936412] bcmfb: creating surface with pixel format 0x07e48888 (BPXL_eA8_R8_G8_B8) <4>[ 29.972690] HDMI: CEC device open ... state is 3, flushed 0 old messages from RX fifo <4>[ 29.972722] HDMI: CEC returned state 3 <4>[ 29.972726] HDMI: CEC state now 803 <4>[ 30.099852] HDMI: CEC send ok! <4>[ 30.424164] vtuner open! <4>[ 30.424187] attaching virtual tuner 0 <6>[ 30.424197] (NULL device *): DVB: registering adapter 0 frontend 0 (virtual DVB-S)... <6>[ 30.427642] si2166b(0): voltage is disabled now <6>[ 30.428873] si2166b(0): voltage is disabled now <6>[ 30.429729] si2166b(0): voltage is disabled now <6>[ 30.431580] si2166b(1): voltage is disabled now <6>[ 30.432959] si2166b(1): voltage is disabled now <6>[ 30.433693] si2166b(1): voltage is disabled now <4>[ 30.880349] set current avs auto aspect to 16:9 <4>[ 30.880376] set sb to 3(1) <4>[ 30.880449] set current avs auto aspect to 16:9 <4>[ 30.880462] set sb to 3(1) <4>[ 30.880566] VIDEO0: dst_left, dst_top, dst_width and dst_height is 0.. reset to defaults! <4>[ 30.880571] set current avs auto aspect to 16:9 <4>[ 30.880582] set sb to 3(1) <4>[ 30.880637] set current avs auto aspect to 16:9 <4>[ 30.880647] set sb to 3(1) <4>[ 30.880837] VIDEO1: dst_left, dst_top, dst_width and dst_height is 0.. reset to defaults! <4>[ 30.953214] 2432 ms: 49 <4>[ 30.955251] 2 ms: 50 <4>[ 30.973377] 18 ms: 50 <4>[ 30.979535] 6 ms: 50 <4>[ 31.008708] 29 ms: 50 <4>[ 31.138128] 130 ms: 50 <4>[ 31.256289] 118 ms: 50 <4>[ 31.261146] 5 ms: 50 <4>[ 31.261866] 0 ms: 51 <4>[ 31.262579] 1 ms: 51 <4>[ 32.065841] 803 ms: 51 <4>[ 32.084000] 18 ms: 51 <4>[ 32.084965] 1 ms: 52 <4>[ 32.088425] 4 ms: 52 <4>[ 32.089049] 0 ms: 52 <4>[ 32.089887] 1 ms: 52 <4>[ 32.094390] 5 ms: 52 <4>[ 32.097585] 3 ms: 52 <4>[ 32.105523] 8 ms: 52 <4>[ 32.106289] 1 ms: 52 <4>[ 32.128389] 22 ms: 52 <4>[ 32.436763] 308 ms: 52 <4>[ 32.437914] 1 ms: 53 <4>[ 32.441414] 4 ms: 53 <4>[ 32.443859] 2 ms: 53 <4>[ 32.446697] 3 ms: 53 <4>[ 32.447444] 1 ms: 53 <4>[ 32.448526] 1 ms: 53 <4>[ 32.454699] 6 ms: 53 <4>[ 34.012965] 1558 ms: 53 <4>[ 34.014017] 1 ms: 58 <4>[ 34.031611] 18 ms: 58 <4>[ 34.032291] 1 ms: 58 <4>[ 34.047540] 15 ms: 58 <4>[ 34.048658] 1 ms: 58 <4>[ 34.049581] 1 ms: 58 <4>[ 34.051536] 2 ms: 58 <4>[ 34.056213] 5 ms: 58 <4>[ 34.056769] 0 ms: 58 <4>[ 34.192226] 136 ms: 58 <4>[ 34.255600] 63 ms: 58 <4>[ 34.384355] 129 ms: 58 <4>[ 34.394654] set current avs auto aspect to 16:9 <4>[ 34.394691] set sb to 3(1) <4>[ 34.395553] set current avs auto aspect to 16:9 <4>[ 34.395584] set sb to 3(1) <4>[ 34.396584] set input to 0 <4>[ 34.396592] set fb to 2(-1) <4>[ 34.415395] set current avs auto aspect to 16:9 <4>[ 34.415420] set sb to 3(1) <4>[ 34.435383] using 50.00Hz as MonitorRefreshRate <4>[ 34.435397] VIDEO1: shared window not available <4>[ 34.435422] SET FORMAT: display[0], format='720p50' <4>[ 34.435432] HDMI mode <4>[ 34.435437] use EDID to detect colorspace <4>[ 34.435440] preferred colorspace: Itu_R_BT_709 <4>[ 34.435443] using 8bit output <4>[ 34.556124] VIDEO1: shared window not available <4>[ 34.556156] set current avs auto aspect to 16:9 <4>[ 34.556186] set sb to 3(1) <4>[ 34.557644] set ac3 to downmix <4>[ 34.557977] set ac3plus to use_hdmi_caps <4>[ 34.558376] set dtshd to hdmi_best <4>[ 34.558622] set wmapro to hdmi_best <4>[ 34.558944] set aac to downmix <4>[ 34.656087] using 50.00Hz as MonitorRefreshRate <4>[ 34.656100] VIDEO1: shared window not available <4>[ 34.656187] SET FORMAT: display[0], format='1080i50' <4>[ 34.656204] HDMI mode <4>[ 34.656209] use EDID to detect colorspace <4>[ 34.656214] preferred colorspace: Itu_R_BT_709 <4>[ 34.656219] using 8bit output <4>[ 34.816579] VIDEO1: shared window not available <4>[ 34.835576] set current avs auto aspect to 16:9 <4>[ 34.835859] set current avs auto aspect to 16:9 <4>[ 34.836466] 452 ms: 59 <4>[ 34.839082] set sb to 3(1) <4>[ 34.843787] 7 ms: 59 <4>[ 34.969043] 125 ms: 59 <4>[ 34.989187] 21 ms: 60 <4>[ 34.989873] 0 ms: 60 <4>[ 34.991859] 2 ms: 60 <4>[ 35.030017] 38 ms: 60 <4>[ 35.034909] 5 ms: 60 <4>[ 35.114060] set current avs auto aspect to 16:9 <4>[ 35.114100] set sb to 3(1) <4>[ 35.116179] set current avs auto aspect to 16:9 <4>[ 35.116207] set sb to 3(1) <4>[ 35.117704] set current avs auto aspect to 16:9 <4>[ 35.117730] set sb to 3(1) <4>[ 35.118003] set current avs auto aspect to 16:9 <4>[ 35.118017] set sb to 3(1) <4>[ 40.025003] using 50.00Hz as MonitorRefreshRate <4>[ 40.025018] VIDEO1: shared window not available <4>[ 40.055541] SET FORMAT: display[0], format='1080i50' <4>[ 40.055556] HDMI mode <4>[ 40.055560] use EDID to detect colorspace <4>[ 40.055564] preferred colorspace: Itu_R_BT_709 <4>[ 40.055567] using 8bit output <4>[ 40.175504] VIDEO1: shared window not available <4>[ 40.175539] set current avs auto aspect to 16:9 <4>[ 40.175561] set sb to 3(1) <4>[ 48.449155] set mute 0 1 <6>[ 49.201084] si2166b(0): voltage is disabled now <6>[ 49.329981] si2166b(0): voltage is 13V now <6>[ 50.020570] si2166b(0): tune freq 1319000, sr 29900000, delsys 5 <6>[ 50.069080] si2166b(0): found carrier after 49 ms <6>[ 50.089887] si2166b(0): final lock after 70 ms <4>[ 50.095039] start PCR0 on dmx 0 pid:6e0 index 2 <4>[ 50.095183] audio ch0: clip stuff already freed <4>[ 50.095564] start AUDIO0 on dmx 0 pid:6e1 index 3 <4>[ 50.095570] audio ch0: xpt start <4>[ 50.095578] audio ch0: reset GA threshold to 8ms <4>[ 50.095594] sync0 is off so we enable it <4>[ 50.095599] sync0: allocated pcroffset 0 <4>[ 50.095610] sync0: demux-index 0 pcr pid 6e0 <4>[ 50.095621] sync0: using 2 as pcr pidchannel <4>[ 50.095639] audio ch0: MPEG <4>[ 50.095645] audio ch0: streamtype set 0 <4>[ 50.095648] audio ch0: streamtype_set: stop rave <4>[ 50.095676] audio ch0: add stereo input to mixer DAC <4>[ 50.095694] audio ch0: add stereo input to mixer SPDIF <4>[ 50.095706] audio ch0: add stereo input to mixer MAI <4>[ 50.095718] audio ch0: mai sample rate changed isr (48000) <4>[ 50.095744] audio ch0: streamtype_set: restart rave <4>[ 50.095880] audio ch0: decoder start <4>[ 50.095887] audio ch0: video delay 0 (0 ms) <4>[ 50.095891] VIDEO0: total delay: 0 (0 ms) <4>[ 50.096805] VIDEO0: clip stuff already freed <4>[ 50.096969] start VIDEO0 on dmx 0 pid:6e0 index 2 <4>[ 50.097002] VIDEO0: total delay: 0 (0 ms) <4>[ 50.102071] VIDEO0: start decode <4>[ 50.726080] audio ch0: first pts 0x18df5aee0, stc 0x18df5836e, pcr valid <4>[ 50.748244] audio ch0: lock <4>[ 50.748286] audio ch0: sample rate changed isr (48000) <4>[ 50.891727] VIDEO0: first pts 0x18df5f9c0 <4>[ 50.891752] VIDEO0: resolution changed (720 576) <4>[ 50.894363] audio ch0: status ready isr <4>[ 51.041122] VIDEO0: enable tsm <4>[ 51.111731] VIDEO0: progressive changed (0) <4>[ 51.516815] set current avs auto aspect to 16:9 <4>[ 51.516846] set sb to 3(1) <4>[ 51.517308] set current avs auto aspect to 16:9 <4>[ 51.517342] set sb to 3(1) <4>[ 65.858192] HDMI: CEC recv ok! <4>[ 69.564196] HDMI: CEC recv ok! <4>[ 146.285977] stop PCR0 on dmx 0 pid:6e0 <4>[ 146.286072] stop VIDEO0 on dmx 0 pid:6e0 <4>[ 146.286717] VIDEO0: stop decode <4>[ 146.286866] VIDEO0: set blank to 1 <4>[ 146.286989] audio ch0: decoder stop <4>[ 146.307295] stop AUDIO0 on dmx 0 pid:6e1 <4>[ 146.307303] audio ch0: xpt stop <4>[ 146.307309] sync0 1fff no decoder left in sync handling so disable <4>[ 146.307313] sync0: was last pcroffset0 user so free it <6>[ 152.312182] si2166b(0): voltage is disabled now <6>[ 152.312571] si2166b(0): voltage is disabled now <6>[ 175.412839] si2166b(0): voltage is disabled now <6>[ 175.424754] si2166b(0): voltage is 13V now <6>[ 175.570638] si2166b(0): tune timeout! <6>[ 176.108554] si2166b(0): tune freq 1319000, sr 29900000, delsys 5 <6>[ 176.155083] si2166b(0): found carrier after 46 ms <6>[ 176.155091] si2166b(0): final lock after 46 ms <4>[ 176.158522] start PCR0 on dmx 0 pid:6e0 index 2 <4>[ 176.158597] audio ch0: clip stuff already freed <4>[ 176.158811] start AUDIO0 on dmx 0 pid:6e1 index 3 <4>[ 176.158818] audio ch0: xpt start <4>[ 176.158837] sync0 is off so we enable it <4>[ 176.158843] sync0: allocated pcroffset 0 <4>[ 176.158853] sync0: demux-index 0 pcr pid 6e0 <4>[ 176.158863] sync0: using 2 as pcr pidchannel <4>[ 176.158881] audio ch0: MPEG <4>[ 176.158975] audio ch0: decoder start <4>[ 176.158983] audio ch0: video delay 0 (0 ms) <4>[ 176.158988] VIDEO0: total delay: 0 (0 ms) <4>[ 176.159695] VIDEO0: set blank to 0 <4>[ 176.159706] VIDEO0: clip stuff already freed <4>[ 176.159845] start VIDEO0 on dmx 0 pid:6e0 index 2 <4>[ 176.159878] VIDEO0: total delay: 0 (0 ms) <4>[ 176.164937] VIDEO0: start decode <4>[ 176.294094] audio ch0: first pts 0x18ea21fe0, stc 0x18ea1f480, pcr valid <4>[ 176.317082] audio ch0: lock <4>[ 176.317117] audio ch0: sample rate changed isr (48000) <4>[ 176.462167] audio ch0: status ready isr <4>[ 176.851619] VIDEO0: first pts 0x18ea2e680 <4>[ 177.002128] VIDEO0: enable tsm <4>[ 192.982967] stop PCR0 on dmx 0 pid:6e0 <4>[ 192.983060] stop VIDEO0 on dmx 0 pid:6e0 <4>[ 192.983713] VIDEO0: stop decode <4>[ 192.983859] VIDEO0: set blank to 1 <4>[ 192.983978] audio ch0: decoder stop <4>[ 193.004288] stop AUDIO0 on dmx 0 pid:6e1 <4>[ 193.004295] audio ch0: xpt stop <4>[ 193.004301] sync0 1fff no decoder left in sync handling so disable <4>[ 193.004305] sync0: was last pcroffset0 user so free it <6>[ 196.053501] si2166b(0): voltage is disabled now <6>[ 196.053831] si2166b(0): voltage is disabled now <4>[ 2723.943637] HDMI: CEC recv ok! <4>[ 2724.088077] HDMI: CEC recv ok! <4>[ 2724.233093] HDMI: CEC recv ok! <4>[ 2755.916539] HDMI: CEC recv ok! <4>[ 2777.637588] HDMI: CEC recv ok! <6>[ 5550.014940] si2166b(0): voltage is disabled now <6>[ 5550.029703] si2166b(0): voltage is 13V now <6>[ 5550.713613] si2166b(0): tune freq 1434000, sr 29900000, delsys 5 <6>[ 5550.760063] si2166b(0): found carrier after 46 ms <6>[ 5550.780872] si2166b(0): final lock after 67 ms <4>[ 5930.081926] HDMI: CEC recv ok! <4>[ 5930.226228] HDMI: CEC recv ok! <4>[ 5930.371124] HDMI: CEC recv ok! <4>[ 5959.998924] HDMI: CEC recv ok! <4>[ 5981.730073] HDMI: CEC recv ok! <4>[ 7129.804261] start PCR0 on dmx 1 pid:bd3 index 12 <4>[ 7129.804467] audio ch0: clip stuff already freed <4>[ 7129.804748] start AUDIO0 on dmx 1 pid:bdd index 15 <4>[ 7129.804753] audio ch0: xpt start <4>[ 7129.804772] sync0 is off so we enable it <4>[ 7129.804777] sync0: allocated pcroffset 0 <4>[ 7129.804786] sync0: playback! <4>[ 7129.805030] audio ch0: MPEG <4>[ 7129.805212] audio ch0: decoder start <4>[ 7129.805226] audio ch0 delay 45000 (500 ms) <4>[ 7129.805232] VIDEO0: total delay: 0 (0 ms) <4>[ 7129.805994] VIDEO0: set blank to 0 <4>[ 7129.806003] VIDEO0: clip stuff already freed <4>[ 7129.806225] start VIDEO0 on dmx 1 pid:bd3 index 12 <4>[ 7129.806336] VIDEO0: total delay: 45000 (500 ms) <4>[ 7129.811304] VIDEO0: start decode <4>[ 7129.946768] audio ch0: first pts 0x1e7caadfe, stc 0x2616a824, pcr invalid <4>[ 7129.946790] audio ch0: video_stc is not valid yet.. so ignore audio first pts <4>[ 7129.971880] audio ch0: lock <4>[ 7129.971917] audio ch0: sample rate changed isr (48000) <4>[ 7129.971928] audio ch0: adjusting GA threshold to: 36ms <4>[ 7130.341824] VIDEO0: first pts 0x1e7cc1712 <4>[ 7130.341977] audio ch0: tsm fail <4>[ 7130.342002] audio ch0: pts error 0 PTS 0xe7caadfe, STC 0xe7cbe836, type 0 <4>[ 7130.342016] audio ch0: tsm fail <4>[ 7130.342028] audio ch0: pts error 0 PTS 0xe7cab66e, STC 0xe7cbe838, type 1 <4>[ 7130.342052] audio ch0: tsm fail <4>[ 7130.342061] audio ch0: pts error 0 PTS 0xe7cabede, STC 0xe7cbe83c, type 1 <4>[ 7130.342071] audio ch0: tsm fail <4>[ 7130.342081] audio ch0: pts error 0 PTS 0xe7cac74e, STC 0xe7cbe83e, type 1 <4>[ 7130.342150] audio ch0: tsm fail <4>[ 7130.492102] VIDEO0: enable tsm <4>[ 7139.035291] set mute 1 1 <4>[ 7139.041835] stop PCR0 on dmx 1 pid:bd3 <4>[ 7139.041975] stop VIDEO0 on dmx 1 pid:bd3 <4>[ 7139.044049] VIDEO0: stop decode <4>[ 7139.044317] VIDEO0: set blank to 1 <4>[ 7139.044468] audio ch0: decoder stop <4>[ 7139.055748] stop AUDIO0 on dmx 1 pid:bdd <4>[ 7139.055755] audio ch0: xpt stop <4>[ 7139.055761] sync0 1fff no decoder left in sync handling so disable <4>[ 7139.055764] sync0: was last pcroffset0 user so free it <4>[ 7139.057945] set input to 1 <4>[ 7139.057954] set fb to 2(0) <4>[ 7139.085493] set sb to 3(0) <4>[ 8110.144975] HDMI: CEC recv ok! <4>[ 8143.340421] HDMI: CEC recv ok! <4>[ 8183.202913] HDMI: CEC recv ok! <4>[ 8200.239058] HDMI: CEC recv ok! <4>[ 8796.037571] HDMI: CEC recv ok! <4>[ 8796.181965] HDMI: CEC recv ok! <4>[ 8796.327901] HDMI: CEC recv ok! <4>[ 8826.475366] HDMI: CEC recv ok! <4>[ 8847.807810] HDMI: CEC recv ok! <4>[ 9128.633218] HDMI: CEC recv ok! <4>[ 9128.777468] HDMI: CEC recv ok! <4>[ 9128.922410] HDMI: CEC recv ok! <4>[ 9162.525216] HDMI: CEC recv ok! <4>[ 9183.570762] HDMI: CEC recv ok! <6>[10266.605177] usb 3-1: USB disconnect, device number 2 <3>[10266.669226] FAT-fs (sdb1): unable to read boot sector to mark fs as dirty <4>[10368.506012] HDMI: CEC recv ok! <4>[10368.650457] HDMI: CEC recv ok! <4>[10368.795648] HDMI: CEC recv ok! <4>[10400.841057] HDMI: CEC recv ok! <4>[10420.040058] HDMI: CEC recv ok! <6>[11151.725968] si2166b(0): voltage is disabled now <6>[11151.726408] si2166b(0): voltage is disabled now <4>[12397.206599] HDMI: CEC recv ok! <4>[12400.910700] HDMI: CEC recv ok! <4>[12666.431848] HDMI: CEC recv ok! <4>[12670.136999] HDMI: CEC recv ok! <4>[12829.106495] HDMI: CEC recv ok! <4>[13269.645099] HDMI: force disconnect! <4>[13269.645108] HDMI: now disconnected <4>[13269.645113] HDMI: CEC RX stopped, state is now 0 <4>[13269.645232] HDMI: CEC returned state 0 <4>[13269.645237] HDMI: CEC state now 800 <4>[29924.766936] HDMI: CEC recv ok! <4>[37256.485896] set input to 0 <4>[37256.485905] set fb to 2(-1) <4>[37256.485913] using 50.00Hz as MonitorRefreshRate <4>[37256.485922] VIDEO1: shared window not available <4>[37256.485973] SET FORMAT: display[0], format='1080i50' <4>[37256.486022] VIDEO1: shared window not available <4>[37256.602816] set current avs auto aspect to 16:9 <4>[37256.603056] set mute 0 0 <4>[37256.606879] set sb to 3(1) <4>[37261.974030] HDMI: now connected <4>[37262.002665] HDMI: CEC Physical Addr 2.0.0.0 set from EDID <4>[37262.002670] HDMI: EDID read ok <4>[37262.002717] using 50.00Hz as MonitorRefreshRate <4>[37262.002728] VIDEO1: shared window not available <4>[37262.002752] SET FORMAT: display[0], format='1080i50' <4>[37262.002760] HDMI mode <4>[37262.002764] use EDID to detect colorspace <4>[37262.002768] preferred colorspace: Itu_R_BT_709 <4>[37262.002770] using 8bit output <4>[37262.184033] VIDEO1: shared window not available <4>[37262.184081] set current avs auto aspect to 16:9 <4>[37262.184118] HDMI: CEC start ... state is now 1 <4>[37262.184147] set sb to 3(1) <4>[37262.192524] HDMI: CEC returned state 1 <4>[37262.192531] HDMI: CEC state now 801 <4>[37262.247057] HDMI: CEC use logical address 3 <4>[37262.247076] HDMI: CEC init completed... state is now 3! <4>[37262.247161] HDMI: CEC returned state 3 <4>[37262.247165] HDMI: CEC state now 803 <4>[37262.383855] HDMI: CEC send ok! <6>[37723.782924] si2166b(0): voltage is disabled now <6>[37723.798169] si2166b(0): voltage is 13V now <6>[37724.460803] si2166b(0): tune timeout! <6>[37724.470742] si2166b(0): tune freq 1242125, sr 27500000, delsys 5 <6>[37724.517708] si2166b(0): found carrier after 47 ms <6>[37724.517716] si2166b(0): final lock after 47 ms <4>[37724.603724] start PCR0 on dmx 0 pid:202 index 7 <4>[37724.603805] audio ch0: clip stuff already freed <4>[37724.604124] start AUDIO0 on dmx 0 pid:28c index 8 <4>[37724.604131] audio ch0: xpt start <4>[37724.604143] audio ch0: reset GA threshold to 8ms <4>[37724.604160] sync0 is off so we enable it <4>[37724.604169] sync0: allocated pcroffset 0 <4>[37724.604184] sync0: demux-index 0 pcr pid 202 <4>[37724.604201] sync0: using 7 as pcr pidchannel <4>[37724.604228] audio ch0: MPEG <4>[37724.604380] audio ch0: decoder start <4>[37724.604387] audio ch0: video delay 0 (0 ms) <4>[37724.604393] VIDEO0: total delay: 0 (0 ms) <4>[37724.605023] VIDEO0: set blank to 0 <4>[37724.605037] VIDEO0: clip stuff already freed <4>[37724.605216] start VIDEO0 on dmx 0 pid:202 index 7 <4>[37724.605250] VIDEO0: total delay: 0 (0 ms) <4>[37724.610307] VIDEO0: start decode <4>[37724.694501] audio ch0: first pts 0x244870d2, stc 0x24486534, pcr valid <4>[37724.718398] audio ch0: lock <4>[37724.718441] bcm_audio_decoder_sample_rate_change_isr: 8 callbacks suppressed <4>[37724.718448] audio ch0: sample rate changed isr (48000) <4>[37724.772335] audio ch0: status ready isr <4>[37724.938448] VIDEO0: first pts 0x2448f6ec <4>[37725.088011] VIDEO0: enable tsm <4>[37855.764165] stop PCR0 on dmx 0 pid:202 <4>[37855.764260] stop VIDEO0 on dmx 0 pid:202 <4>[37855.764915] VIDEO0: stop decode <4>[37855.765098] VIDEO0: set blank to 1 <4>[37855.765275] audio ch0: decoder stop <4>[37855.785569] stop AUDIO0 on dmx 0 pid:28c <4>[37855.785577] audio ch0: xpt stop <4>[37855.785587] sync0 1fff no decoder left in sync handling so disable <4>[37855.785593] sync0: was last pcroffset0 user so free it <6>[37855.995051] si2166b(0): tune freq 1319000, sr 29900000, delsys 5 <6>[37856.041769] si2166b(0): found carrier after 46 ms <6>[37856.041781] si2166b(0): final lock after 46 ms <4>[37856.046122] start PCR0 on dmx 0 pid:3d4 index 2 <4>[37856.046227] audio ch0: clip stuff already freed <4>[37856.046556] start AUDIO0 on dmx 0 pid:3d5 index 3 <4>[37856.046565] audio ch0: xpt start <4>[37856.046588] sync0 is off so we enable it <4>[37856.046598] sync0: allocated pcroffset 0 <4>[37856.046613] sync0: demux-index 0 pcr pid 3d4 <4>[37856.046630] sync0: using 2 as pcr pidchannel <4>[37856.046656] audio ch0: MPEG <4>[37856.046797] audio ch0: decoder start <4>[37856.046809] audio ch0: video delay 0 (0 ms) <4>[37856.046818] VIDEO0: total delay: 0 (0 ms) <4>[37856.047751] VIDEO0: set blank to 0 <4>[37856.047765] VIDEO0: clip stuff already freed <4>[37856.048030] start VIDEO0 on dmx 0 pid:3d4 index 2 <4>[37856.048066] VIDEO0: total delay: 0 (0 ms) <4>[37856.053223] VIDEO0: start decode <4>[37857.581473] audio ch0: first pts 0x180550832, stc 0x18054dcbe, pcr valid <4>[37857.603776] audio ch0: lock <4>[37857.603811] audio ch0: sample rate changed isr (48000) <4>[37857.749714] audio ch0: status ready isr <4>[37857.899062] VIDEO0: first pts 0x180557138 <4>[37858.050011] VIDEO0: enable tsm <4>[37865.536927] stop PCR0 on dmx 0 pid:3d4 <4>[37865.537094] stop VIDEO0 on dmx 0 pid:3d4 <4>[37865.537749] VIDEO0: stop decode <4>[37865.537885] VIDEO0: set blank to 1 <4>[37865.538039] audio ch0: decoder stop <4>[37865.558382] stop AUDIO0 on dmx 0 pid:3d5 <4>[37865.558389] audio ch0: xpt stop <4>[37865.558394] sync0 1fff no decoder left in sync handling so disable <4>[37865.558398] sync0: was last pcroffset0 user so free it <6>[37865.781975] si2166b(0): tune freq 1242125, sr 27500000, delsys 5 <6>[37865.828905] si2166b(0): found carrier after 47 ms <6>[37865.828916] si2166b(0): final lock after 47 ms <4>[37865.833139] start PCR0 on dmx 0 pid:208 index 2 <4>[37865.833243] audio ch0: clip stuff already freed <4>[37865.833569] start AUDIO0 on dmx 0 pid:2b2 index 3 <4>[37865.833579] audio ch0: xpt start <4>[37865.833602] sync0 is off so we enable it <4>[37865.833611] sync0: allocated pcroffset 0 <4>[37865.833626] sync0: demux-index 0 pcr pid 208 <4>[37865.833643] sync0: using 2 as pcr pidchannel <4>[37865.833668] audio ch0: MPEG <4>[37865.833809] audio ch0: decoder start <4>[37865.833821] audio ch0: video delay 0 (0 ms) <4>[37865.833830] VIDEO0: total delay: 0 (0 ms) <4>[37865.834526] VIDEO0: set blank to 0 <4>[37865.834541] VIDEO0: clip stuff already freed <4>[37865.834823] start VIDEO0 on dmx 0 pid:208 index 2 <4>[37865.834864] VIDEO0: total delay: 0 (0 ms) <4>[37865.840129] VIDEO0: start decode <4>[37865.939479] audio ch0: first pts 0xa473d54e, stc 0xa473c4f8, pcr valid <4>[37865.977395] audio ch0: lock <4>[37865.977428] audio ch0: sample rate changed isr (48000) <4>[37866.030637] audio ch0: status ready isr <4>[37866.219052] VIDEO0: first pts 0xa4746626 <4>[37866.369012] VIDEO0: enable tsm <4>[38115.267276] stop PCR0 on dmx 0 pid:208 <4>[38115.267366] stop VIDEO0 on dmx 0 pid:208 <4>[38115.268236] VIDEO0: stop decode <4>[38115.268377] VIDEO0: set blank to 1 <4>[38115.268504] audio ch0: decoder stop <4>[38115.288791] stop AUDIO0 on dmx 0 pid:2b2 <4>[38115.288798] audio ch0: xpt stop <4>[38115.288805] sync0 1fff no decoder left in sync handling so disable <4>[38115.288809] sync0: was last pcroffset0 user so free it <6>[38115.488119] si2166b(0): tune freq 1319000, sr 29900000, delsys 5 <6>[38115.534660] si2166b(0): found carrier after 46 ms <6>[38115.534674] si2166b(0): final lock after 46 ms <4>[38115.540587] start PCR0 on dmx 0 pid:712 index 2 <4>[38115.540692] audio ch0: clip stuff already freed <4>[38115.541278] start AUDIO0 on dmx 0 pid:713 index 3 <4>[38115.541288] audio ch0: xpt start <4>[38115.541312] sync0 is off so we enable it <4>[38115.541321] sync0: allocated pcroffset 0 <4>[38115.541336] sync0: demux-index 0 pcr pid 712 <4>[38115.541354] sync0: using 2 as pcr pidchannel <4>[38115.541382] audio ch0: MPEG <4>[38115.541546] audio ch0: decoder start <4>[38115.541558] audio ch0: video delay 0 (0 ms) <4>[38115.541568] VIDEO0: total delay: 0 (0 ms) <4>[38115.542816] VIDEO0: set blank to 0 <4>[38115.542831] VIDEO0: clip stuff already freed <4>[38115.543225] start VIDEO0 on dmx 0 pid:712 index 2 <4>[38115.543265] VIDEO0: total delay: 0 (0 ms) <4>[38115.548421] VIDEO0: start decode <4>[38115.675753] audio ch0: first pts 0x196bf3168, stc 0x196bf0638, pcr valid <4>[38115.697774] audio ch0: lock <4>[38115.697816] audio ch0: sample rate changed isr (48000) <4>[38115.843249] audio ch0: status ready isr <4>[38116.518662] VIDEO0: first pts 0x196c04cba <4>[38116.668008] VIDEO0: enable tsm <4>[38280.055373] stop PCR0 on dmx 0 pid:712 <4>[38280.055466] stop VIDEO0 on dmx 0 pid:712 <4>[38280.056257] VIDEO0: stop decode <4>[38280.056397] VIDEO0: set blank to 1 <4>[38280.056520] audio ch0: decoder stop <4>[38280.076811] stop AUDIO0 on dmx 0 pid:713 <4>[38280.076818] audio ch0: xpt stop <4>[38280.076825] sync0 1fff no decoder left in sync handling so disable <4>[38280.076829] sync0: was last pcroffset0 user so free it <6>[38280.300895] si2166b(0): tune freq 1242125, sr 27500000, delsys 5 <6>[38280.347938] si2166b(0): found carrier after 47 ms <6>[38280.347951] si2166b(0): final lock after 47 ms <4>[38280.352281] start PCR0 on dmx 0 pid:208 index 2 <4>[38280.352385] audio ch0: clip stuff already freed <4>[38280.353482] start AUDIO0 on dmx 0 pid:2b2 index 3 <4>[38280.353493] audio ch0: xpt start <4>[38280.353516] sync0 is off so we enable it <4>[38280.353525] sync0: allocated pcroffset 0 <4>[38280.353539] sync0: demux-index 0 pcr pid 208 <4>[38280.353556] sync0: using 2 as pcr pidchannel <4>[38280.353616] audio ch0: MPEG <4>[38280.353774] audio ch0: decoder start <4>[38280.353785] audio ch0: video delay 0 (0 ms) <4>[38280.353793] VIDEO0: total delay: 0 (0 ms) <4>[38280.354912] VIDEO0: set blank to 0 <4>[38280.354927] VIDEO0: clip stuff already freed <4>[38280.355307] start VIDEO0 on dmx 0 pid:208 index 2 <4>[38280.355346] VIDEO0: total delay: 0 (0 ms) <4>[38280.360549] VIDEO0: start decode <4>[38280.406068] audio ch0: first pts 0xa6ad007e, stc 0xa6acf41c, pcr valid <4>[38280.429616] audio ch0: lock <4>[38280.429645] audio ch0: sample rate changed isr (48000) <4>[38280.485932] audio ch0: status ready isr <4>[38280.878488] VIDEO0: first pts 0xa6adee26 <4>[38281.028008] VIDEO0: enable tsm <4>[38339.986116] stop PCR0 on dmx 0 pid:208 <4>[38339.986211] stop VIDEO0 on dmx 0 pid:208 <4>[38339.986861] VIDEO0: stop decode <4>[38339.987004] VIDEO0: set blank to 1 <4>[38339.987183] audio ch0: decoder stop <4>[38340.007618] stop AUDIO0 on dmx 0 pid:2b2 <4>[38340.007626] audio ch0: xpt stop <4>[38340.007633] sync0 1fff no decoder left in sync handling so disable <4>[38340.007638] sync0: was last pcroffset0 user so free it <6>[38340.213832] si2166b(0): tune freq 1664000, sr 27500000, delsys 6 <6>[38340.303543] si2166b(0): found carrier after 90 ms <6>[38340.303551] si2166b(0): final lock after 90 ms <4>[38340.625222] start PCR0 on dmx 0 pid:12d index 4 <4>[38340.625292] audio ch0: clip stuff already freed <4>[38340.625506] start AUDIO0 on dmx 0 pid:137 index 7 <4>[38340.625512] audio ch0: xpt start <4>[38340.625532] sync0 is off so we enable it <4>[38340.625537] sync0: allocated pcroffset 0 <4>[38340.625547] sync0: demux-index 0 pcr pid 12d <4>[38340.625558] sync0: using 4 as pcr pidchannel <4>[38340.625575] audio ch0: MPEG <4>[38340.625756] audio ch0: decoder start <4>[38340.625763] audio ch0: video delay 0 (0 ms) <4>[38340.625769] VIDEO0: total delay: 0 (0 ms) <4>[38340.626499] VIDEO0: set blank to 0 <4>[38340.626509] VIDEO0: clip stuff already freed <4>[38340.626576] VIDEO0: set streamtype! <4>[38340.626590] -> set H264 <4>[38340.626593] -> sw rave disabled! <4>[38340.626610] audio ch0 delay 18000 (200 ms) <4>[38340.626619] VIDEO0: total delay: 18000 (200 ms) <4>[38340.626748] start VIDEO0 on dmx 0 pid:12d index 4 <4>[38340.626781] VIDEO0: total delay: 18000 (200 ms) <4>[38340.631811] VIDEO0: start decode <4>[38340.888757] audio ch0: first pts 0x118b50b36, stc 0x118b4f0f4, pcr valid <4>[38340.911250] audio ch0: lock <4>[38340.911282] audio ch0: sample rate changed isr (48000) <4>[38340.911297] audio ch0: tsm fail <4>[38340.911303] audio ch0: pts error 0 PTS 0x18b50b36, STC 0x18b4f8e2, type 0 <4>[38341.232149] audio ch0: status ready isr <4>[38342.998393] VIDEO0: first pts 0x118b80360 <4>[38342.998420] VIDEO0: resolution changed (1920 1080) <4>[38343.148011] VIDEO0: enable tsm <4>[38343.404143] set current avs auto aspect to 16:9 <4>[38343.404172] set sb to 3(1) <4>[38343.404564] set current avs auto aspect to 16:9 <4>[38343.404585] set sb to 3(1) <4>[38359.849813] stop PCR0 on dmx 0 pid:12d <4>[38359.849904] stop VIDEO0 on dmx 0 pid:12d <4>[38359.850640] VIDEO0: stop decode <4>[38359.850789] VIDEO0: set blank to 1 <4>[38359.850916] audio ch0: decoder stop <4>[38359.871383] stop AUDIO0 on dmx 0 pid:137 <4>[38359.871392] audio ch0: xpt stop <4>[38359.871398] sync0 1fff no decoder left in sync handling so disable <4>[38359.871403] sync0: was last pcroffset0 user so free it <6>[38360.097022] si2166b(0): tune freq 1242125, sr 27500000, delsys 5 <6>[38360.144692] si2166b(0): found carrier after 48 ms <6>[38360.144701] si2166b(0): final lock after 48 ms <4>[38360.148446] start PCR0 on dmx 0 pid:202 index 2 <4>[38360.148552] audio ch0: clip stuff already freed <4>[38360.148885] start AUDIO0 on dmx 0 pid:28c index 3 <4>[38360.148895] audio ch0: xpt start <4>[38360.148918] sync0 is off so we enable it <4>[38360.148928] sync0: allocated pcroffset 0 <4>[38360.148943] sync0: demux-index 0 pcr pid 202 <4>[38360.148960] sync0: using 2 as pcr pidchannel <4>[38360.149020] audio ch0: MPEG <4>[38360.149174] audio ch0: decoder start <4>[38360.149186] audio ch0 delay 18000 (200 ms) <4>[38360.149192] VIDEO0: total delay: 18000 (200 ms) <4>[38360.149900] VIDEO0: set blank to 0 <4>[38360.149914] VIDEO0: clip stuff already freed <4>[38360.150022] VIDEO0: set streamtype! <4>[38360.150029] -> set MPEG2 <4>[38360.150033] -> sw rave disabled! <4>[38360.150050] audio ch0: video delay 0 (0 ms) <4>[38360.150055] VIDEO0: total delay: 0 (0 ms) <4>[38360.150197] start VIDEO0 on dmx 0 pid:202 index 2 <4>[38360.150231] VIDEO0: total delay: 0 (0 ms) <4>[38360.155374] VIDEO0: start decode <4>[38360.340801] audio ch0: first pts 0x27b16472, stc 0x27b1518a, pcr valid <4>[38360.385333] audio ch0: lock <4>[38360.385362] audio ch0: sample rate changed isr (48000) <4>[38360.438371] VIDEO0: first pts 0x27b1b7ec <4>[38360.438401] VIDEO0: resolution changed (720 576) <4>[38360.439337] audio ch0: status ready isr <4>[38360.588011] VIDEO0: enable tsm <4>[38361.568385] set current avs auto aspect to 16:9 <4>[38361.568417] set sb to 3(1) <4>[38361.568792] set current avs auto aspect to 16:9 <4>[38361.568811] set sb to 3(1) <4>[38559.258166] stop PCR0 on dmx 0 pid:202 <4>[38559.258258] stop VIDEO0 on dmx 0 pid:202 <4>[38559.258915] VIDEO0: stop decode <4>[38559.259090] VIDEO0: set blank to 1 <4>[38559.259454] audio ch0: decoder stop <4>[38559.279746] stop AUDIO0 on dmx 0 pid:28c <4>[38559.279753] audio ch0: xpt stop <4>[38559.279759] sync0 1fff no decoder left in sync handling so disable <4>[38559.279763] sync0: was last pcroffset0 user so free it <4>[38559.297759] start PCR0 on dmx 0 pid:208 index 1 <4>[38559.297829] audio ch0: clip stuff already freed <4>[38559.298299] start AUDIO0 on dmx 0 pid:2b2 index 2 <4>[38559.298306] audio ch0: xpt start <4>[38559.298325] sync0 is off so we enable it <4>[38559.298330] sync0: allocated pcroffset 0 <4>[38559.298341] sync0: demux-index 0 pcr pid 208 <4>[38559.298351] sync0: using 1 as pcr pidchannel <4>[38559.298370] audio ch0: MPEG <4>[38559.298492] audio ch0: decoder start <4>[38559.298500] audio ch0: video delay 0 (0 ms) <4>[38559.298504] VIDEO0: total delay: 0 (0 ms) <4>[38559.299142] VIDEO0: set blank to 0 <4>[38559.299153] VIDEO0: clip stuff already freed <4>[38559.299536] start VIDEO0 on dmx 0 pid:208 index 1 <4>[38559.299572] VIDEO0: total delay: 0 (0 ms) <4>[38559.304644] VIDEO0: start decode <4>[38559.322433] audio ch0: first pts 0xa82c0d1e, stc 0x9063f27c, pcr invalid <4>[38559.356957] audio ch0: lock <4>[38559.357005] audio ch0: sample rate changed isr (48000) <4>[38559.357025] audio ch0: tsm fail <4>[38559.357035] audio ch0: pts error 0 PTS 0xa82c0d1e, STC 0xa82c08fa, type 0 <4>[38559.437546] audio ch0: status ready isr <4>[38559.779306] VIDEO0: first pts 0xa82ce9e6 <4>[38559.929010] VIDEO0: enable tsm