Wed 11/2 6:37:55.108 [SageTVMiniDiscoveryServer@383a37d3] Server got broadcast packet: java.net.DatagramPacket@2f745ea2
Wed 11/2 6:37:55.111 [SageTVMiniDiscoveryServer@383a37d3] Server sent back mini discovery data:java.net.DatagramPacket@2f745ea2 to /192.168.1.110 8271
Wed 11/2 6:37:56.985 [EventRouter-a45e60cbaa8d@3330f1f5] processUserEvent-UserEvent[anything] evtTime=Wed 11/2 6:37:56.985
Wed 11/2 6:37:56.990 [EventRouter-a45e60cbaa8d@3330f1f5] VF.submitJob(VFJob[CloseMF r=0.0 t=0 file=null ifn=null])
Wed 11/2 6:37:56.990 [VideoFrame-a45e60cbaa8d@35e646e1] VF processing job VFJob[CloseMF r=0.0 t=0 file=null ifn=null] nPlayin=false
Wed 11/2 6:37:56.991 [VideoFrame-a45e60cbaa8d@35e646e1] VF processing on UI Thread VFJob[CloseMF r=0.0 t=0 file=null ifn=null]
Wed 11/2 6:37:56.991 [VideoFrame-a45e60cbaa8d@35e646e1] VF processing job null nPlayin=false
Wed 11/2 6:37:56.991 [VideoFrame-a45e60cbaa8d@35e646e1] VF thread is now waiting for 0:00:00.000
Wed 11/2 6:37:56.991 [EventRouter-a45e60cbaa8d@3330f1f5] Seeker.finishWatch(UIManager:localhost@@a45e60cbaa8d-29b58429)
Wed 11/2 6:37:56.993 [AsyncWatch@29a3924] VideoFrame.watch(A[36872809,36543483,"Killing Zoe",0@1121.13:10,0,V])
Wed 11/2 6:37:56.993 [AsyncWatch@29a3924] watchThisFile=MediaFile[id=36543482 A[36872809,36543483,"Killing Zoe",0@1121.13:10,0,V] mask=V host=sagetv encodedBy= format=MATROSKA 0:00:00 0 kbps [] /var/media/videos/Movies/misc/killing_zoe.mkv, Seg0[Thu 11/21 13:10:44.999-Thu 11/21 13:10:45.000]]
Wed 11/2 6:37:56.994 [AsyncWatch@29a3924] Watch airing is a file & over, do that instead dvd=false
Wed 11/2 6:37:56.994 [AsyncWatch@29a3924] Seeker.finishWatch(UIManager:localhost@@a45e60cbaa8d-29b58429)
Wed 11/2 6:37:56.994 [AsyncWatch@29a3924] VF.submitJob(VFJob[WatchMF r=0.0 t=0 file=MediaFile[id=36543482 A[36872809,36543483,"Killing Zoe",0@1121.13:10,0,V] mask=V host=sagetv encodedBy= format=MATROSKA 0:00:00 0 kbps [] /var/media/videos/Movies/misc/killing_zoe.mkv, Seg0[Thu 11/21 13:10:44.999-Thu 11/21 13:10:45.000]] ifn=null])
Wed 11/2 6:37:56.994 [VideoFrame-a45e60cbaa8d@35e646e1] VF processing job VFJob[WatchMF r=0.0 t=0 file=MediaFile[id=36543482 A[36872809,36543483,"Killing Zoe",0@1121.13:10,0,V] mask=V host=sagetv encodedBy= format=MATROSKA 0:00:00 0 kbps [] /var/media/videos/Movies/misc/killing_zoe.mkv, Seg0[Thu 11/21 13:10:44.999-Thu 11/21 13:10:45.000]] ifn=null] nPlayin=false
Wed 11/2 6:37:56.995 [VideoFrame-a45e60cbaa8d@35e646e1] VF processing on UI Thread VFJob[WatchMF r=0.0 t=0 file=MediaFile[id=36543482 A[36872809,36543483,"Killing Zoe",0@1121.13:10,0,V] mask=V host=sagetv encodedBy= format=MATROSKA 0:00:00 0 kbps [] /var/media/videos/Movies/misc/killing_zoe.mkv, Seg0[Thu 11/21 13:10:44.999-Thu 11/21 13:10:45.000]] ifn=null]
Wed 11/2 6:37:56.995 [VideoFrame-a45e60cbaa8d@35e646e1] VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[id=36543482 A[36872809,36543483,"Killing Zoe",0@1121.13:10,0,V] mask=V host=sagetv encodedBy= format=MATROSKA 0:00:00 0 kbps [] /var/media/videos/Movies/misc/killing_zoe.mkv, Seg0[Thu 11/21 13:10:44.999-Thu 11/21 13:10:45.000]] ifn=null] nPlayin=false
Wed 11/2 6:37:56.995 [VideoFrame-a45e60cbaa8d@35e646e1] VF processing on UI Thread VFJob[LoadMF r=0.0 t=0 file=MediaFile[id=36543482 A[36872809,36543483,"Killing Zoe",0@1121.13:10,0,V] mask=V host=sagetv encodedBy= format=MATROSKA 0:00:00 0 kbps [] /var/media/videos/Movies/misc/killing_zoe.mkv, Seg0[Thu 11/21 13:10:44.999-Thu 11/21 13:10:45.000]] ifn=null]
Wed 11/2 6:37:56.995 [VideoFrame-a45e60cbaa8d@35e646e1] VideoFrame creating new media player for file:MediaFile[id=36543482 A[36872809,36543483,"Killing Zoe",0@1121.13:10,0,V] mask=V host=sagetv encodedBy= format=MATROSKA 0:00:00 0 kbps [] /var/media/videos/Movies/misc/killing_zoe.mkv, Seg0[Thu 11/21 13:10:44.999-Thu 11/21 13:10:45.000]]
Wed 11/2 6:37:56.997 [VideoFrame-a45e60cbaa8d@35e646e1] VF file=MediaFile[id=36543482 A[36872809,36543483,"Killing Zoe",0@1121.13:10,0,V] mask=V host=sagetv encodedBy= format=MATROSKA 0:00:00 0 kbps [] /var/media/videos/Movies/misc/killing_zoe.mkv, Seg0[Thu 11/21 13:10:44.999-Thu 11/21 13:10:45.000]] targetTime = Thu 11/21 13:10:44.999
Wed 11/2 6:37:56.998 [VideoFrame-a45e60cbaa8d@35e646e1] VideoFrame.timeSelected(Thu 11/21 13:10:44.999, true) currFile=MediaFile[id=36543482 A[36872809,36543483,"Killing Zoe",0@1121.13:10,0,V] mask=V host=sagetv encodedBy= format=MATROSKA 0:00:00 0 kbps [] /var/media/videos/Movies/misc/killing_zoe.mkv, Seg0[Thu 11/21 13:10:44.999-Thu 11/21 13:10:45.000]]
Wed 11/2 6:37:57.001 [VideoFrame-a45e60cbaa8d@35e646e1] initDriver0()
Wed 11/2 6:37:57.002 [VideoFrame-a45e60cbaa8d@35e646e1] Issuing MCSR client socket to a45e60cbaa8d
Wed 11/2 6:37:57.002 [VideoFrame-a45e60cbaa8d@35e646e1] MiniPlayer established for a45e60cbaa8d
Wed 11/2 6:37:57.005 [VideoFrame-a45e60cbaa8d@35e646e1] getEstimatedBW=0 estimatedBWBytes=0 estimatedBWTime=0
Wed 11/2 6:37:57.005 [VideoFrame-a45e60cbaa8d@35e646e1] MiniPlayer was not able to get a bandwidth estimate from the UI system, sending data to get its own estimate...
Wed 11/2 6:37:57.007 [AsyncWatch@29a3924] setUI(sage.PseudoMenu@1128d006[MediaPlayer OSD]) histIdx=2 uiHistory=[sage.PseudoMenu@79ea3dc[Main Menu], sage.PseudoMenu@4c194fa5[Browser - Videos], sage.PseudoMenu@1128d006[MediaPlayer OSD]] redo=false performingActivation=null
Wed 11/2 6:37:57.027 [VideoFrame-a45e60cbaa8d@35e646e1] Bandwidth test base=4 base*2=11 BW=32792000
Wed 11/2 6:37:57.028 [VideoFrame-a45e60cbaa8d@35e646e1] MiniPlayer got an estimate from the UI on bandwidth of 32792Kbps
Wed 11/2 6:37:57.028 [VideoFrame-a45e60cbaa8d@35e646e1] MiniPlayer is using Pull mode playback
Wed 11/2 6:37:57.028 [VideoFrame-a45e60cbaa8d@35e646e1] openURL0(stv://192.168.1.6//var/media/videos/Movies/misc/killing_zoe.mkv)
Wed 11/2 6:37:57.028 [ActiveRender-a45e60cbaa8d@5f28f66a] VideoFrame got registration of a subtitle UI component: sage.ZCCLabel[loc=java.awt.Point[x=26,y=47] size=java.awt.Dimension[width=123,height=12]]
Wed 11/2 6:37:57.028 [ActiveRender-a45e60cbaa8d@5f28f66a] VideoFrame got registration of a cc UI component: sage.ZCCLabel[loc=java.awt.Point[x=17,y=6] size=java.awt.Dimension[width=141,height=49]]
Wed 11/2 6:37:57.061 [AsyncWatch@29a3924] VideoFrame got registration of a subtitle UI component: null
Wed 11/2 6:37:57.061 [AsyncWatch@29a3924] VideoFrame got registration of a cc UI component: null
Wed 11/2 6:37:57.300 [MediaServerConnection@29a3924] Error in MediaServerConnection of :java.io.EOFException
Wed 11/2 6:37:57.301 [MediaServerConnection@29a3924] java.io.EOFException
Wed 11/2 6:37:57.301 [MediaServerConnection@29a3924] 	at sage.MediaServer$Connection.readLineBytes(MediaServer.java:246)
Wed 11/2 6:37:57.301 [MediaServerConnection@29a3924] 	at sage.MediaServer$Connection.run(MediaServer.java:1416)
Wed 11/2 6:37:57.301 [MediaServerConnection@29a3924] 	at sage.Pooler$PooledThread.run(Pooler.java:253)
Wed 11/2 6:37:57.307 [MediaServerConnection@29a3924] Error in MediaServerConnection of :java.io.EOFException
Wed 11/2 6:37:57.307 [MediaServerConnection@29a3924] java.io.EOFException
Wed 11/2 6:37:57.307 [MediaServerConnection@29a3924] 	at sage.MediaServer$Connection.readLineBytes(MediaServer.java:246)
Wed 11/2 6:37:57.307 [MediaServerConnection@29a3924] 	at sage.MediaServer$Connection.run(MediaServer.java:1416)
Wed 11/2 6:37:57.307 [MediaServerConnection@29a3924] 	at sage.Pooler$PooledThread.run(Pooler.java:253)
Wed 11/2 6:37:58.126 [AWTThreadWatcher-a45e60cbaa8d@538d04ec] EventThread-a45e60cbaa8d Hang Detected - hang time = 751 UILocker=Thread[ActiveRender-a45e60cbaa8d,2,main]
Wed 11/2 6:37:58.309 [FinalRender-a45e60cbaa8d@13edeb7f] Allocated image-5 for images/tvicon_anim1.png width=31 height=31 ptr=84
Wed 11/2 6:37:58.437 [VideoFrame-a45e60cbaa8d@35e646e1] Sigma video dim=java.awt.Dimension[width=1920,height=1030]
Wed 11/2 6:37:58.440 [VideoFrame-a45e60cbaa8d@35e646e1] getDuration : 0
Wed 11/2 6:37:58.440 [VideoFrame-a45e60cbaa8d@35e646e1] setMute0()
Wed 11/2 6:37:58.443 [VideoFrame-a45e60cbaa8d@35e646e1] VideoFrame.timeSelected2(Thu 11/21 13:10:44.999, true) currFile=MediaFile[id=36543482 A[36872809,36543483,"Killing Zoe",0@1121.13:10,0,V] mask=V host=sagetv encodedBy= format=MATROSKA 0:00:00 0 kbps [] /var/media/videos/Movies/misc/killing_zoe.mkv, Seg0[Thu 11/21 13:10:44.999-Thu 11/21 13:10:45.000]] realDur=0
Wed 11/2 6:37:58.443 [VideoFrame-a45e60cbaa8d@35e646e1] playPush0()
Wed 11/2 6:37:58.445 [VideoFrame-a45e60cbaa8d@35e646e1] Channel Change Time=1478083078445 NOTE: Not valid if this was not a direct channel change!
Wed 11/2 6:37:58.446 [VideoFrame-a45e60cbaa8d@35e646e1] getDuration : 0
Wed 11/2 6:37:58.446 [VideoFrame-a45e60cbaa8d@35e646e1] VF processing job null nPlayin=true
Wed 11/2 6:37:58.446 [VideoFrame-a45e60cbaa8d@35e646e1] isRec=false rd=1 base=0 eos=false
Wed 11/2 6:37:58.446 [VideoFrame-a45e60cbaa8d@35e646e1] VF thread is now waiting for 0:00:00.000
Wed 11/2 6:37:58.447 [ActiveRender-a45e60cbaa8d@5f28f66a] VideoFrame got registration of a subtitle UI component: sage.ZCCLabel[loc=java.awt.Point[x=79,y=281] size=java.awt.Dimension[width=369,height=74]]
Wed 11/2 6:37:58.447 [ActiveRender-a45e60cbaa8d@5f28f66a] VideoFrame got registration of a cc UI component: sage.ZCCLabel[loc=java.awt.Point[x=52,y=37] size=java.awt.Dimension[width=422,height=296]]
Wed 11/2 6:37:58.456 [FinalRender-a45e60cbaa8d@13edeb7f] Allocated image-5 for /opt/sagetv/server/STVs/SageTV7/Themes/Standard/OSDBGTop.png width=8 height=150 ptr=85
Wed 11/2 6:37:58.457 [ReProcessHook@e741fa5] Start looking for max channel digits
Wed 11/2 6:37:58.458 [ReProcessHook@e741fa5] New max channel = 3, from channel 22-4 on lineup 'Digital Broadcast - 21075'
Wed 11/2 6:37:58.459 [ReProcessHook@e741fa5] New max channel = 4, from channel 30-11 on lineup 'Digital Broadcast - 21075'
Wed 11/2 6:37:58.466 [ReProcessHook@e741fa5] Done looking for max channel digits; elapsed time: 9
Wed 11/2 6:37:58.656 [FinalRender-a45e60cbaa8d@13edeb7f] Allocated image-5 for /opt/sagetv/server/STVs/SageTV7/Themes/Standard/OSDVolBarVertical.png width=4 height=64 ptr=86
Wed 11/2 6:37:58.675 [Fork-OPUS4-111999@34bac53c] ENDING background video thumb preloader thread, ID = 0.6482231473459615
Wed 11/2 6:37:58.772 [FinalRender-a45e60cbaa8d@13edeb7f] Allocated image-5 for /opt/sagetv/server/STVs/SageTV7/Themes/Standard/OSDVolSliderVertical.png width=15 height=13 ptr=87
Wed 11/2 6:37:58.798 [FinalRender-a45e60cbaa8d@13edeb7f] Allocated image-5 for /opt/sagetv/server/STVs/SageTV7/Themes/Standard/OSDVolOn.png width=16 height=13 ptr=88
Wed 11/2 6:37:58.835 [FinalRender-a45e60cbaa8d@13edeb7f] Allocated image-5 for MetaFont[fonts/DejaVuSansMono, size=11, style=0, height=13.0, ascent=10.0, descent=3.0, leading=0.0, class=class sage.FreetypeFont] width=128 height=128 ptr=89
Wed 11/2 6:37:58.987 [FinalRender-a45e60cbaa8d@13edeb7f] Allocated image-5 for /opt/sagetv/server/STVs/SageTV7/Themes/Standard/OSDBGBottom.png width=8 height=150 ptr=90
Wed 11/2 6:37:59.146 [FinalRender-a45e60cbaa8d@13edeb7f] Allocated image-5 for /opt/sagetv/server/STVs/SageTV7/Themes/Standard/OSDStatusPause.png width=6 height=8 ptr=91
Wed 11/2 6:37:59.158 [FinalRender-a45e60cbaa8d@13edeb7f] Allocated image-5 for /opt/sagetv/server/STVs/SageTV7/Themes/Standard/OSDReplay.png width=29 height=26 ptr=92
Wed 11/2 6:37:59.204 [FinalRender-a45e60cbaa8d@13edeb7f] Allocated image-5 for /opt/sagetv/server/STVs/SageTV7/Themes/Standard/OSDRew.png width=30 height=26 ptr=93
Wed 11/2 6:37:59.244 [FinalRender-a45e60cbaa8d@13edeb7f] Allocated image-5 for /opt/sagetv/server/STVs/SageTV7/Themes/Standard/OSDFwd.png width=30 height=26 ptr=94
Wed 11/2 6:37:59.292 [FinalRender-a45e60cbaa8d@13edeb7f] Allocated image-5 for /opt/sagetv/server/STVs/SageTV7/Themes/Standard/OSDSkip.png width=29 height=26 ptr=95
Wed 11/2 6:37:59.323 [FinalRender-a45e60cbaa8d@13edeb7f] Allocated image-5 for /opt/sagetv/server/STVs/SageTV7/Themes/Standard/OSDStop.png width=30 height=26 ptr=96
Wed 11/2 6:37:59.323 [FinalRender-a45e60cbaa8d@13edeb7f] Loading RawImage of size 31x31 for images/tvicon_anim2.png fileSize=10631
Wed 11/2 6:37:59.324 [FinalRender-a45e60cbaa8d@13edeb7f] Loading 31x31 image from file /tmp/stv9159022202069252083.img
Wed 11/2 6:37:59.363 [FinalRender-a45e60cbaa8d@13edeb7f] Allocated image-5 for images/tvicon_anim2.png width=31 height=31 ptr=97
Wed 11/2 6:37:59.503 [FinalRender-a45e60cbaa8d@13edeb7f] Allocated image-5 for /opt/sagetv/server/STVs/SageTV7/Themes/Standard/OSDStatusPlay.png width=8 height=8 ptr=98
Wed 11/2 6:37:59.520 [FinalRender-a45e60cbaa8d@13edeb7f] Allocated image-5 for /opt/sagetv/server/STVs/SageTV7/Themes/Standard/OSDPause.png width=30 height=26 ptr=99