openATV Enigma2 crash log
crashdate=ط± ظ†ظˆظپ 13 01:23:39 2019
compiledate=Mar 28 2019
skin=MetrixHD/skin.MySkin.xml
sourcedate=2019-03-27
branch=6.3
rev=2.8.0-20227-ga25cd1f
component=Enigma2
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 openATV for %h
9-11-13 01:23:21+0300 [-] action -> SetupActions right
01:23:21.8111 [ ] driver/rcinput.cpp:38 handleCode [eRCDeviceInputDev] 0 6a 1
01:23:21.8138 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:21+0300 [-] KEY: 106 1 KEY_RIGHT RIGHT
01:23:24.7386 [ ] driver/rcinput.cpp:38 handleCode [eRCDeviceInputDev] 1 67 1
01:23:24.7413 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:24+0300 [-] KEY: 103 0 KEY_UP UP
01:23:24.7421 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:24+0300 [-] action -> SetupActions up
01:23:24.7425 { W } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:24+0300 [-] unknown action SetupActions/up! typo in keymap?
01:23:24.7429 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:24+0300 [-] action -> SetupActions up
01:23:24.7433 { W } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:24+0300 [-] unknown action SetupActions/up! typo in keymap?
01:23:24.7451 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:24+0300 [-] [SKIN] processing screen NumericalTextInputHelpDialog:
01:23:24.7507 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:24+0300 [-] [SCREENNAME] NumericalTextInputHelpDialog
01:23:24.9347 [ ] driver/rcinput.cpp:38 handleCode [eRCDeviceInputDev] 0 67 1
01:23:24.9373 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:24+0300 [-] KEY: 103 1 KEY_UP UP
01:23:26.5298 [ ] driver/rcinput.cpp:38 handleCode [eRCDeviceInputDev] 1 6c 1
01:23:26.5323 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:26+0300 [-] KEY: 108 0 KEY_DOWN DOWN
01:23:26.5331 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:26+0300 [-] action -> SetupActions down
01:23:26.5335 { W } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:26+0300 [-] unknown action SetupActions/down! typo in keymap?
01:23:26.5340 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:26+0300 [-] action -> SetupActions down
01:23:26.5344 { W } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:26+0300 [-] unknown action SetupActions/down! typo in keymap?
01:23:26.7723 [ ] driver/rcinput.cpp:38 handleCode [eRCDeviceInputDev] 0 6c 1
01:23:26.7749 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:26+0300 [-] KEY: 108 1 KEY_DOWN DOWN
01:23:29.1900 [ ] driver/rcinput.cpp:38 handleCode [eRCDeviceInputDev] 1 18f 1
01:23:29.1925 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:29+0300 [-] KEY: 399 0 KEY_GREEN GREEN
01:23:29.3594 [ ] driver/rcinput.cpp:38 handleCode [eRCDeviceInputDev] 0 18f 1
01:23:29.3620 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:29+0300 [-] KEY: 399 1 KEY_GREEN GREEN
01:23:29.3627 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:29+0300 [-] action -> SetupActions save
01:23:29.3703 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:29+0300 [-] [SKIN] Parsing embedded skin <embedded-in-'JediMakerXtream_ChooseBouquets'>
01:23:29.3718 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:29+0300 [-] [SKIN] processing screen <embedded-in-'JediMakerXtream_ChooseBouquets'>:
01:23:29.3951 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:29+0300 [-] [SKIN] processing screen SimpleSummary:
01:23:29.3975 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:29+0300 [-] [SCREENNAME] ['JediMakerXtream_ChooseBouquets_summary', 'SimpleSummary']
01:23:29.4183 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:29+0300 [-] [SKIN] Parsing embedded skin <embedded-in-'JediMakerXtream_BuildBouquets'>
01:23:29.4195 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:29+0300 [-] [SKIN] processing screen <embedded-in-'JediMakerXtream_BuildBouquets'>:
01:23:29.4236 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:29+0300 [-] [SKIN] processing screen SimpleSummary:
01:23:29.4256 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:29+0300 [-] [SCREENNAME] ['JediMakerXtream_BuildBouquets_summary', 'SimpleSummary']
01:23:29.4282 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:29+0300 [-] [SCREENNAME] JediMakerXtream_BuildBouquets
01:23:29.4339 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:29+0300 [-]
01:23:29.4348 { E } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:29+0300 [-] lzma failed
01:23:33.4790 [ ] gdi/grc.cpp:211 thread [gRC] main thread is non-idle! display spinner!
01:23:33.6385 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:33+0300 [-] StackTrace
01:23:33.6422 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:33+0300 [-] ========== Stacktrace of active Python threads ===========
01:23:33.6427 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:33+0300 [-] ========== Thread ID 0x-518ffc00 =========================
01:23:33.6432 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:33+0300 [-] File: "/usr/lib/python2.7/threading.py", line 774, in __bootstrap
01:23:33.6436 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:33+0300 [-] File: "/usr/lib/python2.7/threading.py", line 801, in __bootstrap_inner
01:23:33.6439 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:33+0300 [-] File: "/usr/lib/python2.7/threading.py", line 754, in run
01:23:33.6443 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:33+0300 [-] File: "/usr/lib/python2.7/site-packages/twisted/_threads/_threadworker.py", line 45, in work
01:23:33.6447 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:33+0300 [-] File: "/usr/lib/python2.7/Queue.py", line 168, in get
01:23:33.6451 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:33+0300 [-] File: "/usr/lib/python2.7/threading.py", line 340, in wait
01:23:33.6455 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:33+0300 [-] ========== Thread ID 0x-510fec00 =========================
01:23:33.6458 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:33+0300 [-] File: "/usr/lib/python2.7/threading.py", line 774, in __bootstrap
01:23:33.6462 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:33+0300 [-] File: "/usr/lib/python2.7/threading.py", line 801, in __bootstrap_inner
01:23:33.6466 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:33+0300 [-] File: "/usr/lib/python2.7/threading.py", line 754, in run
01:23:33.6470 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:33+0300 [-] File: "/usr/lib/python2.7/site-packages/twisted/_threads/_threadworker.py", line 45, in work
01:23:33.6473 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:33+0300 [-] File: "/usr/lib/python2.7/Queue.py", line 168, in get
01:23:33.6477 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:33+0300 [-] File: "/usr/lib/python2.7/threading.py", line 340, in wait
01:23:33.6481 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:33+0300 [-] ========== MainThread 0x-4a2cff90 =========================
01:23:33.6484 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:33+0300 [-] File: "/usr/lib/enigma2/python/mytest.py", line 875, in <module>
01:23:33.6488 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:33+0300 [-] runScreenTest()
01:23:33.6492 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:33+0300 [-] File: "/usr/lib/enigma2/python/mytest.py", line 646, in runScreenTest
01:23:33.6497 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:33+0300 [-] runReactor()
01:23:33.6502 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:33+0300 [-] File: "/usr/lib/enigma2/python/mytest.py", line 121, in runReactor
01:23:33.6506 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:33+0300 [-] reactor.run(installSignalHandlers=False)
01:23:33.6510 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:33+0300 [-] File: "/usr/lib/python2.7/site-packages/twisted/internet/base.py", line 1261, in run
01:23:33.6514 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:33+0300 [-] File: "/usr/lib/python2.7/site-packages/twisted/internet/base.py", line 1273, in mainLoop
01:23:33.6518 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:33+0300 [-] File: "/usr/lib/enigma2/python/e2reactor.py", line 150, in doPoll
01:23:33.6522 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:33+0300 [-] File: "/usr/lib/enigma2/python/e2reactor.py", line 40, in poll
01:23:33.6525 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:33+0300 [-] File: "/usr/lib/enigma2/python/Plugins/Extensions/JediMakerXtream/buildbouquet.py", line 122, in downloadrytec
01:23:33.6530 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:33+0300 [-] self.rytec_ref, self.epg_alias_names, self.rytec_allrefs = downloads.downloadrytec()
01:23:33.6534 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:33+0300 [-] File: "/usr/lib/enigma2/python/Plugins/Extensions/JediMakerXtream/downloads.py", line 422, in downloadrytec
01:23:33.6537 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:33+0300 [-] response = urllib2.urlopen(req)
01:23:33.6541 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:33+0300 [-] File: "/usr/lib/python2.7/urllib2.py", line 155, in urlopen
01:23:33.6545 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:33+0300 [-] File: "/usr/lib/python2.7/urllib2.py", line 430, in open
01:23:33.6549 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:33+0300 [-] File: "/usr/lib/python2.7/urllib2.py", line 448, in _open
01:23:33.6553 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:33+0300 [-] File: "/usr/lib/python2.7/urllib2.py", line 408, in _call_chain
01:23:33.6556 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:33+0300 [-] File: "/usr/lib/python2.7/urllib2.py", line 1229, in http_open
01:23:33.6560 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:33+0300 [-] File: "/usr/lib/python2.7/urllib2.py", line 1196, in do_open
01:23:33.6564 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:33+0300 [-] File: "/usr/lib/python2.7/httplib.py", line 1042, in request
01:23:33.6568 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:33+0300 [-] File: "/usr/lib/python2.7/httplib.py", line 1082, in _send_request
01:23:33.6571 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:33+0300 [-] File: "/usr/lib/python2.7/httplib.py", line 1038, in endheaders
01:23:33.6575 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:33+0300 [-] File: "/usr/lib/python2.7/httplib.py", line 882, in _send_output
01:23:33.6579 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:33+0300 [-] File: "/usr/lib/python2.7/httplib.py", line 844, in send
01:23:33.6582 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:33+0300 [-] File: "/usr/lib/python2.7/httplib.py", line 821, in connect
01:23:33.6586 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:33+0300 [-] File: "/usr/lib/python2.7/socket.py", line 566, in create_connection
01:23:33.6590 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:33+0300 [-] File: "/usr/lib/python2.7/socket.py", line 228, in meth
01:23:33.6593 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:33+0300 [-] ========== Thread ID 0x-52100c00 =========================
01:23:33.6597 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:33+0300 [-] File: "/usr/lib/python2.7/threading.py", line 774, in __bootstrap
01:23:33.6601 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:33+0300 [-] File: "/usr/lib/python2.7/threading.py", line 801, in __bootstrap_inner
01:23:33.6604 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:33+0300 [-] File: "/usr/lib/python2.7/threading.py", line 1071, in run
01:23:33.6608 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:33+0300 [-] File: "/usr/lib/python2.7/threading.py", line 614, in wait
01:23:33.6612 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:33+0300 [-] File: "/usr/lib/python2.7/threading.py", line 359, in wait
01:23:33.6615 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:33+0300 [-] ========== Stacktrace end ================================
01:23:39.6980 { E } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:39+0300 [-] <urlopen error timed out>
01:23:39.6987 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:39+0300 [-] Traceback (most recent call last):
01:23:39.6991 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:39+0300 [-] File "/usr/lib/enigma2/python/Plugins/Extensions/JediMakerXtream/buildbouquet.py", line 122, in downloadrytec
01:23:39.6997 { } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:39+0300 [-] self.rytec_ref, self.epg_alias_names, self.rytec_allrefs = downloads.downloadrytec()
01:23:39.7001 { E } /usr/lib/python2.7/site-packages/twisted/python/util.py:815 untilConcludes 2019-11-13 01:23:39+0300 [-] TypeError: 'NoneType' object is not iterable
01:23:39.7002 [ E ] python/python.cpp:210 call [ePyObject] (PyObject_CallObject(<bound method JediMakerXtream_BuildBouquets.downloadrytec of <class 'Plugins.Extensions.JediMakerXtream.buildbouquet.JediMakerXtream_BuildBouquets'>>,()) failed)
dmesg
<4>[ 104.629531] VIDEO0: dst_left, dst_top, dst_width and dst_height is 0.. reset to defaults!
<4>[ 104.629593] set current avs auto aspect to 16:9
<4>[ 104.633913] set sb to 3(1)
<4>[ 120.490130] audio ch0: tsm fail
<4>[ 120.490150] audio ch0: pts error 0 PTS 0x9cfc8f7c, STC 0x9cfcfa20, type 0
<4>[ 120.490163] audio ch0: tsm fail
<4>[ 120.490169] audio ch0: pts error 0 PTS 0x9cfc96fc, STC 0x9cfcfa22, type 1
<4>[ 120.490177] audio ch0: tsm fail
<4>[ 120.490182] audio ch0: pts error 0 PTS 0x9cfc9e7c, STC 0x9cfcfa24, type 1
<4>[ 146.753075] audio ch0: tsm fail
<4>[ 146.753097] audio ch0: pts error 0 PTS 0x9d209ffc, STC 0x9d210a74, type 0
<4>[ 146.753109] audio ch0: tsm fail
<4>[ 146.753115] audio ch0: pts error 0 PTS 0x9d20a77c, STC 0x9d210a76, type 1
<4>[ 146.753127] audio ch0: tsm fail
<4>[ 146.753132] audio ch0: pts error 0 PTS 0x9d20aefc, STC 0x9d210a78, type 1
<4>[ 175.385910] audio ch0: tsm fail
<4>[ 175.385921] audio ch0: pts error 0 PTS 0x9d47f0fc, STC 0x9d485c0a, type 0
<4>[ 175.385935] audio ch0: tsm fail
<4>[ 175.385940] audio ch0: pts error 0 PTS 0x9d47f87c, STC 0x9d485c0c, type 1
<4>[ 175.385961] audio ch0: tsm fail
<4>[ 175.385965] audio ch0: pts error 0 PTS 0x9d47fffc, STC 0x9d485c0e, type 1
<4>[ 177.951767] audio ch0: tsm fail
<4>[ 177.951789] audio ch0: pts error 0 PTS 0x9d4b74fc, STC 0x9d4be184, type 0
<4>[ 177.951801] audio ch0: tsm fail
<4>[ 177.951807] audio ch0: pts error 0 PTS 0x9d4b7c7c, STC 0x9d4be186, type 1
<4>[ 211.058259] VIDEO0: dst_left, dst_top, dst_width and dst_height is 0.. reset to defaults!
<4>[ 211.058322] set current avs auto aspect to 16:9
<4>[ 211.060462] set sb to 3(1)
<4>[ 258.321923] bcm_audio_decoder_tsm_fail_isr: 2 callbacks suppressed
<4>[ 258.321940] audio ch0: tsm fail
<4>[ 258.321948] audio ch0: pts error 0 PTS 0x9db9d17c, STC 0x9dba4686, type 0
<4>[ 258.321960] audio ch0: tsm fail
<4>[ 258.321965] audio ch0: pts error 0 PTS 0x9db9d8fc, STC 0x9dba4686, type 1
<4>[ 258.321973] audio ch0: tsm fail
<4>[ 258.321978] audio ch0: pts error 0 PTS 0x9db9e07c, STC 0x9dba4688, type 1
<4>[ 258.322014] audio ch0: tsm fail
<4>[ 258.322019] audio ch0: pts error 0 PTS 0x9db9e7fc, STC 0x9dba468c, type 1
<4>[ 258.343246] audio ch0: tsm fail
<4>[ 258.343263] audio ch0: pts error 0 PTS 0x9db9ef7c, STC 0x9dba4e04, type 1
<4>[ 265.805318] bcm_audio_decoder_tsm_fail_isr: 6 callbacks suppressed
<4>[ 265.805337] audio ch0: tsm fail
<4>[ 265.805344] audio ch0: pts error 0 PTS 0x9dc4217c, STC 0x9dc48dce, type 0
<4>[ 265.805356] audio ch0: tsm fail
<4>[ 265.805362] audio ch0: pts error 0 PTS 0x9dc428fc, STC 0x9dc48dce, type 1
<4>[ 265.805370] audio ch0: tsm fail
<4>[ 265.805375] audio ch0: pts error 0 PTS 0x9dc4307c, STC 0x9dc48dd0, type 1
<4>[ 269.571738] audio ch0: tsm fail
<4>[ 269.571760] audio ch0: pts error 0 PTS 0x9dc9497c, STC 0x9dc9b81e, type 0
<4>[ 269.571773] audio ch0: tsm fail
<4>[ 269.571778] audio ch0: pts error 0 PTS 0x9dc950fc, STC 0x9dc9b820, type 1
<4>[ 271.439827] bcm_audio_decoder_tsm_fail_isr: 2 callbacks suppressed
<4>[ 271.439835] audio ch0: tsm fail
<4>[ 271.439842] audio ch0: pts error 0 PTS 0x9dcbdd7c, STC 0x9dcc4a8a, type 0
<4>[ 271.439855] audio ch0: tsm fail
<4>[ 271.439874] audio ch0: pts error 0 PTS 0x9dcbe4fc, STC 0x9dcc4a8a, type 1
<4>[ 271.439883] audio ch0: tsm fail
<4>[ 271.439888] audio ch0: pts error 0 PTS 0x9dcbec7c, STC 0x9dcc4a8e, type 1
<4>[ 273.012170] VIDEO0: dst_left, dst_top, dst_width and dst_height is 0.. reset to defaults!
<4>[ 273.012304] set current avs auto aspect to 16:9
<4>[ 273.012496] set sb to 3(1)
<4>[ 277.029672] audio ch0: tsm fail
<4>[ 277.029694] audio ch0: pts error 0 PTS 0x9dd38a7c, STC 0x9dd3f612, type 0
<4>[ 277.029708] audio ch0: tsm fail
<4>[ 277.029713] audio ch0: pts error 0 PTS 0x9dd391fc, STC 0x9dd3f614, type 1
<4>[ 277.029726] audio ch0: tsm fail
<4>[ 277.029730] audio ch0: pts error 0 PTS 0x9dd3997c, STC 0x9dd3f616, type 1
<4>[ 279.929700] audio ch0: tsm fail
<4>[ 279.929722] audio ch0: pts error 0 PTS 0x9dd7867c, STC 0x9dd7f36e, type 0
<4>[ 279.929736] audio ch0: tsm fail
<4>[ 279.929741] audio ch0: pts error 0 PTS 0x9dd78dfc, STC 0x9dd7f370, type 1
<4>[ 287.180647] stop PCR0 on dmx 1 pid:60c
<4>[ 287.180739] stop VIDEO0 on dmx 1 pid:60c
<4>[ 287.181490] VIDEO0: stop decode
<4>[ 287.181630] VIDEO0: set blank to 1
<4>[ 287.181747] audio ch0: decoder stop
<4>[ 287.201992] stop AUDIO0 on dmx 1 pid:60d
<4>[ 287.201999] audio ch0: xpt stop
<4>[ 287.202005] sync0 1fff no decoder left in sync handling so disable
<4>[ 287.202009] sync0: was last pcroffset0 user so free it
<4>[ 287.219411] start PCR0 on dmx 1 pid:600 index 3
<4>[ 287.219481] audio ch0: clip stuff already freed
<4>[ 287.219708] start AUDIO0 on dmx 1 pid:601 index 5
<4>[ 287.219713] audio ch0: xpt start
<4>[ 287.219734] sync0 is off so we enable it
<4>[ 287.219739] sync0: allocated pcroffset 0
<4>[ 287.219750] sync0: demux-index 1 pcr pid 600
<4>[ 287.219760] sync0: using 3 as pcr pidchannel
<4>[ 287.219777] audio ch0: AAC ADTS
<4>[ 287.219914] audio ch0: decoder start
<4>[ 287.219927] audio ch0 delay 18000 (200 ms)
<4>[ 287.219936] VIDEO0: total delay: 18000 (200 ms)
<4>[ 287.220635] VIDEO0: set blank to 0
<4>[ 287.220645] VIDEO0: clip stuff already freed
<4>[ 287.220806] start VIDEO0 on dmx 1 pid:600 index 3
<4>[ 287.220841] VIDEO0: total delay: 18000 (200 ms)
<4>[ 287.225908] VIDEO0: start decode
<4>[ 288.357143] audio ch0: first pts 0xc4a408e8, stc 0xc4a34ba8, pcr valid
<4>[ 288.357185] audio ch0: lock
<4>[ 288.357209] bcm_audio_decoder_sample_rate_change_isr: 2 callbacks suppressed
<4>[ 288.357214] audio ch0: sample rate changed isr (48000)
<4>[ 288.357229] audio ch0: tsm fail
<4>[ 288.357235] audio ch0: pts error 0 PTS 0xc4a408e8, STC 0xc4a34bb0, type 0
<4>[ 288.357239] audio ch0: tsm fail
<4>[ 288.357243] audio ch0: pts error 0 PTS 0xc4a41068, STC 0xc4a34bb2, type 1
<4>[ 289.215752] VIDEO0: first pts 0xc4a55ea0
<4>[ 289.215779] VIDEO0: framerate changed 5
<4>[ 289.216540] set current avs auto aspect to 16:9
<4>[ 289.216577] set sb to 3(1)
<4>[ 289.365892] VIDEO0: enable tsm
<4>[ 304.558677] stop PCR0 on dmx 1 pid:600
<4>[ 304.558763] stop VIDEO0 on dmx 1 pid:600
<4>[ 304.559594] VIDEO0: stop decode
<4>[ 304.559734] VIDEO0: set blank to 1
<4>[ 304.559854] audio ch0: decoder stop
<4>[ 304.580195] stop AUDIO0 on dmx 1 pid:601
<4>[ 304.580203] audio ch0: xpt stop
<4>[ 304.580209] sync0 1fff no decoder left in sync handling so disable
<4>[ 304.580213] sync0: was last pcroffset0 user so free it
<6>[ 304.655306] si2166b(1): voltage is 18V now
<6>[ 304.911524] si2166b(1): tune freq 1434000, sr 27500000, delsys 5
<6>[ 304.998572] si2166b(1): found carrier after 87 ms
<6>[ 305.019576] si2166b(1): final lock after 108 ms
<4>[ 305.022893] start PCR0 on dmx 1 pid:83 index 5
<4>[ 305.022964] audio ch0: clip stuff already freed
<4>[ 305.023171] start AUDIO0 on dmx 1 pid:2a8 index 6
<4>[ 305.023177] audio ch0: xpt start
<4>[ 305.023196] sync0 is off so we enable it
<4>[ 305.023202] sync0: allocated pcroffset 0
<4>[ 305.023212] sync0: demux-index 1 pcr pid 83
<4>[ 305.023222] sync0: using 5 as pcr pidchannel
<4>[ 305.023240] audio ch0: MPEG
<4>[ 305.023246] audio ch0: streamtype set 0
<4>[ 305.023249] audio ch0: streamtype_set: stop rave
<4>[ 305.023285] audio ch0: streamtype_set: restart rave
<4>[ 305.023391] audio ch0: decoder start
<4>[ 305.023397] audio ch0 delay 18000 (200 ms)
<4>[ 305.023402] VIDEO0: total delay: 18000 (200 ms)
<4>[ 305.024024] VIDEO0: set blank to 0
<4>[ 305.024038] VIDEO0: clip stuff already freed
<4>[ 305.024107] VIDEO0: set streamtype!
<4>[ 305.024116] -> set MPEG2
<4>[ 305.024123] -> sw rave disabled!
<4>[ 305.024138] audio ch0: video delay 0 (0 ms)
<4>[ 305.024143] VIDEO0: total delay: 0 (0 ms)
<4>[ 305.024300] start VIDEO0 on dmx 1 pid:203 index 9
<4>[ 305.024339] VIDEO0: total delay: 0 (0 ms)
<4>[ 305.029413] VIDEO0: start decode
<4>[ 312.467827] stop PCR0 on dmx 1 pid:83
<4>[ 312.468014] stop VIDEO0 on dmx 1 pid:203
<4>[ 312.468030] VIDEO0 use saved pts 0xc4b93c90 (ret 0)
<4>[ 312.468634] VIDEO0: stop decode
<4>[ 312.468764] VIDEO0: set blank to 1
<4>[ 312.468910] audio ch0: use saved pts 0xc4b92fe8 (0xc00e0226) (ret 1)
<4>[ 312.468933] audio ch0: decoder stop
<4>[ 312.473684] stop AUDIO0 on dmx 1 pid:2a8
<4>[ 312.473691] audio ch0: xpt stop
<4>[ 312.473697] sync0 1fff no decoder left in sync handling so disable
<4>[ 312.473700] sync0: was last pcroffset0 user so free it
<4>[ 312.490846] start PCR0 on dmx 1 pid:84 index 5
<4>[ 312.491026] audio ch0: clip stuff already freed
<4>[ 312.491263] start AUDIO0 on dmx 1 pid:2b2 index 6
<4>[ 312.491268] audio ch0: xpt start
<4>[ 312.491288] sync0 is off so we enable it
<4>[ 312.491293] sync0: allocated pcroffset 0
<4>[ 312.491304] sync0: demux-index 1 pcr pid 84
<4>[ 312.491315] sync0: using 5 as pcr pidchannel
<4>[ 312.491333] audio ch0: MPEG
<4>[ 312.491423] audio ch0: decoder start
<4>[ 312.491430] audio ch0: video delay 0 (0 ms)
<4>[ 312.491435] VIDEO0: total delay: 0 (0 ms)
<4>[ 312.492099] VIDEO0: set blank to 0
<4>[ 312.492114] VIDEO0: clip stuff already freed
<4>[ 312.492293] start VIDEO0 on dmx 1 pid:204 index 9
<4>[ 312.492331] VIDEO0: total delay: 0 (0 ms)
<4>[ 312.497377] VIDEO0: start decode
<4>[ 313.678116] stop PCR0 on dmx 1 pid:84
<4>[ 313.678215] stop VIDEO0 on dmx 1 pid:204
<4>[ 313.678226] VIDEO0 use saved pts 0xc4b93c90 (ret 0)
<4>[ 313.678831] VIDEO0: stop decode
<4>[ 313.679014] VIDEO0: set blank to 1
<4>[ 313.679162] audio ch0: use saved pts 0xc4b92fe8 (0x1953fcd00) (ret 1)
<4>[ 313.679186] audio ch0: decoder stop
<4>[ 313.684090] stop AUDIO0 on dmx 1 pid:2b2
<4>[ 313.684098] audio ch0: xpt stop
<4>[ 313.684104] sync0 1fff no decoder left in sync handling so disable
<4>[ 313.684108] sync0: was last pcroffset0 user so free it
<6>[ 313.912424] si2166b(1): tune freq 1450000, sr 27501000, delsys 6
<6>[ 314.001473] si2166b(1): found carrier after 89 ms
<6>[ 314.001481] si2166b(1): final lock after 89 ms
<4>[ 314.004910] start PCR0 on dmx 1 pid:70a index 5
<4>[ 314.004985] audio ch0: clip stuff already freed
<4>[ 314.005190] start AUDIO0 on dmx 1 pid:709 index 6
<4>[ 314.005196] audio ch0: xpt start
<4>[ 314.005216] sync0 is off so we enable it
<4>[ 314.005221] sync0: allocated pcroffset 0
<4>[ 314.005231] sync0: demux-index 1 pcr pid 70a
<4>[ 314.005241] sync0: using 5 as pcr pidchannel
<4>[ 314.005259] audio ch0: MPEG
<4>[ 314.005343] audio ch0: decoder start
<4>[ 314.005351] audio ch0: video delay 0 (0 ms)
<4>[ 314.005356] VIDEO0: total delay: 0 (0 ms)
<4>[ 314.005951] VIDEO0: set blank to 0
<4>[ 314.005964] VIDEO0: clip stuff already freed
<4>[ 314.006183] start VIDEO0 on dmx 1 pid:708 index 9
<4>[ 314.006220] VIDEO0: total delay: 0 (0 ms)
<4>[ 314.011283] VIDEO0: start decode
<4>[ 314.998373] audio ch0: first pts 0x1661c49e6, stc 0x1661c2346, pcr valid
<4>[ 315.023825] audio ch0: lock
<4>[ 315.023856] audio ch0: sample rate changed isr (48000)
<4>[ 315.152898] audio ch0: status ready isr
<4>[ 315.415874] VIDEO0: first pts 0x1661d220e
<4>[ 315.415901] VIDEO0: framerate changed 3
<4>[ 315.415906] VIDEO0: resolution changed (704 576)
<4>[ 315.415910] VIDEO0: aspect changed 2
<4>[ 315.415942] set current avs auto aspect to 16:9
<4>[ 315.415966] set current avs auto aspect to 16:9
<4>[ 315.416002] set sb to 3(1)
<4>[ 315.580893] VIDEO0: enable tsm
<4>[ 316.032519] VIDEO0: progressive changed (0)
<4>[ 317.324260] stop PCR0 on dmx 1 pid:70a
<4>[ 317.324359] stop VIDEO0 on dmx 1 pid:708
<4>[ 317.325057] VIDEO0: stop decode
<4>[ 317.325210] VIDEO0: set blank to 1
<4>[ 317.325336] audio ch0: decoder stop
<4>[ 317.345580] stop AUDIO0 on dmx 1 pid:709
<4>[ 317.345588] audio ch0: xpt stop
<4>[ 317.345595] sync0 1fff no decoder left in sync handling so disable
<4>[ 317.345599] sync0: was last pcroffset0 user so free it
<4>[ 317.363440] start PCR0 on dmx 1 pid:70d index 5
<4>[ 317.363513] audio ch0: clip stuff already freed
<4>[ 317.363732] start AUDIO0 on dmx 1 pid:70c index 6
<4>[ 317.363739] audio ch0: xpt start
<4>[ 317.363757] sync0 is off so we enable it
<4>[ 317.363763] sync0: allocated pcroffset 0
<4>[ 317.363774] sync0: demux-index 1 pcr pid 70d
<4>[ 317.363784] sync0: using 5 as pcr pidchannel
<4>[ 317.363801] audio ch0: MPEG
<4>[ 317.363937] audio ch0: decoder start
<4>[ 317.363952] audio ch0: video delay 0 (0 ms)
<4>[ 317.363964] VIDEO0: total delay: 0 (0 ms)
<4>[ 317.364598] VIDEO0: set blank to 0
<4>[ 317.364606] VIDEO0: clip stuff already freed
<4>[ 317.364768] start VIDEO0 on dmx 1 pid:70b index 9
<4>[ 317.364802] VIDEO0: total delay: 0 (0 ms)
<4>[ 317.369913] VIDEO0: start decode
<4>[ 318.216991] audio ch0: first pts 0x16620b52c, stc 0x166208ecc, pcr valid
<4>[ 318.242994] audio ch0: lock
<4>[ 318.243029] audio ch0: sample rate changed isr (48000)
<4>[ 318.370766] audio ch0: status ready isr
<4>[ 319.492558] VIDEO0: first pts 0x16622d88c
<4>[ 319.657882] VIDEO0: enable tsm
<4>[ 355.322065] stop PCR0 on dmx 1 pid:70d
<4>[ 355.322162] stop VIDEO0 on dmx 1 pid:70b
<4>[ 355.322802] VIDEO0: stop decode
<4>[ 355.323326] VIDEO0: set blank to 1
<4>[ 355.323449] audio ch0: decoder stop
<4>[ 355.343706] stop AUDIO0 on dmx 1 pid:70c
<4>[ 355.343714] audio ch0: xpt stop
<4>[ 355.343720] sync0 1fff no decoder left in sync handling so disable
<4>[ 355.343724] sync0: was last pcroffset0 user so free it
<6>[ 355.383609] si2166b(1): tune freq 1200000, sr 27501000, delsys 6
<6>[ 355.472824] si2166b(1): found carrier after 89 ms
<6>[ 355.472838] si2166b(1): final lock after 89 ms
<4>[ 355.476858] start PCR0 on dmx 1 pid:89c index 5
<4>[ 355.477019] audio ch0: clip stuff already freed
<4>[ 355.477356] start AUDIO0 on dmx 1 pid:89d index 6
<4>[ 355.477367] audio ch0: xpt start
<4>[ 355.477390] sync0 is off so we enable it
<4>[ 355.477400] sync0: allocated pcroffset 0
<4>[ 355.477416] sync0: demux-index 1 pcr pid 89c
<4>[ 355.477432] sync0: using 5 as pcr pidchannel
<4>[ 355.477458] audio ch0: MPEG
<4>[ 355.477592] audio ch0: decoder start
<4>[ 355.477604] audio ch0: video delay 0 (0 ms)
<4>[ 355.477614] VIDEO0: total delay: 0 (0 ms)
<4>[ 355.478277] VIDEO0: set blank to 0
<4>[ 355.478292] VIDEO0: clip stuff already freed
<4>[ 355.478367] VIDEO0: set streamtype!
<4>[ 355.478379] -> set H264
<4>[ 355.478387] -> sw rave disabled!
<4>[ 355.478406] audio ch0 delay 18000 (200 ms)
<4>[ 355.478415] VIDEO0: total delay: 18000 (200 ms)
<4>[ 355.478572] start VIDEO0 on dmx 1 pid:89c index 5
<4>[ 355.478611] VIDEO0: total delay: 18000 (200 ms)
<4>[ 355.483692] VIDEO0: start decode
<4>[ 356.266296] audio ch0: first pts 0x5e04142c, stc 0x5e03fb36, pcr valid
<4>[ 356.285371] audio ch0: lock
<4>[ 356.285411] audio ch0: sample rate changed isr (48000)
<4>[ 356.581796] audio ch0: status ready isr
<4>[ 356.869290] audio ch0: tsm fail
<4>[ 356.869312] audio ch0: pts error 0 PTS 0x5e04796c, STC 0x5e04cf40, type 0
<4>[ 357.292945] VIDEO0: first pts 0x5e05bd74
<4>[ 357.292971] VIDEO0: resolution changed (1920 1080)
<4>[ 357.292977] VIDEO0: aspect changed 3
<4>[ 357.293252] set current avs auto aspect to 16:9
<4>[ 357.293302] set sb to 3(1)
<4>[ 357.325390] audio ch0: tsm fail
<4>[ 357.325410] audio ch0: pts error 0 PTS 0x5e0519bc, STC 0x5e0573e4, type 0
<4>[ 357.442897] VIDEO0: enable tsm
<4>[ 357.613514] audio ch0: tsm fail
<4>[ 357.613534] audio ch0: pts error 0 PTS 0x5e057efc, STC 0x5e05d4e2, type 0
<4>[ 357.877738] audio ch0: tsm fail
<4>[ 357.877756] audio ch0: pts error 0 PTS 0x5e05dbcc, STC 0x5e0631ca, type 0
<4>[ 358.532905] VIDEO0: progressive changed (1)
<4>[ 362.005546] bcm_audio_decoder_tsm_fail_isr: 20 callbacks suppressed
<4>[ 362.005567] audio ch0: tsm fail
<4>[ 362.005575] audio ch0: pts error 0 PTS 0x5e0b8710, STC 0x5e0be33e, type 0
<4>[ 362.173539] audio ch0: tsm fail
<4>[ 362.173560] audio ch0: pts error 0 PTS 0x5e0bc220, STC 0x5e0c17fa, type 0
<4>[ 362.293643] audio ch0: tsm fail
<4>[ 362.293664] audio ch0: pts error 0 PTS 0x5e0bec50, STC 0x5e0c4244, type 0
<4>[ 362.653703] audio ch0: tsm fail
<4>[ 362.653724] audio ch0: pts error 0 PTS 0x5e0c6ae2, STC 0x5e0cc0d0, type 0
<4>[ 362.845681] audio ch0: tsm fail
<4>[ 362.845701] audio ch0: pts error 0 PTS 0x5e0cae62, STC 0x5e0d044c, type 0
<4>[ 367.814056] bcm_audio_decoder_tsm_fail_isr: 28 callbacks suppressed
<4>[ 367.814075] audio ch0: tsm fail
<4>[ 367.814083] audio ch0: pts error 0 PTS 0x5e1380f6, STC 0x5e13d708, type 0
<4>[ 367.933469] audio ch0: tsm fail
<4>[ 367.933486] audio ch0: pts error 0 PTS 0x5e13ab26, STC 0x5e1406ae, type 0
<4>[ 368.053577] audio ch0: tsm fail
<4>[ 368.053600] audio ch0: pts error 0 PTS 0x5e13d556, STC 0x5e142b3a, type 0
<4>[ 368.245576] audio ch0: tsm fail
<4>[ 368.245596] audio ch0: pts error 0 PTS 0x5e1418d6, STC 0x5e146eb8, type 0
<4>[ 368.821829] audio ch0: tsm fail
<4>[ 368.821851] audio ch0: pts error 0 PTS 0x5e14e358, STC 0x5e15394e, type 0
<4>[ 373.165819] bcm_audio_decoder_tsm_fail_isr: 32 callbacks suppressed
<4>[ 373.165836] audio ch0: tsm fail
<4>[ 373.165844] audio ch0: pts error 0 PTS 0x5e1ada8c, STC 0x5e1b3078, type 0
<4>[ 373.285879] audio ch0: tsm fail
<4>[ 373.285899] audio ch0: pts error 0 PTS 0x5e1b04bc, STC 0x5e1b5ab2, type 0
<4>[ 373.909461] audio ch0: tsm fail
<4>[ 373.909483] audio ch0: pts error 0 PTS 0x5e1be01c, STC 0x5e1c35ea, type 0
<4>[ 374.413733] audio ch0: tsm fail
<4>[ 374.413754] audio ch0: pts error 0 PTS 0x5e1c914c, STC 0x5e1ced88, type 0
<4>[ 375.253733] audio ch0: tsm fail
<4>[ 375.253756] audio ch0: pts error 0 PTS 0x5e1db89e, STC 0x5e1e0e86, type 0
<4>[ 378.309328] stop PCR0 on dmx 1 pid:89c
<4>[ 378.309458] stop VIDEO0 on dmx 1 pid:89c
<4>[ 378.310153] VIDEO0: stop decode
<4>[ 378.310297] VIDEO0: set blank to 1
<4>[ 378.310413] audio ch0: decoder stop
<4>[ 378.330650] stop AUDIO0 on dmx 1 pid:89d
<4>[ 378.330656] audio ch0: xpt stop
<4>[ 378.330663] sync0 1fff no decoder left in sync handling so disable
<4>[ 378.330667] sync0: was last pcroffset0 user so free it
<4>[ 378.364146] start PCR0 on dmx 1 pid:89e index 3
<4>[ 378.364217] audio ch0: clip stuff already freed
<4>[ 378.364424] start AUDIO0 on dmx 1 pid:89f index 5
<4>[ 378.364430] audio ch0: xpt start
<4>[ 378.364448] sync0 is off so we enable it
<4>[ 378.364454] sync0: allocated pcroffset 0
<4>[ 378.364464] sync0: demux-index 1 pcr pid 89e
<4>[ 378.364474] sync0: using 3 as pcr pidchannel
<4>[ 378.364492] audio ch0: MPEG
<4>[ 378.364579] audio ch0: decoder start
<4>[ 378.364586] audio ch0 delay 18000 (200 ms)
<4>[ 378.364591] VIDEO0: total delay: 18000 (200 ms)
<4>[ 378.365451] VIDEO0: set blank to 0
<4>[ 378.365462] VIDEO0: clip stuff already freed
<4>[ 378.365604] start VIDEO0 on dmx 1 pid:89e index 3
<4>[ 378.365637] VIDEO0: total delay: 18000 (200 ms)
<4>[ 378.370650] VIDEO0: start decode
<4>[ 378.627006] audio ch0: first pts 0x5e22cdcc, stc 0x5e22b076, pcr invalid
<4>[ 378.653906] audio ch0: lock
<4>[ 378.653942] bcm_audio_decoder_sample_rate_change_isr: 16 callbacks suppressed
<4>[ 378.653947] audio ch0: sample rate changed isr (48000)
<4>[ 378.653962] audio ch0: tsm fail
<4>[ 378.653969] audio ch0: pts error 0 PTS 0x5e22cdcc, STC 0x5e22c00a, type 0
<4>[ 378.961641] audio ch0: status ready isr
<4>[ 379.170733] audio ch0: tsm fail
<4>[ 379.170755] audio ch0: pts error 0 PTS 0x5e2319bc, STC 0x5e236f96, type 0
<4>[ 379.363048] audio ch0: tsm fail
<4>[ 379.363062] audio ch0: pts error 0 PTS 0x5e235d3c, STC 0x5e23b962, type 0
<4>[ 379.392905] VIDEO0: first pts 0x5e24182a
<4>[ 379.542914] VIDEO0: enable tsm
<4>[ 379.699253] audio ch0: tsm fail
<4>[ 379.699273] audio ch0: pts error 0 PTS 0x5e23d35c, STC 0x5e24296e, type 0
<4>[ 383.827025] bcm_audio_decoder_tsm_fail_isr: 42 callbacks suppressed
<4>[ 383.827042] audio ch0: tsm fail
<4>[ 383.827050] audio ch0: pts error 0 PTS 0x5e297e9e, STC 0x5e29d48e, type 0
<4>[ 383.947204] audio ch0: tsm fail
<4>[ 383.947222] audio ch0: pts error 0 PTS 0x5e29a8ce, STC 0x5e29fece, type 0
<4>[ 384.162665] audio ch0: tsm fail
<4>[ 384.162684] audio ch0: pts error 0 PTS 0x5e29f4be, STC 0x5e2a4a98, type 0
<4>[ 384.282849] audio ch0: tsm fail
<4>[ 384.282870] audio ch0: pts error 0 PTS 0x5e2a1eee, STC 0x5e2a74d2, type 0
<4>[ 384.403033] audio ch0: tsm fail
<4>[ 384.403052] audio ch0: pts error 0 PTS 0x5e2a491e, STC 0x5e2aa666, type 0
<4>[ 385.150857] stop PCR0 on dmx 1 pid:89e
<4>[ 385.151102] stop VIDEO0 on dmx 1 pid:89e
<4>[ 385.151794] VIDEO0: stop decode
<4>[ 385.151957] VIDEO0: set blank to 1
<4>[ 385.152076] audio ch0: decoder stop
<4>[ 385.172292] stop AUDIO0 on dmx 1 pid:89f
<4>[ 385.172299] audio ch0: xpt stop
<4>[ 385.172305] sync0 1fff no decoder left in sync handling so disable
<4>[ 385.172308] sync0: was last pcroffset0 user so free it
<6>[ 385.240293] si2166b(1): voltage is 13V now
<6>[ 385.371817] si2166b(1): tune timeout!
<6>[ 385.492350] si2166b(1): tune freq 1400000, sr 27501000, delsys 6
<6>[ 385.580309] si2166b(1): found carrier after 88 ms
<6>[ 385.580322] si2166b(1): final lock after 88 ms
<4>[ 385.583744] start PCR0 on dmx 1 pid:60c index 5
<4>[ 385.583810] audio ch0: clip stuff already freed
<4>[ 385.584141] start AUDIO0 on dmx 1 pid:60d index 6
<4>[ 385.584149] audio ch0: xpt start
<4>[ 385.584168] sync0 is off so we enable it
<4>[ 385.584173] sync0: allocated pcroffset 0
<4>[ 385.584184] sync0: demux-index 1 pcr pid 60c
<4>[ 385.584194] sync0: using 5 as pcr pidchannel
<4>[ 385.584211] audio ch0: AAC ADTS
<4>[ 385.584217] audio ch0: streamtype set 5
<4>[ 385.584221] audio ch0: streamtype_set: stop rave
<4>[ 385.584246] audio ch0: decode
<4>[ 385.584261] audio ch0: streamtype_set: restart rave
<4>[ 385.584374] audio ch0: decoder start
<4>[ 385.584381] audio ch0 delay 18000 (200 ms)
<4>[ 385.584386] VIDEO0: total delay: 18000 (200 ms)
<4>[ 385.585049] VIDEO0: set blank to 0
<4>[ 385.585064] VIDEO0: clip stuff already freed
<4>[ 385.585221] start VIDEO0 on dmx 1 pid:60c index 5
<4>[ 385.585255] VIDEO0: total delay: 18000 (200 ms)
<4>[ 385.590287] VIDEO0: start decode
<4>[ 386.217427] audio ch0: first pts 0x9e6a987c, stc 0x9e69e690, pcr valid
<4>[ 386.217471] audio ch0: lock
<4>[ 388.272866] VIDEO0: first pts 0x9e6d9c0c
<4>[ 388.272891] VIDEO0: framerate changed 2
<4>[ 388.272895] VIDEO0: resolution changed (1280 720)
<4>[ 388.273042] set current avs auto aspect to 16:9
<4>[ 388.273196] set sb to 3(1)
<4>[ 388.427919] VIDEO0: enable tsm
<4>[ 425.480414] bcm_audio_decoder_tsm_fail_isr: 9 callbacks suppressed
<4>[ 425.480430] audio ch0: tsm fail
<4>[ 425.480439] audio ch0: pts error 0 PTS 0x9e9f79fc, STC 0x9e9fd6e2, type 0
<4>[ 430.474883] audio ch0: tsm fail
<4>[ 430.474904] audio ch0: pts error 0 PTS 0x9ea645fc, STC 0x9ea6b126, type 0
<4>[ 430.474920] audio ch0: tsm fail
<4>[ 430.474934] audio ch0: pts error 0 PTS 0x9ea64d7c, STC 0x9ea6b12a, type 1
<4>[ 430.474952] audio ch0: tsm fail
<4>[ 430.474961] audio ch0: pts error 0 PTS 0x9ea654fc, STC 0x9ea6b12c, type 1
<4>[ 432.518095] audio ch0: tsm fail
<4>[ 432.518112] audio ch0: pts error 0 PTS 0x9ea90e7c, STC 0x9ea97d32, type 0
<4>[ 432.518127] audio ch0: tsm fail
<4>[ 432.518142] audio ch0: pts error 0 PTS 0x9ea915fc, STC 0x9ea97d36, type 1
<4>[ 432.518160] audio ch0: tsm fail
<4>[ 432.518171] audio ch0: pts error 0 PTS 0x9ea91d7c, STC 0x9ea97d38, type 1
<4>[ 461.449205] audio ch0: tsm fail
<4>[ 461.449226] audio ch0: pts error 0 PTS 0x9ed0c87c, STC 0x9ed133d8, type 0
<4>[ 461.449244] audio ch0: tsm fail
<4>[ 461.449260] audio ch0: pts error 0 PTS 0x9ed0cffc, STC 0x9ed133dc, type 1
<4>[ 461.449279] audio ch0: tsm fail
<4>[ 461.449289] audio ch0: pts error 0 PTS 0x9ed0d77c, STC 0x9ed133de, type 1
<4>[ 494.577289] stop PCR0 on dmx 1 pid:60c
<4>[ 494.577384] stop VIDEO0 on dmx 1 pid:60c
<4>[ 494.578140] VIDEO0: stop decode
<4>[ 494.578278] VIDEO0: set blank to 1
<4>[ 494.578402] audio ch0: decoder stop
<4>[ 494.598627] stop AUDIO0 on dmx 1 pid:60d
<4>[ 494.598635] audio ch0: xpt stop
<4>[ 494.598641] sync0 1fff no decoder left in sync handling so disable
<4>[ 494.598645] sync0: was last pcroffset0 user so free it
<4>[ 494.631638] start PCR0 on dmx 1 pid:60e index 3
<4>[ 494.631706] audio ch0: clip stuff already freed
<4>[ 494.632170] start AUDIO0 on dmx 1 pid:60f index 5
<4>[ 494.632178] audio ch0: xpt start
<4>[ 494.632199] sync0 is off so we enable it
<4>[ 494.632204] sync0: allocated pcroffset 0
<4>[ 494.632215] sync0: demux-index 1 pcr pid 60e
<4>[ 494.632225] sync0: using 3 as pcr pidchannel
<4>[ 494.632243] audio ch0: AAC ADTS
<4>[ 494.632418] audio ch0: decoder start
<4>[ 494.632426] audio ch0 delay 18000 (200 ms)
<4>[ 494.632431] VIDEO0: total delay: 18000 (200 ms)
<4>[ 494.633325] VIDEO0: set blank to 0
<4>[ 494.633335] VIDEO0: clip stuff already freed
<4>[ 494.633477] start VIDEO0 on dmx 1 pid:60e index 3
<4>[ 494.633511] VIDEO0: total delay: 18000 (200 ms)
<4>[ 494.638518] VIDEO0: start decode
<4>[ 495.407589] audio ch0: first pts 0x7864b57c, stc 0x78640a64, pcr valid
<4>[ 495.407634] audio ch0: lock
<4>[ 495.407657] audio ch0: sample rate changed isr (48000)
<4>[ 495.407669] audio ch0: tsm fail
<4>[ 495.407674] audio ch0: pts error 0 PTS 0x7864b57c, STC 0x78640a6c, type 0
<4>[ 495.407773] audio ch0: tsm fail
<4>[ 495.407779] audio ch0: pts error 0 PTS 0x7864bcfc, STC 0x78640a76, type 1
<4>[ 497.510086] VIDEO0: first pts 0x7867d658
<4>[ 497.659888] VIDEO0: enable tsm
<4>[ 569.697310] audio ch0: tsm fail
<4>[ 569.697334] audio ch0: pts error 0 PTS 0x78c9a2fc, STC 0x78ca1a6a, type 0
<4>[ 569.697348] audio ch0: tsm fail
<4>[ 569.697353] audio ch0: pts error 0 PTS 0x78c9aa7c, STC 0x78ca1a6c, type 1
<4>[ 569.697361] audio ch0: tsm fail
<4>[ 569.697366] audio ch0: pts error 0 PTS 0x78c9b1fc, STC 0x78ca1a6e, type 1
<4>[ 569.697401] audio ch0: tsm fail
<4>[ 569.697406] audio ch0: pts error 0 PTS 0x78c9b97c, STC 0x78ca1a70, type 1
<4>[ 569.718725] audio ch0: tsm fail
<4>[ 569.718742] audio ch0: pts error 0 PTS 0x78c9c0fc, STC 0x78ca21f2, type 1
<4>[ 574.930137] bcm_audio_decoder_tsm_fail_isr: 2 callbacks suppressed
<4>[ 574.930155] audio ch0: tsm fail
<4>[ 574.930162] audio ch0: pts error 0 PTS 0x78d0df7c, STC 0x78d14aba, type 0
<4>[ 574.930174] audio ch0: tsm fail
<4>[ 574.930179] audio ch0: pts error 0 PTS 0x78d0e6fc, STC 0x78d14abc, type 1
<4>[ 574.930187] audio ch0: tsm fail
<4>[ 574.930192] audio ch0: pts error 0 PTS 0x78d0ee7c, STC 0x78d14abc, type 1
<4>[ 634.720382] audio ch0: tsm fail
<4>[ 634.720404] audio ch0: pts error 0 PTS 0x7922f67c, STC 0x79236344, type 0
<4>[ 634.720417] audio ch0: tsm fail
<4>[ 634.720422] audio ch0: pts error 0 PTS 0x7922fdfc, STC 0x79236346, type 1
<4>[ 634.720430] audio ch0: tsm fail
<4>[ 634.720435] audio ch0: pts error 0 PTS 0x7923057c, STC 0x79236346, type 1
<4>[ 648.476485] audio ch0: tsm fail
<4>[ 648.476507] audio ch0: pts error 0 PTS 0x7935dbfc, STC 0x79364672, type 0
<4>[ 648.476520] audio ch0: tsm fail
<4>[ 648.476526] audio ch0: pts error 0 PTS 0x7935e37c, STC 0x79364674, type 1
<4>[ 648.476534] audio ch0: tsm fail
<4>[ 648.476538] audio ch0: pts error 0 PTS 0x7935eafc, STC 0x79364674, type 1
<4>[ 846.457690] stop PCR0 on dmx 1 pid:60e
<4>[ 846.457779] stop VIDEO0 on dmx 1 pid:60e
<4>[ 846.458565] VIDEO0: stop decode
<4>[ 846.458710] VIDEO0: set blank to 1
<4>[ 846.458831] audio ch0: decoder stop
<4>[ 846.478882] stop AUDIO0 on dmx 1 pid:60f
<4>[ 846.478889] audio ch0: xpt stop
<4>[ 846.478896] sync0 1fff no decoder left in sync handling so disable
<4>[ 846.478899] sync0: was last pcroffset0 user so free it
<6>[ 846.545381] si2166b(1): voltage is 18V now
<6>[ 846.798494] si2166b(1): tune freq 1200000, sr 27501000, delsys 6
<6>[ 846.886179] si2166b(1): found carrier after 88 ms
<6>[ 846.886188] si2166b(1): final lock after 88 ms
<4>[ 846.889571] start PCR0 on dmx 1 pid:884 index 5
<4>[ 846.889634] audio ch0: clip stuff already freed
<4>[ 846.889838] start AUDIO0 on dmx 1 pid:885 index 6
<4>[ 846.889844] audio ch0: xpt start
<4>[ 846.889909] sync0 is off so we enable it
<4>[ 846.889924] sync0: allocated pcroffset 0
<4>[ 846.889940] sync0: demux-index 1 pcr pid 884
<4>[ 846.889955] sync0: using 5 as pcr pidchannel
<4>[ 846.889993] audio ch0: MPEG
<4>[ 846.890000] audio ch0: streamtype set 0
<4>[ 846.890007] audio ch0: streamtype_set: stop rave
<4>[ 846.890044] audio ch0: streamtype_set: restart rave
<4>[ 846.890352] audio ch0: decoder start
<4>[ 846.890361] audio ch0 delay 18000 (200 ms)
<4>[ 846.890366] VIDEO0: total delay: 18000 (200 ms)
<4>[ 846.891039] VIDEO0: set blank to 0
<4>[ 846.891049] VIDEO0: clip stuff already freed
<4>[ 846.891185] start VIDEO0 on dmx 1 pid:884 index 5
<4>[ 846.891219] VIDEO0: total delay: 18000 (200 ms)
<4>[ 846.896306] VIDEO0: start decode
<4>[ 847.542926] audio ch0: first pts 0x609f40b6, stc 0x609f26ae, pcr invalid
<4>[ 847.563527] audio ch0: lock
<4>[ 847.563569] audio ch0: sample rate changed isr (48000)
<4>[ 847.563604] audio ch0: tsm fail
<4>[ 847.563617] audio ch0: pts error 0 PTS 0x609f40b6, STC 0x609f332e, type 0
<4>[ 847.870623] audio ch0: status ready isr
<4>[ 848.054221] audio ch0: tsm fail
<4>[ 848.054243] audio ch0: pts error 0 PTS 0x609f8436, STC 0x609fdd32, type 0
<4>[ 848.176952] audio ch0: tsm fail
<4>[ 848.176972] audio ch0: pts error 0 PTS 0x609fae66, STC 0x60a0088e, type 0
<4>[ 848.378547] VIDEO0: first pts 0x60a0a940
<4>[ 848.378574] VIDEO0: framerate changed 3
<4>[ 848.378585] VIDEO0: resolution changed (1920 1080)
<4>[ 848.378904] set current avs auto aspect to 16:9
<4>[ 848.378946] set sb to 3(1)
<4>[ 848.405619] audio ch0: tsm fail
<4>[ 848.405637] audio ch0: pts error 0 PTS 0x60a002c6, STC 0x60a058ea, type 0
<4>[ 848.528890] VIDEO0: enable tsm
<4>[ 850.318153] stop PCR0 on dmx 1 pid:884
<4>[ 850.318247] stop VIDEO0 on dmx 1 pid:884
<4>[ 850.318960] VIDEO0: stop decode
<4>[ 850.319156] VIDEO0: set blank to 1
<4>[ 850.319283] audio ch0: decoder stop
<4>[ 850.339537] stop AUDIO0 on dmx 1 pid:885
<4>[ 850.339545] audio ch0: xpt stop
<4>[ 850.339551] sync0 1fff no decoder left in sync handling so disable
<4>[ 850.339556] sync0: was last pcroffset0 user so free it
<6>[ 850.406064] si2166b(1): voltage is 13V now
<6>[ 850.537933] si2166b(1): tune timeout!
<6>[ 850.659419] si2166b(1): tune freq 1400000, sr 27501000, delsys 6
<6>[ 850.747296] si2166b(1): found carrier after 88 ms
<6>[ 850.747305] si2166b(1): final lock after 88 ms
<4>[ 850.750575] start PCR0 on dmx 1 pid:612 index 5
<4>[ 850.750642] audio ch0: clip stuff already freed
<4>[ 850.750844] start AUDIO0 on dmx 1 pid:613 index 6
<4>[ 850.750851] audio ch0: xpt start
<4>[ 850.750915] sync0 is off so we enable it
<4>[ 850.750931] sync0: allocated pcroffset 0
<4>[ 850.750947] sync0: demux-index 1 pcr pid 612
<4>[ 850.750964] sync0: using 5 as pcr pidchannel
<4>[ 850.750992] audio ch0: AAC ADTS
<4>[ 850.751010] audio ch0: streamtype set 5
<4>[ 850.751019] audio ch0: streamtype_set: stop rave
<4>[ 850.751045] audio ch0: decode
<4>[ 850.751066] audio ch0: streamtype_set: restart rave
<4>[ 850.751380] audio ch0: decoder start
<4>[ 850.751388] audio ch0 delay 18000 (200 ms)
<4>[ 850.751393] VIDEO0: total delay: 18000 (200 ms)
<4>[ 850.752143] VIDEO0: set blank to 0
<4>[ 850.752152] VIDEO0: clip stuff already freed
<4>[ 850.752291] start VIDEO0 on dmx 1 pid:612 index 5
<4>[ 850.752324] VIDEO0: total delay: 18000 (200 ms)
<4>[ 850.757420] VIDEO0: start decode
<4>[ 851.321025] audio ch0: first pts 0xa12fdfd6, stc 0xa12f1ed0, pcr valid
<4>[ 851.321198] audio ch0: lock
<4>[ 852.055277] VIDEO0: first pts 0xa13106ae
<4>[ 852.055314] VIDEO0: framerate changed 2
<4>[ 852.055321] VIDEO0: resolution changed (1280 720)
<4>[ 852.055623] set current avs auto aspect to 16:9
<4>[ 852.055680] set sb to 3(1)
<4>[ 852.204885] VIDEO0: enable tsm
<4>[ 933.266505] bcm_audio_decoder_tsm_fail_isr: 21 callbacks suppressed
<4>[ 933.266523] audio ch0: tsm fail
<4>[ 933.266530] audio ch0: pts error 0 PTS 0xa19f51d6, STC 0xa19fb01a, type 0