Thu 1/1 0:00:08.210 [main] user.dir2=/app/sage
Thu 1/1 0:00:08.212 [main] classpath=Sage-CDC.jar:vecmath.jar
Thu 1/1 0:00:08.214 [main] JVM version=phoneme_advanced_mr2-b122
Thu 1/1 0:00:08.216 [main] OS=Linux 2.6.33-5-sigma
Thu 1/1 0:00:08.300 [main] SageTV V7.0.11.1
Thu 1/1 0:00:08.303 [main] hostname=HD300
Thu 1/1 0:00:08.353 [main] Executing process: [sh, -c, mount | grep nfs]
Thu 1/1 0:00:08.423 [main] NFS Mount Detected=false
Thu 1/1 0:00:08.579 [main] Increased Thread pool to size=1
Thu 1/1 0:00:08.591 [main] Splash: User Interface Manager is initializing...
Thu 1/1 0:00:08.593 [NetworkConfig] Establishing network setup...
Thu 1/1 0:00:08.660 [NetworkConfig] Performing network configuration...
Thu 1/1 0:00:08.666 [NetworkConfig] Executing process: /app/sage/scripts/stopdhcp
Thu 1/1 0:00:08.834 [NetworkConfig] Executing process: /app/sage/scripts/stopwpasup
Thu 1/1 0:00:09.033 [NetworkConfig] Executing process: [ifconfig, eth0, down]
Thu 1/1 0:00:09.062 [NetworkConfig] Using static IP addressing
Thu 1/1 0:00:09.064 [NetworkConfig] Executing process: [/app/sage/scripts/setnet, eth0, 10.0.0.22, 255.255.255.0, 10.0.0.1, 10.0.0.2]
Thu 1/1 0:00:09.231 [NetworkConfig] Executing process: [sh, -c, rdate -s time-a.nist.gov &]
Thu 1/1 0:00:09.401 [main] Splash: Object Database is initializing...
Thu 1/1 0:00:09.635 [NetworkConfig] Establishing network setup...DONE
Thu 1/1 0:00:09.895 [main] dbFile=/rw/sage/Wiz.bin dbBackupFile=/rw/sage/Wiz.bak
Thu 1/1 0:00:09.896 [main] Wizard starting to load database info...
Thu 1/1 0:00:09.897 [main] Splash: Object Database Source is initializing...
Thu 1/1 0:00:09.898 [main] loadDBFile file=/rw/sage/Wiz.bin
Thu 1/1 0:00:09.921 [main] DBFile at version 70 FileSize=2867
Thu 1/1 0:00:09.922 [main] Wizard allocating table for Year of size 0
Thu 1/1 0:00:09.925 [main] Splash: Object Database is initializing... 0% done
Thu 1/1 0:00:09.926 [main] Wizard allocating table for Network of size 0
Thu 1/1 0:00:09.927 [main] Splash: Object Database is initializing... 0% done
Thu 1/1 0:00:09.928 [main] Wizard allocating table for Title of size 8
Thu 1/1 0:00:09.929 [main] Splash: Object Database is initializing... 1% done
Thu 1/1 0:00:09.945 [main] Wizard loading main index for Title bytes=288
Thu 1/1 0:00:09.947 [main] Wizard building alt. index 0 for Title
Thu 1/1 0:00:09.977 [main] Splash: Object Database is initializing... 1% done
Thu 1/1 0:00:09.978 [main] Wizard allocating table for Channel of size 1
Thu 1/1 0:00:10.016 [main] Splash: Object Database is initializing... 11% done
Thu 1/1 0:00:10.017 [main] Wizard loading main index for Channel bytes=26
Thu 1/1 0:00:10.028 [main] Splash: Object Database is initializing... 12% done
Thu 1/1 0:00:10.029 [main] Wizard allocating table for People of size 0
Thu 1/1 0:00:10.030 [main] Splash: Object Database is initializing... 12% done
Thu 1/1 0:00:10.034 [main] Wizard allocating table for SubCategory of size 0
Thu 1/1 0:00:10.035 [main] Splash: Object Database is initializing... 13% done
Thu 1/1 0:00:10.036 [main] Wizard allocating table for Rated of size 0
Thu 1/1 0:00:10.037 [main] Splash: Object Database is initializing... 13% done
Thu 1/1 0:00:10.038 [main] Wizard allocating table for ParentalRating of size 0
Thu 1/1 0:00:10.039 [main] Splash: Object Database is initializing... 14% done
Thu 1/1 0:00:10.040 [main] Wizard allocating table for ExtendedRating of size 0
Thu 1/1 0:00:10.041 [main] Splash: Object Database is initializing... 14% done
Thu 1/1 0:00:10.042 [main] Wizard allocating table for Category of size 0
Thu 1/1 0:00:10.044 [main] Splash: Object Database is initializing... 14% done
Thu 1/1 0:00:10.045 [main] Wizard allocating table for PrimeTitle of size 0
Thu 1/1 0:00:10.057 [main] Splash: Object Database is initializing... 15% done
Thu 1/1 0:00:10.058 [main] Wizard allocating table for Bonus of size 0
Thu 1/1 0:00:10.059 [main] Splash: Object Database is initializing... 15% done
Thu 1/1 0:00:10.059 [main] Wizard allocating table for Show of size 12
Thu 1/1 0:00:10.061 [main] Splash: Object Database is initializing... 15% done
Thu 1/1 0:00:10.061 [main] Wizard loading main index for Show bytes=1241
Thu 1/1 0:00:10.066 [main] Wizard building alt. index 0 for Show
Thu 1/1 0:00:10.072 [main] Splash: Object Database is initializing... 16% done
Thu 1/1 0:00:10.073 [main] Wizard allocating table for Airing of size 11
Thu 1/1 0:00:10.079 [main] Splash: Object Database is initializing... 59% done
Thu 1/1 0:00:10.080 [main] Wizard loading main index for Airing bytes=270
Thu 1/1 0:00:10.081 [main] Wizard building alt. index 0 for Airing
Thu 1/1 0:00:10.082 [main] Wizard building alt. index 1 for Airing
Thu 1/1 0:00:10.083 [main] Splash: Object Database is initializing... 59% done
Thu 1/1 0:00:10.084 [main] Wizard allocating table for ManualRecord of size 0
Thu 1/1 0:00:10.088 [main] Splash: Object Database is initializing... 69% done
Thu 1/1 0:00:10.089 [main] Wizard allocating table for MediaFile of size 0
Thu 1/1 0:00:10.091 [main] Splash: Object Database is initializing... 69% done
Thu 1/1 0:00:10.091 [main] Wizard allocating table for Watched of size 11
Thu 1/1 0:00:10.093 [main] Splash: Object Database is initializing... 69% done
Thu 1/1 0:00:10.093 [main] Wizard loading main index for Watched bytes=633
Thu 1/1 0:00:10.107 [main] Wizard building alt. index 0 for Watched
Thu 1/1 0:00:10.113 [main] Splash: Object Database is initializing... 70% done
Thu 1/1 0:00:10.113 [main] Wizard allocating table for Agent of size 0
Thu 1/1 0:00:10.114 [main] Splash: Object Database is initializing... 92% done
Thu 1/1 0:00:10.115 [main] Wizard allocating table for Wasted of size 0
Thu 1/1 0:00:10.119 [main] Splash: Object Database is initializing... 92% done
Thu 1/1 0:00:10.120 [main] Wizard allocating table for Playlist of size 0
Thu 1/1 0:00:10.121 [main] Splash: Object Database is initializing... 93% done
Thu 1/1 0:00:10.125 [main] Wizard allocating table for TVEditorial of size 0
Thu 1/1 0:00:10.174 [main] Splash: Object Database is initializing... 93% done
Thu 1/1 0:00:10.176 [main] Wizard allocating table for SeriesInfo of size 0
Thu 1/1 0:00:10.179 [main] Splash: Object Database is initializing... 93% done
Thu 1/1 0:00:10.182 [main] Wizard processing transactional records...
Thu 1/1 0:00:10.185 [main] Splash: Object Database is initializing... 94% done
Thu 1/1 0:00:10.204 [main] Wizard performing validation on database objects...
Thu 1/1 0:00:10.206 [main] Wizard DONE loading database info. loadTime=0.31 sec
Thu 1/1 0:00:10.207 [main] DB RAM Size estimated to be: 192
Thu 1/1 0:00:10.208 [main] Splash: Object Database is initializing... 100% done
Thu 1/1 0:00:10.263 [main] Splash: EPG is initializing...
Thu 1/1 0:00:10.325 [main] Increased hash set pool to size=1
Thu 1/1 0:00:10.377 [main] Splash: Profiler is initializing...
Thu 1/1 0:00:10.511 [main] Splash: Acquisition System is initializing...
Thu 1/1 0:00:10.512 [main] Splash: SageTV Core is initializing...
Thu 1/1 0:00:10.637 [main] Alive!!!
Thu 1/1 0:00:10.640 [SageTV] Splash: Profiler is initializing... 0% done
Thu 1/1 0:00:10.651 [SageTV] CARNY Processing 0 Agents & 0 Airs
Thu 1/1 0:00:10.655 [SageTV] CARNY Negative Energy Size: 0
Thu 1/1 0:00:10.657 [SageTV] CARNY Traitors:[]
Thu 1/1 0:00:10.670 [Seeker] Stopping the smbd/nmbd servers
Thu 1/1 0:00:10.672 [FSManager] FSManager is running...
Thu 1/1 0:00:10.676 [Seeker] Executing process: /app/sage/scripts/stopsmbd
Thu 1/1 0:00:10.719 [SageTV] Splash: Waiting for SageTV to finish initialization...
Thu 1/1 0:00:10.780 [Scheduler] Scheduler.updateSchedule() called time=Thu 1/1 0:00:10.761 manual=[] schedules={} scheduleRandSize=0
Thu 1/1 0:00:10.823 [SageTVServer] SageTVServer was instantiated.
Thu 1/1 0:00:10.887 [SageTVDiscoveryServer] SageTVDiscoveryServer was instantiated.
Thu 1/1 0:00:11.154 [SageTV] Splash: User Interface Manager is initializing...
Thu 1/1 0:00:11.219 [HotplugStorage] StorageDeviceDetector started...
Thu 1/1 0:00:11.220 [SageTV] Loading all core plugins...
Thu 1/1 0:00:11.448 [SageTV] LOADED PLUGINS=[]
Thu 1/1 0:00:11.452 [SageTV] Don't save the properties file....it's not dirty...
Thu 1/1 0:00:11.452 [SageTV] Starting all core plugins...
Thu 1/1 0:00:11.540 [SageTV] Done starting core plugins.
Thu 1/1 0:00:11.756 [PluginRepoUpdater] Checking to see if the plugin repository file needs to be updated...
Thu 1/1 0:00:11.967 [PluginRepoUpdater] Downloading new SageTVPlugins.xml file from the server...
Thu 1/1 0:00:12.029 [PluginRepoUpdater] Download requested for files server=http://download.sagetv.com/SageTVPlugins.xml src=null dest=/rw/sage/SageTVPlugins.xml
Thu 1/1 0:00:12.090 [PluginRepoUpdater] Download remoteSize=272287
Thu 1/1 0:00:12.488 [FileDownload] Read -1 bytes from the URL stream; download has completed
Thu 1/1 0:00:12.515 [FileDownload] VF.submitJob(VFJob[InactiveFile r=0.0 t=0 file=null ifn=/rw/sage/SageTVPlugins.xml])
Thu 1/1 0:00:12.569 [FileDownload] Download completed for files server=http://download.sagetv.com/SageTVPlugins.xml src=null dest=/rw/sage/SageTVPlugins.xml abort=false
Thu 1/1 0:00:12.872 [PluginRepoUpdater] Analyzing plugin repository XML file...
Thu 1/1 0:00:15.162 [PluginRepoUpdater] Done processing plugin repository XML file  repositoryVersion=1.0.672
Thu 1/1 0:00:16.815 [Seeker] Diskspace checking is running
Thu 1/1 0:00:16.816 [Seeker] Seeker waiting for 153722867280912 mins.
Thu 1/1 0:00:16.818 [Seeker] Seeker awoken
Thu 1/1 0:00:16.819 [Seeker] MemStats: Used=2MB Total=2MB Max=167MB
Thu 1/1 0:00:16.835 [Seeker] Diskspace checking is running
Thu 1/1 0:00:16.836 [Seeker] Seeker waiting for 153722867280912 mins.
Thu 1/1 0:00:17.450 [MiniUIServer] MiniUI got connection from java.nio.channels.SocketChannel[connected local=/127.0.0.1:31099 remote=/127.0.0.1:38133]
Thu 1/1 0:00:17.461 [MiniUIServer] MiniUI got connection from java.nio.channels.SocketChannel[connected local=/127.0.0.1:31099 remote=/127.0.0.1:38134]
Thu 1/1 0:00:17.472 [MiniUIServerConnection] MiniPlayer is adding to its map:0023a50000e0
Thu 1/1 0:00:17.479 [MiniUIServerConnection] MiniUI is adding to its map:0023a50000e0
Thu 1/1 0:00:17.482 [MiniUIServerConnection] Creating new UI for client:0023a50000e0
Thu 1/1 0:00:17.484 [MiniUIServerConnection] Creating-2 new UI for client:0023a50000e0 UIManager:localhost@@0023a50000e0--7566cc7a
Thu 1/1 0:00:17.592 [MiniUIServerConnection] MiniUI established for 0023a50000e0
Thu 1/1 0:00:17.596 [MiniUIClientReceiver] Got UI size update to 1024x576
Thu 1/1 0:00:18.491 [MiniUIServerConnection] MiniClient GFX_TEXTMODE=NONE
Thu 1/1 0:00:18.498 [MiniUIServerConnection] MiniClient FIRMWARE_VERSION=20100921-0
Thu 1/1 0:00:18.498 [MiniUIServerConnection] MiniClient GFX_BLENDMODE=POSTMULTIPLY
Thu 1/1 0:00:18.502 [MiniUIServerConnection] MiniClient GFX_DRAWMODE=UPDATE
Thu 1/1 0:00:18.507 [MiniUIServerConnection] MiniClient GFX_SURFACES=TRUE
Thu 1/1 0:00:18.509 [MiniUIServerConnection] MiniClient GFX_HIRES_SURFACES=TRUE
Thu 1/1 0:00:18.513 [MiniUIServerConnection] MiniClient GFX_VIDEO_UPDATE=TRUE
Thu 1/1 0:00:18.515 [MiniUIServerConnection] MiniClient GFX_VIDEO_MASKS=31
Thu 1/1 0:00:18.520 [MiniUIServerConnection] MiniClient GFX_BITMAP_FORMAT=RAW32,RAW8,PNG,JPG,SCALE,DIRECT
Thu 1/1 0:00:18.521 [MiniUIServerConnection] MiniClient GFX_SCALING=HARDWARE
Thu 1/1 0:00:18.525 [MiniUIServerConnection] MiniClient GFX_NEGSCALING=VERTICAL
Thu 1/1 0:00:18.527 [MiniUIServerConnection] MiniClient GFX_OFFLINE_IMAGE_CACHE=null
Thu 1/1 0:00:18.600 [MiniUIServerConnection] MiniClient GFX_YUV_IMAGE_CACHE=UNIFIED
Thu 1/1 0:00:18.601 [MiniUIServerConnection] MiniClient GFX_SUPPORTED_ASPECTS=4:3,16:9
Thu 1/1 0:00:18.602 [MiniUIServerConnection] MiniClient GFX_ASPECT=
Thu 1/1 0:00:18.604 [MiniUIServerConnection] MiniClient GFX_DIFFUSE_TEXTURES=ALPHA
Thu 1/1 0:00:18.606 [MiniUIServerConnection] MiniClient GFX_XFORMS=null
Thu 1/1 0:00:18.608 [MiniUIServerConnection] MiniClient GFX_TEXTURE_BATCH_LIMIT=8192
Thu 1/1 0:00:18.609 [MiniUIServerConnection] MiniClient VIDEO_ADVANCED_ASPECT_LIST=Source;Fill|blackstrip=0,0|cutstrip=0,0;ZoomA|blackstrip=0,4096;ZoomB|source=2048,2048,3072,3072,FrontEdgeToCenter,FrontEdgeToCenter,Relative,Relative,Relative,Relative;ZoomC|source=2048,2048,4096,3072,FrontEdgeToCenter,FrontEdgeToCenter,Relative,Relative,Relative,Relative;Fill Wide|source=2048,2048,3072,4096,FrontEdgeToCenter,FrontEdgeToCenter,Relative,Relative,Relative,Relative|blackstrip=0,0|cutstrip=0,0;
Thu 1/1 0:00:18.613 [MiniUIServerConnection] MiniClient GFX_SUPPORTED_RESOLUTIONS=720x480i@59.94|standard=NTSC_M;720x480p@59.94|standard=480p59;1280x720p@59.94|standard=720p59;1920x1080p@23.976|standard=1080p23;1920x1080i@59.94|standard=1080i59;1920x1080p@59.94|standard=1080p59;
Thu 1/1 0:00:18.653 [MiniUIServerConnection] MiniClient GFX_RESOLUTION=0
Thu 1/1 0:00:18.654 [MiniUIServerConnection] MiniClient GFX_COMPOSITE=BLEND
Thu 1/1 0:00:18.655 [MiniUIServerConnection] MiniClient GFX_COLORKEY=00000000
Thu 1/1 0:00:18.656 [MiniUIServerConnection] MiniClient AUDIO_OUTPUTS=Analog;Digital;HDMI;HDMIHBR
Thu 1/1 0:00:18.658 [MiniUIServerConnection] MiniClient AUDIO_OUTPUT=null
Thu 1/1 0:00:18.659 [MiniUIServerConnection] MiniClient INPUT_DEVICES=null
Thu 1/1 0:00:18.660 [MiniUIServerConnection] MiniClient DISPLAY_OVERSCAN=null
Thu 1/1 0:00:18.661 [MiniUIServerConnection] MiniClient VIDEO_CODECS=MPEG2-VIDEO,MPEG2-VIDEO@HL,MPEG1-VIDEO,MPEG4-VIDEO,DIVX3,MSMPEG4,FLASHVIDEO,H.264,WMV9,VC1,MJPEG
Thu 1/1 0:00:18.662 [MiniUIServerConnection] MiniClient AUDIO_CODECS=MPG1L2,MPG1L3,AC3,AAC,AAC-HE,WMA,FLAC,VORBIS,PCM,DTS,DCA,PCM_S16LE,WMA8,ALAC,WMAPRO,0X0162,DolbyTrueHD,DTS-HD,DTS-MA,EAC3,EC-3
Thu 1/1 0:00:18.664 [MiniUIServerConnection] MiniClient PULL_AV_CONTAINERS=AVI,FLASHVIDEO,Quicktime,Ogg,MP3,AAC,WMV,ASF,FLAC,MATROSKA,WAV,AC3
Thu 1/1 0:00:18.666 [MiniUIServerConnection] MiniClient PUSH_AV_CONTAINERS=MPEG2-PS,MPEG2-TS,MPEG1-PS
Thu 1/1 0:00:18.667 [MiniUIServerConnection] MiniClient STREAMING_PROTOCOLS=file,stv
Thu 1/1 0:00:18.668 [MiniUIServerConnection] MiniClient FIXED_PUSH_MEDIA_FORMAT=null
Thu 1/1 0:00:18.669 [MiniUIServerConnection] MiniClient DETAILED_BUFFER_STATS=null
Thu 1/1 0:00:18.669 [MiniUIServerConnection] MiniClient PUSH_BUFFER_SEEKING=null
Thu 1/1 0:00:18.670 [MiniUIServerConnection] MiniClient MEDIA_PLAYER_BUFFER_DELAY=null
Thu 1/1 0:00:18.671 [MiniUIServerConnection] MiniClient REMOTE_FS=FALSE
Thu 1/1 0:00:18.672 [MiniUIServerConnection] MiniClient IR_PROTOCOL=RC5
Thu 1/1 0:00:18.673 [MiniUIServerConnection] MiniClient GFX_SUBTITLES=TRUE
Thu 1/1 0:00:18.673 [MiniUIServerConnection] MiniClient FORCED_MEDIA_RECONNECT=TRUE
Thu 1/1 0:00:18.674 [MiniUIServerConnection] MiniClient AUTH_CACHE=TRUE
Thu 1/1 0:00:18.677 [MiniUIServerConnection] MiniClient PUSH_BUFFER_LIMIT=524288
Thu 1/1 0:00:18.678 [MiniUIServerConnection] MiniClient VIDEO_ADVANCED_ASPECT=Source
Thu 1/1 0:00:18.682 [MiniUIServerConnection] MiniClient GFX_HDMI_MODE=None
Thu 1/1 0:00:18.685 [MiniUIServerConnection] MiniClient GFX_SUPPORTED_RESOLUTIONS=720x480i@59.94|standard=NTSC_M;720x480p@59.94|standard=480p59;1280x720p@59.94|standard=720p59;1920x1080p@23.976|standard=1080p23;1920x1080i@59.94|standard=1080i59;1920x1080p@59.94|standard=1080p59;
Thu 1/1 0:00:18.689 [MiniUIServerConnection] MiniClient sending GFX_ASPECT=1.7677778
Thu 1/1 0:00:18.694 [MiniUIClientReceiver] Got UI size update to 1024x576
Thu 1/1 0:00:18.700 [MiniUIServerConnection] MiniClient CRYPTO_ALGORITHMS=RSA,Blowfish
Thu 1/1 0:00:18.821 [MiniUIServerConnection] Splash: Rendering Engine is initializing...
Thu 1/1 0:00:18.824 [MiniUIServerConnection] Creating-3 new UI for client:0023a50000e0 UIManager:localhost@@0023a50000e0--7566cc7a
Thu 1/1 0:00:18.894 [VideoFrame-0023a50000e0] VF thread is now running...
Thu 1/1 0:00:18.896 [VideoFrame-0023a50000e0] VF thread is now waiting for 0:00:00.000
Thu 1/1 0:00:18.897 [MiniUIServerConnection] UIMgr loading UI from: /app/sage/STVs/Cheetah/Cheetah.opt.stv
Thu 1/1 0:00:19.328 [MiniUIClientReceiver] Server got notification to update the output modes...
Thu 1/1 0:00:20.816 [AWTThreadWatcher-0023a50000e0] EventThread-0023a50000e0 Hang Detected - hang time = 2001
Thu 1/1 0:00:22.817 [AWTThreadWatcher-0023a50000e0] EventThread-0023a50000e0 Hang Detected - hang time = 4002
Thu 1/1 0:00:23.048 [MiniUIServerConnection] UIMgr done loading UI from: /app/sage/STVs/Cheetah/Cheetah.opt.stv
Thu 1/1 0:00:23.155 [MiniUIServerConnection] Increased vector pool to size=1
Thu 1/1 0:00:23.252 [MiniUIServerConnection] Orig NetworkPathPwdCache=[{}]
Thu 1/1 0:00:23.278 [MiniUIServerConnection] Loading all "Standard SDTV" theme settings...
Thu 1/1 0:00:23.324 [MiniUIServerConnection] Created Property set w/o defaults
Thu 1/1 0:00:23.330 [MiniUIServerConnection] PropCacheFile = [/app/sage/STVs/Cheetah/Themes/Standard SDTV/theme.properties]
Thu 1/1 0:00:23.335 [MiniUIServerConnection] FAILED theme properties does not exist: /app/sage/STVs/Cheetah/Themes/Standard SDTV/theme.properties
Thu 1/1 0:00:23.356 [MiniUIServerConnection] The default theme set is "Standard SDTV"
Thu 1/1 0:00:23.396 [MiniUIServerConnection] Created Property set w/o defaults
Thu 1/1 0:00:23.397 [MiniUIServerConnection] PropCacheFile = [/app/sage/STVs/Cheetah/Themes/Standard SDTV/theme.properties]
Thu 1/1 0:00:23.398 [MiniUIServerConnection] FAILED theme properties does not exist: /app/sage/STVs/Cheetah/Themes/Standard SDTV/theme.properties
Thu 1/1 0:00:23.410 [MiniUIServerConnection] The "Standard SDTV" theme consists of 1 themes: [Standard SDTV]
Thu 1/1 0:00:23.415 [MiniUIServerConnection] The "Standard SDTV" theme uses 2 image paths: [/app/sage/STVs/Cheetah/Themes/Standard SDTV, /app/sage/STVs/Cheetah]
Thu 1/1 0:00:23.440 [MiniUIServerConnection] Relative Weather icon path: WeatherIcons
Thu 1/1 0:00:23.476 [MiniUIServerConnection] Themed image: Found "VideoArt.png" in /app/sage/STVs/Cheetah
Thu 1/1 0:00:23.534 [MiniUIServerConnection] Themed image: Found "dvd.png" in /app/sage/STVs/Cheetah
Thu 1/1 0:00:23.543 [MiniUIServerConnection] Themed image: Found "MusicArt.png" in /app/sage/STVs/Cheetah
Thu 1/1 0:00:23.560 [MiniUIServerConnection] Themed image: Found "audio_feed.png" in /app/sage/STVs/Cheetah
Thu 1/1 0:00:23.573 [MiniUIServerConnection] Themed image: Found "MarkerW.png" in /app/sage/STVs/Cheetah
Thu 1/1 0:00:23.579 [MiniUIServerConnection] Themed image: Found "MarkerTransW.png" in /app/sage/STVs/Cheetah
Thu 1/1 0:00:23.600 [MiniUIServerConnection] Themed image: Found "MarkerHD.png" in /app/sage/STVs/Cheetah
Thu 1/1 0:00:23.607 [MiniUIServerConnection] Themed image: Found "MarkerTransHD.png" in /app/sage/STVs/Cheetah
Thu 1/1 0:00:23.616 [MiniUIServerConnection] Themed image: Found "MarkerDelete.png" in /app/sage/STVs/Cheetah
Thu 1/1 0:00:23.631 [MiniUIServerConnection] Themed image: Found "MarkerInfo.png" in /app/sage/STVs/Cheetah
Thu 1/1 0:00:23.645 [MiniUIServerConnection] Themed image: Found "MarkerFilterNot.png" in /app/sage/STVs/Cheetah
Thu 1/1 0:00:23.666 [MiniUIServerConnection] Themed image: Found "SlideshowOpts.png" in /app/sage/STVs/Cheetah
Thu 1/1 0:00:23.678 [MiniUIServerConnection] Themed image: Found "Slideshow.png" in /app/sage/STVs/Cheetah
Thu 1/1 0:00:23.685 [MiniUIServerConnection] Themed image: Found "MusicVisuals.png" in /app/sage/STVs/Cheetah
Thu 1/1 0:00:23.696 [MiniUIServerConnection] Themed image: Found "SortAlpha.png" in /app/sage/STVs/Cheetah
Thu 1/1 0:00:23.702 [MiniUIServerConnection] Themed image: Found "SortAlphaUp.png" in /app/sage/STVs/Cheetah
Thu 1/1 0:00:23.712 [MiniUIServerConnection] Themed image: Found "SortDate.png" in /app/sage/STVs/Cheetah
Thu 1/1 0:00:23.722 [MiniUIServerConnection] Themed image: Found "SortDateUp.png" in /app/sage/STVs/Cheetah
Thu 1/1 0:00:23.728 [MiniUIServerConnection] Themed image: Found "MusicRandOffRptOff.png" in /app/sage/STVs/Cheetah
Thu 1/1 0:00:23.734 [MiniUIServerConnection] Themed image: Found "MusicRandOffRptOn.png" in /app/sage/STVs/Cheetah
Thu 1/1 0:00:23.747 [MiniUIServerConnection] Themed image: Found "MusicRandOnRptOff.png" in /app/sage/STVs/Cheetah
Thu 1/1 0:00:23.765 [MiniUIServerConnection] Themed image: Found "MusicRandOnRptOn.png" in /app/sage/STVs/Cheetah
Thu 1/1 0:00:23.771 [MiniUIServerConnection] Themed image: Found "SortingIcon.png" in /app/sage/STVs/Cheetah
Thu 1/1 0:00:23.779 [MiniUIServerConnection] Themed image: Found "SortUp.png" in /app/sage/STVs/Cheetah
Thu 1/1 0:00:23.785 [MiniUIServerConnection] Themed image: Found "SortDown.png" in /app/sage/STVs/Cheetah
Thu 1/1 0:00:23.793 [MiniUIServerConnection] Themed image: Found "FilterIcon.png" in null
Thu 1/1 0:00:23.799 [MiniUIServerConnection] Themed image: Found "GoVideoLib.png" in /app/sage/STVs/Cheetah
Thu 1/1 0:00:23.804 [MiniUIServerConnection] Themed image: Found "GoMusicLib.png" in /app/sage/STVs/Cheetah
Thu 1/1 0:00:23.812 [MiniUIServerConnection] Themed image: Found "GoPhotoLib.png" in /app/sage/STVs/Cheetah
Thu 1/1 0:00:23.818 [MiniUIServerConnection] Themed image: Found "IconRefresh.png" in /app/sage/STVs/Cheetah
Thu 1/1 0:00:23.823 [MiniUIServerConnection] Themed image: Found "IconGoRootFS.png" in /app/sage/STVs/Cheetah
Thu 1/1 0:00:23.845 [MiniUIServerConnection] Themed image: Found "OSDPlay.png" in /app/sage/STVs/Cheetah
Thu 1/1 0:00:23.851 [MiniUIServerConnection] Themed image: Found "OSDPause.png" in /app/sage/STVs/Cheetah
Thu 1/1 0:00:23.857 [MiniUIServerConnection] Themed image: Found "OSDRecord.png" in /app/sage/STVs/Cheetah
Thu 1/1 0:00:23.880 [MiniUIServerConnection] Themed image: Found "OSDFwd.png" in /app/sage/STVs/Cheetah
Thu 1/1 0:00:23.898 [MiniUIServerConnection] Themed image: Found "OSDRew.png" in /app/sage/STVs/Cheetah
Thu 1/1 0:00:23.905 [MiniUIServerConnection] Themed image: Found "OSDSkip.png" in /app/sage/STVs/Cheetah
Thu 1/1 0:00:23.911 [MiniUIServerConnection] Themed image: Found "OSDReplay.png" in /app/sage/STVs/Cheetah
Thu 1/1 0:00:23.917 [MiniUIServerConnection] Themed image: Found "OSDStop.png" in /app/sage/STVs/Cheetah
Thu 1/1 0:00:23.923 [MiniUIServerConnection] Themed image: Found "OSDChDisplay.png" in /app/sage/STVs/Cheetah
Thu 1/1 0:00:23.929 [MiniUIServerConnection] Themed image: Found "OSDChUp.png" in /app/sage/STVs/Cheetah
Thu 1/1 0:00:23.934 [MiniUIServerConnection] Themed image: Found "OSDChDown.png" in /app/sage/STVs/Cheetah
Thu 1/1 0:00:24.213 [MiniUIServerConnection] Creating new FreeType font face for Arial size=24 style=0 from=/app/sage/ArialB.ttf
Thu 1/1 0:00:24.236 [MiniUIServerConnection] Increased hash Map pool to size=1
Thu 1/1 0:00:24.288 [MiniUIServerConnection] Creating new FreeType font face for Arial size=18 style=0 from=/app/sage/Arial.ttf
Thu 1/1 0:00:24.313 [MiniUIServerConnection] Deriving FreeType font face for Arial size=24 style=0 from=/app/sage/Arial.ttf
Thu 1/1 0:00:24.321 [MiniUIServerConnection] Creating new FreeType font face for Arial size=24 style=0 from=/app/sage/ArialI.ttf
Thu 1/1 0:00:24.346 [MiniUIServerConnection] Creating new FreeType font face for Arial size=24 style=0 from=/app/sage/ArialBI.ttf
Thu 1/1 0:00:24.377 [MiniUIServerConnection] Deriving FreeType font face for Arial size=16 style=0 from=/app/sage/ArialB.ttf
Thu 1/1 0:00:24.608 [MiniUIServerConnection] setUI(sage.at@d5c4433b[Main Menu]) histIdx=0 uiHistory=[sage.at@d5c4433b[Main Menu]]
Thu 1/1 0:00:24.661 [MiniUIServerConnection] Loading RawImage of size 1024x576 for /app/sage/STVs/Cheetah/darkbg.png
Thu 1/1 0:00:24.663 [MiniUIServerConnection] Loading 0x0 image from file /app/sage/STVs/Cheetah/darkbg.png
Thu 1/1 0:00:24.821 [AWTThreadWatcher-0023a50000e0] EventThread-0023a50000e0 Hang Detected - hang time = 6006
Thu 1/1 0:00:25.316 [MiniUIServerConnection] Loading RawImage of size 256x89 for /app/sage/STVs/Cheetah/SageLogo256.png
Thu 1/1 0:00:25.317 [MiniUIServerConnection] Loading 0x0 image from file /app/sage/STVs/Cheetah/SageLogo256.png
Thu 1/1 0:00:25.363 [MiniUIServerConnection] Loading RawImage of size 4x29 for /app/sage/STVs/Cheetah/ProgressBarBG.png
Thu 1/1 0:00:25.365 [MiniUIServerConnection] Loading 0x0 image from file /app/sage/STVs/Cheetah/ProgressBarBG.png
Thu 1/1 0:00:25.379 [MiniUIServerConnection] Loading RawImage of size 500x29 for /app/sage/STVs/Cheetah/ProgressBar1.png
Thu 1/1 0:00:25.380 [MiniUIServerConnection] Loading 0x0 image from file /app/sage/STVs/Cheetah/ProgressBar1.png
Thu 1/1 0:00:25.401 [MiniUIServerConnection] Loading RawImage of size 500x29 for /app/sage/STVs/Cheetah/ProgressBar2.png
Thu 1/1 0:00:25.402 [MiniUIServerConnection] Loading 0x0 image from file /app/sage/STVs/Cheetah/ProgressBar2.png
Thu 1/1 0:00:25.422 [MiniUIServerConnection] Loading RawImage of size 500x29 for /app/sage/STVs/Cheetah/ProgressBar3.png
Thu 1/1 0:00:25.423 [MiniUIServerConnection] Loading 0x0 image from file /app/sage/STVs/Cheetah/ProgressBar3.png
Thu 1/1 0:00:25.443 [MiniUIServerConnection] Loading RawImage of size 500x29 for /app/sage/STVs/Cheetah/ProgressBar4.png
Thu 1/1 0:00:25.444 [MiniUIServerConnection] Loading 0x0 image from file /app/sage/STVs/Cheetah/ProgressBar4.png
Thu 1/1 0:00:25.464 [MiniUIServerConnection] Loading RawImage of size 500x29 for /app/sage/STVs/Cheetah/ProgressBar5.png
Thu 1/1 0:00:25.465 [MiniUIServerConnection] Loading 0x0 image from file /app/sage/STVs/Cheetah/ProgressBar5.png
Thu 1/1 0:00:25.542 [MiniUIServerConnection] Deriving FreeType font face for Arial size=27 style=0 from=/app/sage/ArialB.ttf
Thu 1/1 0:00:25.543 [MiniUIServerConnection] Deriving FreeType font face for Arial size=27 style=0 from=/app/sage/Arial.ttf
Thu 1/1 0:00:25.545 [MiniUIServerConnection] Deriving FreeType font face for Arial size=27 style=0 from=/app/sage/ArialI.ttf
Thu 1/1 0:00:25.547 [MiniUIServerConnection] Deriving FreeType font face for Arial size=27 style=0 from=/app/sage/ArialBI.ttf
Thu 1/1 0:00:25.549 [MiniUIServerConnection] Deriving FreeType font face for Arial size=18 style=0 from=/app/sage/ArialB.ttf
Thu 1/1 0:00:25.557 [MiniUIServerConnection] Creating-4 new UI for client:0023a50000e0 UIManager:localhost@@0023a50000e0--7566cc7a
Thu 1/1 0:00:25.627 [ActiveRender-0023a50000e0] Loading new font to cache font=MetaFont[Arial, size=27, style=1, height=32.0, ascent=21.0, descent=7.0, leading=4.0, class=class sage.FreetypeFont]
Thu 1/1 0:00:25.682 [ReProcessHook] CEPoolSize=1
Thu 1/1 0:00:25.716 [ReProcessHook] Default STV: [/app/sage/STVs/Cheetah/Cheetah.opt.stv]
Thu 1/1 0:00:25.720 [ReProcessHook] Current STV: [/app/sage/STVs/Cheetah/Cheetah.opt.stv]
Thu 1/1 0:00:25.838 [ReProcessHook] NFSMountDetected=false
Thu 1/1 0:00:26.047 [ReProcessHook] Setup resolution map={1920x1080i@59.94=1080i, 720x480i@59.94=480i, 1920x1080p@59.94=1080p, 1920x1080p@23.976=1080p24, 1280x720p@59.94=720p, 720x480p@59.94=480p}
Thu 1/1 0:00:26.067 [ReProcessHook] Loading configuration data
Thu 1/1 0:00:26.150 [ReProcessHook] Loading version info
Thu 1/1 0:00:26.189 [ReProcessHook] Setting up video output
Thu 1/1 0:00:26.213 [ReProcessHook] Sending remote resolution change of: 1920x1080p@59.94 val=1920x1080p@59.94|standard=1080p59
Thu 1/1 0:00:26.248 [ReProcessHook] Setting up network configuration
Thu 1/1 0:00:26.273 [ReProcessHook] Discovering servers on the network in the foreground...
Thu 1/1 0:00:26.276 [ReProcessHook] Sending out discovery packets to find SageTVPlaceshifter/Extender Servers...
Thu 1/1 0:00:26.410 [FinalRender-0023a50000e0] MiniClient GFX_HDMI_MODE=HDMI
Thu 1/1 0:00:26.415 [FinalRender-0023a50000e0] MiniClient GFX_SUPPORTED_RESOLUTIONS_DIGITAL=720x480i@59.94|standard=HDMI_480i59;720x480p@59.94|standard=HDMI_480p59;720x480p@60|standard=HDMI_480p60;1280x720p@59.94|standard=HDMI_720p59;1280x720p@60|standard=HDMI_720p60;1920x1080i@60|standard=HDMI_1080i60;1920x1080i@59.94|standard=HDMI_1080i59;1920x1080p@59.94|standard=HDMI_1080p59;1920x1080p@60|standard=HDMI_1080p60;
Thu 1/1 0:00:26.519 [ReProcessHook] Discovery packet received:java.net.DatagramPacket@47292858
Thu 1/1 0:00:26.642 [ReProcessHook] Added server info:NAS;10.0.0.2
Thu 1/1 0:00:27.735 [FinalRender-0023a50000e0] MiniClient sending GFX_ASPECT=1.7677778
Thu 1/1 0:00:27.749 [MiniUIClientReceiver] Got UI size update to 1024x576
Thu 1/1 0:00:27.756 [FinalRender-0023a50000e0] Creating new RawImage for font rendering w=128 h=128
Thu 1/1 0:00:27.816 [FinalRender-0023a50000e0] Rendered new font to raw cache index=0 font=MetaFont[Arial, size=27, style=1, height=32.0, ascent=21.0, descent=7.0, leading=4.0, class=class sage.FreetypeFont]
Thu 1/1 0:00:27.840 [FinalRender-0023a50000e0] Creating new RawImage for font rendering w=128 h=128
Thu 1/1 0:00:27.872 [FinalRender-0023a50000e0] Rendered new font to raw cache index=1 font=MetaFont[Arial, size=27, style=1, height=32.0, ascent=21.0, descent=7.0, leading=4.0, class=class sage.FreetypeFont]
Thu 1/1 0:00:27.897 [FinalRender-0023a50000e0] Initiaing the BGResourceLoader for the rendering engine
Thu 1/1 0:00:28.710 [MiniUIClientReceiver] Server got notification to update the output modes...
Thu 1/1 0:00:28.731 [FinalRender-0023a50000e0] MiniClient GFX_HDMI_MODE=HDMI
Thu 1/1 0:00:28.737 [FinalRender-0023a50000e0] MiniClient GFX_SUPPORTED_RESOLUTIONS_DIGITAL=720x480i@59.94|standard=HDMI_480i59;720x480p@59.94|standard=HDMI_480p59;720x480p@60|standard=HDMI_480p60;1280x720p@59.94|standard=HDMI_720p59;1280x720p@60|standard=HDMI_720p60;1920x1080i@60|standard=HDMI_1080i60;1920x1080i@59.94|standard=HDMI_1080i59;1920x1080p@59.94|standard=HDMI_1080p59;1920x1080p@60|standard=HDMI_1080p60;
Thu 1/1 0:00:28.738 [FinalRender-0023a50000e0] MiniClient sending GFX_ASPECT=1.7677778
Thu 1/1 0:00:28.745 [MiniUIClientReceiver] Got UI size update to 1024x576
Thu 1/1 0:00:29.288 [ReProcessHook] Discovered servers:[NAS;10.0.0.2]
Thu 1/1 0:00:29.350 [ReProcessHook] processOptionsMenu optionsMenu=default:null|OptionsMenu:AutoConnectTimeout
Thu 1/1 0:00:30.332 [ReProcessHook] Deriving FreeType font face for Arial size=20 style=0 from=/app/sage/ArialB.ttf
Thu 1/1 0:00:30.588 [ActiveRender-0023a50000e0] Loading new font to cache font=MetaFont[Arial, size=20, style=1, height=24.0, ascent=15.0, descent=5.0, leading=4.0, class=class sage.FreetypeFont]
Thu 1/1 0:00:30.950 [FinalRender-0023a50000e0] Loading compressed image directly from: /app/sage/STVs/Cheetah/DialogBG.png width=595 height=186
Thu 1/1 0:00:31.045 [FinalRender-0023a50000e0] Creating new RawImage for font rendering w=128 h=128
Thu 1/1 0:00:31.298 [FinalRender-0023a50000e0] Rendered new font to raw cache index=0 font=MetaFont[Arial, size=20, style=1, height=24.0, ascent=15.0, descent=5.0, leading=4.0, class=class sage.FreetypeFont]
Thu 1/1 0:00:31.321 [FinalRender-0023a50000e0] Creating new RawImage for font rendering w=128 h=128
Thu 1/1 0:00:31.370 [FinalRender-0023a50000e0] Rendered new font to raw cache index=1 font=MetaFont[Arial, size=20, style=1, height=24.0, ascent=15.0, descent=5.0, leading=4.0, class=class sage.FreetypeFont]
Thu 1/1 0:00:31.403 [FinalRender-0023a50000e0] Loading compressed image directly from: /app/sage/STVs/Cheetah/ButtonHighlight.png width=248 height=43
Thu 1/1 0:00:31.417 [FinalRender-0023a50000e0] Loading compressed image directly from: /app/sage/STVs/Cheetah/ButtonBG.png width=248 height=43
Thu 1/1 0:00:31.432 [FinalRender-0023a50000e0] Loading compressed image directly from: /app/sage/STVs/Cheetah/OSDStop.png width=30 height=26
Thu 1/1 0:00:31.541 [FinalRender-0023a50000e0] Loading compressed image directly from: /app/sage/STVs/Cheetah/OSDPlay.png width=30 height=26
Thu 1/1 0:00:33.636 [ActiveRender-0023a50000e0] ZRoot is aborting this draw because its unnecessary
Thu 1/1 0:00:34.153 [Fork-null] Preloading 12 images for the Home Menu.
Thu 1/1 0:00:34.258 [Fork-null] Loading RawImage of size 120x124 for /app/sage/STVs/Cheetah/internet.png
Thu 1/1 0:00:34.258 [Fork-null] Loading 0x0 image from file /app/sage/STVs/Cheetah/internet.png
Thu 1/1 0:00:34.461 [Fork-null] Loading RawImage of size 120x120 for /app/sage/STVs/Cheetah/search.png
Thu 1/1 0:00:34.462 [Fork-null] Loading 0x0 image from file /app/sage/STVs/Cheetah/search.png
Thu 1/1 0:00:34.494 [Fork-null] Loading RawImage of size 120x120 for /app/sage/STVs/Cheetah/servers.png
Thu 1/1 0:00:34.495 [Fork-null] Loading 0x0 image from file /app/sage/STVs/Cheetah/servers.png
Thu 1/1 0:00:34.596 [Fork-null] Loading RawImage of size 120x120 for /app/sage/STVs/Cheetah/MusicArt.png
Thu 1/1 0:00:34.597 [Fork-null] Loading 0x0 image from file /app/sage/STVs/Cheetah/MusicArt.png
Thu 1/1 0:00:34.733 [Fork-null] Loading RawImage of size 120x120 for /app/sage/STVs/Cheetah/photos.png
Thu 1/1 0:00:34.734 [Fork-null] Loading 0x0 image from file /app/sage/STVs/Cheetah/photos.png
Thu 1/1 0:00:34.767 [Fork-null] Loading RawImage of size 120x122 for /app/sage/STVs/Cheetah/video.png
Thu 1/1 0:00:34.768 [Fork-null] Loading 0x0 image from file /app/sage/STVs/Cheetah/video.png
Thu 1/1 0:00:34.823 [Fork-null] Loading RawImage of size 120x120 for /app/sage/STVs/Cheetah/files.png
Thu 1/1 0:00:34.824 [Fork-null] Loading 0x0 image from file /app/sage/STVs/Cheetah/files.png
Thu 1/1 0:00:34.866 [Fork-null] Loading RawImage of size 120x120 for /app/sage/STVs/Cheetah/settings.png
Thu 1/1 0:00:34.870 [Fork-null] Loading 0x0 image from file /app/sage/STVs/Cheetah/settings.png
Thu 1/1 0:00:34.904 [Fork-null] Loading RawImage of size 296x230 for /app/sage/STVs/Cheetah/selector.png
Thu 1/1 0:00:34.905 [Fork-null] Loading 0x0 image from file /app/sage/STVs/Cheetah/selector.png
Thu 1/1 0:00:35.001 [Fork-null] Loading RawImage of size 169x59 for /app/sage/STVs/Cheetah/SageLogo256small.png
Thu 1/1 0:00:35.002 [Fork-null] Loading 0x0 image from file /app/sage/STVs/Cheetah/SageLogo256small.png
Thu 1/1 0:00:35.063 [Fork-null] Loading RawImage of size 48x52 for /app/sage/STVs/Cheetah/importing.png
Thu 1/1 0:00:35.064 [Fork-null] Loading 0x0 image from file /app/sage/STVs/Cheetah/importing.png
Thu 1/1 0:00:35.088 [Fork-null] Preloading Bold font, Size  18
Thu 1/1 0:00:35.128 [Fork-null] Preloading Bold font, Size  20
Thu 1/1 0:00:35.155 [Fork-null] Preloading Bold font, Size  26
Thu 1/1 0:00:35.159 [ActiveRender-0023a50000e0] Deriving FreeType font face for Arial size=22 style=0 from=/app/sage/ArialB.ttf
Thu 1/1 0:00:35.273 [ActiveRender-0023a50000e0] Loading new font to cache font=MetaFont[Arial, size=22, style=1, height=26.0, ascent=17.0, descent=6.0, leading=3.0, class=class sage.FreetypeFont]
Thu 1/1 0:00:35.624 [FinalRender-0023a50000e0] Creating new RawImage for font rendering w=128 h=128
Thu 1/1 0:00:35.671 [ActiveRender-0023a50000e0] Deriving FreeType font face for Arial size=26 style=0 from=/app/sage/ArialB.ttf
Thu 1/1 0:00:35.673 [ActiveRender-0023a50000e0] Deriving FreeType font face for Arial size=29 style=0 from=/app/sage/ArialB.ttf
Thu 1/1 0:00:35.675 [FinalRender-0023a50000e0] Rendered new font to raw cache index=0 font=MetaFont[Arial, size=22, style=1, height=26.0, ascent=17.0, descent=6.0, leading=3.0, class=class sage.FreetypeFont]
Thu 1/1 0:00:35.748 [FinalRender-0023a50000e0] Creating new RawImage for font rendering w=128 h=128
Thu 1/1 0:00:35.796 [FinalRender-0023a50000e0] Rendered new font to raw cache index=1 font=MetaFont[Arial, size=22, style=1, height=26.0, ascent=17.0, descent=6.0, leading=3.0, class=class sage.FreetypeFont]
Thu 1/1 0:00:35.824 [ActiveRender-0023a50000e0] Loading new font to cache font=MetaFont[Arial, size=29, style=1, height=35.0, ascent=22.0, descent=8.0, leading=5.0, class=class sage.FreetypeFont]
Thu 1/1 0:00:35.840 [Fork-null] No HDD is installed...run w/out a hard disk
Thu 1/1 0:00:35.855 [Fork-null] Launching UPnP automounter...
Thu 1/1 0:00:36.196 [Fork-null] Deriving FreeType font face for Arial size=28 style=0 from=/app/sage/Arial.ttf
Thu 1/1 0:00:36.205 [FinalRender-0023a50000e0] Creating new RawImage for font rendering w=128 h=128
Thu 1/1 0:00:36.250 [Fork-null] Creating new FreeType font face for Monospaced size=20 style=0 from=/app/sage/MonospacedB.ttf
Thu 1/1 0:00:36.257 [FinalRender-0023a50000e0] Rendered new font to raw cache index=0 font=MetaFont[Arial, size=29, style=1, height=35.0, ascent=22.0, descent=8.0, leading=5.0, class=class sage.FreetypeFont]
Thu 1/1 0:00:36.320 [FinalRender-0023a50000e0] Creating new RawImage for font rendering w=128 h=128
Thu 1/1 0:00:36.354 [Fork-null] Deriving FreeType font face for Monospaced size=22 style=0 from=/app/sage/MonospacedB.ttf
Thu 1/1 0:00:36.357 [FinalRender-0023a50000e0] Rendered new font to raw cache index=1 font=MetaFont[Arial, size=29, style=1, height=35.0, ascent=22.0, descent=8.0, leading=5.0, class=class sage.FreetypeFont]
Thu 1/1 0:00:36.384 [FinalRender-0023a50000e0] Creating new RawImage for font rendering w=128 h=128
Thu 1/1 0:00:36.475 [FinalRender-0023a50000e0] Rendered new font to raw cache index=2 font=MetaFont[Arial, size=29, style=1, height=35.0, ascent=22.0, descent=8.0, leading=5.0, class=class sage.FreetypeFont]
Thu 1/1 0:00:36.588 [Fork-null] Deriving FreeType font face for Arial size=30 style=0 from=/app/sage/ArialB.ttf
Thu 1/1 0:00:36.617 [Fork-null] setUI(sage.at@f8990016[Home]) histIdx=1 uiHistory=[sage.at@d5c4433b[Main Menu], sage.at@f8990016[Home]]
Thu 1/1 0:00:36.622 [Fork-null] Global variables already set.
Thu 1/1 0:00:36.628 [Fork-null] Theme settings already loaded.
Thu 1/1 0:00:36.653 [Fork-null] Error reading cached weather data of:java.io.FileNotFoundException: /tmp/weather_cache.properties (No such file or directory)
Thu 1/1 0:00:36.655 [Fork-null] Increased Thread pool to size=6
Thu 1/1 0:00:36.661 [Fork-null] ParserPoolSize=1
Thu 1/1 0:00:36.704 [Fork-null] Deriving FreeType font face for Arial size=31 style=0 from=/app/sage/Arial.ttf
Thu 1/1 0:00:36.708 [Fork-null] Deriving FreeType font face for Arial size=34 style=0 from=/app/sage/ArialB.ttf
Thu 1/1 0:00:36.909 [ReProcessHook] Download requested for files server=http://download.sagetv.com/firmware/stp300.ver src=null dest=/tmp/firmware.ver
Thu 1/1 0:00:36.976 [ReProcessHook] Download remoteSize=56
Thu 1/1 0:00:37.001 [FileDownload] Read -1 bytes from the URL stream; download has completed
Thu 1/1 0:00:37.002 [FileDownload] VF.submitJob(VFJob[InactiveFile r=0.0 t=0 file=null ifn=/tmp/firmware.ver])
Thu 1/1 0:00:37.011 [FileDownload] VF.submitJob(VFJob[InactiveFile r=0.0 t=0 file=null ifn=/tmp/firmware.ver])
Thu 1/1 0:00:37.017 [VideoFrame-0023a50000e0] VF processing job VFJob[InactiveFile r=0.0 t=0 file=null ifn=/tmp/firmware.ver] nPlayin=false
Thu 1/1 0:00:37.018 [VideoFrame-0023a50000e0] VF discarding job VFJob[InactiveFile r=0.0 t=0 file=null ifn=/tmp/firmware.ver]
Thu 1/1 0:00:37.018 [VideoFrame-0023a50000e0] VF processing job null nPlayin=false
Thu 1/1 0:00:37.023 [VideoFrame-0023a50000e0] VF thread is now waiting for 0:00:00.000
Thu 1/1 0:00:37.024 [FileDownload] Download completed for files server=http://download.sagetv.com/firmware/stp300.ver src=null dest=/tmp/firmware.ver abort=false
Thu 1/1 0:00:37.194 [ReProcessHook] NewVersion=20100909-0
Thu 1/1 0:00:37.210 [Fork-null] Starting Online Video properties updater thread.
Thu 1/1 0:00:37.247 [Fork-null] Using default STV; online video path is /rw dir.
Thu 1/1 0:00:37.256 [Fork-null] FinalLinkFilePath: [/rw/OnlineVideoLinks.properties]
Thu 1/1 0:00:37.258 [Fork-null] FinalUITextFilePath: [/rw/OnlineVideoUIText.properties]
Thu 1/1 0:00:37.262 [Fork-null] Created Property set w/o defaults
Thu 1/1 0:00:37.263 [Fork-null] PropCacheFile = [/rw/OnlineVideoLinks.properties]
Thu 1/1 0:00:37.315 [Fork-null] Loaded theme properties file: /rw/OnlineVideoLinks.properties
Thu 1/1 0:00:37.336 [Fork-null] Current online video properties version=2010081801, last update version number=2010081801
Thu 1/1 0:00:37.347 [Fork-null] Checking remote file: http://download.sage.tv/OnlineVideos/Cheetah/6_6_2c/version.txt, saving to: /rw/version.txt
Thu 1/1 0:00:37.349 [Fork-null] Download requested for files server=http://download.sage.tv/OnlineVideos/Cheetah/6_6_2c/version.txt src=null dest=/rw/version.txt
Thu 1/1 0:00:37.414 [Fork-null] Download remoteSize=107
Thu 1/1 0:00:37.422 [FileDownload] Read -1 bytes from the URL stream; download has completed
Thu 1/1 0:00:37.432 [FileDownload] VF.submitJob(VFJob[InactiveFile r=0.0 t=0 file=null ifn=/rw/version.txt])
Thu 1/1 0:00:37.433 [FileDownload] VF.submitJob(VFJob[InactiveFile r=0.0 t=0 file=null ifn=/rw/version.txt])
Thu 1/1 0:00:37.435 [VideoFrame-0023a50000e0] VF processing job VFJob[InactiveFile r=0.0 t=0 file=null ifn=/rw/version.txt] nPlayin=false
Thu 1/1 0:00:37.435 [VideoFrame-0023a50000e0] VF discarding job VFJob[InactiveFile r=0.0 t=0 file=null ifn=/rw/version.txt]
Thu 1/1 0:00:37.437 [VideoFrame-0023a50000e0] VF processing job null nPlayin=false
Thu 1/1 0:00:37.438 [FileDownload] Download completed for files server=http://download.sage.tv/OnlineVideos/Cheetah/6_6_2c/version.txt src=null dest=/rw/version.txt abort=false
Thu 1/1 0:00:37.440 [VideoFrame-0023a50000e0] VF thread is now waiting for 0:00:00.000
Thu 1/1 0:00:37.923 [Fork-null] Done downloading remote file.
Thu 1/1 0:00:37.928 [Fork-null] Created Property set w/o defaults
Thu 1/1 0:00:37.929 [Fork-null] PropCacheFile = [/rw/version.txt]
Thu 1/1 0:00:37.932 [Fork-null] Loaded theme properties file: /rw/version.txt
Thu 1/1 0:00:37.936 [Fork-null] Current online video properties version=2010081801, update version number=2010081801
Thu 1/1 0:00:37.950 [Fork-null] Downloading the online file: http://download.sage.tv/OnlineVideos/Cheetah/6_6_2c/OnlineVideoLinks.properties, saving to: /rw/NewOnlineVideoLinks.properties, attempt 1
Thu 1/1 0:00:37.951 [Fork-null] Download requested for files server=http://download.sage.tv/OnlineVideos/Cheetah/6_6_2c/OnlineVideoLinks.properties src=null dest=/rw/NewOnlineVideoLinks.properties
Thu 1/1 0:00:38.012 [Fork-null] Download remoteSize=52225
Thu 1/1 0:00:38.128 [FileDownload] Read -1 bytes from the URL stream; download has completed
Thu 1/1 0:00:38.141 [FileDownload] VF.submitJob(VFJob[InactiveFile r=0.0 t=0 file=null ifn=/rw/NewOnlineVideoLinks.properties])
Thu 1/1 0:00:38.141 [FileDownload] VF.submitJob(VFJob[InactiveFile r=0.0 t=0 file=null ifn=/rw/NewOnlineVideoLinks.properties])
Thu 1/1 0:00:38.142 [VideoFrame-0023a50000e0] VF processing job VFJob[InactiveFile r=0.0 t=0 file=null ifn=/rw/NewOnlineVideoLinks.properties] nPlayin=false
Thu 1/1 0:00:38.143 [VideoFrame-0023a50000e0] VF discarding job VFJob[InactiveFile r=0.0 t=0 file=null ifn=/rw/NewOnlineVideoLinks.properties]
Thu 1/1 0:00:38.144 [VideoFrame-0023a50000e0] VF processing job null nPlayin=false
Thu 1/1 0:00:38.145 [FileDownload] Download completed for files server=http://download.sage.tv/OnlineVideos/Cheetah/6_6_2c/OnlineVideoLinks.properties src=null dest=/rw/NewOnlineVideoLinks.properties abort=false
Thu 1/1 0:00:38.148 [VideoFrame-0023a50000e0] VF thread is now waiting for 0:00:00.000
Thu 1/1 0:00:38.532 [Fork-null] MD5 success downloading file 'http://download.sage.tv/OnlineVideos/Cheetah/6_6_2c/OnlineVideoLinks.properties', attempt 1, DownloadMD5=9087d548d51322ba830bb792406a8b5c, CheckMD5=9087d548d51322ba830bb792406a8b5c
Thu 1/1 0:00:38.537 [Fork-null] Downloading the online file: http://download.sage.tv/OnlineVideos/Cheetah/6_6_2c/OnlineVideoUIText.properties, saving to: /rw/NewOnlineVideoUIText.properties, attempt 1
Thu 1/1 0:00:38.538 [Fork-null] Download requested for files server=http://download.sage.tv/OnlineVideos/Cheetah/6_6_2c/OnlineVideoUIText.properties src=null dest=/rw/NewOnlineVideoUIText.properties
Thu 1/1 0:00:38.605 [Fork-null] Download remoteSize=76229
Thu 1/1 0:00:38.772 [FileDownload] Read -1 bytes from the URL stream; download has completed
Thu 1/1 0:00:38.784 [FileDownload] VF.submitJob(VFJob[InactiveFile r=0.0 t=0 file=null ifn=/rw/NewOnlineVideoUIText.properties])
Thu 1/1 0:00:38.786 [FileDownload] VF.submitJob(VFJob[InactiveFile r=0.0 t=0 file=null ifn=/rw/NewOnlineVideoUIText.properties])
Thu 1/1 0:00:38.787 [VideoFrame-0023a50000e0] VF processing job VFJob[InactiveFile r=0.0 t=0 file=null ifn=/rw/NewOnlineVideoUIText.properties] nPlayin=false
Thu 1/1 0:00:38.788 [VideoFrame-0023a50000e0] VF discarding job VFJob[InactiveFile r=0.0 t=0 file=null ifn=/rw/NewOnlineVideoUIText.properties]
Thu 1/1 0:00:38.789 [VideoFrame-0023a50000e0] VF processing job null nPlayin=false
Thu 1/1 0:00:38.790 [FileDownload] Download completed for files server=http://download.sage.tv/OnlineVideos/Cheetah/6_6_2c/OnlineVideoUIText.properties src=null dest=/rw/NewOnlineVideoUIText.properties abort=false
Thu 1/1 0:00:38.795 [VideoFrame-0023a50000e0] VF thread is now waiting for 0:00:00.000
Thu 1/1 0:00:39.126 [Fork-null] MD5 success downloading file 'http://download.sage.tv/OnlineVideos/Cheetah/6_6_2c/OnlineVideoUIText.properties', attempt 1, DownloadMD5=e3c817a28c40d215d3f8b74d09a62bf7, CheckMD5=e3c817a28c40d215d3f8b74d09a62bf7
Thu 1/1 0:00:39.163 [Fork-null] Copying SourceFilename [/rw/NewOnlineVideoLinks.properties] to BackupFilePath [/rw/OnlineVideoLinks.properties]
Thu 1/1 0:00:39.221 [Fork-null] Copied existing file '/rw/NewOnlineVideoLinks.properties' to '/rw/OnlineVideoLinks.properties'.
Thu 1/1 0:00:39.226 [Fork-null] Copying SourceFilename [/rw/NewOnlineVideoUIText.properties] to BackupFilePath [/rw/OnlineVideoUIText.properties]
Thu 1/1 0:00:39.259 [Fork-null] Copied existing file '/rw/NewOnlineVideoUIText.properties' to '/rw/OnlineVideoUIText.properties'.
Thu 1/1 0:00:39.263 [Fork-null] Copied new online video properties files to replace old ones.
Thu 1/1 0:00:39.279 [Fork-null] Marked property for last online video update time.
Thu 1/1 0:00:39.286 [Fork-null] Ending Online Video properties updater thread. Update was completed.
Thu 1/1 0:00:46.817 [ReProcessHook] CEPoolSize=2
Thu 1/1 0:00:47.316 [LibraryImporter] Seeker is starting the library import scan...
Thu 1/1 0:00:47.317 [LibraryImporter] Seeker has finished the library import scan.
Thu 1/1 0:00:51.364 [HotplugStorage] Setup dir for external device mount: /tmp/external/ST932032 5AS (298 GB)
Thu 1/1 0:00:51.365 [HotplugStorage] Executing process: [sh, -c, mount  | grep -i "/tmp/external/ST932032 5AS (298 GB)"]
Thu 1/1 0:00:51.529 [HotplugStorage] Executing process: [/usr/local/bin/ntfs-3g, /dev/sda1, /tmp/external/ST932032 5AS (298 GB), -o, nls=utf8,noatime]
Thu 1/1 0:00:52.395 [ReProcessHook] processOptionsMenu optionsMenu=default:null|OptionsMenu:NewDriveOptions
Thu 1/1 0:00:57.141 [EventRouter-0023a50000e0] Increased ArrayList pool to size=101
Thu 1/1 0:00:57.240 [EventRouter-0023a50000e0] Increased ArrayList pool to size=201
Thu 1/1 0:00:57.390 [EventRouter-0023a50000e0] setUI(sage.at@4fe688a1[Browser - Files]) histIdx=2 uiHistory=[sage.at@d5c4433b[Main Menu], sage.at@f8990016[Home], sage.at@4fe688a1[Browser - Files]]
Thu 1/1 0:00:57.400 [EventRouter-0023a50000e0] START: BeforeMenuLoad hook processing for the 'Browser - Files' menu.
Thu 1/1 0:00:57.442 [EventRouter-0023a50000e0] LocalDeviceMap Keys: 1 = [[ST932032 5AS (298 GB)]], map size = 1
Thu 1/1 0:00:57.446 [EventRouter-0023a50000e0] LocalDeviceMap LocalItem 0: [ST932032 5AS (298 GB)], value: [/tmp/external/ST932032 5AS (298 GB)]
Thu 1/1 0:00:57.450 [EventRouter-0023a50000e0] LocalDeviceMap LocalItem 1: [null], value: [null]
Thu 1/1 0:00:57.486 [EventRouter-0023a50000e0] LastFolderDevicesMap = [{ST932032 5AS (298 GB)=/tmp/external/ST932032 5AS (298 GB)}]
Thu 1/1 0:00:57.516 [EventRouter-0023a50000e0] Done with BeforeMenuLoad hook for the 'Browser - Files' menu. Total Time = '115'
Thu 1/1 0:00:57.598 [EventRouter-0023a50000e0] Deriving FreeType font face for Arial size=22 style=0 from=/app/sage/ArialBI.ttf
Thu 1/1 0:00:58.070 [EventRouter-0023a50000e0] Deriving FreeType font face for Arial size=25 style=0 from=/app/sage/ArialBI.ttf
Thu 1/1 0:00:58.174 [FinalRender-0023a50000e0] Loading compressed image directly from: /app/sage/STVs/Cheetah/MusicOptions.png width=72 height=72
Thu 1/1 0:00:58.187 [FinalRender-0023a50000e0] Loading compressed image directly from: /app/sage/STVs/Cheetah/MusicBack.png width=72 height=72
Thu 1/1 0:00:58.200 [FinalRender-0023a50000e0] Loading compressed image directly from: /app/sage/STVs/Cheetah/IconRefresh.png width=72 height=72
Thu 1/1 0:00:58.216 [FinalRender-0023a50000e0] Loading compressed image directly from: /app/sage/STVs/Cheetah/net-link.png width=88 height=68
Thu 1/1 0:00:58.236 [FinalRender-0023a50000e0] Loading compressed image directly from: /app/sage/STVs/Cheetah/lib-link.png width=88 height=68
Thu 1/1 0:00:58.249 [FinalRender-0023a50000e0] Loading compressed image directly from: /app/sage/STVs/Cheetah/usb-link.png width=88 height=68
Thu 1/1 0:01:00.888 [EventRouter-0023a50000e0] CEPoolSize=3
Thu 1/1 0:01:02.030 [FinalRender-0023a50000e0] Loading compressed image directly from: /app/sage/STVs/Cheetah/IconGoRootFS.png width=72 height=59
Thu 1/1 0:01:02.051 [FinalRender-0023a50000e0] Loading compressed image directly from: /app/sage/STVs/Cheetah/SortUp.png width=32 height=68
Thu 1/1 0:01:02.062 [FinalRender-0023a50000e0] Loading compressed image directly from: /app/sage/STVs/Cheetah/FileFolder.png width=56 height=46
Thu 1/1 0:01:02.090 [FinalRender-0023a50000e0] Loading compressed image directly from: /app/sage/STVs/Cheetah/ScrollVBG.png width=40 height=651
Thu 1/1 0:01:02.105 [FinalRender-0023a50000e0] Loading compressed image directly from: /app/sage/STVs/Cheetah/ScrollVUp.png width=31 height=28
Thu 1/1 0:01:02.115 [FinalRender-0023a50000e0] Loading compressed image directly from: /app/sage/STVs/Cheetah/ScrollVDown.png width=31 height=28
Thu 1/1 0:01:02.123 [FinalRender-0023a50000e0] Loading compressed image directly from: /app/sage/STVs/Cheetah/ScrollVArea.png width=29 height=576
Thu 1/1 0:01:02.136 [FinalRender-0023a50000e0] Loading compressed image directly from: /app/sage/STVs/Cheetah/ScrollVBar.png width=18 height=40
Thu 1/1 0:01:02.145 [FinalRender-0023a50000e0] Loading compressed image directly from: /app/sage/STVs/Cheetah/MarkerInfo.png width=47 height=46
Thu 1/1 0:01:06.034 [FinalRender-0023a50000e0] Loading compressed image directly from: /app/sage/STVs/Cheetah/VideoArt.png width=120 height=120
Thu 1/1 0:01:59.751 [EventRouter-0023a50000e0] processOptionsMenu optionsMenu=default:null|OptionsMenu:FileBrowser File Options
Thu 1/1 0:01:59.929 [FinalRender-0023a50000e0] Loading compressed image directly from: /app/sage/STVs/Cheetah/MarkerDelete.png width=47 height=46
Thu 1/1 0:02:01.701 [Fork-null] processOptionsMenu optionsMenu=default:null|OptionsMenu:Preparing for video file playback notice
Thu 1/1 0:02:01.780 [ReProcessHook] VF.submitJob(VFJob[CloseMF r=0.0 t=0 file=null ifn=null])
Thu 1/1 0:02:01.782 [VideoFrame-0023a50000e0] VF processing job VFJob[CloseMF r=0.0 t=0 file=null ifn=null] nPlayin=false
Thu 1/1 0:02:01.783 [VideoFrame-0023a50000e0] VF processing on UI Thread VFJob[CloseMF r=0.0 t=0 file=null ifn=null]
Thu 1/1 0:02:01.784 [VideoFrame-0023a50000e0] VF processing job null nPlayin=false
Thu 1/1 0:02:01.785 [VideoFrame-0023a50000e0] VF thread is now waiting for 0:00:00.000
Thu 1/1 0:02:01.791 [ReProcessHook] Seeker.finishWatch(UIManager:localhost@@0023a50000e0--7566cc7a)
Thu 1/1 0:02:02.291 [AsyncWatch] Using internal format detector first...
Thu 1/1 0:02:02.499 [AsyncWatch] File Format Parsed-2a /tmp/external/ST932032 5AS (298 GB)/Video/Glee-BritneyBrittany-3883282-0.mpg=MPEG2-PS 0:59:56 3044 kbps [#0 Video[MPEG2-Video 59.94006 fps 1280x720 16:9 progressive]#1 Audio[AC3 48000 Hz 6 channels 448 kbps MAIN idx=1 id=bd-81010002]#2 Audio[AC3 48000 Hz 2 channels 96 kbps idx=2 id=bd-82010002]]
Thu 1/1 0:02:03.014 [AsyncWatch] File Format Parsed-2b /tmp/external/ST932032 5AS (298 GB)/Video/Glee-BritneyBrittany-3883282-0.mpg=MPEG2-PS 0:59:56 3044 kbps [#0 Video[MPEG2-Video 59.94006 fps 1280x720 16:9 progressive]#1 Audio[AC3 48000 Hz 6 channels 448 kbps MAIN idx=1 id=bd-81010002]#2 Audio[AC3 48000 Hz 2 channels 96 kbps idx=2 id=bd-82010002]]
Thu 1/1 0:02:03.021 [AsyncWatch] Increased StringBuffer pool to size=1
Thu 1/1 0:02:03.030 [AsyncWatch] VideoFrame.watch(A[48,47,"Glee-BritneyBrittany-3883282-0",0@0929.03:00,59])
Thu 1/1 0:02:03.032 [AsyncWatch] watchThisFile=MediaFile[id=0 A[48,47,"Glee-BritneyBrittany-3883282-0",0@0929.03:00,59] mask=V host=HD300 encodedBy= format=MPEG2-PS 0:59:56 3044 kbps [#0 Video[MPEG2-Video 59.94006 fps 1280x720 16:9 progressive]#1 Audio[AC3 48000 Hz 6 channels 448 kbps MAIN idx=1 id=bd-81010002]#2 Audio[AC3 48000 Hz 2 channels 96 kbps idx=2 id=bd-82010002]] /tmp/external/ST932032 5AS (298 GB)/Video/Glee-BritneyBrittany-3883282-0.mpg, Seg0[Wed 9/29 3:00:03.441-Wed 9/29 4:00:00.000]]
Thu 1/1 0:02:03.044 [AsyncWatch] Watch airing is a file & over, do that instead dvd=false
Thu 1/1 0:02:03.050 [AsyncWatch] Seeker.finishWatch(UIManager:localhost@@0023a50000e0--7566cc7a)
Thu 1/1 0:02:03.058 [AsyncWatch] VF.submitJob(VFJob[WatchMF r=0.0 t=0 file=MediaFile[id=0 A[48,47,"Glee-BritneyBrittany-3883282-0",0@0929.03:00,59] mask=V host=HD300 encodedBy= format=MPEG2-PS 0:59:56 3044 kbps [#0 Video[MPEG2-Video 59.94006 fps 1280x720 16:9 progressive]#1 Audio[AC3 48000 Hz 6 channels 448 kbps MAIN idx=1 id=bd-81010002]#2 Audio[AC3 48000 Hz 2 channels 96 kbps idx=2 id=bd-82010002]] /tmp/external/ST932032 5AS (298 GB)/Video/Glee-BritneyBrittany-3883282-0.mpg, Seg0[Wed 9/29 3:00:03.441-Wed 9/29 4:00:00.000]] ifn=null])
Thu 1/1 0:02:03.063 [VideoFrame-0023a50000e0] VF processing job VFJob[WatchMF r=0.0 t=0 file=MediaFile[id=0 A[48,47,"Glee-BritneyBrittany-3883282-0",0@0929.03:00,59] mask=V host=HD300 encodedBy= format=MPEG2-PS 0:59:56 3044 kbps [#0 Video[MPEG2-Video 59.94006 fps 1280x720 16:9 progressive]#1 Audio[AC3 48000 Hz 6 channels 448 kbps MAIN idx=1 id=bd-81010002]#2 Audio[AC3 48000 Hz 2 channels 96 kbps idx=2 id=bd-82010002]] /tmp/external/ST932032 5AS (298 GB)/Video/Glee-BritneyBrittany-3883282-0.mpg, Seg0[Wed 9/29 3:00:03.441-Wed 9/29 4:00:00.000]] ifn=null] nPlayin=false
Thu 1/1 0:02:03.069 [VideoFrame-0023a50000e0] VF processing on UI Thread VFJob[WatchMF r=0.0 t=0 file=MediaFile[id=0 A[48,47,"Glee-BritneyBrittany-3883282-0",0@0929.03:00,59] mask=V host=HD300 encodedBy= format=MPEG2-PS 0:59:56 3044 kbps [#0 Video[MPEG2-Video 59.94006 fps 1280x720 16:9 progressive]#1 Audio[AC3 48000 Hz 6 channels 448 kbps MAIN idx=1 id=bd-81010002]#2 Audio[AC3 48000 Hz 2 channels 96 kbps idx=2 id=bd-82010002]] /tmp/external/ST932032 5AS (298 GB)/Video/Glee-BritneyBrittany-3883282-0.mpg, Seg0[Wed 9/29 3:00:03.441-Wed 9/29 4:00:00.000]] ifn=null]
Thu 1/1 0:02:03.073 [VideoFrame-0023a50000e0] VideoFrame creating new media player for file:MediaFile[id=0 A[48,47,"Glee-BritneyBrittany-3883282-0",0@0929.03:00,59] mask=V host=HD300 encodedBy= format=MPEG2-PS 0:59:56 3044 kbps [#0 Video[MPEG2-Video 59.94006 fps 1280x720 16:9 progressive]#1 Audio[AC3 48000 Hz 6 channels 448 kbps MAIN idx=1 id=bd-81010002]#2 Audio[AC3 48000 Hz 2 channels 96 kbps idx=2 id=bd-82010002]] /tmp/external/ST932032 5AS (298 GB)/Video/Glee-BritneyBrittany-3883282-0.mpg, Seg0[Wed 9/29 3:00:03.441-Wed 9/29 4:00:00.000]]
Thu 1/1 0:02:03.116 [ReProcessHook]  ***** Checking to see if video has been completely watched.
Thu 1/1 0:02:03.146 [VideoFrame-0023a50000e0] VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[id=0 A[48,47,"Glee-BritneyBrittany-3883282-0",0@0929.03:00,59] mask=V host=HD300 encodedBy= format=MPEG2-PS 0:59:56 3044 kbps [#0 Video[MPEG2-Video 59.94006 fps 1280x720 16:9 progressive]#1 Audio[AC3 48000 Hz 6 channels 448 kbps MAIN idx=1 id=bd-81010002]#2 Audio[AC3 48000 Hz 2 channels 96 kbps idx=2 id=bd-82010002]] /tmp/external/ST932032 5AS (298 GB)/Video/Glee-BritneyBrittany-3883282-0.mpg, Seg0[Wed 9/29 3:00:03.441-Wed 9/29 4:00:00.000]] ifn=null] nPlayin=false
Thu 1/1 0:02:03.154 [VideoFrame-0023a50000e0] VF processing on UI Thread VFJob[LoadMF r=0.0 t=0 file=MediaFile[id=0 A[48,47,"Glee-BritneyBrittany-3883282-0",0@0929.03:00,59] mask=V host=HD300 encodedBy= format=MPEG2-PS 0:59:56 3044 kbps [#0 Video[MPEG2-Video 59.94006 fps 1280x720 16:9 progressive]#1 Audio[AC3 48000 Hz 6 channels 448 kbps MAIN idx=1 id=bd-81010002]#2 Audio[AC3 48000 Hz 2 channels 96 kbps idx=2 id=bd-82010002]] /tmp/external/ST932032 5AS (298 GB)/Video/Glee-BritneyBrittany-3883282-0.mpg, Seg0[Wed 9/29 3:00:03.441-Wed 9/29 4:00:00.000]] ifn=null]
Thu 1/1 0:02:03.169 [VideoFrame-0023a50000e0] VF file=MediaFile[id=0 A[48,47,"Glee-BritneyBrittany-3883282-0",0@0929.03:00,59] mask=V host=HD300 encodedBy= format=MPEG2-PS 0:59:56 3044 kbps [#0 Video[MPEG2-Video 59.94006 fps 1280x720 16:9 progressive]#1 Audio[AC3 48000 Hz 6 channels 448 kbps MAIN idx=1 id=bd-81010002]#2 Audio[AC3 48000 Hz 2 channels 96 kbps idx=2 id=bd-82010002]] /tmp/external/ST932032 5AS (298 GB)/Video/Glee-BritneyBrittany-3883282-0.mpg, Seg0[Wed 9/29 3:00:03.441-Wed 9/29 4:00:00.000]] targetTime = Wed 9/29 3:00:03.441
Thu 1/1 0:02:03.218 [VideoFrame-0023a50000e0] Using native resolution matching to switch output resolution; targetFormat=Video[MPEG2-Video 59.94006 fps 1280x720 16:9 progressive] newResolution=Video[1280x720p@59.94 59.0 fps 1280x720 progressive]
Thu 1/1 0:02:03.219 [VideoFrame-0023a50000e0] Sending remote resolution change of: 1280x720p@59.94 val=1280x720p@59.94|standard=HDMI_720p59
Thu 1/1 0:02:03.225 [VideoFrame-0023a50000e0] VideoFrame.timeSelected(Wed 9/29 3:00:03.441, true) currFile=MediaFile[id=0 A[48,47,"Glee-BritneyBrittany-3883282-0",0@0929.03:00,59] mask=V host=HD300 encodedBy= format=MPEG2-PS 0:59:56 3044 kbps [#0 Video[MPEG2-Video 59.94006 fps 1280x720 16:9 progressive]#1 Audio[AC3 48000 Hz 6 channels 448 kbps MAIN idx=1 id=bd-81010002]#2 Audio[AC3 48000 Hz 2 channels 96 kbps idx=2 id=bd-82010002]] /tmp/external/ST932032 5AS (298 GB)/Video/Glee-BritneyBrittany-3883282-0.mpg, Seg0[Wed 9/29 3:00:03.441-Wed 9/29 4:00:00.000]]
Thu 1/1 0:02:03.232 [VideoFrame-0023a50000e0] initDriver0()
Thu 1/1 0:02:03.294 [VideoFrame-0023a50000e0] MiniPlayer established for 0023a50000e0
Thu 1/1 0:02:03.398 [VideoFrame-0023a50000e0] getEstimatedBW=0 estimatedBWBytes=0 estimatedBWTime=0
Thu 1/1 0:02:03.400 [VideoFrame-0023a50000e0] MiniPlayer got an estimate from the UI on bandwidth of 50000Kbps
Thu 1/1 0:02:03.401 [VideoFrame-0023a50000e0] MiniPlayer is using Push mode playback
Thu 1/1 0:02:03.407 [VideoFrame-0023a50000e0] MiniPlayer is using the MPEG2 pusher
Thu 1/1 0:02:03.469 [VideoFrame-0023a50000e0] Mpeg2Reader is detecting timestamp boundaries in the file...
Thu 1/1 0:02:03.514 [VideoFrame-0023a50000e0] Mpeg2Reader about to determine the firstPTS in the file...
Thu 1/1 0:02:03.520 [VideoFrame-0023a50000e0] MPEG2 file pack size=2048
Thu 1/1 0:02:03.525 [VideoFrame-0023a50000e0] Mpeg2Reader has found the firstPTS, now determining the duration of the file...
Thu 1/1 0:02:03.860 [VideoFrame-0023a50000e0] Opened MPEG-2 PS file: /tmp/external/ST932032 5AS (298 GB)/Video/Glee-BritneyBrittany-3883282-0.mpg firstPTS=44158 durationMsec=3596559
Thu 1/1 0:02:03.861 [VideoFrame-0023a50000e0] Mpeg2Reader seeking to pos=0
Thu 1/1 0:02:03.864 [VideoFrame-0023a50000e0] openURL0(push:f=MPEG2-PS;dur=3596559;br=3044000;[bf=vid;f=MPEG2-Video;index=0;main=yes;tag=e0;fps=59.94006;fpsn=60000;fpsd=1001;ar=1.777778;arn=16;ard=9;w=1280;h=720;][bf=aud;f=AC3;br=448000;index=1;main=yes;tag=bd-81010002;sr=48000;ch=6;][bf=aud;f=AC3;br=96000;index=2;tag=bd-82010002;sr=48000;ch=2;])
Thu 1/1 0:02:04.175 [VideoFrame-0023a50000e0] Setting audio stream for playback to be ID=0xbd81
Thu 1/1 0:02:04.831 [VideoFrame-0023a50000e0] Sigma video dim=java.awt.Dimension[width=720,height=480]
Thu 1/1 0:02:04.832 [VideoFrame-0023a50000e0] pausePush0()
Thu 1/1 0:02:04.834 [VideoFrame-0023a50000e0] setMute0()
Thu 1/1 0:02:04.837 [FinalRender-0023a50000e0] MiniClient sending GFX_ASPECT=1.7677778
Thu 1/1 0:02:04.841 [VideoFrame-0023a50000e0] getDuration : 3596559
Thu 1/1 0:02:04.850 [MiniUIClientReceiver] Got UI size update to 1024x576
Thu 1/1 0:02:04.889 [VideoFrame-0023a50000e0] VideoFrame.timeSelected2(Wed 9/29 3:00:03.441, true) currFile=MediaFile[id=0 A[48,47,"Glee-BritneyBrittany-3883282-0",0@0929.03:00,59] mask=V host=HD300 encodedBy= format=MPEG2-PS 0:59:56 3044 kbps [#0 Video[MPEG2-Video 59.94006 fps 1280x720 16:9 progressive]#1 Audio[AC3 48000 Hz 6 channels 448 kbps MAIN idx=1 id=bd-81010002]#2 Audio[AC3 48000 Hz 2 channels 96 kbps idx=2 id=bd-82010002]] /tmp/external/ST932032 5AS (298 GB)/Video/Glee-BritneyBrittany-3883282-0.mpg, Seg0[Wed 9/29 3:00:03.441-Wed 9/29 4:00:00.000]] realDur=3596559
Thu 1/1 0:02:04.890 [VideoFrame-0023a50000e0] seeking numpushbuffers=0 seekTime=0
Thu 1/1 0:02:04.891 [VideoFrame-0023a50000e0] Mpeg2Reader seeking to pos=0
Thu 1/1 0:02:04.891 [VideoFrame-0023a50000e0] flushPush0()
Thu 1/1 0:02:04.969 [VideoFrame-0023a50000e0] Creating new push thread
Thu 1/1 0:02:04.997 [Pusher] Pusher thread is starting
Thu 1/1 0:02:04.997 [Pusher] Miniplayer pusher using buffer size of 131072
Thu 1/1 0:02:05.015 [AsyncWatch] setUI(sage.at@fbeeda15[MediaPlayer OSD]) histIdx=3 uiHistory=[sage.at@d5c4433b[Main Menu], sage.at@f8990016[Home], sage.at@4fe688a1[Browser - Files], sage.at@fbeeda15[MediaPlayer OSD]]
Thu 1/1 0:02:05.032 [Pusher] Adjusting peek buffer size to: 131072
Thu 1/1 0:02:05.087 [VideoFrame-0023a50000e0] VF processing job null nPlayin=true
Thu 1/1 0:02:05.088 [ReProcessHook]  ***** Checking to see if video has been completely watched.
Thu 1/1 0:02:05.091 [VideoFrame-0023a50000e0] isRec=false rd=3596559 base=0 eos=false
Thu 1/1 0:02:05.092 [VideoFrame-0023a50000e0] VF thread is now waiting for 0:59:56.309
Thu 1/1 0:02:05.099 [ReProcessHook]  ***** This is not a TV show; checking for imported video statusstatus.
Thu 1/1 0:02:05.125 [ReProcessHook]  ***** This is an imported video; checking watched status.
Thu 1/1 0:02:05.149 [Pusher] BW=7943757 numPushes=1 numWaits=1
Thu 1/1 0:02:05.262 [Pusher] playPush0()
Thu 1/1 0:02:05.852 [ActiveRender-0023a50000e0] VideoFrame got registration of a subtitle UI component: sage.bf[loc=java.awt.Point[x=145,y=414] size=java.awt.Dimension[width=678,height=109]]
Thu 1/1 0:02:05.854 [ActiveRender-0023a50000e0] VideoFrame got registration of a cc UI component: sage.bf[loc=java.awt.Point[x=96,y=54] size=java.awt.Dimension[width=775,height=436]]
Thu 1/1 0:02:05.859 [ActiveRender-0023a50000e0] Creating new FreeType font face for Monospaced size=22 style=0 from=/app/sage/MonospacedBI.ttf
Thu 1/1 0:02:05.868 [MiniUIClientReceiver] Server got notification to update the output modes...
Thu 1/1 0:02:05.914 [FinalRender-0023a50000e0] MiniClient GFX_HDMI_MODE=HDMI
Thu 1/1 0:02:05.922 [FinalRender-0023a50000e0] MiniClient GFX_SUPPORTED_RESOLUTIONS_DIGITAL=720x480i@59.94|standard=HDMI_480i59;720x480p@59.94|standard=HDMI_480p59;720x480p@60|standard=HDMI_480p60;1280x720p@59.94|standard=HDMI_720p59;1280x720p@60|standard=HDMI_720p60;1920x1080i@60|standard=HDMI_1080i60;1920x1080i@59.94|standard=HDMI_1080i59;1920x1080p@59.94|standard=HDMI_1080p59;1920x1080p@60|standard=HDMI_1080p60;
Thu 1/1 0:02:05.923 [FinalRender-0023a50000e0] MiniClient sending GFX_ASPECT=1.7677778
Thu 1/1 0:02:05.942 [MiniUIClientReceiver] Got UI size update to 1024x576
Thu 1/1 0:02:06.133 [Pusher] BW=47875896 numPushes=53 numWaits=3
Thu 1/1 0:02:06.759 [FinalRender-0023a50000e0] Loading compressed image directly from: /app/sage/STVs/Cheetah/OSDStatusPlay.png width=8 height=8
Thu 1/1 0:02:06.810 [FinalRender-0023a50000e0] Loading compressed image directly from: /app/sage/STVs/Cheetah/OSDVolOn.png width=16 height=13
Thu 1/1 0:02:06.822 [FinalRender-0023a50000e0] Loading compressed image directly from: /app/sage/STVs/Cheetah/OSDVolBar.png width=64 height=4
Thu 1/1 0:02:06.884 [FinalRender-0023a50000e0] Loading compressed image directly from: /app/sage/STVs/Cheetah/OSDVolSlider.png width=13 height=15
Thu 1/1 0:02:07.071 [Pusher] BW=51535852 numPushes=106 numWaits=6
Thu 1/1 0:02:10.573 [Pusher] BW=28492887 numPushes=247 numWaits=97
Thu 1/1 0:02:14.050 [Pusher] BW=23330061 numPushes=404 numWaits=204
Thu 1/1 0:02:14.231 [EventRouter-0023a50000e0] VF.submitJob(VFJob[CloseMF r=0.0 t=0 file=null ifn=null])
Thu 1/1 0:02:14.232 [VideoFrame-0023a50000e0] VF processing job VFJob[CloseMF r=0.0 t=0 file=null ifn=null] nPlayin=true
Thu 1/1 0:02:14.232 [VideoFrame-0023a50000e0] VF processing on UI Thread VFJob[CloseMF r=0.0 t=0 file=null ifn=null]
Thu 1/1 0:02:14.237 [VideoFrame-0023a50000e0] Added:Watched[id=49 Airing=A[48,47,"Glee-BritneyBrittany-3883282-0",0@0929.03:00,59], WatchStart=Wed 9/29 3:00:03.441, WatchEnd=Wed 9/29 3:00:12.059, RealStart=Thu 1/1 0:02:03.169, RealEnd=Thu 1/1 0:02:14.236]
Thu 1/1 0:02:14.238 [VideoFrame-0023a50000e0] VF logFileWatch doneAir=A[48,47,"Glee-BritneyBrittany-3883282-0",0@0929.03:00,59] theTime=Wed 9/29 3:00:12.059 recTime=Wed 9/29 3:00:03.441
Thu 1/1 0:02:14.244 [VideoFrame-0023a50000e0] stopPush0()
Thu 1/1 0:02:14.356 [VideoFrame-0023a50000e0] Closing down MiniPlayer
Thu 1/1 0:02:14.357 [VideoFrame-0023a50000e0] closeDriver0()
Thu 1/1 0:02:14.467 [VideoFrame-0023a50000e0] Using native resolution matching to switch output resolution back to optimal UI resolution=Video[1920x1080p@59.94 59.0 fps 1920x1080 progressive]
Thu 1/1 0:02:14.467 [VideoFrame-0023a50000e0] Sending remote resolution change of: 1920x1080p@59.94 val=1920x1080p@59.94|standard=HDMI_1080p59
Thu 1/1 0:02:14.471 [VideoFrame-0023a50000e0] cleanupLocalFile called for MediaFile[id=0 A[48,47,"Glee-BritneyBrittany-3883282-0",0@0929.03:00,59] mask=V host=HD300 encodedBy= format=MPEG2-PS 0:59:56 3044 kbps [#0 Video[MPEG2-Video 59.94006 fps 1280x720 16:9 progressive]#1 Audio[AC3 48000 Hz 6 channels 448 kbps MAIN idx=1 id=bd-81010002]#2 Audio[AC3 48000 Hz 2 channels 96 kbps idx=2 id=bd-82010002]] /tmp/external/ST932032 5AS (298 GB)/Video/Glee-BritneyBrittany-3883282-0.mpg, Seg0[Wed 9/29 3:00:03.441-Wed 9/29 4:00:00.000]]
Thu 1/1 0:02:14.478 [MiniUIServer] MiniUI got connection from java.nio.channels.SocketChannel[connected local=/127.0.0.1:31099 remote=/127.0.0.1:38154]
Thu 1/1 0:02:14.480 [MiniUIServerConnection] MiniPlayer is adding to its map:0023a50000e0
Thu 1/1 0:02:14.482 [EventRouter-0023a50000e0] Seeker.finishWatch(UIManager:localhost@@0023a50000e0--7566cc7a)
Thu 1/1 0:02:14.483 [Seeker] Seeker awoken
Thu 1/1 0:02:14.483 [Seeker] MemStats: Used=18MB Total=23MB Max=167MB
Thu 1/1 0:02:14.485 [Seeker] Saving properties file to /rw/sage/Sage.properties
Thu 1/1 0:02:14.488 [Scheduler] Scheduler awoken
Thu 1/1 0:02:14.537 [Scheduler] Scheduler.updateSchedule() called time=Thu 1/1 0:02:14.536 manual=[] schedules={} scheduleRandSize=0
Thu 1/1 0:02:14.540 [VideoFrame-0023a50000e0] VF processing job null nPlayin=false
Thu 1/1 0:02:14.541 [VideoFrame-0023a50000e0] VF thread is now waiting for 0:00:00.000
Thu 1/1 0:02:14.557 [Seeker] Executing process: sync
Thu 1/1 0:02:14.586 [Seeker] Diskspace checking is running
Thu 1/1 0:02:14.591 [Seeker] Seeker waiting for 153722867280910 mins.
Thu 1/1 0:02:14.594 [Seeker] Seeker awoken
Thu 1/1 0:02:14.596 [Seeker] MemStats: Used=18MB Total=23MB Max=167MB
Thu 1/1 0:02:14.606 [VideoFrame-0023a50000e0] VF processing job null nPlayin=false
Thu 1/1 0:02:14.606 [VideoFrame-0023a50000e0] VF thread is now waiting for 0:00:00.000
Thu 1/1 0:02:14.607 [Seeker] Diskspace checking is running
Thu 1/1 0:02:14.616 [Seeker] Seeker waiting for 153722867280910 mins.
Thu 1/1 0:02:14.656 [EventRouter-0023a50000e0] setUI(sage.at@4fe688a1[Browser - Files]) histIdx=2 uiHistory=[sage.at@d5c4433b[Main Menu], sage.at@f8990016[Home], sage.at@4fe688a1[Browser - Files], sage.at@fbeeda15[MediaPlayer OSD]]
Thu 1/1 0:02:14.659 [EventRouter-0023a50000e0] VideoFrame got registration of a subtitle UI component: null
Thu 1/1 0:02:14.661 [EventRouter-0023a50000e0] VideoFrame got registration of a cc UI component: null
Thu 1/1 0:02:14.663 [EventRouter-0023a50000e0] START: BeforeMenuLoad hook processing for the 'Browser - Files' menu.
Thu 1/1 0:02:14.672 [EventRouter-0023a50000e0] LocalDeviceMap Keys: 1 = [[ST932032 5AS (298 GB)]], map size = 1
Thu 1/1 0:02:14.674 [EventRouter-0023a50000e0] LocalDeviceMap LocalItem 0: [ST932032 5AS (298 GB)], value: [/tmp/external/ST932032 5AS (298 GB)]
Thu 1/1 0:02:14.676 [EventRouter-0023a50000e0] LocalDeviceMap LocalItem 1: [null], value: [null]
Thu 1/1 0:02:14.687 [EventRouter-0023a50000e0] LastFolderDevicesMap = [{ST932032 5AS (298 GB)=/tmp/external/ST932032 5AS (298 GB)/Video}]
Thu 1/1 0:02:14.691 [EventRouter-0023a50000e0] Done with BeforeMenuLoad hook for the 'Browser - Files' menu. Total Time = '27'
Thu 1/1 0:02:15.603 [FinalRender-0023a50000e0] MiniClient sending GFX_ASPECT=1.7677778
Thu 1/1 0:02:15.620 [MiniUIClientReceiver] Got UI size update to 1024x576
Thu 1/1 0:02:16.629 [MiniUIClientReceiver] Server got notification to update the output modes...
Thu 1/1 0:02:16.672 [FinalRender-0023a50000e0] MiniClient GFX_HDMI_MODE=HDMI
Thu 1/1 0:02:16.676 [FinalRender-0023a50000e0] MiniClient GFX_SUPPORTED_RESOLUTIONS_DIGITAL=720x480i@59.94|standard=HDMI_480i59;720x480p@59.94|standard=HDMI_480p59;720x480p@60|standard=HDMI_480p60;1280x720p@59.94|standard=HDMI_720p59;1280x720p@60|standard=HDMI_720p60;1920x1080i@60|standard=HDMI_1080i60;1920x1080i@59.94|standard=HDMI_1080i59;1920x1080p@59.94|standard=HDMI_1080p59;1920x1080p@60|standard=HDMI_1080p60;
Thu 1/1 0:02:16.678 [FinalRender-0023a50000e0] MiniClient sending GFX_ASPECT=1.7677778
Thu 1/1 0:02:16.681 [MiniUIClientReceiver] Got UI size update to 1024x576
Thu 1/1 0:02:27.211 [EventRouter-0023a50000e0] processOptionsMenu optionsMenu=default:null|OptionsMenu:FileBrowser File Options
Thu 1/1 0:02:28.463 [Fork-null] processOptionsMenu optionsMenu=default:null|OptionsMenu:Preparing for video file playback notice
Thu 1/1 0:02:28.545 [ReProcessHook] VF.submitJob(VFJob[CloseMF r=0.0 t=0 file=null ifn=null])
Thu 1/1 0:02:28.546 [VideoFrame-0023a50000e0] VF processing job VFJob[CloseMF r=0.0 t=0 file=null ifn=null] nPlayin=false
Thu 1/1 0:02:28.547 [VideoFrame-0023a50000e0] VF processing on UI Thread VFJob[CloseMF r=0.0 t=0 file=null ifn=null]
Thu 1/1 0:02:28.547 [VideoFrame-0023a50000e0] VF processing job null nPlayin=false
Thu 1/1 0:02:28.549 [ReProcessHook] Seeker.finishWatch(UIManager:localhost@@0023a50000e0--7566cc7a)
Thu 1/1 0:02:28.558 [VideoFrame-0023a50000e0] VF thread is now waiting for 0:00:00.000
Thu 1/1 0:02:28.862 [AsyncWatch] Using internal format detector first...
Thu 1/1 0:02:28.988 [AsyncWatch] File Format Parsed-2a /tmp/external/ST932032 5AS (298 GB)/Video/HawaiiFive0-Ohana-3883370-0.mpg=MPEG2-PS 0:59:58 3076 kbps [#0 Video[MPEG2-Video 29.97003 fps 1920x1080 16:9 interlaced]#1 Audio[AC3 48000 Hz 1 channels 192 kbps idx=2 id=bd-82010002]#2 Audio[AC3 48000 Hz 6 channels 384 kbps MAIN idx=1 id=bd-81010002]]
Thu 1/1 0:02:29.125 [AsyncWatch] File Format Parsed-2b /tmp/external/ST932032 5AS (298 GB)/Video/HawaiiFive0-Ohana-3883370-0.mpg=MPEG2-PS 0:59:58 3076 kbps [#0 Video[MPEG2-Video 29.97003 fps 1920x1080 16:9 interlaced]#1 Audio[AC3 48000 Hz 1 channels 192 kbps idx=2 id=bd-82010002]#2 Audio[AC3 48000 Hz 6 channels 384 kbps MAIN idx=1 id=bd-81010002]]
Thu 1/1 0:02:29.150 [AsyncWatch] VideoFrame.watch(A[51,50,"HawaiiFive0-Ohana-3883370-0",0@0928.05:00,59])
Thu 1/1 0:02:29.185 [AsyncWatch] watchThisFile=MediaFile[id=0 A[51,50,"HawaiiFive0-Ohana-3883370-0",0@0928.05:00,59] mask=V host=HD300 encodedBy= format=MPEG2-PS 0:59:58 3076 kbps [#0 Video[MPEG2-Video 29.97003 fps 1920x1080 16:9 interlaced]#1 Audio[AC3 48000 Hz 1 channels 192 kbps idx=2 id=bd-82010002]#2 Audio[AC3 48000 Hz 6 channels 384 kbps MAIN idx=1 id=bd-81010002]] /tmp/external/ST932032 5AS (298 GB)/Video/HawaiiFive0-Ohana-3883370-0.mpg, Seg0[Tue 9/28 5:00:01.472-Tue 9/28 6:00:00.000]]
Thu 1/1 0:02:29.187 [AsyncWatch] Watch airing is a file & over, do that instead dvd=false
Thu 1/1 0:02:29.190 [AsyncWatch] Seeker.finishWatch(UIManager:localhost@@0023a50000e0--7566cc7a)
Thu 1/1 0:02:29.192 [AsyncWatch] VF.submitJob(VFJob[WatchMF r=0.0 t=0 file=MediaFile[id=0 A[51,50,"HawaiiFive0-Ohana-3883370-0",0@0928.05:00,59] mask=V host=HD300 encodedBy= format=MPEG2-PS 0:59:58 3076 kbps [#0 Video[MPEG2-Video 29.97003 fps 1920x1080 16:9 interlaced]#1 Audio[AC3 48000 Hz 1 channels 192 kbps idx=2 id=bd-82010002]#2 Audio[AC3 48000 Hz 6 channels 384 kbps MAIN idx=1 id=bd-81010002]] /tmp/external/ST932032 5AS (298 GB)/Video/HawaiiFive0-Ohana-3883370-0.mpg, Seg0[Tue 9/28 5:00:01.472-Tue 9/28 6:00:00.000]] ifn=null])
Thu 1/1 0:02:29.194 [VideoFrame-0023a50000e0] VF processing job VFJob[WatchMF r=0.0 t=0 file=MediaFile[id=0 A[51,50,"HawaiiFive0-Ohana-3883370-0",0@0928.05:00,59] mask=V host=HD300 encodedBy= format=MPEG2-PS 0:59:58 3076 kbps [#0 Video[MPEG2-Video 29.97003 fps 1920x1080 16:9 interlaced]#1 Audio[AC3 48000 Hz 1 channels 192 kbps idx=2 id=bd-82010002]#2 Audio[AC3 48000 Hz 6 channels 384 kbps MAIN idx=1 id=bd-81010002]] /tmp/external/ST932032 5AS (298 GB)/Video/HawaiiFive0-Ohana-3883370-0.mpg, Seg0[Tue 9/28 5:00:01.472-Tue 9/28 6:00:00.000]] ifn=null] nPlayin=false
Thu 1/1 0:02:29.200 [VideoFrame-0023a50000e0] VF processing on UI Thread VFJob[WatchMF r=0.0 t=0 file=MediaFile[id=0 A[51,50,"HawaiiFive0-Ohana-3883370-0",0@0928.05:00,59] mask=V host=HD300 encodedBy= format=MPEG2-PS 0:59:58 3076 kbps [#0 Video[MPEG2-Video 29.97003 fps 1920x1080 16:9 interlaced]#1 Audio[AC3 48000 Hz 1 channels 192 kbps idx=2 id=bd-82010002]#2 Audio[AC3 48000 Hz 6 channels 384 kbps MAIN idx=1 id=bd-81010002]] /tmp/external/ST932032 5AS (298 GB)/Video/HawaiiFive0-Ohana-3883370-0.mpg, Seg0[Tue 9/28 5:00:01.472-Tue 9/28 6:00:00.000]] ifn=null]
Thu 1/1 0:02:29.202 [VideoFrame-0023a50000e0] VideoFrame creating new media player for file:MediaFile[id=0 A[51,50,"HawaiiFive0-Ohana-3883370-0",0@0928.05:00,59] mask=V host=HD300 encodedBy= format=MPEG2-PS 0:59:58 3076 kbps [#0 Video[MPEG2-Video 29.97003 fps 1920x1080 16:9 interlaced]#1 Audio[AC3 48000 Hz 1 channels 192 kbps idx=2 id=bd-82010002]#2 Audio[AC3 48000 Hz 6 channels 384 kbps MAIN idx=1 id=bd-81010002]] /tmp/external/ST932032 5AS (298 GB)/Video/HawaiiFive0-Ohana-3883370-0.mpg, Seg0[Tue 9/28 5:00:01.472-Tue 9/28 6:00:00.000]]
Thu 1/1 0:02:29.207 [AsyncWatch] setUI(sage.at@fbeeda15[MediaPlayer OSD]) histIdx=3 uiHistory=[sage.at@d5c4433b[Main Menu], sage.at@f8990016[Home], sage.at@4fe688a1[Browser - Files], sage.at@fbeeda15[MediaPlayer OSD]]
Thu 1/1 0:02:29.209 [ReProcessHook]  ***** Checking to see if video has been completely watched.
Thu 1/1 0:02:29.214 [VideoFrame-0023a50000e0] VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[id=0 A[51,50,"HawaiiFive0-Ohana-3883370-0",0@0928.05:00,59] mask=V host=HD300 encodedBy= format=MPEG2-PS 0:59:58 3076 kbps [#0 Video[MPEG2-Video 29.97003 fps 1920x1080 16:9 interlaced]#1 Audio[AC3 48000 Hz 1 channels 192 kbps idx=2 id=bd-82010002]#2 Audio[AC3 48000 Hz 6 channels 384 kbps MAIN idx=1 id=bd-81010002]] /tmp/external/ST932032 5AS (298 GB)/Video/HawaiiFive0-Ohana-3883370-0.mpg, Seg0[Tue 9/28 5:00:01.472-Tue 9/28 6:00:00.000]] ifn=null] nPlayin=false
Thu 1/1 0:02:29.221 [VideoFrame-0023a50000e0] VF processing on UI Thread VFJob[LoadMF r=0.0 t=0 file=MediaFile[id=0 A[51,50,"HawaiiFive0-Ohana-3883370-0",0@0928.05:00,59] mask=V host=HD300 encodedBy= format=MPEG2-PS 0:59:58 3076 kbps [#0 Video[MPEG2-Video 29.97003 fps 1920x1080 16:9 interlaced]#1 Audio[AC3 48000 Hz 1 channels 192 kbps idx=2 id=bd-82010002]#2 Audio[AC3 48000 Hz 6 channels 384 kbps MAIN idx=1 id=bd-81010002]] /tmp/external/ST932032 5AS (298 GB)/Video/HawaiiFive0-Ohana-3883370-0.mpg, Seg0[Tue 9/28 5:00:01.472-Tue 9/28 6:00:00.000]] ifn=null]
Thu 1/1 0:02:29.223 [VideoFrame-0023a50000e0] VF file=MediaFile[id=0 A[51,50,"HawaiiFive0-Ohana-3883370-0",0@0928.05:00,59] mask=V host=HD300 encodedBy= format=MPEG2-PS 0:59:58 3076 kbps [#0 Video[MPEG2-Video 29.97003 fps 1920x1080 16:9 interlaced]#1 Audio[AC3 48000 Hz 1 channels 192 kbps idx=2 id=bd-82010002]#2 Audio[AC3 48000 Hz 6 channels 384 kbps MAIN idx=1 id=bd-81010002]] /tmp/external/ST932032 5AS (298 GB)/Video/HawaiiFive0-Ohana-3883370-0.mpg, Seg0[Tue 9/28 5:00:01.472-Tue 9/28 6:00:00.000]] targetTime = Tue 9/28 5:00:01.472
Thu 1/1 0:02:29.226 [VideoFrame-0023a50000e0] Using native resolution matching to switch output resolution; targetFormat=Video[MPEG2-Video 29.97003 fps 1920x1080 16:9 interlaced] newResolution=Video[1920x1080i@59.94 59.0 fps 1920x1080 interlaced]
Thu 1/1 0:02:29.228 [VideoFrame-0023a50000e0] Sending remote resolution change of: 1920x1080i@59.94 val=1920x1080i@59.94|standard=HDMI_1080i59
Thu 1/1 0:02:29.231 [VideoFrame-0023a50000e0] VideoFrame.timeSelected(Tue 9/28 5:00:01.472, true) currFile=MediaFile[id=0 A[51,50,"HawaiiFive0-Ohana-3883370-0",0@0928.05:00,59] mask=V host=HD300 encodedBy= format=MPEG2-PS 0:59:58 3076 kbps [#0 Video[MPEG2-Video 29.97003 fps 1920x1080 16:9 interlaced]#1 Audio[AC3 48000 Hz 1 channels 192 kbps idx=2 id=bd-82010002]#2 Audio[AC3 48000 Hz 6 channels 384 kbps MAIN idx=1 id=bd-81010002]] /tmp/external/ST932032 5AS (298 GB)/Video/HawaiiFive0-Ohana-3883370-0.mpg, Seg0[Tue 9/28 5:00:01.472-Tue 9/28 6:00:00.000]]
Thu 1/1 0:02:29.232 [VideoFrame-0023a50000e0] initDriver0()
Thu 1/1 0:02:29.245 [VideoFrame-0023a50000e0] MiniPlayer established for 0023a50000e0
Thu 1/1 0:02:29.352 [VideoFrame-0023a50000e0] getEstimatedBW=0 estimatedBWBytes=0 estimatedBWTime=0
Thu 1/1 0:02:29.354 [VideoFrame-0023a50000e0] MiniPlayer got an estimate from the UI on bandwidth of 50000Kbps
Thu 1/1 0:02:29.359 [VideoFrame-0023a50000e0] MiniPlayer is using Push mode playback
Thu 1/1 0:02:29.361 [VideoFrame-0023a50000e0] MiniPlayer is using the MPEG2 pusher
Thu 1/1 0:02:29.370 [VideoFrame-0023a50000e0] Mpeg2Reader is detecting timestamp boundaries in the file...
Thu 1/1 0:02:29.378 [VideoFrame-0023a50000e0] Mpeg2Reader about to determine the firstPTS in the file...
Thu 1/1 0:02:29.380 [VideoFrame-0023a50000e0] MPEG2 file pack size=2048
Thu 1/1 0:02:29.382 [VideoFrame-0023a50000e0] Mpeg2Reader has found the firstPTS, now determining the duration of the file...
Thu 1/1 0:02:29.577 [VideoFrame-0023a50000e0] Opened MPEG-2 PS file: /tmp/external/ST932032 5AS (298 GB)/Video/HawaiiFive0-Ohana-3883370-0.mpg firstPTS=81229 durationMsec=3598494
Thu 1/1 0:02:29.577 [VideoFrame-0023a50000e0] Mpeg2Reader seeking to pos=0
Thu 1/1 0:02:29.579 [VideoFrame-0023a50000e0] openURL0(push:f=MPEG2-PS;dur=3598528;br=3076000;[bf=vid;f=MPEG2-Video;index=0;main=yes;tag=e0;fps=29.97003;fpsn=30000;fpsd=1001;ar=1.777778;arn=16;ard=9;w=1920;h=1080;lace=1;][bf=aud;f=AC3;br=192000;index=2;tag=bd-82010002;sr=48000;ch=1;][bf=aud;f=AC3;br=384000;index=1;main=yes;tag=bd-81010002;sr=48000;ch=6;])
Thu 1/1 0:02:29.840 [VideoFrame-0023a50000e0] Setting audio stream for playback to be ID=0xbd81
Thu 1/1 0:02:29.841 [VideoFrame-0023a50000e0] Sigma video dim=java.awt.Dimension[width=720,height=480]
Thu 1/1 0:02:29.842 [VideoFrame-0023a50000e0] pausePush0()
Thu 1/1 0:02:29.844 [VideoFrame-0023a50000e0] setMute0()
Thu 1/1 0:02:29.847 [VideoFrame-0023a50000e0] getDuration : 3598494
Thu 1/1 0:02:29.850 [VideoFrame-0023a50000e0] VideoFrame.timeSelected2(Tue 9/28 5:00:01.472, true) currFile=MediaFile[id=0 A[51,50,"HawaiiFive0-Ohana-3883370-0",0@0928.05:00,59] mask=V host=HD300 encodedBy= format=MPEG2-PS 0:59:58 3076 kbps [#0 Video[MPEG2-Video 29.97003 fps 1920x1080 16:9 interlaced]#1 Audio[AC3 48000 Hz 1 channels 192 kbps idx=2 id=bd-82010002]#2 Audio[AC3 48000 Hz 6 channels 384 kbps MAIN idx=1 id=bd-81010002]] /tmp/external/ST932032 5AS (298 GB)/Video/HawaiiFive0-Ohana-3883370-0.mpg, Seg0[Tue 9/28 5:00:01.472-Tue 9/28 6:00:00.000]] realDur=3598494
Thu 1/1 0:02:29.851 [VideoFrame-0023a50000e0] seeking numpushbuffers=0 seekTime=0
Thu 1/1 0:02:29.852 [VideoFrame-0023a50000e0] Mpeg2Reader seeking to pos=0
Thu 1/1 0:02:29.853 [VideoFrame-0023a50000e0] flushPush0()
Thu 1/1 0:02:29.920 [VideoFrame-0023a50000e0] Creating new push thread
Thu 1/1 0:02:29.928 [Pusher] Pusher thread is starting
Thu 1/1 0:02:29.929 [Pusher] Miniplayer pusher using buffer size of 131072
Thu 1/1 0:02:29.939 [VideoFrame-0023a50000e0] VF processing job null nPlayin=true
Thu 1/1 0:02:29.940 [VideoFrame-0023a50000e0] isRec=false rd=3598494 base=0 eos=false
Thu 1/1 0:02:29.941 [VideoFrame-0023a50000e0] VF thread is now waiting for 0:59:58.244
Thu 1/1 0:02:29.942 [ActiveRender-0023a50000e0] VideoFrame got registration of a subtitle UI component: sage.bf[loc=java.awt.Point[x=145,y=414] size=java.awt.Dimension[width=678,height=109]]
Thu 1/1 0:02:29.944 [ActiveRender-0023a50000e0] VideoFrame got registration of a cc UI component: sage.bf[loc=java.awt.Point[x=96,y=54] size=java.awt.Dimension[width=775,height=436]]
Thu 1/1 0:02:29.955 [Pusher] Adjusting peek buffer size to: 131072
Thu 1/1 0:02:29.998 [Pusher] BW=16131938 numPushes=1 numWaits=1
Thu 1/1 0:02:30.003 [ReProcessHook]  ***** Checking to see if video has been completely watched.
Thu 1/1 0:02:30.010 [ReProcessHook]  ***** This is not a TV show; checking for imported video statusstatus.
Thu 1/1 0:02:30.011 [ReProcessHook]  ***** This is an imported video; checking watched status.
Thu 1/1 0:02:30.100 [Pusher] playPush0()
Thu 1/1 0:02:30.719 [FinalRender-0023a50000e0] MiniClient sending GFX_ASPECT=1.7677778
Thu 1/1 0:02:30.730 [MiniUIClientReceiver] Got UI size update to 1024x576
Thu 1/1 0:02:30.772 [FinalRender-0023a50000e0] Loading compressed image directly from: /app/sage/STVs/Cheetah/OSDStatusPause.png width=6 height=8
Thu 1/1 0:02:31.298 [Pusher] BW=39177564 numPushes=53 numWaits=3
Thu 1/1 0:02:31.767 [MiniUIClientReceiver] Server got notification to update the output modes...
Thu 1/1 0:02:31.782 [Pusher] BW=57277542 numPushes=107 numWaits=7
Thu 1/1 0:02:31.829 [FinalRender-0023a50000e0] MiniClient GFX_HDMI_MODE=HDMI
Thu 1/1 0:02:31.836 [FinalRender-0023a50000e0] MiniClient GFX_SUPPORTED_RESOLUTIONS_DIGITAL=720x480i@59.94|standard=HDMI_480i59;720x480p@59.94|standard=HDMI_480p59;720x480p@60|standard=HDMI_480p60;1280x720p@59.94|standard=HDMI_720p59;1280x720p@60|standard=HDMI_720p60;1920x1080i@60|standard=HDMI_1080i60;1920x1080i@59.94|standard=HDMI_1080i59;1920x1080p@59.94|standard=HDMI_1080p59;1920x1080p@60|standard=HDMI_1080p60;
Thu 1/1 0:02:31.856 [FinalRender-0023a50000e0] MiniClient sending GFX_ASPECT=1.7677778
Thu 1/1 0:02:31.866 [MiniUIClientReceiver] Got UI size update to 1024x576
Thu 1/1 0:02:34.352 [Pusher] BW=35830499 numPushes=235 numWaits=85
Thu 1/1 0:02:37.593 [Pusher] BW=27514853 numPushes=385 numWaits=185
Thu 1/1 0:02:40.709 [Pusher] BW=20777595 numPushes=532 numWaits=282
Thu 1/1 0:02:43.589 [Pusher] BW=18740016 numPushes=671 numWaits=371
Thu 1/1 0:02:44.705 [EventRouter-0023a50000e0] VF.submitJob(VFJob[CloseMF r=0.0 t=0 file=null ifn=null])
Thu 1/1 0:02:44.706 [VideoFrame-0023a50000e0] VF processing job VFJob[CloseMF r=0.0 t=0 file=null ifn=null] nPlayin=true
Thu 1/1 0:02:44.707 [VideoFrame-0023a50000e0] VF processing on UI Thread VFJob[CloseMF r=0.0 t=0 file=null ifn=null]
Thu 1/1 0:02:44.711 [VideoFrame-0023a50000e0] Added:Watched[id=52 Airing=A[51,50,"HawaiiFive0-Ohana-3883370-0",0@0928.05:00,59], WatchStart=Tue 9/28 5:00:01.472, WatchEnd=Tue 9/28 5:00:15.287, RealStart=Thu 1/1 0:02:29.225, RealEnd=Thu 1/1 0:02:44.710]
Thu 1/1 0:02:44.712 [VideoFrame-0023a50000e0] VF logFileWatch doneAir=A[51,50,"HawaiiFive0-Ohana-3883370-0",0@0928.05:00,59] theTime=Tue 9/28 5:00:15.287 recTime=Tue 9/28 5:00:01.472
Thu 1/1 0:02:44.722 [VideoFrame-0023a50000e0] stopPush0()
Thu 1/1 0:02:44.809 [VideoFrame-0023a50000e0] Closing down MiniPlayer
Thu 1/1 0:02:44.810 [VideoFrame-0023a50000e0] closeDriver0()
Thu 1/1 0:02:44.915 [VideoFrame-0023a50000e0] Using native resolution matching to switch output resolution back to optimal UI resolution=Video[1920x1080p@59.94 59.0 fps 1920x1080 progressive]
Thu 1/1 0:02:44.919 [VideoFrame-0023a50000e0] Sending remote resolution change of: 1920x1080p@59.94 val=1920x1080p@59.94|standard=HDMI_1080p59
Thu 1/1 0:02:44.924 [MiniUIServer] MiniUI got connection from java.nio.channels.SocketChannel[connected local=/127.0.0.1:31099 remote=/127.0.0.1:38159]
Thu 1/1 0:02:44.931 [VideoFrame-0023a50000e0] cleanupLocalFile called for MediaFile[id=0 A[51,50,"HawaiiFive0-Ohana-3883370-0",0@0928.05:00,59] mask=V host=HD300 encodedBy= format=MPEG2-PS 0:59:58 3076 kbps [#0 Video[MPEG2-Video 29.97003 fps 1920x1080 16:9 interlaced]#1 Audio[AC3 48000 Hz 1 channels 192 kbps idx=2 id=bd-82010002]#2 Audio[AC3 48000 Hz 6 channels 384 kbps MAIN idx=1 id=bd-81010002]] /tmp/external/ST932032 5AS (298 GB)/Video/HawaiiFive0-Ohana-3883370-0.mpg, Seg0[Tue 9/28 5:00:01.472-Tue 9/28 6:00:00.000]]
Thu 1/1 0:02:44.935 [MiniUIServerConnection] MiniPlayer is adding to its map:0023a50000e0
Thu 1/1 0:02:44.939 [EventRouter-0023a50000e0] Seeker.finishWatch(UIManager:localhost@@0023a50000e0--7566cc7a)
Thu 1/1 0:02:44.940 [Seeker] Seeker awoken
Thu 1/1 0:02:44.940 [Seeker] MemStats: Used=19MB Total=23MB Max=167MB
Thu 1/1 0:02:44.942 [Scheduler] Scheduler awoken
Thu 1/1 0:02:44.943 [Scheduler] Scheduler.updateSchedule() called time=Thu 1/1 0:02:44.942 manual=[] schedules={} scheduleRandSize=0
Thu 1/1 0:02:44.945 [Seeker] Diskspace checking is running
Thu 1/1 0:02:44.946 [Seeker] Seeker waiting for 153722867280910 mins.
Thu 1/1 0:02:44.947 [Seeker] Seeker awoken
Thu 1/1 0:02:44.950 [Seeker] MemStats: Used=19MB Total=23MB Max=167MB
Thu 1/1 0:02:44.970 [Seeker] Diskspace checking is running
Thu 1/1 0:02:44.971 [Seeker] Seeker waiting for 153722867280910 mins.
Thu 1/1 0:02:44.973 [VideoFrame-0023a50000e0] VF processing job null nPlayin=false
Thu 1/1 0:02:44.974 [VideoFrame-0023a50000e0] VF thread is now waiting for 0:00:00.000
Thu 1/1 0:02:44.988 [EventRouter-0023a50000e0] setUI(sage.at@4fe688a1[Browser - Files]) histIdx=2 uiHistory=[sage.at@d5c4433b[Main Menu], sage.at@f8990016[Home], sage.at@4fe688a1[Browser - Files], sage.at@fbeeda15[MediaPlayer OSD]]
Thu 1/1 0:02:44.989 [EventRouter-0023a50000e0] VideoFrame got registration of a subtitle UI component: null
Thu 1/1 0:02:44.990 [EventRouter-0023a50000e0] VideoFrame got registration of a cc UI component: null
Thu 1/1 0:02:44.991 [EventRouter-0023a50000e0] START: BeforeMenuLoad hook processing for the 'Browser - Files' menu.
Thu 1/1 0:02:44.994 [EventRouter-0023a50000e0] LocalDeviceMap Keys: 1 = [[ST932032 5AS (298 GB)]], map size = 1
Thu 1/1 0:02:44.995 [EventRouter-0023a50000e0] LocalDeviceMap LocalItem 0: [ST932032 5AS (298 GB)], value: [/tmp/external/ST932032 5AS (298 GB)]
Thu 1/1 0:02:44.997 [EventRouter-0023a50000e0] LocalDeviceMap LocalItem 1: [null], value: [null]
Thu 1/1 0:02:45.003 [EventRouter-0023a50000e0] LastFolderDevicesMap = [{ST932032 5AS (298 GB)=/tmp/external/ST932032 5AS (298 GB)/Video}]
Thu 1/1 0:02:45.004 [EventRouter-0023a50000e0] Done with BeforeMenuLoad hook for the 'Browser - Files' menu. Total Time = '12'
Thu 1/1 0:02:45.728 [FinalRender-0023a50000e0] MiniClient sending GFX_ASPECT=1.7677778
Thu 1/1 0:02:45.747 [MiniUIClientReceiver] Got UI size update to 1024x576
Thu 1/1 0:02:46.779 [MiniUIClientReceiver] Server got notification to update the output modes...
Thu 1/1 0:02:46.831 [FinalRender-0023a50000e0] MiniClient GFX_HDMI_MODE=HDMI
Thu 1/1 0:02:46.836 [FinalRender-0023a50000e0] MiniClient GFX_SUPPORTED_RESOLUTIONS_DIGITAL=720x480i@59.94|standard=HDMI_480i59;720x480p@59.94|standard=HDMI_480p59;720x480p@60|standard=HDMI_480p60;1280x720p@59.94|standard=HDMI_720p59;1280x720p@60|standard=HDMI_720p60;1920x1080i@60|standard=HDMI_1080i60;1920x1080i@59.94|standard=HDMI_1080i59;1920x1080p@59.94|standard=HDMI_1080p59;1920x1080p@60|standard=HDMI_1080p60;
Thu 1/1 0:02:46.837 [FinalRender-0023a50000e0] MiniClient sending GFX_ASPECT=1.7677778
Thu 1/1 0:02:46.841 [MiniUIClientReceiver] Got UI size update to 1024x576
Thu 1/1 0:02:51.217 [EventRouter-0023a50000e0] processOptionsMenu optionsMenu=default:null|OptionsMenu:FileBrowser File Options
Thu 1/1 0:02:52.336 [Fork-null] processOptionsMenu optionsMenu=default:null|OptionsMenu:Preparing for video file playback notice
Thu 1/1 0:02:52.413 [ReProcessHook] VF.submitJob(VFJob[CloseMF r=0.0 t=0 file=null ifn=null])
Thu 1/1 0:02:52.416 [VideoFrame-0023a50000e0] VF processing job VFJob[CloseMF r=0.0 t=0 file=null ifn=null] nPlayin=false
Thu 1/1 0:02:52.424 [VideoFrame-0023a50000e0] VF processing on UI Thread VFJob[CloseMF r=0.0 t=0 file=null ifn=null]
Thu 1/1 0:02:52.428 [ReProcessHook] Seeker.finishWatch(UIManager:localhost@@0023a50000e0--7566cc7a)
Thu 1/1 0:02:52.436 [VideoFrame-0023a50000e0] VF processing job null nPlayin=false
Thu 1/1 0:02:52.437 [VideoFrame-0023a50000e0] VF thread is now waiting for 0:00:00.000
Thu 1/1 0:02:52.500 [AsyncWatch] Using internal format detector first...
Thu 1/1 0:02:52.653 [AsyncWatch] File Format Parsed-2a /tmp/external/ST932032 5AS (298 GB)/Video/Nikita-RoughTrade-3893920-0.ts=MPEG2-TS 0:59:46 2884 kbps [#0 Video[H.264 59.94006 fps 1280x720 16:9 progressive]#1 Audio[AC3 48000 Hz 6 channels 384 kbps MAIN idx=1 id=1100]]
Thu 1/1 0:02:52.693 [AsyncWatch] File Format Parsed-2b /tmp/external/ST932032 5AS (298 GB)/Video/Nikita-RoughTrade-3893920-0.ts=MPEG2-TS 0:59:46 2884 kbps [#0 Video[H.264 59.94006 fps 1280x720 16:9 progressive]#1 Audio[AC3 48000 Hz 6 channels 384 kbps MAIN idx=1 id=1100]]
Thu 1/1 0:02:52.711 [AsyncWatch] VideoFrame.watch(A[54,53,"Nikita-RoughTrade-3893920-0",0@1001.04:00,59])
Thu 1/1 0:02:52.713 [AsyncWatch] watchThisFile=MediaFile[id=0 A[54,53,"Nikita-RoughTrade-3893920-0",0@1001.04:00,59] mask=V host=HD300 encodedBy= format=MPEG2-TS 0:59:46 2884 kbps [#0 Video[H.264 59.94006 fps 1280x720 16:9 progressive]#1 Audio[AC3 48000 Hz 6 channels 384 kbps MAIN idx=1 id=1100]] /tmp/external/ST932032 5AS (298 GB)/Video/Nikita-RoughTrade-3893920-0.ts, Seg0[Fri 10/1 4:00:14.251-Fri 10/1 5:00:01.000]]
Thu 1/1 0:02:52.722 [AsyncWatch] Watch airing is a file & over, do that instead dvd=false
Thu 1/1 0:02:52.727 [AsyncWatch] Seeker.finishWatch(UIManager:localhost@@0023a50000e0--7566cc7a)
Thu 1/1 0:02:52.734 [AsyncWatch] VF.submitJob(VFJob[WatchMF r=0.0 t=0 file=MediaFile[id=0 A[54,53,"Nikita-RoughTrade-3893920-0",0@1001.04:00,59] mask=V host=HD300 encodedBy= format=MPEG2-TS 0:59:46 2884 kbps [#0 Video[H.264 59.94006 fps 1280x720 16:9 progressive]#1 Audio[AC3 48000 Hz 6 channels 384 kbps MAIN idx=1 id=1100]] /tmp/external/ST932032 5AS (298 GB)/Video/Nikita-RoughTrade-3893920-0.ts, Seg0[Fri 10/1 4:00:14.251-Fri 10/1 5:00:01.000]] ifn=null])
Thu 1/1 0:02:52.743 [VideoFrame-0023a50000e0] VF processing job VFJob[WatchMF r=0.0 t=0 file=MediaFile[id=0 A[54,53,"Nikita-RoughTrade-3893920-0",0@1001.04:00,59] mask=V host=HD300 encodedBy= format=MPEG2-TS 0:59:46 2884 kbps [#0 Video[H.264 59.94006 fps 1280x720 16:9 progressive]#1 Audio[AC3 48000 Hz 6 channels 384 kbps MAIN idx=1 id=1100]] /tmp/external/ST932032 5AS (298 GB)/Video/Nikita-RoughTrade-3893920-0.ts, Seg0[Fri 10/1 4:00:14.251-Fri 10/1 5:00:01.000]] ifn=null] nPlayin=false
Thu 1/1 0:02:52.748 [AsyncWatch] setUI(sage.at@fbeeda15[MediaPlayer OSD]) histIdx=3 uiHistory=[sage.at@d5c4433b[Main Menu], sage.at@f8990016[Home], sage.at@4fe688a1[Browser - Files], sage.at@fbeeda15[MediaPlayer OSD]]
Thu 1/1 0:02:52.777 [VideoFrame-0023a50000e0] VF processing on UI Thread VFJob[WatchMF r=0.0 t=0 file=MediaFile[id=0 A[54,53,"Nikita-RoughTrade-3893920-0",0@1001.04:00,59] mask=V host=HD300 encodedBy= format=MPEG2-TS 0:59:46 2884 kbps [#0 Video[H.264 59.94006 fps 1280x720 16:9 progressive]#1 Audio[AC3 48000 Hz 6 channels 384 kbps MAIN idx=1 id=1100]] /tmp/external/ST932032 5AS (298 GB)/Video/Nikita-RoughTrade-3893920-0.ts, Seg0[Fri 10/1 4:00:14.251-Fri 10/1 5:00:01.000]] ifn=null]
Thu 1/1 0:02:52.803 [VideoFrame-0023a50000e0] VideoFrame creating new media player for file:MediaFile[id=0 A[54,53,"Nikita-RoughTrade-3893920-0",0@1001.04:00,59] mask=V host=HD300 encodedBy= format=MPEG2-TS 0:59:46 2884 kbps [#0 Video[H.264 59.94006 fps 1280x720 16:9 progressive]#1 Audio[AC3 48000 Hz 6 channels 384 kbps MAIN idx=1 id=1100]] /tmp/external/ST932032 5AS (298 GB)/Video/Nikita-RoughTrade-3893920-0.ts, Seg0[Fri 10/1 4:00:14.251-Fri 10/1 5:00:01.000]]
Thu 1/1 0:02:52.808 [ReProcessHook]  ***** Checking to see if video has been completely watched.
Thu 1/1 0:02:52.810 [VideoFrame-0023a50000e0] VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[id=0 A[54,53,"Nikita-RoughTrade-3893920-0",0@1001.04:00,59] mask=V host=HD300 encodedBy= format=MPEG2-TS 0:59:46 2884 kbps [#0 Video[H.264 59.94006 fps 1280x720 16:9 progressive]#1 Audio[AC3 48000 Hz 6 channels 384 kbps MAIN idx=1 id=1100]] /tmp/external/ST932032 5AS (298 GB)/Video/Nikita-RoughTrade-3893920-0.ts, Seg0[Fri 10/1 4:00:14.251-Fri 10/1 5:00:01.000]] ifn=null] nPlayin=false
Thu 1/1 0:02:52.823 [VideoFrame-0023a50000e0] VF processing on UI Thread VFJob[LoadMF r=0.0 t=0 file=MediaFile[id=0 A[54,53,"Nikita-RoughTrade-3893920-0",0@1001.04:00,59] mask=V host=HD300 encodedBy= format=MPEG2-TS 0:59:46 2884 kbps [#0 Video[H.264 59.94006 fps 1280x720 16:9 progressive]#1 Audio[AC3 48000 Hz 6 channels 384 kbps MAIN idx=1 id=1100]] /tmp/external/ST932032 5AS (298 GB)/Video/Nikita-RoughTrade-3893920-0.ts, Seg0[Fri 10/1 4:00:14.251-Fri 10/1 5:00:01.000]] ifn=null]
Thu 1/1 0:02:52.831 [VideoFrame-0023a50000e0] VF file=MediaFile[id=0 A[54,53,"Nikita-RoughTrade-3893920-0",0@1001.04:00,59] mask=V host=HD300 encodedBy= format=MPEG2-TS 0:59:46 2884 kbps [#0 Video[H.264 59.94006 fps 1280x720 16:9 progressive]#1 Audio[AC3 48000 Hz 6 channels 384 kbps MAIN idx=1 id=1100]] /tmp/external/ST932032 5AS (298 GB)/Video/Nikita-RoughTrade-3893920-0.ts, Seg0[Fri 10/1 4:00:14.251-Fri 10/1 5:00:01.000]] targetTime = Fri 10/1 4:00:14.251
Thu 1/1 0:02:52.836 [VideoFrame-0023a50000e0] Using native resolution matching to switch output resolution; targetFormat=Video[H.264 59.94006 fps 1280x720 16:9 progressive] newResolution=Video[1280x720p@59.94 59.0 fps 1280x720 progressive]
Thu 1/1 0:02:52.839 [VideoFrame-0023a50000e0] Sending remote resolution change of: 1280x720p@59.94 val=1280x720p@59.94|standard=HDMI_720p59
Thu 1/1 0:02:52.849 [VideoFrame-0023a50000e0] VideoFrame.timeSelected(Fri 10/1 4:00:14.251, true) currFile=MediaFile[id=0 A[54,53,"Nikita-RoughTrade-3893920-0",0@1001.04:00,59] mask=V host=HD300 encodedBy= format=MPEG2-TS 0:59:46 2884 kbps [#0 Video[H.264 59.94006 fps 1280x720 16:9 progressive]#1 Audio[AC3 48000 Hz 6 channels 384 kbps MAIN idx=1 id=1100]] /tmp/external/ST932032 5AS (298 GB)/Video/Nikita-RoughTrade-3893920-0.ts, Seg0[Fri 10/1 4:00:14.251-Fri 10/1 5:00:01.000]]
Thu 1/1 0:02:52.850 [VideoFrame-0023a50000e0] initDriver0()
Thu 1/1 0:02:52.859 [VideoFrame-0023a50000e0] MiniPlayer established for 0023a50000e0
Thu 1/1 0:02:52.985 [VideoFrame-0023a50000e0] getEstimatedBW=0 estimatedBWBytes=0 estimatedBWTime=0
Thu 1/1 0:02:52.988 [VideoFrame-0023a50000e0] MiniPlayer got an estimate from the UI on bandwidth of 50000Kbps
Thu 1/1 0:02:52.989 [VideoFrame-0023a50000e0] MiniPlayer is using Push mode playback
Thu 1/1 0:02:52.990 [VideoFrame-0023a50000e0] MiniPlayer is using the MPEG2 pusher
Thu 1/1 0:02:52.994 [VideoFrame-0023a50000e0] Mpeg2Reader is detecting timestamp boundaries in the file...
Thu 1/1 0:02:52.997 [VideoFrame-0023a50000e0] Mpeg2Reader about to determine the firstPTS in the file...
Thu 1/1 0:02:52.999 [VideoFrame-0023a50000e0] Mpeg2Reader has found the firstPTS, now determining the duration of the file...
Thu 1/1 0:02:53.073 [VideoFrame-0023a50000e0] Opened MPEG-2 TS file: /tmp/external/ST932032 5AS (298 GB)/Video/Nikita-RoughTrade-3893920-0.ts firstPTS=34834 durationMsec=3586833
Thu 1/1 0:02:53.074 [VideoFrame-0023a50000e0] Mpeg2Reader seeking to pos=0
Thu 1/1 0:02:53.076 [VideoFrame-0023a50000e0] openURL0(push:f=MPEG2-TS;dur=3586749;br=2884000;[bf=vid;f=H.264;index=0;main=yes;tag=1011;fps=59.94006;fpsn=60000;fpsd=1001;ar=1.777778;arn=16;ard=9;w=1280;h=720;][bf=aud;f=AC3;br=384000;index=1;main=yes;tag=1100;sr=48000;ch=6;])
Thu 1/1 0:02:53.669 [FinalRender-0023a50000e0] MiniClient sending GFX_ASPECT=1.7677778
Thu 1/1 0:02:53.672 [MiniUIClientReceiver] Got UI size update to 1024x576
Thu 1/1 0:02:53.844 [VideoFrame-0023a50000e0] Setting audio stream for playback to be ID=0x1100
Thu 1/1 0:02:53.878 [VideoFrame-0023a50000e0] Sigma video dim=java.awt.Dimension[width=720,height=480]
Thu 1/1 0:02:53.878 [VideoFrame-0023a50000e0] pausePush0()
Thu 1/1 0:02:53.881 [VideoFrame-0023a50000e0] setMute0()
Thu 1/1 0:02:53.885 [VideoFrame-0023a50000e0] getDuration : 3586833
Thu 1/1 0:02:53.887 [VideoFrame-0023a50000e0] VideoFrame.timeSelected2(Fri 10/1 4:00:14.251, true) currFile=MediaFile[id=0 A[54,53,"Nikita-RoughTrade-3893920-0",0@1001.04:00,59] mask=V host=HD300 encodedBy= format=MPEG2-TS 0:59:46 2884 kbps [#0 Video[H.264 59.94006 fps 1280x720 16:9 progressive]#1 Audio[AC3 48000 Hz 6 channels 384 kbps MAIN idx=1 id=1100]] /tmp/external/ST932032 5AS (298 GB)/Video/Nikita-RoughTrade-3893920-0.ts, Seg0[Fri 10/1 4:00:14.251-Fri 10/1 5:00:01.000]] realDur=3586833
Thu 1/1 0:02:53.888 [VideoFrame-0023a50000e0] seeking numpushbuffers=0 seekTime=0
Thu 1/1 0:02:53.888 [VideoFrame-0023a50000e0] Mpeg2Reader seeking to pos=0
Thu 1/1 0:02:53.889 [VideoFrame-0023a50000e0] flushPush0()
Thu 1/1 0:02:53.949 [VideoFrame-0023a50000e0] Creating new push thread
Thu 1/1 0:02:53.961 [Pusher] Pusher thread is starting
Thu 1/1 0:02:53.962 [Pusher] Miniplayer pusher using buffer size of 131072
Thu 1/1 0:02:53.972 [Pusher] Adjusting peek buffer size to: 131072
Thu 1/1 0:02:53.998 [VideoFrame-0023a50000e0] VF processing job null nPlayin=true
Thu 1/1 0:02:53.999 [ReProcessHook]  ***** Checking to see if video has been completely watched.
Thu 1/1 0:02:54.000 [ReProcessHook]  ***** This is not a TV show; checking for imported video statusstatus.
Thu 1/1 0:02:54.001 [ReProcessHook]  ***** This is an imported video; checking watched status.
Thu 1/1 0:02:54.002 [VideoFrame-0023a50000e0] isRec=false rd=3586833 base=0 eos=false
Thu 1/1 0:02:54.010 [VideoFrame-0023a50000e0] VF thread is now waiting for 0:59:46.583
Thu 1/1 0:02:54.011 [Pusher] BW=26214400 numPushes=1 numWaits=1
Thu 1/1 0:02:54.099 [Pusher] playPush0()
Thu 1/1 0:02:54.207 [ActiveRender-0023a50000e0] VideoFrame got registration of a subtitle UI component: sage.bf[loc=java.awt.Point[x=145,y=414] size=java.awt.Dimension[width=678,height=109]]
Thu 1/1 0:02:54.218 [ActiveRender-0023a50000e0] VideoFrame got registration of a cc UI component: sage.bf[loc=java.awt.Point[x=96,y=54] size=java.awt.Dimension[width=775,height=436]]
Thu 1/1 0:02:54.665 [MiniUIClientReceiver] Server got notification to update the output modes...
Thu 1/1 0:02:54.680 [Pusher] BW=74898285 numPushes=53 numWaits=3
Thu 1/1 0:02:54.738 [FinalRender-0023a50000e0] MiniClient GFX_HDMI_MODE=HDMI
Thu 1/1 0:02:54.745 [FinalRender-0023a50000e0] MiniClient GFX_SUPPORTED_RESOLUTIONS_DIGITAL=720x480i@59.94|standard=HDMI_480i59;720x480p@59.94|standard=HDMI_480p59;720x480p@60|standard=HDMI_480p60;1280x720p@59.94|standard=HDMI_720p59;1280x720p@60|standard=HDMI_720p60;1920x1080i@60|standard=HDMI_1080i60;1920x1080i@59.94|standard=HDMI_1080i59;1920x1080p@59.94|standard=HDMI_1080p59;1920x1080p@60|standard=HDMI_1080p60;
Thu 1/1 0:02:54.747 [FinalRender-0023a50000e0] MiniClient sending GFX_ASPECT=1.7677778
Thu 1/1 0:02:54.759 [MiniUIClientReceiver] Got UI size update to 1024x576
Thu 1/1 0:02:55.970 [Pusher] BW=52847393 numPushes=128 numWaits=28
Thu 1/1 0:03:02.352 [Pusher] BW=18880870 numPushes=357 numWaits=207
Thu 1/1 0:03:03.500 [EventRouter-0023a50000e0] VF.submitJob(VFJob[CloseMF r=0.0 t=0 file=null ifn=null])
Thu 1/1 0:03:03.500 [VideoFrame-0023a50000e0] VF processing job VFJob[CloseMF r=0.0 t=0 file=null ifn=null] nPlayin=true
Thu 1/1 0:03:03.501 [VideoFrame-0023a50000e0] VF processing on UI Thread VFJob[CloseMF r=0.0 t=0 file=null ifn=null]
Thu 1/1 0:03:03.505 [VideoFrame-0023a50000e0] Added:Watched[id=55 Airing=A[54,53,"Nikita-RoughTrade-3893920-0",0@1001.04:00,59], WatchStart=Fri 10/1 4:00:14.251, WatchEnd=Fri 10/1 4:00:23.547, RealStart=Thu 1/1 0:02:52.835, RealEnd=Thu 1/1 0:03:03.503]
Thu 1/1 0:03:03.506 [VideoFrame-0023a50000e0] VF logFileWatch doneAir=A[54,53,"Nikita-RoughTrade-3893920-0",0@1001.04:00,59] theTime=Fri 10/1 4:00:23.547 recTime=Fri 10/1 4:00:14.251
Thu 1/1 0:03:03.509 [VideoFrame-0023a50000e0] stopPush0()
Thu 1/1 0:03:03.571 [VideoFrame-0023a50000e0] Closing down MiniPlayer
Thu 1/1 0:03:03.572 [VideoFrame-0023a50000e0] closeDriver0()
Thu 1/1 0:03:03.642 [VideoFrame-0023a50000e0] Using native resolution matching to switch output resolution back to optimal UI resolution=Video[1920x1080p@59.94 59.0 fps 1920x1080 progressive]
Thu 1/1 0:03:03.642 [VideoFrame-0023a50000e0] Sending remote resolution change of: 1920x1080p@59.94 val=1920x1080p@59.94|standard=HDMI_1080p59
Thu 1/1 0:03:03.644 [VideoFrame-0023a50000e0] cleanupLocalFile called for MediaFile[id=0 A[54,53,"Nikita-RoughTrade-3893920-0",0@1001.04:00,59] mask=V host=HD300 encodedBy= format=MPEG2-TS 0:59:46 2884 kbps [#0 Video[H.264 59.94006 fps 1280x720 16:9 progressive]#1 Audio[AC3 48000 Hz 6 channels 384 kbps MAIN idx=1 id=1100]] /tmp/external/ST932032 5AS (298 GB)/Video/Nikita-RoughTrade-3893920-0.ts, Seg0[Fri 10/1 4:00:14.251-Fri 10/1 5:00:01.000]]
Thu 1/1 0:03:03.650 [EventRouter-0023a50000e0] Seeker.finishWatch(UIManager:localhost@@0023a50000e0--7566cc7a)
Thu 1/1 0:03:03.652 [Seeker] Seeker awoken
Thu 1/1 0:03:03.652 [Seeker] MemStats: Used=20MB Total=23MB Max=167MB
Thu 1/1 0:03:03.654 [Scheduler] Scheduler awoken
Thu 1/1 0:03:03.655 [Scheduler] Scheduler.updateSchedule() called time=Thu 1/1 0:03:03.654 manual=[] schedules={} scheduleRandSize=0
Thu 1/1 0:03:03.660 [Seeker] Diskspace checking is running
Thu 1/1 0:03:03.661 [Seeker] Seeker waiting for 153722867280909 mins.
Thu 1/1 0:03:03.661 [VideoFrame-0023a50000e0] VF processing job null nPlayin=false
Thu 1/1 0:03:03.662 [MiniUIServer] MiniUI got connection from java.nio.channels.SocketChannel[connected local=/127.0.0.1:31099 remote=/127.0.0.1:38160]
Thu 1/1 0:03:03.665 [MiniUIServerConnection] MiniPlayer is adding to its map:0023a50000e0
Thu 1/1 0:03:03.665 [Seeker] Seeker awoken
Thu 1/1 0:03:03.667 [Seeker] MemStats: Used=20MB Total=23MB Max=167MB
Thu 1/1 0:03:03.671 [VideoFrame-0023a50000e0] VF thread is now waiting for 0:00:00.000
Thu 1/1 0:03:03.676 [VideoFrame-0023a50000e0] VF processing job null nPlayin=false
Thu 1/1 0:03:03.677 [VideoFrame-0023a50000e0] VF thread is now waiting for 0:00:00.000
Thu 1/1 0:03:03.677 [Seeker] Diskspace checking is running
Thu 1/1 0:03:03.678 [Seeker] Seeker waiting for 153722867280909 mins.
Thu 1/1 0:03:03.688 [EventRouter-0023a50000e0] setUI(sage.at@4fe688a1[Browser - Files]) histIdx=2 uiHistory=[sage.at@d5c4433b[Main Menu], sage.at@f8990016[Home], sage.at@4fe688a1[Browser - Files], sage.at@fbeeda15[MediaPlayer OSD]]
Thu 1/1 0:03:03.690 [EventRouter-0023a50000e0] VideoFrame got registration of a subtitle UI component: null
Thu 1/1 0:03:03.692 [EventRouter-0023a50000e0] VideoFrame got registration of a cc UI component: null
Thu 1/1 0:03:03.696 [EventRouter-0023a50000e0] START: BeforeMenuLoad hook processing for the 'Browser - Files' menu.
Thu 1/1 0:03:03.702 [EventRouter-0023a50000e0] LocalDeviceMap Keys: 1 = [[ST932032 5AS (298 GB)]], map size = 1
Thu 1/1 0:03:03.703 [EventRouter-0023a50000e0] LocalDeviceMap LocalItem 0: [ST932032 5AS (298 GB)], value: [/tmp/external/ST932032 5AS (298 GB)]
Thu 1/1 0:03:03.704 [EventRouter-0023a50000e0] LocalDeviceMap LocalItem 1: [null], value: [null]
Thu 1/1 0:03:03.710 [EventRouter-0023a50000e0] LastFolderDevicesMap = [{ST932032 5AS (298 GB)=/tmp/external/ST932032 5AS (298 GB)/Video}]
Thu 1/1 0:03:03.712 [EventRouter-0023a50000e0] Done with BeforeMenuLoad hook for the 'Browser - Files' menu. Total Time = '15'
Thu 1/1 0:03:04.471 [FinalRender-0023a50000e0] MiniClient sending GFX_ASPECT=1.7677778
Thu 1/1 0:03:04.488 [MiniUIClientReceiver] Got UI size update to 1024x576
Thu 1/1 0:03:05.276 [EventRouter-0023a50000e0] VF.submitJob(VFJob[CloseMF r=0.0 t=0 file=null ifn=null])
Thu 1/1 0:03:05.278 [VideoFrame-0023a50000e0] VF processing job VFJob[CloseMF r=0.0 t=0 file=null ifn=null] nPlayin=false
Thu 1/1 0:03:05.280 [VideoFrame-0023a50000e0] VF processing on UI Thread VFJob[CloseMF r=0.0 t=0 file=null ifn=null]
Thu 1/1 0:03:05.281 [VideoFrame-0023a50000e0] VF processing job null nPlayin=false
Thu 1/1 0:03:05.281 [VideoFrame-0023a50000e0] VF thread is now waiting for 0:00:00.000
Thu 1/1 0:03:05.282 [EventRouter-0023a50000e0] Seeker.finishWatch(UIManager:localhost@@0023a50000e0--7566cc7a)
Thu 1/1 0:03:05.489 [MiniUIClientReceiver] Server got notification to update the output modes...
Thu 1/1 0:03:05.600 [FinalRender-0023a50000e0] MiniClient GFX_HDMI_MODE=HDMI
Thu 1/1 0:03:05.604 [FinalRender-0023a50000e0] MiniClient GFX_SUPPORTED_RESOLUTIONS_DIGITAL=720x480i@59.94|standard=HDMI_480i59;720x480p@59.94|standard=HDMI_480p59;720x480p@60|standard=HDMI_480p60;1280x720p@59.94|standard=HDMI_720p59;1280x720p@60|standard=HDMI_720p60;1920x1080i@60|standard=HDMI_1080i60;1920x1080i@59.94|standard=HDMI_1080i59;1920x1080p@59.94|standard=HDMI_1080p59;1920x1080p@60|standard=HDMI_1080p60;
Thu 1/1 0:03:05.607 [FinalRender-0023a50000e0] MiniClient sending GFX_ASPECT=1.7677778
Thu 1/1 0:03:05.610 [MiniUIClientReceiver] Got UI size update to 1024x576
Thu 1/1 0:03:15.160 [EventRouter-0023a50000e0] processOptionsMenu optionsMenu=default:null|OptionsMenu:FileBrowser File Options
Thu 1/1 0:03:16.227 [Fork-null] processOptionsMenu optionsMenu=default:null|OptionsMenu:Preparing for video file playback notice
Thu 1/1 0:03:16.301 [ReProcessHook] VF.submitJob(VFJob[CloseMF r=0.0 t=0 file=null ifn=null])
Thu 1/1 0:03:16.302 [VideoFrame-0023a50000e0] VF processing job VFJob[CloseMF r=0.0 t=0 file=null ifn=null] nPlayin=false
Thu 1/1 0:03:16.303 [VideoFrame-0023a50000e0] VF processing on UI Thread VFJob[CloseMF r=0.0 t=0 file=null ifn=null]
Thu 1/1 0:03:16.303 [VideoFrame-0023a50000e0] VF processing job null nPlayin=false
Thu 1/1 0:03:16.304 [VideoFrame-0023a50000e0] VF thread is now waiting for 0:00:00.000
Thu 1/1 0:03:16.307 [ReProcessHook] Seeker.finishWatch(UIManager:localhost@@0023a50000e0--7566cc7a)
Thu 1/1 0:03:16.323 [AsyncWatch] Using internal format detector first...
Thu 1/1 0:03:16.774 [AsyncWatch] File Format Parsed-2a /tmp/external/ST932032 5AS (298 GB)/Video/A Bugs Life.M2TS=MPEG2-TS 1:34:53 6442 kbps [#0 Video[H.264 23.976025 fps 1920x1080 16:9 progressive]#1 Audio[DTS 48000 Hz 6 channels 192 kbps MAIN idx=1 id=1100 eng]]
Thu 1/1 0:03:16.807 [AsyncWatch] File Format Parsed-2b /tmp/external/ST932032 5AS (298 GB)/Video/A Bugs Life.M2TS=MPEG2-TS 1:34:53 6442 kbps [#0 Video[H.264 23.976025 fps 1920x1080 16:9 progressive]#1 Audio[DTS 48000 Hz 6 channels 192 kbps MAIN idx=1 id=1100 eng]]
Thu 1/1 0:03:16.824 [AsyncWatch] VideoFrame.watch(A[57,56,"A Bugs Life",0@0724.00:49,94])
Thu 1/1 0:03:16.826 [AsyncWatch] watchThisFile=MediaFile[id=0 A[57,56,"A Bugs Life",0@0724.00:49,94] mask=V host=HD300 encodedBy= format=MPEG2-TS 1:34:53 6442 kbps [#0 Video[H.264 23.976025 fps 1920x1080 16:9 progressive]#1 Audio[DTS 48000 Hz 6 channels 192 kbps MAIN idx=1 id=1100 eng]] /tmp/external/ST932032 5AS (298 GB)/Video/A Bugs Life.M2TS, Seg0[Sat 7/24 0:49:53.284-Sat 7/24 2:24:47.000]]
Thu 1/1 0:03:16.832 [AsyncWatch] Watch airing is a file & over, do that instead dvd=false
Thu 1/1 0:03:16.835 [AsyncWatch] Seeker.finishWatch(UIManager:localhost@@0023a50000e0--7566cc7a)
Thu 1/1 0:03:16.837 [AsyncWatch] VF.submitJob(VFJob[WatchMF r=0.0 t=0 file=MediaFile[id=0 A[57,56,"A Bugs Life",0@0724.00:49,94] mask=V host=HD300 encodedBy= format=MPEG2-TS 1:34:53 6442 kbps [#0 Video[H.264 23.976025 fps 1920x1080 16:9 progressive]#1 Audio[DTS 48000 Hz 6 channels 192 kbps MAIN idx=1 id=1100 eng]] /tmp/external/ST932032 5AS (298 GB)/Video/A Bugs Life.M2TS, Seg0[Sat 7/24 0:49:53.284-Sat 7/24 2:24:47.000]] ifn=null])
Thu 1/1 0:03:16.838 [VideoFrame-0023a50000e0] VF processing job VFJob[WatchMF r=0.0 t=0 file=MediaFile[id=0 A[57,56,"A Bugs Life",0@0724.00:49,94] mask=V host=HD300 encodedBy= format=MPEG2-TS 1:34:53 6442 kbps [#0 Video[H.264 23.976025 fps 1920x1080 16:9 progressive]#1 Audio[DTS 48000 Hz 6 channels 192 kbps MAIN idx=1 id=1100 eng]] /tmp/external/ST932032 5AS (298 GB)/Video/A Bugs Life.M2TS, Seg0[Sat 7/24 0:49:53.284-Sat 7/24 2:24:47.000]] ifn=null] nPlayin=false
Thu 1/1 0:03:16.842 [VideoFrame-0023a50000e0] VF processing on UI Thread VFJob[WatchMF r=0.0 t=0 file=MediaFile[id=0 A[57,56,"A Bugs Life",0@0724.00:49,94] mask=V host=HD300 encodedBy= format=MPEG2-TS 1:34:53 6442 kbps [#0 Video[H.264 23.976025 fps 1920x1080 16:9 progressive]#1 Audio[DTS 48000 Hz 6 channels 192 kbps MAIN idx=1 id=1100 eng]] /tmp/external/ST932032 5AS (298 GB)/Video/A Bugs Life.M2TS, Seg0[Sat 7/24 0:49:53.284-Sat 7/24 2:24:47.000]] ifn=null]
Thu 1/1 0:03:16.848 [VideoFrame-0023a50000e0] VideoFrame creating new media player for file:MediaFile[id=0 A[57,56,"A Bugs Life",0@0724.00:49,94] mask=V host=HD300 encodedBy= format=MPEG2-TS 1:34:53 6442 kbps [#0 Video[H.264 23.976025 fps 1920x1080 16:9 progressive]#1 Audio[DTS 48000 Hz 6 channels 192 kbps MAIN idx=1 id=1100 eng]] /tmp/external/ST932032 5AS (298 GB)/Video/A Bugs Life.M2TS, Seg0[Sat 7/24 0:49:53.284-Sat 7/24 2:24:47.000]]
Thu 1/1 0:03:16.852 [AsyncWatch] setUI(sage.at@fbeeda15[MediaPlayer OSD]) histIdx=3 uiHistory=[sage.at@d5c4433b[Main Menu], sage.at@f8990016[Home], sage.at@4fe688a1[Browser - Files], sage.at@fbeeda15[MediaPlayer OSD]]
Thu 1/1 0:03:16.854 [ReProcessHook]  ***** Checking to see if video has been completely watched.
Thu 1/1 0:03:17.250 [VideoFrame-0023a50000e0] VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[id=0 A[57,56,"A Bugs Life",0@0724.00:49,94] mask=V host=HD300 encodedBy= format=MPEG2-TS 1:34:53 6442 kbps [#0 Video[H.264 23.976025 fps 1920x1080 16:9 progressive]#1 Audio[DTS 48000 Hz 6 channels 192 kbps MAIN idx=1 id=1100 eng]] /tmp/external/ST932032 5AS (298 GB)/Video/A Bugs Life.M2TS, Seg0[Sat 7/24 0:49:53.284-Sat 7/24 2:24:47.000]] ifn=null] nPlayin=false
Thu 1/1 0:03:17.255 [VideoFrame-0023a50000e0] VF processing on UI Thread VFJob[LoadMF r=0.0 t=0 file=MediaFile[id=0 A[57,56,"A Bugs Life",0@0724.00:49,94] mask=V host=HD300 encodedBy= format=MPEG2-TS 1:34:53 6442 kbps [#0 Video[H.264 23.976025 fps 1920x1080 16:9 progressive]#1 Audio[DTS 48000 Hz 6 channels 192 kbps MAIN idx=1 id=1100 eng]] /tmp/external/ST932032 5AS (298 GB)/Video/A Bugs Life.M2TS, Seg0[Sat 7/24 0:49:53.284-Sat 7/24 2:24:47.000]] ifn=null]
Thu 1/1 0:03:17.259 [VideoFrame-0023a50000e0] VF file=MediaFile[id=0 A[57,56,"A Bugs Life",0@0724.00:49,94] mask=V host=HD300 encodedBy= format=MPEG2-TS 1:34:53 6442 kbps [#0 Video[H.264 23.976025 fps 1920x1080 16:9 progressive]#1 Audio[DTS 48000 Hz 6 channels 192 kbps MAIN idx=1 id=1100 eng]] /tmp/external/ST932032 5AS (298 GB)/Video/A Bugs Life.M2TS, Seg0[Sat 7/24 0:49:53.284-Sat 7/24 2:24:47.000]] targetTime = Sat 7/24 0:49:53.284
Thu 1/1 0:03:17.263 [VideoFrame-0023a50000e0] Using native resolution matching to switch output resolution; targetFormat=Video[H.264 23.976025 fps 1920x1080 16:9 progressive] newResolution=Video[1920x1080p@23.976 23.0 fps 1920x1080 progressive]
Thu 1/1 0:03:17.264 [VideoFrame-0023a50000e0] Sending remote resolution change of: 1920x1080p@23.976 val=1920x1080p@23.976|standard=1080p23
Thu 1/1 0:03:17.267 [VideoFrame-0023a50000e0] VideoFrame.timeSelected(Sat 7/24 0:49:53.284, true) currFile=MediaFile[id=0 A[57,56,"A Bugs Life",0@0724.00:49,94] mask=V host=HD300 encodedBy= format=MPEG2-TS 1:34:53 6442 kbps [#0 Video[H.264 23.976025 fps 1920x1080 16:9 progressive]#1 Audio[DTS 48000 Hz 6 channels 192 kbps MAIN idx=1 id=1100 eng]] /tmp/external/ST932032 5AS (298 GB)/Video/A Bugs Life.M2TS, Seg0[Sat 7/24 0:49:53.284-Sat 7/24 2:24:47.000]]
Thu 1/1 0:03:17.269 [VideoFrame-0023a50000e0] initDriver0()
Thu 1/1 0:03:17.278 [VideoFrame-0023a50000e0] MiniPlayer established for 0023a50000e0
Thu 1/1 0:03:17.390 [VideoFrame-0023a50000e0] getEstimatedBW=0 estimatedBWBytes=0 estimatedBWTime=0
Thu 1/1 0:03:17.391 [VideoFrame-0023a50000e0] MiniPlayer got an estimate from the UI on bandwidth of 50000Kbps
Thu 1/1 0:03:17.391 [VideoFrame-0023a50000e0] MiniPlayer is using Push mode playback
Thu 1/1 0:03:17.393 [VideoFrame-0023a50000e0] MiniPlayer is using the MPEG2 pusher
Thu 1/1 0:03:17.404 [VideoFrame-0023a50000e0] Mpeg2Reader is detecting timestamp boundaries in the file...
Thu 1/1 0:03:17.407 [VideoFrame-0023a50000e0] Mpeg2Reader about to determine the firstPTS in the file...
Thu 1/1 0:03:17.410 [VideoFrame-0023a50000e0] Mpeg2Reader has found the firstPTS, now determining the duration of the file...
Thu 1/1 0:03:17.475 [VideoFrame-0023a50000e0] Opened MPEG-2 TS file: /tmp/external/ST932032 5AS (298 GB)/Video/A Bugs Life.M2TS firstPTS=54000000 durationMsec=5693674
Thu 1/1 0:03:17.476 [VideoFrame-0023a50000e0] Mpeg2Reader seeking to pos=0
Thu 1/1 0:03:17.481 [VideoFrame-0023a50000e0] openURL0(push:f=MPEG2-TS;dur=5693716;br=6442000;ps=192;[bf=vid;f=H.264;index=0;main=yes;tag=1011;fps=23.976025;fpsn=24000;fpsd=1001;ar=1.777778;arn=16;ard=9;w=1920;h=1080;][bf=aud;f=DTS;br=192000;index=1;main=yes;tag=1100;sr=48000;ch=6;lang=eng;])
Thu 1/1 0:03:17.688 [VideoFrame-0023a50000e0] Setting audio stream for playback to be ID=0x1100
Thu 1/1 0:03:17.738 [VideoFrame-0023a50000e0] Sigma video dim=java.awt.Dimension[width=720,height=480]
Thu 1/1 0:03:17.738 [VideoFrame-0023a50000e0] pausePush0()
Thu 1/1 0:03:17.740 [VideoFrame-0023a50000e0] setMute0()
Thu 1/1 0:03:17.742 [VideoFrame-0023a50000e0] getDuration : 5693674
Thu 1/1 0:03:17.746 [VideoFrame-0023a50000e0] VideoFrame.timeSelected2(Sat 7/24 0:49:53.284, true) currFile=MediaFile[id=0 A[57,56,"A Bugs Life",0@0724.00:49,94] mask=V host=HD300 encodedBy= format=MPEG2-TS 1:34:53 6442 kbps [#0 Video[H.264 23.976025 fps 1920x1080 16:9 progressive]#1 Audio[DTS 48000 Hz 6 channels 192 kbps MAIN idx=1 id=1100 eng]] /tmp/external/ST932032 5AS (298 GB)/Video/A Bugs Life.M2TS, Seg0[Sat 7/24 0:49:53.284-Sat 7/24 2:24:47.000]] realDur=5693674
Thu 1/1 0:03:17.748 [VideoFrame-0023a50000e0] seeking numpushbuffers=0 seekTime=0
Thu 1/1 0:03:17.748 [VideoFrame-0023a50000e0] Mpeg2Reader seeking to pos=0
Thu 1/1 0:03:17.749 [VideoFrame-0023a50000e0] flushPush0()
Thu 1/1 0:03:17.810 [VideoFrame-0023a50000e0] Creating new push thread
Thu 1/1 0:03:17.820 [Pusher] Pusher thread is starting
Thu 1/1 0:03:17.821 [Pusher] Miniplayer pusher using buffer size of 131072
Thu 1/1 0:03:17.831 [Pusher] Adjusting peek buffer size to: 131072
Thu 1/1 0:03:17.835 [VideoFrame-0023a50000e0] VF processing job null nPlayin=true
Thu 1/1 0:03:17.837 [ReProcessHook]  ***** Checking to see if video has been completely watched.
Thu 1/1 0:03:17.838 [ReProcessHook]  ***** This is not a TV show; checking for imported video statusstatus.
Thu 1/1 0:03:17.839 [ReProcessHook]  ***** This is an imported video; checking watched status.
Thu 1/1 0:03:17.841 [VideoFrame-0023a50000e0] isRec=false rd=5693674 base=0 eos=false
Thu 1/1 0:03:17.842 [VideoFrame-0023a50000e0] VF thread is now waiting for 1:34:53.424
Thu 1/1 0:03:17.855 [Pusher] BW=33825032 numPushes=1 numWaits=1
Thu 1/1 0:03:17.938 [ActiveRender-0023a50000e0] VideoFrame got registration of a subtitle UI component: sage.bf[loc=java.awt.Point[x=145,y=414] size=java.awt.Dimension[width=678,height=109]]
Thu 1/1 0:03:17.939 [ActiveRender-0023a50000e0] VideoFrame got registration of a cc UI component: sage.bf[loc=java.awt.Point[x=96,y=54] size=java.awt.Dimension[width=775,height=436]]
Thu 1/1 0:03:18.008 [Pusher] playPush0()
Thu 1/1 0:03:18.516 [FinalRender-0023a50000e0] MiniClient sending GFX_ASPECT=1.7677778
Thu 1/1 0:03:18.526 [MiniUIClientReceiver] Got UI size update to 1024x576
Thu 1/1 0:03:19.122 [Pusher] BW=41199827 numPushes=53 numWaits=3
Thu 1/1 0:03:19.593 [Pusher] BW=59867821 numPushes=106 numWaits=6
Thu 1/1 0:03:19.653 [MiniUIClientReceiver] Server got notification to update the output modes...
Thu 1/1 0:03:19.724 [FinalRender-0023a50000e0] MiniClient GFX_HDMI_MODE=HDMI
Thu 1/1 0:03:19.731 [FinalRender-0023a50000e0] MiniClient GFX_SUPPORTED_RESOLUTIONS_DIGITAL=720x480i@59.94|standard=HDMI_480i59;720x480p@59.94|standard=HDMI_480p59;720x480p@60|standard=HDMI_480p60;1280x720p@59.94|standard=HDMI_720p59;1280x720p@60|standard=HDMI_720p60;1920x1080i@60|standard=HDMI_1080i60;1920x1080i@59.94|standard=HDMI_1080i59;1920x1080p@59.94|standard=HDMI_1080p59;1920x1080p@60|standard=HDMI_1080p60;
Thu 1/1 0:03:19.759 [FinalRender-0023a50000e0] MiniClient sending GFX_ASPECT=1.7677778
Thu 1/1 0:03:19.767 [MiniUIClientReceiver] Got UI size update to 1024x576
Thu 1/1 0:03:20.383 [Pusher] BW=61873769 numPushes=167 numWaits=17
Thu 1/1 0:03:21.985 [Pusher] BW=50652193 numPushes=281 numWaits=81
Thu 1/1 0:03:23.693 [Pusher] BW=44844535 numPushes=389 numWaits=139
Thu 1/1 0:03:25.390 [Pusher] BW=41715751 numPushes=500 numWaits=200
Thu 1/1 0:03:27.014 [Pusher] BW=40048985 numPushes=607 numWaits=257
Thu 1/1 0:03:28.679 [Pusher] BW=30954273 numPushes=719 numWaits=319
Thu 1/1 0:03:30.368 [Pusher] BW=31013039 numPushes=826 numWaits=376
Thu 1/1 0:03:32.544 [Pusher] BW=27794660 numPushes=947 numWaits=447
Thu 1/1 0:03:34.062 [EventRouter-0023a50000e0] VF.submitJob(VFJob[CloseMF r=0.0 t=0 file=null ifn=null])
Thu 1/1 0:03:34.062 [VideoFrame-0023a50000e0] VF processing job VFJob[CloseMF r=0.0 t=0 file=null ifn=null] nPlayin=true
Thu 1/1 0:03:34.063 [VideoFrame-0023a50000e0] VF processing on UI Thread VFJob[CloseMF r=0.0 t=0 file=null ifn=null]
Thu 1/1 0:03:34.071 [VideoFrame-0023a50000e0] Added:Watched[id=58 Airing=A[57,56,"A Bugs Life",0@0724.00:49,94], WatchStart=Sat 7/24 0:49:53.284, WatchEnd=Sat 7/24 0:50:09.290, RealStart=Thu 1/1 0:03:17.262, RealEnd=Thu 1/1 0:03:34.065]
Thu 1/1 0:03:34.072 [VideoFrame-0023a50000e0] VF logFileWatch doneAir=A[57,56,"A Bugs Life",0@0724.00:49,94] theTime=Sat 7/24 0:50:09.290 recTime=Sat 7/24 0:49:53.284
Thu 1/1 0:03:34.080 [VideoFrame-0023a50000e0] stopPush0()
Thu 1/1 0:03:34.114 [VideoFrame-0023a50000e0] Closing down MiniPlayer
Thu 1/1 0:03:34.115 [VideoFrame-0023a50000e0] closeDriver0()
Thu 1/1 0:03:34.175 [VideoFrame-0023a50000e0] Using native resolution matching to switch output resolution back to optimal UI resolution=Video[1920x1080p@59.94 59.0 fps 1920x1080 progressive]
Thu 1/1 0:03:34.179 [VideoFrame-0023a50000e0] Sending remote resolution change of: 1920x1080p@59.94 val=1920x1080p@59.94|standard=HDMI_1080p59
Thu 1/1 0:03:34.184 [VideoFrame-0023a50000e0] cleanupLocalFile called for MediaFile[id=0 A[57,56,"A Bugs Life",0@0724.00:49,94] mask=V host=HD300 encodedBy= format=MPEG2-TS 1:34:53 6442 kbps [#0 Video[H.264 23.976025 fps 1920x1080 16:9 progressive]#1 Audio[DTS 48000 Hz 6 channels 192 kbps MAIN idx=1 id=1100 eng]] /tmp/external/ST932032 5AS (298 GB)/Video/A Bugs Life.M2TS, Seg0[Sat 7/24 0:49:53.284-Sat 7/24 2:24:47.000]]
Thu 1/1 0:03:34.189 [EventRouter-0023a50000e0] Seeker.finishWatch(UIManager:localhost@@0023a50000e0--7566cc7a)
Thu 1/1 0:03:34.191 [Seeker] Seeker awoken
Thu 1/1 0:03:34.191 [Seeker] MemStats: Used=20MB Total=23MB Max=167MB
Thu 1/1 0:03:34.192 [Seeker] Diskspace checking is running
Thu 1/1 0:03:34.194 [Scheduler] Scheduler awoken
Thu 1/1 0:03:34.195 [Seeker] Seeker waiting for 153722867280909 mins.
Thu 1/1 0:03:34.196 [Scheduler] Scheduler.updateSchedule() called time=Thu 1/1 0:03:34.195 manual=[] schedules={} scheduleRandSize=0
Thu 1/1 0:03:34.197 [VideoFrame-0023a50000e0] VF processing job null nPlayin=false
Thu 1/1 0:03:34.197 [VideoFrame-0023a50000e0] VF thread is now waiting for 0:00:00.000
Thu 1/1 0:03:34.202 [MiniUIServer] MiniUI got connection from java.nio.channels.SocketChannel[connected local=/127.0.0.1:31099 remote=/127.0.0.1:38165]
Thu 1/1 0:03:34.204 [Seeker] Seeker awoken
Thu 1/1 0:03:34.205 [Seeker] MemStats: Used=20MB Total=23MB Max=167MB
Thu 1/1 0:03:34.209 [VideoFrame-0023a50000e0] VF processing job null nPlayin=false
Thu 1/1 0:03:34.209 [VideoFrame-0023a50000e0] VF thread is now waiting for 0:00:00.000
Thu 1/1 0:03:34.211 [MiniUIServerConnection] MiniPlayer is adding to its map:0023a50000e0
Thu 1/1 0:03:34.213 [Seeker] Diskspace checking is running
Thu 1/1 0:03:34.213 [Seeker] Seeker waiting for 153722867280909 mins.
Thu 1/1 0:03:34.224 [EventRouter-0023a50000e0] setUI(sage.at@4fe688a1[Browser - Files]) histIdx=2 uiHistory=[sage.at@d5c4433b[Main Menu], sage.at@f8990016[Home], sage.at@4fe688a1[Browser - Files], sage.at@fbeeda15[MediaPlayer OSD]]
Thu 1/1 0:03:34.226 [EventRouter-0023a50000e0] VideoFrame got registration of a subtitle UI component: null
Thu 1/1 0:03:34.227 [EventRouter-0023a50000e0] VideoFrame got registration of a cc UI component: null
Thu 1/1 0:03:34.229 [EventRouter-0023a50000e0] START: BeforeMenuLoad hook processing for the 'Browser - Files' menu.
Thu 1/1 0:03:34.234 [EventRouter-0023a50000e0] LocalDeviceMap Keys: 1 = [[ST932032 5AS (298 GB)]], map size = 1
Thu 1/1 0:03:34.235 [EventRouter-0023a50000e0] LocalDeviceMap LocalItem 0: [ST932032 5AS (298 GB)], value: [/tmp/external/ST932032 5AS (298 GB)]
Thu 1/1 0:03:34.236 [EventRouter-0023a50000e0] LocalDeviceMap LocalItem 1: [null], value: [null]
Thu 1/1 0:03:34.242 [EventRouter-0023a50000e0] LastFolderDevicesMap = [{ST932032 5AS (298 GB)=/tmp/external/ST932032 5AS (298 GB)/Video}]
Thu 1/1 0:03:34.243 [EventRouter-0023a50000e0] Done with BeforeMenuLoad hook for the 'Browser - Files' menu. Total Time = '13'
Thu 1/1 0:03:35.049 [FinalRender-0023a50000e0] MiniClient sending GFX_ASPECT=1.7677778
Thu 1/1 0:03:35.067 [MiniUIClientReceiver] Got UI size update to 1024x576
Thu 1/1 0:03:36.085 [MiniUIClientReceiver] Server got notification to update the output modes...
Thu 1/1 0:03:36.131 [FinalRender-0023a50000e0] MiniClient GFX_HDMI_MODE=HDMI
Thu 1/1 0:03:36.135 [FinalRender-0023a50000e0] MiniClient GFX_SUPPORTED_RESOLUTIONS_DIGITAL=720x480i@59.94|standard=HDMI_480i59;720x480p@59.94|standard=HDMI_480p59;720x480p@60|standard=HDMI_480p60;1280x720p@59.94|standard=HDMI_720p59;1280x720p@60|standard=HDMI_720p60;1920x1080i@60|standard=HDMI_1080i60;1920x1080i@59.94|standard=HDMI_1080i59;1920x1080p@59.94|standard=HDMI_1080p59;1920x1080p@60|standard=HDMI_1080p60;
Thu 1/1 0:03:36.136 [FinalRender-0023a50000e0] MiniClient sending GFX_ASPECT=1.7677778
Thu 1/1 0:03:36.140 [MiniUIClientReceiver] Got UI size update to 1024x576
Thu 1/1 0:03:45.255 [EventRouter-0023a50000e0] processOptionsMenu optionsMenu=default:null|OptionsMenu:FileBrowser File Options
Thu 1/1 0:03:46.448 [Fork-null] processOptionsMenu optionsMenu=default:null|OptionsMenu:Preparing for video file playback notice
Thu 1/1 0:03:46.539 [ReProcessHook] VF.submitJob(VFJob[CloseMF r=0.0 t=0 file=null ifn=null])
Thu 1/1 0:03:46.540 [VideoFrame-0023a50000e0] VF processing job VFJob[CloseMF r=0.0 t=0 file=null ifn=null] nPlayin=false
Thu 1/1 0:03:46.541 [VideoFrame-0023a50000e0] VF processing on UI Thread VFJob[CloseMF r=0.0 t=0 file=null ifn=null]
Thu 1/1 0:03:46.542 [ReProcessHook] Seeker.finishWatch(UIManager:localhost@@0023a50000e0--7566cc7a)
Thu 1/1 0:03:46.546 [VideoFrame-0023a50000e0] VF processing job null nPlayin=false
Thu 1/1 0:03:46.547 [VideoFrame-0023a50000e0] VF thread is now waiting for 0:00:00.000
Thu 1/1 0:03:46.852 [AsyncWatch] Using internal format detector first...
Thu 1/1 0:03:47.000 [AsyncWatch] File Format Parsed-2a /tmp/external/ST932032 5AS (298 GB)/Video/Planet Earth The Complete Series - Disc 1.M2TS=MPEG2-TS 2:32:26 147461 kbps [#0 Video[VC1 40.0 fps 1920x1080 16:9 progressive]#1 Audio[AC3 48000 Hz 6 channels 448 kbps MAIN idx=1 id=1100 eng]#2 Subpic[pgssub id=1200]]
Thu 1/1 0:03:47.048 [AsyncWatch] File Format Parsed-2b /tmp/external/ST932032 5AS (298 GB)/Video/Planet Earth The Complete Series - Disc 1.M2TS=MPEG2-TS 2:32:26 147461 kbps [#0 Video[VC1 40.0 fps 1920x1080 16:9 progressive]#1 Audio[AC3 48000 Hz 6 channels 448 kbps MAIN idx=1 id=1100 eng]#2 Subpic[pgssub id=1200]]
Thu 1/1 0:03:47.072 [AsyncWatch] VideoFrame.watch(A[60,59,"Planet Earth The Complete Series - Disc 1",0@0416.18:18,152])
Thu 1/1 0:03:47.075 [AsyncWatch] watchThisFile=MediaFile[id=0 A[60,59,"Planet Earth The Complete Series - Disc 1",0@0416.18:18,152] mask=V host=HD300 encodedBy= format=MPEG2-TS 2:32:26 147461 kbps [#0 Video[VC1 40.0 fps 1920x1080 16:9 progressive]#1 Audio[AC3 48000 Hz 6 channels 448 kbps MAIN idx=1 id=1100 eng]#2 Subpic[pgssub id=1200]] /tmp/external/ST932032 5AS (298 GB)/Video/Planet Earth The Complete Series - Disc 1.M2TS, Seg0[Thu 4/16 18:18:15.175-Thu 4/16 20:50:42.000]]
Thu 1/1 0:03:47.084 [AsyncWatch] Watch airing is a file & over, do that instead dvd=false
Thu 1/1 0:03:47.089 [AsyncWatch] Seeker.finishWatch(UIManager:localhost@@0023a50000e0--7566cc7a)
Thu 1/1 0:03:47.096 [AsyncWatch] VF.submitJob(VFJob[WatchMF r=0.0 t=0 file=MediaFile[id=0 A[60,59,"Planet Earth The Complete Series - Disc 1",0@0416.18:18,152] mask=V host=HD300 encodedBy= format=MPEG2-TS 2:32:26 147461 kbps [#0 Video[VC1 40.0 fps 1920x1080 16:9 progressive]#1 Audio[AC3 48000 Hz 6 channels 448 kbps MAIN idx=1 id=1100 eng]#2 Subpic[pgssub id=1200]] /tmp/external/ST932032 5AS (298 GB)/Video/Planet Earth The Complete Series - Disc 1.M2TS, Seg0[Thu 4/16 18:18:15.175-Thu 4/16 20:50:42.000]] ifn=null])
Thu 1/1 0:03:47.099 [VideoFrame-0023a50000e0] VF processing job VFJob[WatchMF r=0.0 t=0 file=MediaFile[id=0 A[60,59,"Planet Earth The Complete Series - Disc 1",0@0416.18:18,152] mask=V host=HD300 encodedBy= format=MPEG2-TS 2:32:26 147461 kbps [#0 Video[VC1 40.0 fps 1920x1080 16:9 progressive]#1 Audio[AC3 48000 Hz 6 channels 448 kbps MAIN idx=1 id=1100 eng]#2 Subpic[pgssub id=1200]] /tmp/external/ST932032 5AS (298 GB)/Video/Planet Earth The Complete Series - Disc 1.M2TS, Seg0[Thu 4/16 18:18:15.175-Thu 4/16 20:50:42.000]] ifn=null] nPlayin=false
Thu 1/1 0:03:47.103 [VideoFrame-0023a50000e0] VF processing on UI Thread VFJob[WatchMF r=0.0 t=0 file=MediaFile[id=0 A[60,59,"Planet Earth The Complete Series - Disc 1",0@0416.18:18,152] mask=V host=HD300 encodedBy= format=MPEG2-TS 2:32:26 147461 kbps [#0 Video[VC1 40.0 fps 1920x1080 16:9 progressive]#1 Audio[AC3 48000 Hz 6 channels 448 kbps MAIN idx=1 id=1100 eng]#2 Subpic[pgssub id=1200]] /tmp/external/ST932032 5AS (298 GB)/Video/Planet Earth The Complete Series - Disc 1.M2TS, Seg0[Thu 4/16 18:18:15.175-Thu 4/16 20:50:42.000]] ifn=null]
Thu 1/1 0:03:47.111 [AsyncWatch] setUI(sage.at@fbeeda15[MediaPlayer OSD]) histIdx=3 uiHistory=[sage.at@d5c4433b[Main Menu], sage.at@f8990016[Home], sage.at@4fe688a1[Browser - Files], sage.at@fbeeda15[MediaPlayer OSD]]
Thu 1/1 0:03:47.116 [VideoFrame-0023a50000e0] VideoFrame creating new media player for file:MediaFile[id=0 A[60,59,"Planet Earth The Complete Series - Disc 1",0@0416.18:18,152] mask=V host=HD300 encodedBy= format=MPEG2-TS 2:32:26 147461 kbps [#0 Video[VC1 40.0 fps 1920x1080 16:9 progressive]#1 Audio[AC3 48000 Hz 6 channels 448 kbps MAIN idx=1 id=1100 eng]#2 Subpic[pgssub id=1200]] /tmp/external/ST932032 5AS (298 GB)/Video/Planet Earth The Complete Series - Disc 1.M2TS, Seg0[Thu 4/16 18:18:15.175-Thu 4/16 20:50:42.000]]
Thu 1/1 0:03:47.127 [ReProcessHook]  ***** Checking to see if video has been completely watched.
Thu 1/1 0:03:47.132 [VideoFrame-0023a50000e0] VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[id=0 A[60,59,"Planet Earth The Complete Series - Disc 1",0@0416.18:18,152] mask=V host=HD300 encodedBy= format=MPEG2-TS 2:32:26 147461 kbps [#0 Video[VC1 40.0 fps 1920x1080 16:9 progressive]#1 Audio[AC3 48000 Hz 6 channels 448 kbps MAIN idx=1 id=1100 eng]#2 Subpic[pgssub id=1200]] /tmp/external/ST932032 5AS (298 GB)/Video/Planet Earth The Complete Series - Disc 1.M2TS, Seg0[Thu 4/16 18:18:15.175-Thu 4/16 20:50:42.000]] ifn=null] nPlayin=false
Thu 1/1 0:03:47.144 [VideoFrame-0023a50000e0] VF processing on UI Thread VFJob[LoadMF r=0.0 t=0 file=MediaFile[id=0 A[60,59,"Planet Earth The Complete Series - Disc 1",0@0416.18:18,152] mask=V host=HD300 encodedBy= format=MPEG2-TS 2:32:26 147461 kbps [#0 Video[VC1 40.0 fps 1920x1080 16:9 progressive]#1 Audio[AC3 48000 Hz 6 channels 448 kbps MAIN idx=1 id=1100 eng]#2 Subpic[pgssub id=1200]] /tmp/external/ST932032 5AS (298 GB)/Video/Planet Earth The Complete Series - Disc 1.M2TS, Seg0[Thu 4/16 18:18:15.175-Thu 4/16 20:50:42.000]] ifn=null]
Thu 1/1 0:03:47.150 [VideoFrame-0023a50000e0] VF file=MediaFile[id=0 A[60,59,"Planet Earth The Complete Series - Disc 1",0@0416.18:18,152] mask=V host=HD300 encodedBy= format=MPEG2-TS 2:32:26 147461 kbps [#0 Video[VC1 40.0 fps 1920x1080 16:9 progressive]#1 Audio[AC3 48000 Hz 6 channels 448 kbps MAIN idx=1 id=1100 eng]#2 Subpic[pgssub id=1200]] /tmp/external/ST932032 5AS (298 GB)/Video/Planet Earth The Complete Series - Disc 1.M2TS, Seg0[Thu 4/16 18:18:15.175-Thu 4/16 20:50:42.000]] targetTime = Thu 4/16 18:18:15.175
Thu 1/1 0:03:47.153 [VideoFrame-0023a50000e0] Using native resolution matching to switch output resolution; targetFormat=Video[VC1 40.0 fps 1920x1080 16:9 progressive] newResolution=Video[1920x1080p@23.976 23.0 fps 1920x1080 progressive]
Thu 1/1 0:03:47.154 [VideoFrame-0023a50000e0] Sending remote resolution change of: 1920x1080p@23.976 val=1920x1080p@23.976|standard=1080p23
Thu 1/1 0:03:47.156 [VideoFrame-0023a50000e0] VideoFrame.timeSelected(Thu 4/16 18:18:15.175, true) currFile=MediaFile[id=0 A[60,59,"Planet Earth The Complete Series - Disc 1",0@0416.18:18,152] mask=V host=HD300 encodedBy= format=MPEG2-TS 2:32:26 147461 kbps [#0 Video[VC1 40.0 fps 1920x1080 16:9 progressive]#1 Audio[AC3 48000 Hz 6 channels 448 kbps MAIN idx=1 id=1100 eng]#2 Subpic[pgssub id=1200]] /tmp/external/ST932032 5AS (298 GB)/Video/Planet Earth The Complete Series - Disc 1.M2TS, Seg0[Thu 4/16 18:18:15.175-Thu 4/16 20:50:42.000]]
Thu 1/1 0:03:47.157 [VideoFrame-0023a50000e0] initDriver0()
Thu 1/1 0:03:47.169 [VideoFrame-0023a50000e0] MiniPlayer established for 0023a50000e0
Thu 1/1 0:03:47.275 [VideoFrame-0023a50000e0] getEstimatedBW=0 estimatedBWBytes=0 estimatedBWTime=0
Thu 1/1 0:03:47.275 [VideoFrame-0023a50000e0] MiniPlayer got an estimate from the UI on bandwidth of 50000Kbps
Thu 1/1 0:03:47.275 [VideoFrame-0023a50000e0] MiniPlayer is using Push mode playback
Thu 1/1 0:03:47.277 [VideoFrame-0023a50000e0] MiniPlayer is using the MPEG2 pusher
Thu 1/1 0:03:47.286 [VideoFrame-0023a50000e0] Mpeg2Reader is detecting timestamp boundaries in the file...
Thu 1/1 0:03:47.293 [VideoFrame-0023a50000e0] Mpeg2Reader about to determine the firstPTS in the file...
Thu 1/1 0:03:47.295 [VideoFrame-0023a50000e0] Mpeg2Reader has found the firstPTS, now determining the duration of the file...
Thu 1/1 0:03:47.358 [VideoFrame-0023a50000e0] Opened MPEG-2 TS file: /tmp/external/ST932032 5AS (298 GB)/Video/Planet Earth The Complete Series - Disc 1.M2TS firstPTS=54000000 durationMsec=9146784
Thu 1/1 0:03:47.359 [VideoFrame-0023a50000e0] Mpeg2Reader seeking to pos=0
Thu 1/1 0:03:47.361 [VideoFrame-0023a50000e0] openURL0(push:f=MPEG2-TS;dur=9146825;br=147461996;ps=192;[bf=vid;f=VC1;index=0;main=yes;tag=1011;fps=40.0;fpsn=1000;fpsd=25;ar=1.777778;arn=16;ard=9;w=1920;h=1080;][bf=aud;f=AC3;br=448000;index=1;main=yes;tag=1100;sr=48000;ch=6;lang=eng;][bf=sub;f=pgssub;index=3;tag=1200;])
Thu 1/1 0:03:47.552 [VideoFrame-0023a50000e0] Setting audio stream for playback to be ID=0x1100
Thu 1/1 0:03:47.680 [VideoFrame-0023a50000e0] Sigma video dim=java.awt.Dimension[width=720,height=480]
Thu 1/1 0:03:47.681 [VideoFrame-0023a50000e0] pausePush0()
Thu 1/1 0:03:47.684 [VideoFrame-0023a50000e0] setMute0()
Thu 1/1 0:03:47.687 [VideoFrame-0023a50000e0] getDuration : 9146784
Thu 1/1 0:03:47.689 [VideoFrame-0023a50000e0] VideoFrame.timeSelected2(Thu 4/16 18:18:15.175, true) currFile=MediaFile[id=0 A[60,59,"Planet Earth The Complete Series - Disc 1",0@0416.18:18,152] mask=V host=HD300 encodedBy= format=MPEG2-TS 2:32:26 147461 kbps [#0 Video[VC1 40.0 fps 1920x1080 16:9 progressive]#1 Audio[AC3 48000 Hz 6 channels 448 kbps MAIN idx=1 id=1100 eng]#2 Subpic[pgssub id=1200]] /tmp/external/ST932032 5AS (298 GB)/Video/Planet Earth The Complete Series - Disc 1.M2TS, Seg0[Thu 4/16 18:18:15.175-Thu 4/16 20:50:42.000]] realDur=9146784
Thu 1/1 0:03:47.690 [VideoFrame-0023a50000e0] seeking numpushbuffers=0 seekTime=0
Thu 1/1 0:03:47.691 [VideoFrame-0023a50000e0] Mpeg2Reader seeking to pos=0
Thu 1/1 0:03:47.691 [VideoFrame-0023a50000e0] flushPush0()
Thu 1/1 0:03:47.751 [VideoFrame-0023a50000e0] Creating new push thread
Thu 1/1 0:03:47.760 [Pusher] Pusher thread is starting
Thu 1/1 0:03:47.761 [Pusher] Miniplayer pusher using buffer size of 131072
Thu 1/1 0:03:47.775 [Pusher] Adjusting peek buffer size to: 131072
Thu 1/1 0:03:47.805 [VideoFrame-0023a50000e0] VF processing job null nPlayin=true
Thu 1/1 0:03:47.809 [ReProcessHook]  ***** Checking to see if video has been completely watched.
Thu 1/1 0:03:47.810 [ReProcessHook]  ***** This is not a TV show; checking for imported video statusstatus.
Thu 1/1 0:03:47.810 [ReProcessHook]  ***** This is an imported video; checking watched status.
Thu 1/1 0:03:47.811 [VideoFrame-0023a50000e0] isRec=false rd=9146784 base=0 eos=false
Thu 1/1 0:03:47.812 [VideoFrame-0023a50000e0] VF thread is now waiting for 2:32:26.534
Thu 1/1 0:03:47.827 [Pusher] BW=16644063 numPushes=1 numWaits=1
Thu 1/1 0:03:47.910 [Pusher] playPush0()
Thu 1/1 0:03:47.987 [ActiveRender-0023a50000e0] VideoFrame got registration of a subtitle UI component: sage.bf[loc=java.awt.Point[x=145,y=414] size=java.awt.Dimension[width=678,height=109]]
Thu 1/1 0:03:47.988 [ActiveRender-0023a50000e0] VideoFrame got registration of a cc UI component: sage.bf[loc=java.awt.Point[x=96,y=54] size=java.awt.Dimension[width=775,height=436]]
Thu 1/1 0:03:48.597 [FinalRender-0023a50000e0] MiniClient sending GFX_ASPECT=1.7677778
Thu 1/1 0:03:48.612 [MiniUIClientReceiver] Got UI size update to 1024x576
Thu 1/1 0:03:49.074 [Pusher] BW=40822424 numPushes=53 numWaits=3
Thu 1/1 0:03:49.495 [MiniUIClientReceiver] Server got notification to update the output modes...
Thu 1/1 0:03:49.560 [FinalRender-0023a50000e0] MiniClient GFX_HDMI_MODE=HDMI
Thu 1/1 0:03:49.566 [FinalRender-0023a50000e0] MiniClient GFX_SUPPORTED_RESOLUTIONS_DIGITAL=720x480i@59.94|standard=HDMI_480i59;720x480p@59.94|standard=HDMI_480p59;720x480p@60|standard=HDMI_480p60;1280x720p@59.94|standard=HDMI_720p59;1280x720p@60|standard=HDMI_720p60;1920x1080i@60|standard=HDMI_1080i60;1920x1080i@59.94|standard=HDMI_1080i59;1920x1080p@59.94|standard=HDMI_1080p59;1920x1080p@60|standard=HDMI_1080p60;
Thu 1/1 0:03:49.567 [FinalRender-0023a50000e0] MiniClient sending GFX_ASPECT=1.7677778
Thu 1/1 0:03:49.576 [MiniUIClientReceiver] Got UI size update to 1024x576
Thu 1/1 0:03:49.581 [Pusher] BW=58286282 numPushes=107 numWaits=7
Thu 1/1 0:03:55.219 [EventRouter-0023a50000e0] VF.submitJob(VFJob[CloseMF r=0.0 t=0 file=null ifn=null])
Thu 1/1 0:03:55.221 [VideoFrame-0023a50000e0] VF processing job VFJob[CloseMF r=0.0 t=0 file=null ifn=null] nPlayin=true
Thu 1/1 0:03:55.222 [VideoFrame-0023a50000e0] VF processing on UI Thread VFJob[CloseMF r=0.0 t=0 file=null ifn=null]
Thu 1/1 0:03:55.226 [VideoFrame-0023a50000e0] Added:Watched[id=61 Airing=A[60,59,"Planet Earth The Complete Series - Disc 1",0@0416.18:18,152], WatchStart=Thu 4/16 18:18:15.175, WatchEnd=Thu 4/16 18:18:22.371, RealStart=Thu 1/1 0:03:47.151, RealEnd=Thu 1/1 0:03:55.225]
Thu 1/1 0:03:55.227 [VideoFrame-0023a50000e0] VF logFileWatch doneAir=A[60,59,"Planet Earth The Complete Series - Disc 1",0@0416.18:18,152] theTime=Thu 4/16 18:18:22.371 recTime=Thu 4/16 18:18:15.175
Thu 1/1 0:03:55.230 [VideoFrame-0023a50000e0] stopPush0()
Thu 1/1 0:03:55.365 [VideoFrame-0023a50000e0] Closing down MiniPlayer
Thu 1/1 0:03:55.366 [VideoFrame-0023a50000e0] closeDriver0()
Thu 1/1 0:03:55.440 [VideoFrame-0023a50000e0] Using native resolution matching to switch output resolution back to optimal UI resolution=Video[1920x1080p@59.94 59.0 fps 1920x1080 progressive]
Thu 1/1 0:03:55.440 [VideoFrame-0023a50000e0] Sending remote resolution change of: 1920x1080p@59.94 val=1920x1080p@59.94|standard=HDMI_1080p59
Thu 1/1 0:03:55.445 [VideoFrame-0023a50000e0] cleanupLocalFile called for MediaFile[id=0 A[60,59,"Planet Earth The Complete Series - Disc 1",0@0416.18:18,152] mask=V host=HD300 encodedBy= format=MPEG2-TS 2:32:26 147461 kbps [#0 Video[VC1 40.0 fps 1920x1080 16:9 progressive]#1 Audio[AC3 48000 Hz 6 channels 448 kbps MAIN idx=1 id=1100 eng]#2 Subpic[pgssub id=1200]] /tmp/external/ST932032 5AS (298 GB)/Video/Planet Earth The Complete Series - Disc 1.M2TS, Seg0[Thu 4/16 18:18:15.175-Thu 4/16 20:50:42.000]]
Thu 1/1 0:03:55.450 [EventRouter-0023a50000e0] Seeker.finishWatch(UIManager:localhost@@0023a50000e0--7566cc7a)
Thu 1/1 0:03:55.452 [Seeker] Seeker awoken
Thu 1/1 0:03:55.452 [Seeker] MemStats: Used=20MB Total=27MB Max=167MB
Thu 1/1 0:03:55.453 [Seeker] Diskspace checking is running
Thu 1/1 0:03:55.455 [Scheduler] Scheduler awoken
Thu 1/1 0:03:55.456 [Seeker] Seeker waiting for 153722867280909 mins.
Thu 1/1 0:03:55.457 [Scheduler] Scheduler.updateSchedule() called time=Thu 1/1 0:03:55.456 manual=[] schedules={} scheduleRandSize=0
Thu 1/1 0:03:55.458 [VideoFrame-0023a50000e0] VF processing job null nPlayin=false
Thu 1/1 0:03:55.458 [VideoFrame-0023a50000e0] VF thread is now waiting for 0:00:00.000
Thu 1/1 0:03:55.464 [MiniUIServer] MiniUI got connection from java.nio.channels.SocketChannel[connected local=/127.0.0.1:31099 remote=/127.0.0.1:38170]
Thu 1/1 0:03:55.466 [Seeker] Seeker awoken
Thu 1/1 0:03:55.466 [Seeker] MemStats: Used=20MB Total=27MB Max=167MB
Thu 1/1 0:03:55.468 [Seeker] Diskspace checking is running
Thu 1/1 0:03:55.469 [Seeker] Seeker waiting for 153722867280909 mins.
Thu 1/1 0:03:55.470 [VideoFrame-0023a50000e0] VF processing job null nPlayin=false
Thu 1/1 0:03:55.470 [VideoFrame-0023a50000e0] VF thread is now waiting for 0:00:00.000
Thu 1/1 0:03:55.475 [MiniUIServerConnection] MiniPlayer is adding to its map:0023a50000e0
Thu 1/1 0:03:55.484 [EventRouter-0023a50000e0] setUI(sage.at@4fe688a1[Browser - Files]) histIdx=2 uiHistory=[sage.at@d5c4433b[Main Menu], sage.at@f8990016[Home], sage.at@4fe688a1[Browser - Files], sage.at@fbeeda15[MediaPlayer OSD]]
Thu 1/1 0:03:55.485 [EventRouter-0023a50000e0] VideoFrame got registration of a subtitle UI component: null
Thu 1/1 0:03:55.486 [EventRouter-0023a50000e0] VideoFrame got registration of a cc UI component: null
Thu 1/1 0:03:55.487 [EventRouter-0023a50000e0] START: BeforeMenuLoad hook processing for the 'Browser - Files' menu.
Thu 1/1 0:03:55.494 [EventRouter-0023a50000e0] LocalDeviceMap Keys: 1 = [[ST932032 5AS (298 GB)]], map size = 1
Thu 1/1 0:03:55.495 [EventRouter-0023a50000e0] LocalDeviceMap LocalItem 0: [ST932032 5AS (298 GB)], value: [/tmp/external/ST932032 5AS (298 GB)]
Thu 1/1 0:03:55.496 [EventRouter-0023a50000e0] LocalDeviceMap LocalItem 1: [null], value: [null]
Thu 1/1 0:03:55.505 [EventRouter-0023a50000e0] LastFolderDevicesMap = [{ST932032 5AS (298 GB)=/tmp/external/ST932032 5AS (298 GB)/Video}]
Thu 1/1 0:03:55.507 [EventRouter-0023a50000e0] Done with BeforeMenuLoad hook for the 'Browser - Files' menu. Total Time = '18'
Thu 1/1 0:03:56.264 [FinalRender-0023a50000e0] MiniClient sending GFX_ASPECT=1.7677778
Thu 1/1 0:03:56.283 [MiniUIClientReceiver] Got UI size update to 1024x576
Thu 1/1 0:03:56.331 [EventRouter-0023a50000e0] VF.submitJob(VFJob[CloseMF r=0.0 t=0 file=null ifn=null])
Thu 1/1 0:03:56.334 [VideoFrame-0023a50000e0] VF processing job VFJob[CloseMF r=0.0 t=0 file=null ifn=null] nPlayin=false
Thu 1/1 0:03:56.335 [VideoFrame-0023a50000e0] VF processing on UI Thread VFJob[CloseMF r=0.0 t=0 file=null ifn=null]
Thu 1/1 0:03:56.335 [VideoFrame-0023a50000e0] VF processing job null nPlayin=false
Thu 1/1 0:03:56.337 [VideoFrame-0023a50000e0] VF thread is now waiting for 0:00:00.000
Thu 1/1 0:03:56.338 [EventRouter-0023a50000e0] Seeker.finishWatch(UIManager:localhost@@0023a50000e0--7566cc7a)
Thu 1/1 0:03:57.360 [MiniUIClientReceiver] Server got notification to update the output modes...
Thu 1/1 0:03:57.398 [FinalRender-0023a50000e0] MiniClient GFX_HDMI_MODE=HDMI
Thu 1/1 0:03:57.401 [FinalRender-0023a50000e0] MiniClient GFX_SUPPORTED_RESOLUTIONS_DIGITAL=720x480i@59.94|standard=HDMI_480i59;720x480p@59.94|standard=HDMI_480p59;720x480p@60|standard=HDMI_480p60;1280x720p@59.94|standard=HDMI_720p59;1280x720p@60|standard=HDMI_720p60;1920x1080i@60|standard=HDMI_1080i60;1920x1080i@59.94|standard=HDMI_1080i59;1920x1080p@59.94|standard=HDMI_1080p59;1920x1080p@60|standard=HDMI_1080p60;
Thu 1/1 0:03:57.403 [FinalRender-0023a50000e0] MiniClient sending GFX_ASPECT=1.7677778
Thu 1/1 0:03:57.406 [MiniUIClientReceiver] Got UI size update to 1024x576
Thu 1/1 0:06:17.767 [EventRouter-0023a50000e0] LocalDeviceMap Keys: 1 = [[ST932032 5AS (298 GB)]], map size = 1
Thu 1/1 0:06:17.768 [EventRouter-0023a50000e0] LocalDeviceMap LocalItem 0: [ST932032 5AS (298 GB)], value: [/tmp/external/ST932032 5AS (298 GB)]
Thu 1/1 0:06:17.769 [EventRouter-0023a50000e0] LocalDeviceMap LocalItem 1: [null], value: [null]
Thu 1/1 0:06:17.775 [EventRouter-0023a50000e0] LastFolderDevicesMap = [{ST932032 5AS (298 GB)=/tmp/external/ST932032 5AS (298 GB)}]
Thu 1/1 0:06:18.778 [EventRouter-0023a50000e0] setUI(sage.at@f8990016[Home]) histIdx=1 uiHistory=[sage.at@d5c4433b[Main Menu], sage.at@f8990016[Home], sage.at@4fe688a1[Browser - Files], sage.at@fbeeda15[MediaPlayer OSD]]
Thu 1/1 0:06:18.781 [EventRouter-0023a50000e0] Global variables already set.
Thu 1/1 0:06:18.782 [EventRouter-0023a50000e0] Theme settings already loaded.
Thu 1/1 0:06:18.810 [Fork-null] Starting Online Video properties updater thread.
Thu 1/1 0:06:18.826 [Fork-null] Using default STV; online video path is /rw dir.
Thu 1/1 0:06:18.828 [Fork-null] FinalLinkFilePath: [/rw/OnlineVideoLinks.properties]
Thu 1/1 0:06:18.829 [Fork-null] FinalUITextFilePath: [/rw/OnlineVideoUIText.properties]
Thu 1/1 0:06:18.834 [Fork-null] Created Property set w/o defaults
Thu 1/1 0:06:18.835 [Fork-null] PropCacheFile = [/rw/OnlineVideoLinks.properties]
Thu 1/1 0:06:18.927 [Fork-null] Loaded theme properties file: /rw/OnlineVideoLinks.properties
Thu 1/1 0:06:18.929 [Fork-null] Current online video properties version=2010081801, last update version number=2010081801
Thu 1/1 0:06:18.935 [Fork-null] Ending Online Video properties updater thread. No Update needed or it isn't time to check again.
Thu 1/1 0:06:21.471 [EventRouter-0023a50000e0] setUI(sage.at@71020e9f[Setup Menu]) histIdx=2 uiHistory=[sage.at@d5c4433b[Main Menu], sage.at@f8990016[Home], sage.at@71020e9f[Setup Menu]]
Thu 1/1 0:06:21.530 [ActiveRender-0023a50000e0] Loading new font to cache font=MetaFont[Arial, size=31, style=0, height=37.0, ascent=24.0, descent=8.0, leading=5.0, class=class sage.FreetypeFont]
Thu 1/1 0:06:21.845 [FinalRender-0023a50000e0] Loading compressed image directly from: /app/sage/STVs/Cheetah/MenuBarLong.png width=618 height=47
Thu 1/1 0:06:21.863 [FinalRender-0023a50000e0] Creating new RawImage for font rendering w=128 h=128
Thu 1/1 0:06:21.890 [FinalRender-0023a50000e0] Rendered new font to raw cache index=1 font=MetaFont[Arial, size=31, style=0, height=37.0, ascent=24.0, descent=8.0, leading=5.0, class=class sage.FreetypeFont]
Thu 1/1 0:06:21.905 [FinalRender-0023a50000e0] Creating new RawImage for font rendering w=128 h=128
Thu 1/1 0:06:21.933 [FinalRender-0023a50000e0] Rendered new font to raw cache index=2 font=MetaFont[Arial, size=31, style=0, height=37.0, ascent=24.0, descent=8.0, leading=5.0, class=class sage.FreetypeFont]
Thu 1/1 0:06:21.950 [FinalRender-0023a50000e0] Creating new RawImage for font rendering w=128 h=128
Thu 1/1 0:06:21.976 [FinalRender-0023a50000e0] Rendered new font to raw cache index=0 font=MetaFont[Arial, size=31, style=0, height=37.0, ascent=24.0, descent=8.0, leading=5.0, class=class sage.FreetypeFont]
Thu 1/1 0:06:25.861 [EventRouter-0023a50000e0] Deriving FreeType font face for Arial size=19 style=0 from=/app/sage/ArialB.ttf
Thu 1/1 0:06:26.334 [EventRouter-0023a50000e0] setUI(sage.at@12873bfc[Detailed Setup]) histIdx=3 uiHistory=[sage.at@d5c4433b[Main Menu], sage.at@f8990016[Home], sage.at@71020e9f[Setup Menu], sage.at@12873bfc[Detailed Setup]]
Thu 1/1 0:06:26.335 [EventRouter-0023a50000e0] Don't save the properties file....it's not dirty...
Thu 1/1 0:06:26.438 [EventRouter-0023a50000e0] Deriving FreeType font face for Arial size=21 style=0 from=/app/sage/ArialB.ttf
Thu 1/1 0:06:26.458 [EventRouter-0023a50000e0] OldSetupArea = xGeneral, NeedLostCleanup = true
Thu 1/1 0:06:26.532 [ActiveRender-0023a50000e0] Loading new font to cache font=MetaFont[Arial, size=21, style=1, height=25.0, ascent=16.0, descent=6.0, leading=3.0, class=class sage.FreetypeFont]
Thu 1/1 0:06:26.856 [FinalRender-0023a50000e0] Loading compressed image directly from: /app/sage/STVs/Cheetah/MenuBarShort.png width=208 height=58
Thu 1/1 0:06:26.868 [FinalRender-0023a50000e0] Creating new RawImage for font rendering w=128 h=128
Thu 1/1 0:06:26.915 [FinalRender-0023a50000e0] Rendered new font to raw cache index=0 font=MetaFont[Arial, size=21, style=1, height=25.0, ascent=16.0, descent=6.0, leading=3.0, class=class sage.FreetypeFont]
Thu 1/1 0:06:26.936 [FinalRender-0023a50000e0] Creating new RawImage for font rendering w=128 h=128
Thu 1/1 0:06:26.981 [FinalRender-0023a50000e0] Rendered new font to raw cache index=1 font=MetaFont[Arial, size=21, style=1, height=25.0, ascent=16.0, descent=6.0, leading=3.0, class=class sage.FreetypeFont]
Thu 1/1 0:06:36.862 [EventRouter-0023a50000e0] OldSetupArea = xGeneral, NeedLostCleanup = true
Thu 1/1 0:06:37.625 [EventRouter-0023a50000e0] OldSetupArea = xGeneral, NeedLostCleanup = false
Thu 1/1 0:06:38.300 [EventRouter-0023a50000e0] OldSetupArea = xMultimedia, NeedLostCleanup = false
Thu 1/1 0:06:38.836 [EventRouter-0023a50000e0] OldSetupArea = xCommands, NeedLostCleanup = false
Thu 1/1 0:06:41.095 [EventRouter-0023a50000e0] processOptionsMenu optionsMenu=default:null|OptionsMenu:ChooseEnableWindowsFileSharing
Thu 1/1 0:06:41.193 [FinalRender-0023a50000e0] Loading compressed image directly from: /app/sage/STVs/Cheetah/MarkerRadioOff.png width=40 height=40
Thu 1/1 0:06:41.212 [FinalRender-0023a50000e0] Loading compressed image directly from: /app/sage/STVs/Cheetah/MarkerRadioOn.png width=40 height=40
Thu 1/1 0:06:42.717 [EventRouter-0023a50000e0] Updating SMB configuration...
Thu 1/1 0:06:42.718 [EventRouter-0023a50000e0] Setting hostname to be: HD300
Thu 1/1 0:06:42.718 [EventRouter-0023a50000e0] Executing process: [hostname, HD300]
Thu 1/1 0:06:42.755 [EventRouter-0023a50000e0] Executing process: [sh, -c, echo "127.0.0.1	localhost HD300" > /tmp/hosts]
Thu 1/1 0:06:42.803 [EventRouter-0023a50000e0] Stopping the smbd/nmbd servers
Thu 1/1 0:06:42.804 [EventRouter-0023a50000e0] Executing process: /app/sage/scripts/stopsmbd
Thu 1/1 0:06:44.901 [EventRouter-0023a50000e0] Starting the smbd/nmbd servers if they're not running...
Thu 1/1 0:06:44.902 [EventRouter-0023a50000e0] Executing process: /app/sage/scripts/startsmbd
Thu 1/1 0:06:44.982 [AWTThreadWatcher-0023a50000e0] EventThread-0023a50000e0 Hang Detected - hang time = 2001
Thu 1/1 0:06:45.460 [FinalRender-0023a50000e0] Loading RawImage of size 46x46 for images/tvicon_anim1.png
Thu 1/1 0:06:45.464 [FinalRender-0023a50000e0] Loading 46x46 image from file images/tvicon_anim1.png
Thu 1/1 0:06:45.898 [FinalRender-0023a50000e0] Loading RawImage of size 46x46 for images/tvicon_anim2.png
Thu 1/1 0:06:45.899 [FinalRender-0023a50000e0] Loading 46x46 image from file images/tvicon_anim2.png
Thu 1/1 0:06:46.111 [FinalRender-0023a50000e0] Loading RawImage of size 46x46 for images/tvicon_anim3.png
Thu 1/1 0:06:46.112 [FinalRender-0023a50000e0] Loading 46x46 image from file images/tvicon_anim3.png
Thu 1/1 0:06:46.416 [FinalRender-0023a50000e0] Loading RawImage of size 46x46 for images/tvicon_anim4.png
Thu 1/1 0:06:46.418 [FinalRender-0023a50000e0] Loading 46x46 image from file images/tvicon_anim4.png
Thu 1/1 0:06:46.525 [FinalRender-0023a50000e0] Loading RawImage of size 46x46 for images/tvicon_anim5.png
Thu 1/1 0:06:46.527 [FinalRender-0023a50000e0] Loading 46x46 image from file images/tvicon_anim5.png
Thu 1/1 0:06:46.689 [FinalRender-0023a50000e0] Loading RawImage of size 46x46 for images/tvicon_anim6.png
Thu 1/1 0:06:46.689 [FinalRender-0023a50000e0] Loading 46x46 image from file images/tvicon_anim6.png
Thu 1/1 0:06:46.785 [FinalRender-0023a50000e0] Loading RawImage of size 46x46 for images/tvicon_anim7.png
Thu 1/1 0:06:46.802 [FinalRender-0023a50000e0] Loading 46x46 image from file images/tvicon_anim7.png