Ticket #13608: nz_tv3.logs

File nz_tv3.logs, 17.3 KB (added by steven-ellis, 5 years ago)

backend logs trying to play TV 3 in NZ via IPTV

Line 
1May 3 14:47:22 mythtv mythbackend: mythbackend[28492]: I TVRecEvent recorders/hlsstreamhandler.cpp:103 (HLSStreamHandler) HLSSH[56](<URL>/three.m3u8(0)): ctor
2May 3 14:47:22 mythtv mythbackend: mythbackend[28492]: I StreamHandler recorders/hlsstreamhandler.cpp:125 (run) HLSSH[56](<URL>/three.m3u8(0)): run() -- begin
3May 3 14:47:22 mythtv mythbackend: mythbackend[28492]: I StreamHandler recorders/HLS/HLSReader.cpp:194 (Throttle) HLSReader: Throttle(false)
4May 3 14:47:22 mythtv mythbackend: mythbackend[28492]: I StreamHandler recorders/HLS/HLSReader.cpp:38 (Open) HLSReader: Opening '<URL>/three.m3u8'
5May 3 14:47:22 mythtv mythbackend: mythbackend[28492]: I TVRecEvent recorders/hlsstreamhandler.cpp:43 (Get) HLSSH[56]: Creating new stream handler <URL>/three.m3u8(0) for [data]<URL>/three.m3u8[fectype][fec0][fec1]
6May 3 14:47:22 mythtv mythbackend: mythbackend[28492]: I TVRecEvent programinfo.cpp:6167 (GetFilesize) ProgramInfo(10902_20200503024707.ts): RI Filesize=0, DB Filesize=41240056
7May 3 14:47:22 mythtv mythbackend: mythbackend[28492]: I TVRecEvent tv_rec.cpp:4697 (SwitchLiveTVRingBuffer) TVRec[56]: SwitchLiveTVRingBuffer(discont 1, set_next_rec 0) curRec(10902_2020-05-03T02:47:07Z) curRec.size(41240056)
8May 3 14:47:22 mythtv mythbackend: mythbackend[28492]: I TVRecEvent tv_rec.cpp:4544 (GetProgramRingBufferForLiveTV) TVRec[56]: GetProgramRingBufferForLiveTV()
9May 3 14:47:22 mythtv mythbackend: mythbackend[28492]: N CoreContext autoexpire.cpp:244 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 7.0 GB w/freq: 14 min
10May 3 14:47:22 mythtv mythbackend: mythbackend[28492]: I TVRecEvent tv_rec.cpp:795 (StartedRecording) TVRec[56]: StartedRecording(10903_2020-05-03T02:47:22Z) fn(/myth2/tv/10903_20200503024722.ts)
11May 3 14:47:22 mythtv mythbackend: mythbackend[28492]: N TVRecEvent recordinginfo.cpp:681 (ApplyRecordRecGroupChange) ApplyRecordRecGroupChange: LiveTV to LiveTV (2)
12May 3 14:47:22 mythtv mythbackend: mythbackend[28492]: I TVRecEvent livetvchain.cpp:107 (AppendNewProgram) Chain: Appended@5 '10903_20200503024722'
13May 3 14:47:22 mythtv mythbackend: mythbackend[28492]: I TVRecEvent tv_rec.cpp:881 (FinishedRecording) TVRec[56]: FinishedRecording(10902_2020-05-03T02:47:07Z) Bad quality#012#011#011#011title: Home And Away#012#011#011#011in recgroup: LiveTV status: Unknown:Recorder Failed not_dummy finished_now
14May 3 14:47:22 mythtv mythbackend: mythbackend[28492]: I TVRecEvent livetvchain.cpp:133 (FinishedRecording) Chain: Updated endtime for '10902_20200503024707' to 20200503024722
15May 3 14:47:22 mythtv mythbackend: mythbackend[28492]: I StreamHandler recorders/HLS/HLSReader.cpp:330 (ParseM3U8) HLSReader: Meta index file
16May 3 14:47:22 mythtv mythbackend: mythbackend[28492]: I StreamHandler recorders/HLS/HLSReader.cpp:340 (ParseM3U8) HLSReader: |#EXTM3U
17May 3 14:47:22 mythtv mythbackend: mythbackend[28492]: I StreamHandler recorders/HLS/HLSReader.cpp:340 (ParseM3U8) HLSReader: |#EXT-X-VERSION:3
18May 3 14:47:22 mythtv mythbackend: mythbackend[28492]: I StreamHandler recorders/HLS/HLSReader.cpp:340 (ParseM3U8) HLSReader: |#EXT-X-INDEPENDENT-SEGMENTS
19May 3 14:47:22 mythtv mythbackend: mythbackend[28492]: I StreamHandler recorders/HLS/HLSReader.cpp:340 (ParseM3U8) HLSReader: |#EXT-X-STREAM-INF:BANDWIDTH=2158932,AVERAGE-BANDWIDTH=2305600,CODECS="avc1.77.30,mp4a.40.2",RESOLUTION=854x480,FRAME-RATE=25.000
20May 3 14:47:22 mythtv mythbackend: mythbackend[28492]: I StreamHandler HLS/m3u.cpp:121 (ParseStreamInformation) Parsing stream from <URL>/three-2000.m3u8
21May 3 14:47:22 mythtv mythbackend: mythbackend[28492]: I StreamHandler HLS/m3u.cpp:133 (ParseStreamInformation) #EXT-X-STREAM-INF: expected PROGRAM-ID=<value>, using -1
22May 3 14:47:22 mythtv mythbackend: mythbackend[28492]: I StreamHandler HLS/m3u.cpp:159 (ParseStreamInformation) bandwidth adaptation detected (program-id=-1, bandwidth=2158932
23May 3 14:47:22 mythtv mythbackend: mythbackend[28492]: I StreamHandler recorders/HLS/HLSReader.cpp:368 (ParseM3U8) HLSReader: Adding stream -1 bitrate 2158932
24May 3 14:47:22 mythtv mythbackend: mythbackend[28492]: I StreamHandler recorders/HLS/HLSReader.cpp:340 (ParseM3U8) HLSReader: |#EXT-X-STREAM-INF:BANDWIDTH=1645600,AVERAGE-BANDWIDTH=1755600,CODECS="avc1.77.30,mp4a.40.2",RESOLUTION=854x480,FRAME-RATE=25.000
25May 3 14:47:22 mythtv mythbackend: mythbackend[28492]: I StreamHandler HLS/m3u.cpp:121 (ParseStreamInformation) Parsing stream from <URL>/three-1500.m3u8
26May 3 14:47:22 mythtv mythbackend: mythbackend[28492]: I StreamHandler HLS/m3u.cpp:133 (ParseStreamInformation) #EXT-X-STREAM-INF: expected PROGRAM-ID=<value>, using -1
27May 3 14:47:22 mythtv mythbackend: mythbackend[28492]: I StreamHandler HLS/m3u.cpp:159 (ParseStreamInformation) bandwidth adaptation detected (program-id=-1, bandwidth=1645600
28May 3 14:47:22 mythtv mythbackend: mythbackend[28492]: I StreamHandler recorders/HLS/HLSReader.cpp:368 (ParseM3U8) HLSReader: Adding stream -1 bitrate 1645600
29May 3 14:47:22 mythtv mythbackend: mythbackend[28492]: I StreamHandler recorders/HLS/HLSReader.cpp:340 (ParseM3U8) HLSReader: |#EXT-X-STREAM-INF:BANDWIDTH=849932,AVERAGE-BANDWIDTH=903100,CODECS="avc1.77.30,mp4a.40.2",RESOLUTION=640x360,FRAME-RATE=25.000
30May 3 14:47:22 mythtv mythbackend: mythbackend[28492]: I StreamHandler HLS/m3u.cpp:121 (ParseStreamInformation) Parsing stream from <URL>/three-725.m3u8
31May 3 14:47:22 mythtv mythbackend: mythbackend[28492]: I StreamHandler HLS/m3u.cpp:133 (ParseStreamInformation) #EXT-X-STREAM-INF: expected PROGRAM-ID=<value>, using -1
32May 3 14:47:22 mythtv mythbackend: mythbackend[28492]: I StreamHandler HLS/m3u.cpp:159 (ParseStreamInformation) bandwidth adaptation detected (program-id=-1, bandwidth=849932
33May 3 14:47:22 mythtv mythbackend: mythbackend[28492]: I StreamHandler recorders/HLS/HLSReader.cpp:368 (ParseM3U8) HLSReader: Adding stream -1 bitrate 849932
34May 3 14:47:22 mythtv mythbackend: mythbackend[28492]: I StreamHandler recorders/HLS/HLSReader.cpp:124 (Open) <URL>/three-2000.m3u8: Selected stream with 2158932 bitrate
35May 3 14:47:22 mythtv mythbackend: mythbackend[28492]: I thread_unknown recorders/HLS/HLSPlaylistWorker.cpp:36 (run) <URL>/three-2000.m3u8 playlist: run -- begin
36May 3 14:47:22 mythtv mythbackend: mythbackend[28492]: I StreamHandler recorders/HLS/HLSReader.cpp:134 (Open) <URL>/three-2000.m3u8: Open -- end
37May 3 14:47:22 mythtv mythbackend: mythbackend[28492]: I thread_unknown recorders/HLS/HLSStreamWorker.cpp:37 (run) <URL>/three-2000.m3u8 worker: run -- begin
38May 3 14:47:22 mythtv mythbackend: mythbackend[28492]: I StreamHandler recorders/HLS/HLSReader.cpp:194 (Throttle) <URL>/three-2000.m3u8: Throttle(true)
39May 3 14:47:22 mythtv mythbackend: mythbackend[28492]: I TVRecEvent programinfo.cpp:4640 (SaveVideoProperties) SaveVideoProperties(0x3a, 0x20)
40May 3 14:47:22 mythtv mythbackend: mythbackend[28492]: I TVRecEvent programinfo.cpp:6167 (GetFilesize) ProgramInfo(10902_20200503024707.ts): RI Filesize=0, DB Filesize=41240056
41May 3 14:47:22 mythtv mythbackend: mythbackend[28492]: I TVRecEvent tv_rec.cpp:3798 (TuningFrequency) TVRec[56]: Starting Signal Monitor
42May 3 14:47:22 mythtv mythbackend: mythbackend[28492]: I TVRecEvent tv_rec.cpp:2034 (SetupSignalMonitor) TVRec[56]: SetupSignalMonitor(1, 1)
43May 3 14:47:22 mythtv mythbackend: mythbackend[28492]: I TVRecEvent tv_rec.cpp:2057 (SetupSignalMonitor) TVRec[56]: Signal monitor successfully created
44May 3 14:47:22 mythtv mythbackend: mythbackend[28492]: I TVRecEvent tv_rec.cpp:1853 (SetupDTVSignalMonitor) TVRec[56]: Setting up table monitoring.
45May 3 14:47:22 mythtv mythbackend: mythbackend[28492]: I TVRecEvent tv_rec.cpp:4141 (LoadProfile) TVRec[56]: Using profile 'Live TV' to record
46May 3 14:47:22 mythtv mythbackend: mythbackend[28492]: I TVRecEvent tv_rec.cpp:1967 (SetupDTVSignalMonitor) TVRec[56]: MPEG program number: 0
47May 3 14:47:22 mythtv mythbackend: mythbackend[28492]: I TVRecEvent recorders/streamhandler.cpp:41 (AddListener) SH[56](<URL>/three.m3u8(0)): AddListener(0x7fbe38078900) -- begin
48May 3 14:47:22 mythtv mythbackend: mythbackend[28492]: I TVRecEvent recorders/streamhandler.cpp:53 (AddListener) SH[56](<URL>/three.m3u8(0)): AddListener(0x7fbe38078900) -- locked
49May 3 14:47:22 mythtv mythbackend: mythbackend[28492]: I TVRecEvent recorders/streamhandler.cpp:75 (AddListener) SH[56](<URL>/three.m3u8(0)): AddListener(0x7fbe38078900) -- end
50May 3 14:47:22 mythtv mythbackend: mythbackend[28492]: I TVRecEvent mpeg/mpegstreamdata.cpp:75 (SetDesiredProgram) MPEGStream[56](0x7fbe38078900): SetDesiredProgram(0)
51May 3 14:47:22 mythtv mythbackend: mythbackend[28492]: I TVRecEvent tv_rec.cpp:1988 (SetupDTVSignalMonitor) TVRec[56]: Successfully set up MPEG table monitoring.
52May 3 14:47:22 mythtv mythbackend: mythbackend[28492]: I TVRecEvent tv_rec.cpp:4425 (SetFlags) TVRec[56]: SetFlags(SignalMonitorRunning,) -> FrontendReady,RunMainLoop,CancelNextRecording,SignalMonitorRunning,RecorderRunning, @ tv_rec.cpp:3825
53May 3 14:47:22 mythtv mythbackend: mythbackend[28492]: I TVRecEvent tv_rec.cpp:4434 (ClearFlags) TVRec[56]: ClearFlags(WaitingForSignal,) -> FrontendReady,RunMainLoop,CancelNextRecording,SignalMonitorRunning,RecorderRunning, @ tv_rec.cpp:3826
54May 3 14:47:22 mythtv mythbackend: mythbackend[28492]: I TVRecEvent tv_rec.cpp:4425 (SetFlags) TVRec[56]: SetFlags(WaitingForSignal,) -> FrontendReady,RunMainLoop,CancelNextRecording,WaitingForSignal,SignalMonitorRunning,RecorderRunning, @ tv_rec.cpp:3831
55May 3 14:47:22 mythtv mythbackend: mythbackend[28492]: I TVRecEvent recorders/recorderbase.cpp:87 (SetRingBuffer) RecBase[56](file:///home/mythfe/iptv-nz.m3u8): SetRingBuffer(0x0)
56May 3 14:47:22 mythtv mythbackend: mythbackend[28492]: I TVRecEvent tv_rec.cpp:4425 (SetFlags) TVRec[56]: SetFlags(DummyRecorderRunning,) -> FrontendReady,RunMainLoop,CancelNextRecording,WaitingForSignal,SignalMonitorRunning,ANYRECRUNNING, @ tv_rec.cpp:3880
57May 3 14:47:22 mythtv mythbackend: mythbackend[28492]: I TVRecEvent tv_rec.cpp:3881 (TuningFrequency) DummyDTVRecorder -- started
58May 3 14:47:22 mythtv mythbackend: mythbackend[28492]: I TVRecEvent tv_rec.cpp:4425 (SetFlags) TVRec[56]: SetFlags(RingBufferReady,) -> FrontendReady,RunMainLoop,CancelNextRecording,WaitingForSignal,SignalMonitorRunning,ANYRECRUNNING,RingBufferReady, @ tv_rec.cpp:3882
59May 3 14:47:22 mythtv mythbackend: mythbackend[28492]: I TVRecEvent tv_rec.cpp:4434 (ClearFlags) TVRec[56]: ClearFlags(NeedToStartRecorder,) -> FrontendReady,RunMainLoop,CancelNextRecording,WaitingForSignal,SignalMonitorRunning,ANYRECRUNNING,RingBufferReady, @ tv_rec.cpp:3892
60May 3 14:47:22 mythtv mythbackend: mythbackend[28492]: I TVRecEvent tv_rec.cpp:4425 (SetFlags) TVRec[56]: SetFlags(NeedToStartRecorder,) -> FrontendReady,RunMainLoop,CancelNextRecording,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning,ANYRECRUNNING,RingBufferReady, @ tv_rec.cpp:3894
61May 3 14:47:22 mythtv mythbackend: mythbackend[28492]: I TVRecEvent tv_rec.cpp:4012 (TuningSignalCheck) TVRec[56]: TuningSignalCheck: Still waiting. Will timeout @ 14:59:50.000
62May 3 14:47:23 mythtv mythbackend: mythbackend[28492]: I HLSPlaylist HLS/m3u.cpp:329 (ParseKey) <URL>/three-2000.m3u8playback of AES-128 encrypted HTTP Live media detected.
63May 3 14:47:24 mythtv mythbackend: mythbackend[28492]: I HLSStream recorders/HLS/HLSReader.cpp:853 (LoadSegments) <URL>/three-2000.m3u8: Throttling -- sleeping 2 secs.
64May 3 14:47:25 mythtv mythbackend: mythbackend[28492]: I StreamHandler recorders/hlsstreamhandler.cpp:184 (run) HLSSH[56](<URL>/three.m3u8(0)): Packet not starting with SYNC Byte (got 0x3a)
65May 3 14:47:25 mythtv mythbackend: mythbackend[28492]: I StreamHandler recorders/hlsstreamhandler.cpp:184 (run) HLSSH[56](<URL>/three.m3u8(0)): Packet not starting with SYNC Byte (got 0x1b)
66May 3 14:47:25 mythtv mythbackend: mythbackend[28492]: I StreamHandler recorders/hlsstreamhandler.cpp:184 (run) HLSSH[56](<URL>/three.m3u8(0)): Packet not starting with SYNC Byte (got 0xffffffffffffff9b)
67May 3 14:47:25 mythtv mythbackend: mythbackend[28492]: I StreamHandler recorders/hlsstreamhandler.cpp:184 (run) HLSSH[56](<URL>/three.m3u8(0)): Packet not starting with SYNC Byte (got 0xfffffffffffffff3)
68May 3 14:47:25 mythtv mythbackend: mythbackend[28492]: I StreamHandler recorders/hlsstreamhandler.cpp:184 (run) HLSSH[56](<URL>/three.m3u8(0)): Packet not starting with SYNC Byte (got 0x0c)
69May 3 14:47:25 mythtv mythbackend: mythbackend[28492]: I StreamHandler recorders/hlsstreamhandler.cpp:184 (run) HLSSH[56](<URL>/three.m3u8(0)): Packet not starting with SYNC Byte (got 0xffffffffffffffd5)
70May 3 14:47:25 mythtv mythbackend: mythbackend[28492]: I StreamHandler recorders/hlsstreamhandler.cpp:184 (run) HLSSH[56](<URL>/three.m3u8(0)): Packet not starting with SYNC Byte (got 0x79)
71May 3 14:47:25 mythtv mythbackend: mythbackend[28492]: I StreamHandler recorders/hlsstreamhandler.cpp:184 (run) HLSSH[56](<URL>/three.m3u8(0)): Packet not starting with SYNC Byte (got 0x74)
72May 3 14:47:25 mythtv mythbackend: mythbackend[28492]: I StreamHandler recorders/hlsstreamhandler.cpp:184 (run) HLSSH[56](<URL>/three.m3u8(0)): Packet not starting with SYNC Byte (got 0x38)
73May 3 14:47:25 mythtv mythbackend: mythbackend[28492]: I StreamHandler recorders/hlsstreamhandler.cpp:184 (run) HLSSH[56](<URL>/three.m3u8(0)): Packet not starting with SYNC Byte (got 0xffffffffffffff8c)
74May 3 14:47:25 mythtv mythbackend: mythbackend[28492]: I StreamHandler recorders/hlsstreamhandler.cpp:184 (run) HLSSH[56](<URL>/three.m3u8(0)): Packet not starting with SYNC Byte (got 0xffffffffffffffff)
75May 3 14:47:25 mythtv mythbackend: mythbackend[28492]: I StreamHandler recorders/hlsstreamhandler.cpp:184 (run) HLSSH[56](<URL>/three.m3u8(0)): Packet not starting with SYNC Byte (got 0xffffffffffffffe7)
76May 3 14:47:25 mythtv mythbackend: mythbackend[28492]: I StreamHandler recorders/hlsstreamhandler.cpp:184 (run) HLSSH[56](<URL>/three.m3u8(0)): Packet not starting with SYNC Byte (got 0x4e)
77May 3 14:47:25 mythtv mythbackend: mythbackend[28492]: I StreamHandler recorders/hlsstreamhandler.cpp:184 (run) HLSSH[56](<URL>/three.m3u8(0)): Packet not starting with SYNC Byte (got 0xffffffffffffffd8)
78May 3 14:47:25 mythtv mythbackend: mythbackend[28492]: I StreamHandler recorders/hlsstreamhandler.cpp:184 (run) HLSSH[56](<URL>/three.m3u8(0)): Packet not starting with SYNC Byte (got 0xffffffffffffffb5)
79May 3 14:47:25 mythtv mythbackend: mythbackend[28492]: I StreamHandler recorders/hlsstreamhandler.cpp:184 (run) HLSSH[56](<URL>/three.m3u8(0)): Packet not starting with SYNC Byte (got 0xffffffffffffff89)
80May 3 14:47:25 mythtv mythbackend: mythbackend[28492]: I StreamHandler recorders/hlsstreamhandler.cpp:184 (run) HLSSH[56](<URL>/three.m3u8(0)): Packet not starting with SYNC Byte (got 0x7e)
81May 3 14:47:25 mythtv mythbackend: mythbackend[28492]: I StreamHandler recorders/hlsstreamhandler.cpp:184 (run) HLSSH[56](<URL>/three.m3u8(0)): Packet not starting with SYNC Byte (got 0x03)
82May 3 14:47:25 mythtv mythbackend: mythbackend[28492]: I StreamHandler recorders/hlsstreamhandler.cpp:184 (run) HLSSH[56](<URL>/three.m3u8(0)): Packet not starting with SYNC Byte (got 0xffffffffffffffff)
83May 3 14:47:25 mythtv mythbackend: mythbackend[28492]: I StreamHandler recorders/hlsstreamhandler.cpp:184 (run) HLSSH[56](<URL>/three.m3u8(0)): Packet not starting with SYNC Byte (got 0xffffffffffffff86)
84May 3 14:47:25 mythtv mythbackend: mythbackend[28492]: I StreamHandler recorders/hlsstreamhandler.cpp:184 (run) HLSSH[56](<URL>/three.m3u8(0)): Packet not starting with SYNC Byte (got 0xffffffffffffffa3)
85May 3 14:47:25 mythtv mythbackend: mythbackend[28492]: I StreamHandler recorders/hlsstreamhandler.cpp:184 (run) HLSSH[56](<URL>/three.m3u8(0)): Packet not starting with SYNC Byte (got 0xffffffffffffffb3)
86May 3 14:47:25 mythtv mythbackend: mythbackend[28492]: I StreamHandler recorders/hlsstreamhandler.cpp:184 (run) HLSSH[56](<URL>/three.m3u8(0)): Packet not starting with SYNC Byte (got 0x0d)
87May 3 14:47:25 mythtv mythbackend: mythbackend[28492]: I StreamHandler recorders/hlsstreamhandler.cpp:184 (run) HLSSH[56](<URL>/three.m3u8(0)): Packet not starting with SYNC Byte (got 0xffffffffffffffae)
88May 3 14:47:25 mythtv mythbackend: mythbackend[28492]: I StreamHandler recorders/hlsstreamhandler.cpp:184 (run) HLSSH[56](<URL>/three.m3u8(0)): Packet not starting with SYNC Byte (got 0xfffffffffffffff8)
89May 3 14:47:25 mythtv mythbackend: mythbackend[28492]: I StreamHandler recorders/hlsstreamhandler.cpp:184 (run) HLSSH[56](<URL>/three.m3u8(0)): Packet not starting with SYNC Byte (got 0xffffffffffffffe4)
90May 3 14:47:25 mythtv mythbackend: mythbackend[28492]: I StreamHandler recorders/hlsstreamhandler.cpp:184 (run) HLSSH[56](<URL>/three.m3u8(0)): Packet not starting with SYNC Byte (got 0xffffffffffffffbe)
91May 3 14:47:25 mythtv mythbackend: mythbackend[28492]: I StreamHandler recorders/hlsstreamhandler.cpp:184 (run) HLSSH[56](<URL>/three.m3u8(0)): Packet not starting with SYNC Byte (got 0xffffffffffffffb8)
92May 3 14:47:25 mythtv mythbackend: mythbackend[28492]: I StreamHandler recorders/hlsstreamhandler.cpp:184 (run) HLSSH[56](<URL>/three.m3u8(0)): Packet not starting with SYNC Byte (got 0x54)
93May 3 14:47:25 mythtv mythbackend: mythbackend[28492]: I StreamHandler recorders/hlsstreamhandler.cpp:184 (run) HLSSH[56](<URL>/three.m3u8(0)): Packet not starting with SYNC Byte (got 0xffffffffffffffa3)
94May 3 14:47:25 mythtv mythbackend: mythbackend[28492]: I StreamHandler recorders/hlsstreamhandler.cpp:184 (run) HLSSH[56](<URL>/three.m3u8(0)): Packet not starting with SYNC Byte (got 0x28)
95May 3 14:47:26 mythtv mythbackend: mythbackend[28492]: I HLSStream recorders/HLS/HLSReader.cpp:859 (LoadSegments) <URL>/three-2000.m3u8: Throttle done
96