So I watched a ton of live TV today and had it skip about 4 times. That being said the error message was different for 3 of them (So not sure if its different issue or you changed the logging). Twice it happened while watching and recording live TV same channel (But I hadn't really done that in the past and do that rarely so not sure that could be a slightly different issue). The last time it happened was probably hardware/system related based off the error message.
All that being said overall I think its in a pretty good state. I am actually super happy with how it is doing right now. If you didn't change the logging I think I am close to calling this fully fixed. I might have stumbled upon another tiny issue (maybe?) but I haven't seen that up till this point so whatever no big deal to me. And of course below is logs with comments above each.
Summary of each of the 4 issues in order.
-
Typical error message and it happened within 2-3 minutes of flipping on live TV which made me fairly nervous.
-
Different error was watching live at the same time as recording
-
Same as above (This and the above happened while watching a show for about 30-40 minutes)
-
Different error (In the error mentions doing too much work on the main thread... so this could be the shields fault and not Channels)
//Very shortly after turning ESPN on 11-13 11:09:59.180 9867 9867 V mpvstats: AV: 103.980 A-V: 0.000 Dropped: 0 Cache: 0.584s video + 0.000s audio 11-13 11:09:59.180 9867 9867 V streamstats: strength=100% quality=100% symbol=100% rate=17.7Mb/sec 11-13 11:10:02.180 9867 9867 V mpvstats: AV: 106.983 A-V: 0.000 Dropped: 0 Cache: 0.484s video + 0.000s audio 11-13 11:10:02.180 9867 9867 V streamstats: strength=100% quality=100% symbol=100% rate=17.7Mb/sec 11-13 11:10:02.436 9867 9907 V mpv : [ao/audiotrack] Underrun detected: 0 -> 1 11-13 11:10:02.444 9867 9907 W mpv : [cplayer] Audio device underrun detected. 11-13 11:10:02.444 9867 9907 V mpv : event: unpause 11-13 11:10:02.444 9867 9907 V mpv : [cplayer] Enter buffering (buffer went from 100% -> 0%) [0.000000s]. 11-13 11:10:02.444 9867 9907 V mpv : property: buffering = true 11-13 11:10:02.444 9867 9867 V PlayerActivity: state PLAYING -> BUFFERING 11-13 11:10:02.612 9867 9907 W mpv : [ao/audiotrack] getLatency: written=5981696 playhead=5983031 diff=4294965961 delay=76695.820732 11-13 11:10:02.665 9867 9907 V mpv : [cplayer] Still buffering (buffer went from 0% -> 25%) [0.128000s]. 11-13 11:10:02.673 9867 9907 V mpv : [cplayer] Still buffering (buffer went from 25% -> 6%) [0.032000s]. 11-13 11:10:02.675 9867 9907 V mpv : [cplayer] Still buffering (buffer went from 6% -> 0%) [0.000000s]. 11-13 11:10:02.681 9867 9867 V mpvstats: demuxer-cache-state: {cache-end=107.42399988888974, reader-pts=107.42399988888974, cache-duration=0.0, eof=false, underrun=true, idle=false, total-bytes=95926416, fw-bytes=739920, raw-input-rate=2325848, debug-low-level-seeks=0, debug-byte-level-seeks=1, debug-ts-last=9685.103500000001, ts-per-stream=[{type=video, duration=0.3837111111115519, reader=9684.71978888889, end=9685.103500000001}, {type=audio, duration=0.0, reader=9684.87688888889, end=9684.87688888889}], bof-cached=false, eof-cached=false, seekable-ranges=[{start=65.74207766666768, end=107.39199988888868}]} 11-13 11:10:02.715 9867 9907 V mpv : [cplayer] Still buffering (buffer went from 0% -> 57%) [0.288000s]. 11-13 11:10:02.862 9867 9907 V mpv : [cplayer] Still buffering (buffer went from 57% -> 76%) [0.384000s]. 11-13 11:10:03.062 9867 9907 V mpv : event: unpause 11-13 11:10:03.062 9867 9907 V mpv : [cplayer] End buffering (waited 0.617832 secs) [0.608000s]. 11-13 11:10:03.062 9867 9907 V mpv : [cplayer] restarting audio after underrun 11-13 11:10:03.063 9867 9907 V mpv : property: buffering = false 11-13 11:10:03.063 9867 9867 V PlayerActivity: state BUFFERING -> PLAYING 11-13 11:10:03.066 9867 9867 V mpvstats: demuxer-cache-state: {cache-end=108.12799988888946, reader-pts=107.51999988888929, cache-duration=0.6080000000001746, eof=false, underrun=false, idle=false, total-bytes=95909040, fw-bytes=2450928, raw-input-rate=2327025, debug-low-level-seeks=0, debug-byte-level-seeks=1, debug-ts-last=9684.736466777778, ts-per-stream=[{type=video, duration=1.0844111111109669, reader=9684.753155555556, end=9685.837566666667}, {type=audio, duration=0.6080000000001746, reader=9684.97288888889, end=9685.58088888889}], bof-cached=false, eof-cached=false, seekable-ranges=[{start=66.49283322222254, end=108.0959998888884}]} 11-13 11:10:03.066 9867 12568 W AudioTrack: releaseBuffer() track 0x2f21c65800 disabled due to previous underrun, restarting 11-13 11:10:03.346 9867 9867 V mpvstats: AV: 107.534 A-V: 0.000 Dropped: 9 Cache: 1.001s video + 0.480s audio 11-13 11:10:05.196 9867 9867 V mpvstats: AV: 109.402 A-V: 0.000 Dropped: 9 Cache: 1.151s video + 0.448s audio 11-13 11:10:05.196 9867 9867 V streamstats: strength=100% quality=100% symbol=100% rate=17.7Mb/sec //Different error but saw it again shortly after going to ESPN News (I was watching live and recording this one at same time) 11-13 14:28:49.684 9867 9867 V streamstats: strength=100% quality=100% symbol=100% rate=12.5Mb/sec 11-13 14:28:52.684 9867 9867 V mpvstats: AV: 58.125 A-V: 0.000 Dropped: 4 Cache: 1.618s video + 0.640s audio 11-13 14:28:52.684 9867 9867 V streamstats: strength=100% quality=100% symbol=100% rate=12.5Mb/sec 11-13 14:28:54.759 9867 17031 W AudioTrack: releaseBuffer() track 0x2f21c65800 disabled due to previous underrun, restarting 11-13 14:28:54.760 9867 9907 V mpv : [ao/audiotrack] Underrun detected: 0 -> 1 11-13 14:28:54.870 9867 9877 I s.dvr.app.alph: Background concurrent copying GC freed 316099(11MB) AllocSpace objects, 37(2MB) LOS objects, 49% free, 11MB/23MB, paused 88us total 266.066ms 11-13 14:28:54.872 9867 9875 W System : A resource failed to call end. 11-13 14:28:54.872 9867 9875 W System : A resource failed to call end. 11-13 14:28:55.519 9867 9867 V mpvstats: AV: 60.928 A-V: 0.000 Dropped: 10 Cache: 1.668s video + 0.704s audio 11-13 14:28:55.685 9867 9867 V mpvstats: AV: 61.095 A-V: 0.000 Dropped: 10 Cache: 1.735s video + 0.704s audio 11-13 14:28:55.685 9867 9867 V streamstats: strength=100% quality=100% symbol=100% rate=12.1Mb/sec 11-13 14:28:58.009 9867 17056 V ApiServer: REQUEST: GET /log 11-13 14:28:58.685 9867 9867 V mpvstats: AV: 64.098 A-V: 0.000 Dropped: 10 Cache: 1.501s video + 0.640s audio 11-13 14:28:58.685 9867 9867 V streamstats: strength=100% quality=100% symbol=100% rate=11.5Mb/sec //Again with the different error on ESPN News again (recording still) 11-13 14:59:36.258 9867 9867 V streamstats: strength=100% quality=100% symbol=100% rate=15.1Mb/sec 11-13 14:59:38.466 9867 9867 V mpvstats: AV: 1903.418 A-V: 0.000 Dropped: 17 Cache: 2.102s video + 1.120s audio 11-13 14:59:38.477 9867 9907 V mpv : [ao/audiotrack] Underrun detected: 1 -> 2 11-13 14:59:38.478 9867 9907 W mpv : [ao/audiotrack] getLatency: written=106568000 playhead=106577668 diff=4294957628 delay=76695.671929 11-13 14:59:38.490 9867 17031 W AudioTrack: releaseBuffer() track 0x2f21c65800 disabled due to previous underrun, restarting 11-13 14:59:38.556 9867 9877 I s.dvr.app.alph: Background concurrent copying GC freed 304279(11MB) AllocSpace objects, 0(0B) LOS objects, 50% free, 11MB/23MB, paused 90us total 543.147ms 11-13 14:59:39.259 9867 9867 V mpvstats: AV: 1904.369 A-V: 0.000 Dropped: 35 Cache: 2.069s video + 1.024s audio 11-13 14:59:39.259 9867 9867 V streamstats: strength=100% quality=100% symbol=100% rate=12.4Mb/sec 11-13 14:59:39.466 9867 9867 V mpvstats: AV: 1904.570 A-V: 0.000 Dropped: 35 Cache: 2.052s video + 1.024s audio //ACC Network (Based of the error message it sounds like the shield not the software but including it just in-case!) 11-13 18:53:59.352 9867 9867 V mpvstats: AV: 12366.930 A-V: 0.000 Dropped: 13 Cache: 4.204s video + 3.200s audio 11-13 18:53:59.353 9867 9867 V streamstats: strength=100% quality=100% symbol=100% rate=14.2Mb/sec 11-13 18:54:00.702 9867 9867 I Choreographer: Skipped 37 frames! The application may be doing too much work on its main thread. 11-13 18:54:00.711 9867 9867 V mpvstats: AV: 12367.664 A-V: 0.160 Dropped: 19 Cache: 4.788s video + 3.840s audio 11-13 18:54:00.789 9867 18335 W AudioTrack: releaseBuffer() track 0x2ed2d00000 disabled due to previous underrun, restarting 11-13 18:54:00.802 9867 9907 V mpv : [ao/audiotrack] Underrun detected: 0 -> 1 11-13 18:54:00.882 9867 9877 I s.dvr.app.alph: Background concurrent copying GC freed 285439(10MB) AllocSpace objects, 0(0B) LOS objects, 50% free, 10MB/21MB, paused 80us total 822.787ms 11-13 18:54:01.713 9867 9867 V mpvstats: AV: 12368.932 A-V: 0.000 Dropped: 28 Cache: 4.454s video + 3.776s audio 11-13 18:54:02.354 9867 9867 V mpvstats: AV: 12369.583 A-V: 0.000 Dropped: 28 Cache: 4.605s video + 3.872s audio 11-13 18:54:02.354 9867 9867 V streamstats: strength=100% quality=100% symbol=100% rate=13.6Mb/sec
I have said it before but thank you so much! I am sure its not a easy issue to work on/track down at all and I do appreciate it. Curious to hear if you have any comments on the above. And of course, If there is anything else I can test or look into let me know!