2009-10-18 09:46:40.549 mythfrontend version: tags/release-0-22-rc1 [Unknown] www.mythtv.org 2009-10-18 09:46:40.550 Using runtime prefix = /usr 2009-10-18 09:46:40.550 Using configuration directory = /root/.mythtv 2009-10-18 09:46:41.138 Unable to read configuration file mysql.txt 2009-10-18 09:46:41.138 Empty LocalHostName. 2009-10-18 09:46:41.138 Using localhost value of revo 2009-10-18 09:46:41.443 Testing network connectivity to '192.168.1.21' 2009-10-18 09:46:41.583 New DB connection, total: 1 2009-10-18 09:46:41.927 Connected to database 'mythconverg' at host: 192.168.1.21 2009-10-18 09:46:41.930 Closing DB connection named 'DBManager0' 2009-10-18 09:46:41.945 DPMS is disabled. 2009-10-18 09:46:41.948 Primary screen: 0. 2009-10-18 09:46:41.950 Connected to database 'mythconverg' at host: 192.168.1.21 2009-10-18 09:46:41.960 Using screen 0, 1920x1080 at 0,0 2009-10-18 09:46:41.981 MythUI Image Cache size set to 20971520 bytes 2009-10-18 09:46:41.982 user: 0 effective user: 0 before privileged thread 2009-10-18 09:46:41.982 user: 0 effective user: 0 run_priv_thread 2009-10-18 09:46:41.998 user: 0 effective user: 0 after privileged thread 2009-10-18 09:46:41.998 Enabled verbose msgs: important general playback 2009-10-18 09:46:42.020 Starting mythlcdserver 2009-10-18 09:46:42.527 Connecting to lcd server: localhost:6545 (try 1 of 10) 2009-10-18 09:46:43.029 Connecting to lcd server: localhost:6545 (try 2 of 10) 2009-10-18 09:46:43.530 Connecting to lcd server: localhost:6545 (try 3 of 10) 2009-10-18 09:46:44.031 Connecting to lcd server: localhost:6545 (try 4 of 10) 2009-10-18 09:46:44.112 Primary screen: 0. 2009-10-18 09:46:44.113 Using screen 0, 1920x1080 at 0,0 2009-10-18 09:46:44.137 Using theme base resolution of 1280x720 2009-10-18 09:46:44.169 LIRC, Error: Failed to connect to Unix socket '/dev/lircd' eno: No such file or directory (2) 2009-10-18 09:46:44.169 JoystickMenuThread Error: Joystick disabled - Failed to read /root/.mythtv/joystickmenurc 2009-10-18 09:46:44.826 Using the OpenGL painter 2009-10-18 09:46:44.985 Loaded base theme from /usr/share/mythtv/themes/MythCenter-wide/base.xml 2009-10-18 09:46:45.401 Loaded base theme from /usr/share/mythtv/themes/default-wide/base.xml 2009-10-18 09:46:45.503 Loaded base theme from /usr/share/mythtv/themes/default/base.xml 2009-10-18 09:46:45.517 Current MythTV Schema Version (DBSchemaVer): 1244 2009-10-18 09:46:45.555 New DB connection, total: 2 2009-10-18 09:46:45.558 Connected to database 'mythconverg' at host: 192.168.1.21 2009-10-18 09:46:46.846 Desktop video mode: 1920x1080 60.0024 Hz 2009-10-18 09:46:46.875 max_width: 1920 max_height: 1080 2009-10-18 09:46:47.203 Registering Internal as a media playback plugin. 2009-10-18 09:46:47.401 MonitorRegisterExtensions(0x100, gif,jpg,png) 2009-10-18 09:46:47.610 Current MythMusic Schema Version (MusicDBSchemaVer): 1017 2009-10-18 09:46:47.686 MonitorRegisterExtensions(0x40, mp3,mp2,ogg,oga,flac,wma,wav,ac3,oma,omg,atp,ra,dts,aac,m4a,aa3,tta,mka,aiff,swa,wv) 2009-10-18 09:46:47.728 Current MythVideo Schema Version (mythvideo.DBSchemaVer): 1028 2009-10-18 09:46:47.865 Loading window theme from /usr/share/mythtv/themes/MythCenter-wide/menu-ui.xml 2009-10-18 09:46:48.220 Loading menu theme from /usr/share/mythtv/themes/defaultmenu//mainmenu.xml 2009-10-18 09:46:48.224 Found mainmenu.xml for theme 'MythCenter-wide' 2009-10-18 09:46:49.206 Using NV NPOT texture extension 2009-10-18 09:46:49.332 MythContext: Connecting to backend server: 192.168.1.21:6543 (try 1 of 1) 2009-10-18 09:46:49.334 Using protocol version 50 2009-10-18 09:46:50.095 Warning! Time difference between the master backend and this system is 40 seconds. 2009-10-18 09:46:58.737 Loading menu theme from /usr/share/mythtv/themes/defaultmenu//library.xml 2009-10-18 09:47:00.423 Loading window theme from /usr/share/mythtv/themes/MythCenter-wide/video-ui.xml 2009-10-18 09:47:00.424 Loading window theme from /usr/share/mythtv/themes/default-wide/video-ui.xml 2009-10-18 09:47:12.899 Loading window theme from /usr/share/mythtv/themes/MythCenter-wide/video-ui.xml 2009-10-18 09:47:12.899 Loading window theme from /usr/share/mythtv/themes/default-wide/video-ui.xml 2009-10-18 09:47:13.999 TV: StartTV() -- begin 2009-10-18 09:47:13.999 TV: ctor 2009-10-18 09:47:14.071 TV: DrawUnusedRects() -- begin 2009-10-18 09:47:14.071 TV: DrawUnusedRects() -- end 2009-10-18 09:47:14.073 TV: tv->Playback() -- begin 2009-10-18 09:47:14.080 TV: tv->Playback() -- end 2009-10-18 09:47:14.080 TV: StartTV -- process events begin 2009-10-18 09:47:14.177 TV: HandleStateChange(0) -- begin 2009-10-18 09:47:14.177 TV: Attempting to change from None to Watching DVD 2009-10-18 09:47:14.177 RingBuf(dvd:/mnt/video/Movies/Blades of Glory/BLADES_OF_GLORY_16X9.iso): OpenFile(dvd:/mnt/video/Movies/Blades of Glory/BLADES_OF_GLORY_16X9.iso, 12) 2009-10-18 09:47:14.178 OpenFile() trying DVD at /mnt/video/Movies/Blades of Glory/BLADES_OF_GLORY_16X9.iso libdvdnav: Using dvdnav version svnR1169 libdvdread: Using libdvdcss version 1.2.10 for DVD access libdvdnav: DVD Title: libdvdnav: DVD Serial Number: 36CE7386 libdvdnav: DVD Title (Alternative): BLADES_OF_GLORY_16X9 libdvdnav: Unable to find map file '/root/.dvdnav/.map' *** Zero check failed in dvdread/ifo_read.c:521 for vmgi_mat->zero_6 = 0x0000000d00000000000000000000000000000000000000000000000000000000 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x000100000000 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x000100000000 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x000100000000 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x000300000100 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x00ff00000000 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x000400000000 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x000600000000 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x006300002000 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x000100000000 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x000d00000300 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x00d500000200 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x009a00000200 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x00d500000200 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x00cc00000300 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x00d500000300 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x000300000300 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x000300000000 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x000200000000 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x00d50000ff00 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x000000000100 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x00ef00000000 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x006600003000 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x000000000600 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x000600007100 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x002200000000 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x006900003000 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x000000000600 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x000900007100 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x002200000000 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x00cc00003000 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x000000000600 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x001100007100 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x002200000000 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x003100003000 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x000000000600 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x000000000700 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x000a00007100 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x00ec00000000 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x000000003000 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x00c00000ff00 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x002000000000 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x000700000000 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x000100003000 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x000200000000 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x000400003000 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x000200000000 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x000700003000 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x000200000000 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x000a00003000 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x000200000000 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x000d00003000 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x000100000000 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x000f00000000 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x000100008000 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x001000000000 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x008000008000 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x001000000000 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x008000000100 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x006600000000 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x00b000000000 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x000100000000 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x000000007100 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x002000000800 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x002e00005600 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x000000000600 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x000000000100 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x000000007100 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x002000001400 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x000000007100 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x006700000000 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x00ad00008000 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x001000000000 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x008000008000 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x001000000000 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x008000008000 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x001000000000 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x008000008000 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x001000000000 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x008000008000 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x001000000000 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x005400000100 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x000c00000000 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x006700000f00 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x000000005600 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x000000000b00 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x000000007100 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x002000000c00 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x00e300005600 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x000000000600 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x000000007100 *** Zero check failed in dvdread/ifo_read.c:2057 for vts_attributes->vtstt_subp_attr[i] = 0x000600000000 libdvdnav: DVD disk reports itself with Region mask 0x00f60000. Regions: 1 4 libdvdread: Attempting to retrieve all CSS keys libdvdread: This can take a _long_ time, please be patient libdvdread: Get key for /VIDEO_TS/VIDEO_TS.VOB at 0x00000190 libdvdread: Elapsed time 0 libdvdread: Get key for /VIDEO_TS/VTS_01_1.VOB at 0x00000608 libdvdread: Elapsed time 0 libdvdread: Get key for /VIDEO_TS/VTS_02_0.VOB at 0x000006b8 libdvdread: Elapsed time 0 libdvdread: Get key for /VIDEO_TS/VTS_02_1.VOB at 0x00002211 libdvdread: Elapsed time 0 libdvdread: Get key for /VIDEO_TS/VTS_03_1.VOB at 0x0000222b libdvdread: Elapsed time 0 libdvdread: Get key for /VIDEO_TS/VTS_04_1.VOB at 0x000326ad libdvdread: Elapsed time 0 libdvdread: Get key for /VIDEO_TS/VTS_05_1.VOB at 0x00046190 libdvdread: Elapsed time 0 libdvdread: Get key for /VIDEO_TS/VTS_06_1.VOB at 0x00085d35 libdvdread: Elapsed time 0 libdvdread: Get key for /VIDEO_TS/VTS_07_0.VOB at 0x000a1bdd libdvdread: Elapsed time 0 libdvdread: Get key for /VIDEO_TS/VTS_07_1.VOB at 0x000a6480 libdvdread: Elapsed time 0 libdvdread: Get key for /VIDEO_TS/VTS_08_0.VOB at 0x000b514c libdvdread: Elapsed time 0 libdvdread: Get key for /VIDEO_TS/VTS_08_1.VOB at 0x000c55a3 libdvdread: Elapsed time 0 libdvdread: Get key for /VIDEO_TS/VTS_09_1.VOB at 0x002df6c9 libdvdread: Elapsed time 0 libdvdread: Get key for /VIDEO_TS/VTS_10_1.VOB at 0x0038c744 libdvdread: Elapsed time 0 libdvdread: Get key for /VIDEO_TS/VTS_11_1.VOB at 0x0038c797 libdvdread: Elapsed time 0 libdvdread: Get key for /VIDEO_TS/VTS_12_1.VOB at 0x0038c7ea libdvdread: Elapsed time 0 libdvdread: Get key for /VIDEO_TS/VTS_13_1.VOB at 0x0038c83d libdvdread: Elapsed time 0 libdvdread: Get key for /VIDEO_TS/VTS_14_1.VOB at 0x0038c890 libdvdread: Elapsed time 0 libdvdread: Get key for /VIDEO_TS/VTS_15_1.VOB at 0x0038c8e3 libdvdread: Elapsed time 0 libdvdread: Found 15 VTS's libdvdread: Elapsed time 0 2009-10-18 09:47:15.307 Opened DVD device at /mnt/video/Movies/Blades of Glory/BLADES_OF_GLORY_16X9.iso 2009-10-18 09:47:15.309 There are 36 titles on the disk 2009-10-18 09:47:15.309 Title 0 has 0 parts. 2009-10-18 09:47:15.309 Title 1 has 12 parts. 2009-10-18 09:47:15.309 Title 2 has 2 parts. 2009-10-18 09:47:15.309 Title 3 has 2 parts. 2009-10-18 09:47:15.309 Title 4 has 2 parts. 2009-10-18 09:47:15.309 Title 5 has 2 parts. 2009-10-18 09:47:15.309 Title 6 has 2 parts. 2009-10-18 09:47:15.309 Title 7 has 2 parts. 2009-10-18 09:47:15.310 Title 8 has 2 parts. 2009-10-18 09:47:15.310 Title 9 has 2 parts. 2009-10-18 09:47:15.310 Title 10 has 5 parts. 2009-10-18 09:47:15.310 Title 11 has 2 parts. 2009-10-18 09:47:15.310 Title 12 has 2 parts. 2009-10-18 09:47:15.310 Title 13 has 2 parts. 2009-10-18 09:47:15.310 Title 14 has 2 parts. 2009-10-18 09:47:15.310 Title 15 has 2 parts. 2009-10-18 09:47:15.310 Title 16 has 2 parts. 2009-10-18 09:47:15.310 Title 17 has 2 parts. 2009-10-18 09:47:15.310 Title 18 has 2 parts. 2009-10-18 09:47:15.310 Title 19 has 4 parts. 2009-10-18 09:47:15.310 Title 20 has 2 parts. 2009-10-18 09:47:15.311 Title 21 has 2 parts. 2009-10-18 09:47:15.311 Title 22 has 2 parts. 2009-10-18 09:47:15.311 Title 23 has 5 parts. 2009-10-18 09:47:15.311 Title 24 has 5 parts. 2009-10-18 09:47:15.311 Title 25 has 1 parts. 2009-10-18 09:47:15.311 Title 26 has 1 parts. 2009-10-18 09:47:15.311 Title 27 has 1 parts. 2009-10-18 09:47:15.311 Title 28 has 1 parts. 2009-10-18 09:47:15.311 Title 29 has 1 parts. 2009-10-18 09:47:15.311 Title 30 has 1 parts. 2009-10-18 09:47:15.311 Title 31 has 1 parts. 2009-10-18 09:47:15.311 Title 32 has 1 parts. 2009-10-18 09:47:15.311 Title 33 has 1 parts. 2009-10-18 09:47:15.312 Title 34 has 2 parts. 2009-10-18 09:47:15.312 Title 35 has 1 parts. 2009-10-18 09:47:15.314 DVD Serial Number 36CE7386 2009-10-18 09:47:15.314 RingBuf(/mnt/video/Movies/Blades of Glory/BLADES_OF_GLORY_16X9.iso): CalcReadAheadThresh(4000 KB) -> threshhold(64 KB) min read(32 KB) blk size(64 KB) 2009-10-18 09:47:15.365 TV: StartPlayer(0, Watching DVD, main) -- begin 2009-10-18 09:47:15.416 FilterManager, Error: Failed to load filter symbol: '/usr/lib/mythtv/filters/libconvert.so' /usr/lib/mythtv/filters/libconvert.so: undefined symbol: filter_table 2009-10-18 09:47:15.416 FilterManager, Warning: Failed to load filter library: /usr/lib/mythtv/filters/libconvert.so 2009-10-18 09:47:15.550 DVDNAV_HIGHLIGHT: display==1, palette==0, sx==0, sy==0, ex==0, ey==0, pts==0, buttonN==1 2009-10-18 09:47:15.551 DVDNAV_VTS_CHANGE: old_vtsN==0, new_vtsN==2, aspect: 3, perm: 2 2009-10-18 09:47:15.551 DVDNAV_CELL_CHANGE: pg_length == 1995000, pgc_length == 1995000, cell_start == 0, pg_start == 0, title == 0, part == 6 titleParts 0 2009-10-18 09:47:15.551 DVDNAV_SPU_CLUT_CHANGE happened. 2009-10-18 09:47:15.551 DVDNAV_SPU_STREAM_CHANGE: physical_wide==0, physical_letterbox==0, physical_pan_scan==0, current_track==0 2009-10-18 09:47:15.551 DVDNAV_AUDIO_STREAM_CHANGE: Current Active Stream 0 2009-10-18 09:47:15.563 DVDNAV_HOP_CHANNEL happened. 2009-10-18 09:47:15.563 DVDNAV_CELL_CHANGE: pg_length == 1995000, pgc_length == 1995000, cell_start == 0, pg_start == 0, title == 0, part == 6 titleParts 0 2009-10-18 09:47:15.563 DVDNAV_SPU_CLUT_CHANGE happened. 2009-10-18 09:47:15.563 DVDNAV_SPU_STREAM_CHANGE: physical_wide==0, physical_letterbox==0, physical_pan_scan==0, current_track==0 2009-10-18 09:47:15.564 DVDNAV_AUDIO_STREAM_CHANGE: Current Active Stream 0 2009-10-18 09:47:15.565 AFD: Stream #0, has id 0x1e0 codec id MPEG2VIDEO, type Video, bitrate 9800000 at 0xb285cd0 2009-10-18 09:47:15.569 VDP: Accepting: cmp(> 0 0) dec(vdpau) cpus(0) rend(vdpau) osd(vdpau) osdfade(enabled) deint(none,none) filt() 2009-10-18 09:47:15.588 VDP: LoadBestPreferences(2048x2048, 0) 2009-10-18 09:47:15.596 VDP: LoadBestPreferences(2048x2048, 60) 2009-10-18 09:47:15.597 VDP: LoadBestPreferences(720x480, 60) 2009-10-18 09:47:15.600 VDP: Accepting: cmp(> 0 0) dec(vdpau) cpus(0) rend(vdpau) osd(vdpau) osdfade(enabled) deint(none,none) filt() 2009-10-18 09:47:15.600 VDP: LoadBestPreferences(2048x2048, 0) 2009-10-18 09:47:15.600 VDP: LoadBestPreferences(2048x2048, 60) 2009-10-18 09:47:15.600 VDP: LoadBestPreferences(720x480, 60) 2009-10-18 09:47:15.603 VDP: Accepting: cmp(> 0 0) dec(vdpau) cpus(0) rend(vdpau) osd(vdpau) osdfade(enabled) deint(none,none) filt() 2009-10-18 09:47:15.603 VDP: LoadBestPreferences(2048x2048, 0) 2009-10-18 09:47:15.604 VDP: LoadBestPreferences(2048x2048, 60) 2009-10-18 09:47:15.604 VDP: LoadBestPreferences(720x480, 60) 2009-10-18 09:47:15.605 Using 1 CPUs for decoding 2009-10-18 09:47:15.606 AFD: InitVideoCodec() 0xb283140 id(MPEG2VIDEO) type (Video). 2009-10-18 09:47:15.606 detectInterlace(Detect Scan, Interlaced Scan, 59.9401, 480) ->Progressive Scan 2009-10-18 09:47:15.606 AFD: Using vdpau for video decoding 2009-10-18 09:47:15.606 AFD: Looking for decoder for MPEG2VIDEO 2009-10-18 09:47:15.606 AFD: Opened codec 0xb283140, id(MPEG2VIDEO) type(Video) 2009-10-18 09:47:15.606 RingBuf(/mnt/video/Movies/Blades of Glory/BLADES_OF_GLORY_16X9.iso): CalcReadAheadThresh(9800 KB) -> threshhold(64 KB) min read(32 KB) blk size(256 KB) 2009-10-18 09:47:15.606 NVP(0): Disabling Audio, params(-1,-1,-1) 2009-10-18 09:47:15.607 No codec context. Returning false 2009-10-18 09:47:15.607 Dec: Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0) 2009-10-18 09:47:15.607 DVD Frame Rate 29.97 2009-10-18 09:47:15.607 Position map filled from DB to: 659 2009-10-18 09:47:15.607 Dec: SyncPositionMap prerecorded, from DB: 1 entries 2009-10-18 09:47:15.607 Dec: SyncPositionMap, new totframes: 659, new length: 22, posMap size: 1 2009-10-18 09:47:15.607 AFD: Position map found 2009-10-18 09:47:15.607 AFD: Successfully opened decoder for file: "/mnt/video/Movies/Blades of Glory/BLADES_OF_GLORY_16X9.iso". novideo(0) 2009-10-18 09:47:15.626 VideoOutput: Allowed renderers: vdpau 2009-10-18 09:47:15.626 VideoOutput: Allowed renderers (filt: vdpau): vdpau 2009-10-18 09:47:15.629 VDP: Accepting: cmp(> 0 0) dec(vdpau) cpus(0) rend(vdpau) osd(vdpau) osdfade(enabled) deint(none,none) filt() 2009-10-18 09:47:15.629 VDP: LoadBestPreferences(2048x2048, 0) 2009-10-18 09:47:15.630 VDP: LoadBestPreferences(2048x2048, 60) 2009-10-18 09:47:15.630 VDP: LoadBestPreferences(720x480, 60) 2009-10-18 09:47:15.630 VideoOutput: Preferred renderer: vdpau 2009-10-18 09:47:15.630 VideoOutput: Trying video renderer: 'vdpau' 2009-10-18 09:47:15.645 VDP: Accepting: cmp(> 0 0) dec(vdpau) cpus(0) rend(vdpau) osd(vdpau) osdfade(enabled) deint(none,none) filt() 2009-10-18 09:47:15.645 VDP: LoadBestPreferences(2048x2048, 0) 2009-10-18 09:47:15.645 VDP: LoadBestPreferences(2048x2048, 60) 2009-10-18 09:47:15.652 VideoOutWindow::SetPIPState. pip_state: 0] 2009-10-18 09:47:15.652 Display Rect left: 0, top: 56, width: 1818, height: 908, aspect: 1.33333 2009-10-18 09:47:15.652 Video Rect left: 0, top: 0, width: 720, height: 480, aspect: 1.5 2009-10-18 09:47:15.652 VDP: LoadBestPreferences(720x480, 60) 2009-10-18 09:47:15.652 Display Rect left: 0, top: 56, width: 1818, height: 908, aspect: 1.33333 2009-10-18 09:47:15.652 Video Rect left: 0, top: 0, width: 720, height: 480, aspect: 1.5 2009-10-18 09:47:15.653 VDP: SetVideoRenderer(vdpau) 2009-10-18 09:47:15.653 VDP: SetVideoRender(vdpau) == GetVideoRenderer() 2009-10-18 09:47:15.654 VidOutVDPAU: VDPAU Colorkey: 0x20202 (depth 24) 2009-10-18 09:47:15.662 VideoOutput: Pixel dimensions: Screen 1920x1080, window 1818x1021 2009-10-18 09:47:15.662 VideoOutput: Actual display dimensions: 827x465 mm Aspect: 1.77849 2009-10-18 09:47:15.662 VideoOutput: Estimated window dimensions: 783x439 mm Aspect: 1.7836 2009-10-18 09:47:16.453 VDPAU: Version 0 2009-10-18 09:47:16.453 VDPAU: Information NVIDIA VDPAU Driver Shared Library 190.32 Wed Sep 2 03:46:28 PDT 2009 2009-10-18 09:47:16.593 Display Rect left: 142, top: 0, width: 1534, height: 1021, aspect: 1.77778 2009-10-18 09:47:16.593 Video Rect left: 0, top: 0, width: 720, height: 480, aspect: 1.5 2009-10-18 09:47:16.593 VidOutVDPAU: Created VDPAU context (GPU decode) 2009-10-18 09:47:16.619 VDPAU: Created OSD (1816x1020) 2009-10-18 09:47:16.619 Over/underscan. V: 0, H: 0 2009-10-18 09:47:16.619 Display Rect left: 142, top: 0, width: 1534, height: 1021, aspect: 1.77778 2009-10-18 09:47:16.619 Video Rect left: 0, top: 0, width: 720, height: 480, aspect: 1.5 2009-10-18 09:47:16.619 VDP: LoadBestPreferences(720x480, 59.9401) 2009-10-18 09:47:16.620 NVP(0): Forcing decode extra audio option on (Video method requires it). 2009-10-18 09:47:16.621 NVP(0): LoadFilters(''..) -> 0x0 2009-10-18 09:47:16.623 OSD Theme Dimensions W: 640 H: 480 2009-10-18 09:47:17.435 playCtx: StartDecoderThread(): took 1808 ms to start player. 2009-10-18 09:47:17.435 TV: StartPlayer(0, Watching DVD, main) -- end ok 2009-10-18 09:47:17.436 TV: Changing from None to Watching DVD 2009-10-18 09:47:17.436 NVP(0): ClearAfterSeek(1) 2009-10-18 09:47:17.436 VidOutVDPAU: ClearAfterSeek() 2009-10-18 09:47:17.436 VidOutVDPAU: DiscardFrames(0) 2009-10-18 09:47:17.438 TV: HandleStateChange(0) -- end 2009-10-18 09:47:17.437 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAA 2009-10-18 09:47:17.440 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAA -- done 2009-10-18 09:47:17.440 VidOutVDPAU: DiscardFrames() 3: AAAAAAAAAAAAAAAAA -- done() 2009-10-18 09:47:17.442 New DB connection, total: 3 2009-10-18 09:47:17.444 VDP: GetFilteredDeint() : vdpau -> 'none' 2009-10-18 09:47:17.445 DRMVideoSync: Could not open device /dev/dri/card0, No such file or directory 2009-10-18 09:47:17.445 OpenGLVideoSync() 2009-10-18 09:47:17.457 Forcing GLX version to 1.2 (orig 1.4) 2009-10-18 09:47:17.462 GLCtx: Created window and GLX context. 2009-10-18 09:47:17.462 GLCtx: GLX Version: 1.2 2009-10-18 09:47:17.462 GLCtx: Direct rendering: Yes 2009-10-18 09:47:17.467 Connected to database 'mythconverg' at host: 192.168.1.21 2009-10-18 09:47:17.474 No codec context. Returning false 2009-10-18 09:47:17.474 AFD: DVD Title Changed 2009-10-18 09:47:17.474 Dec: Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0) 2009-10-18 09:47:17.474 DVD Frame Rate 29.97 2009-10-18 09:47:17.474 Position map filled from DB to: 659 2009-10-18 09:47:17.474 Dec: SyncPositionMap prerecorded, from DB: 1 entries 2009-10-18 09:47:17.474 Dec: SyncPositionMap, new totframes: 659, new length: 22, posMap size: 1 2009-10-18 09:47:17.475 AFD: DVD Cell Changed. Update framesPlayed: 0 2009-10-18 09:47:17.475 DVDNAV_HOP_CHANNEL happened. 2009-10-18 09:47:17.475 DVDNAV_CELL_CHANGE: pg_length == 1995000, pgc_length == 1995000, cell_start == 0, pg_start == 0, title == 0, part == 6 titleParts 0 2009-10-18 09:47:17.475 DVDNAV_SPU_CLUT_CHANGE happened. 2009-10-18 09:47:17.475 DVDNAV_SPU_STREAM_CHANGE: physical_wide==0, physical_letterbox==0, physical_pan_scan==0, current_track==0 2009-10-18 09:47:17.475 DVDNAV_AUDIO_STREAM_CHANGE: Current Active Stream 0 2009-10-18 09:47:17.475 AFD: DVD Cell Changed. Update framesPlayed: 0 2009-10-18 09:47:17.482 VDP: Accepting: cmp(> 0 0) dec(vdpau) cpus(0) rend(vdpau) osd(vdpau) osdfade(enabled) deint(none,none) filt() 2009-10-18 09:47:17.483 VDP: LoadBestPreferences(2048x2048, 0) 2009-10-18 09:47:17.483 VDP: LoadBestPreferences(2048x2048, 60) 2009-10-18 09:47:17.483 VDP: LoadBestPreferences(720x480, 60) 2009-10-18 09:47:17.483 VidOutVDPAU: InputChanged(720,480,1.77778) 'MPEG2 VDPAU'->'MPEG2 VDPAU' 2009-10-18 09:47:17.503 Display Rect left: 0, top: 0, width: 1818, height: 1021, aspect: 1.77778 2009-10-18 09:47:17.504 Video Rect left: 0, top: 0, width: 720, height: 480, aspect: 1.77778 2009-10-18 09:47:17.504 Display Rect left: 0, top: 0, width: 1818, height: 1021, aspect: 1.77778 2009-10-18 09:47:17.504 Video Rect left: 0, top: 0, width: 720, height: 480, aspect: 1.77778 2009-10-18 09:47:17.504 VDP: LoadBestPreferences(720x480, 29.97) 2009-10-18 09:47:17.504 NVP(0): Forcing decode extra audio option on (Video method requires it). 2009-10-18 09:47:17.538 GLCtx: OpenGL vendor : NVIDIA Corporation 2009-10-18 09:47:17.542 GLCtx: OpenGL renderer: ION LE/PCI/SSE2 2009-10-18 09:47:17.542 GLCtx: OpenGL version : 3.2.0 NVIDIA 190.32 2009-10-18 09:47:17.542 GLCtx: Max texture size: 8192 x 8192 2009-10-18 09:47:17.542 GLCtx: Max texture units: 4 2009-10-18 09:47:17.554 Using audio as timebase 2009-10-18 09:47:17.554 Video timing method: SGI OpenGL 2009-10-18 09:47:17.554 Refresh rate: 16666, frame interval: 33366 2009-10-18 09:47:17.809 NVP(0): ClearAfterSeek(1) 2009-10-18 09:47:17.809 VidOutVDPAU: ClearAfterSeek() 2009-10-18 09:47:17.809 VidOutVDPAU: DiscardFrames(0) 2009-10-18 09:47:17.810 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAA 2009-10-18 09:47:17.810 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAA -- done 2009-10-18 09:47:17.810 VidOutVDPAU: DiscardFrames() 3: AAAAAAAAAAAAAAAAA -- done() 2009-10-18 09:47:17.810 NVP(0): LoadFilters(''..) -> 0x0 2009-10-18 09:47:17.810 detectInterlace(Detect Scan, Interlaced Scan, 29.97, 480) ->Interlaced Scan 2009-10-18 09:47:17.810 AFD: avFPS(59.9401) != seqFPS(29.97) 2009-10-18 09:47:17.815 AFD: Stream #0, has id 0x1e0 codec id MPEG2VIDEO, type Video, bitrate 9800000 at 0xb285cd0 2009-10-18 09:47:17.815 AFD: Looking for decoder for MPEG2VIDEO 2009-10-18 09:47:17.815 RingBuf(/mnt/video/Movies/Blades of Glory/BLADES_OF_GLORY_16X9.iso): CalcReadAheadThresh(9800 KB) -> threshhold(64 KB) min read(32 KB) blk size(256 KB) 2009-10-18 09:47:17.820 VDP: Accepting: cmp(> 0 0) dec(vdpau) cpus(0) rend(vdpau) osd(vdpau) osdfade(enabled) deint(none,none) filt() 2009-10-18 09:47:17.820 VDP: LoadBestPreferences(2048x2048, 0) 2009-10-18 09:47:17.820 VDP: LoadBestPreferences(2048x2048, 60) 2009-10-18 09:47:17.820 VDP: LoadBestPreferences(720x480, 60) 2009-10-18 09:47:17.820 VidOutVDPAU: InputChanged(720,480,1.33333) 'MPEG2 VDPAU'->'MPEG2 VDPAU' 2009-10-18 09:47:17.821 Display Rect left: 227, top: 0, width: 1363, height: 1021, aspect: 1.77778 2009-10-18 09:47:17.821 Video Rect left: 0, top: 0, width: 720, height: 480, aspect: 1.33333 2009-10-18 09:47:17.821 Display Rect left: 227, top: 0, width: 1363, height: 1021, aspect: 1.77778 2009-10-18 09:47:17.821 Video Rect left: 0, top: 0, width: 720, height: 480, aspect: 1.33333 2009-10-18 09:47:17.821 VDP: LoadBestPreferences(720x480, 29.97) 2009-10-18 09:47:17.821 NVP(0): Forcing decode extra audio option on (Video method requires it). 2009-10-18 09:47:18.100 NVP(0): ClearAfterSeek(1) 2009-10-18 09:47:18.101 VidOutVDPAU: ClearAfterSeek() 2009-10-18 09:47:18.101 VidOutVDPAU: DiscardFrames(0) 2009-10-18 09:47:18.101 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAA 2009-10-18 09:47:18.101 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAA -- done 2009-10-18 09:47:18.101 VidOutVDPAU: DiscardFrames() 3: AAAAAAAAAAAAAAAAA -- done() 2009-10-18 09:47:18.101 NVP(0): LoadFilters(''..) -> 0x0 2009-10-18 09:47:18.101 detectInterlace(Ignore Scan, Interlaced Scan, 29.97, 480) ->Interlaced Scan 2009-10-18 09:47:18.102 AFD: HandleGopStart: gopset not set, syncing positionMap 2009-10-18 09:47:18.102 Dec: Resyncing position map. posmapStarted = 1 livetv(0) watchingRec(0) 2009-10-18 09:47:18.127 VDPAU: Created VDPAU decoder (2 ref frames) 2009-10-18 09:47:18.127 No codec context. Returning false 2009-10-18 09:47:18.128 No codec context. Returning false 2009-10-18 09:47:18.128 No codec context. Returning false 2009-10-18 09:47:18.131 No codec context. Returning false 2009-10-18 09:47:18.134 No codec context. Returning false 2009-10-18 09:47:18.137 No codec context. Returning false 2009-10-18 09:47:18.140 No codec context. Returning false 2009-10-18 09:47:18.143 No codec context. Returning false 2009-10-18 09:47:18.147 AFD: HandleGopStart: gopset not set, syncing positionMap 2009-10-18 09:47:18.147 Dec: Resyncing position map. posmapStarted = 1 livetv(0) watchingRec(0) 2009-10-18 09:47:18.147 AFD: HandleGopStart: Initial key frame distance: 15. 2009-10-18 09:47:18.147 No codec context. Returning false 2009-10-18 09:47:18.148 AFD: HandleGopStart: Key frame distance changed from 9 to 1. 2009-10-18 09:47:18.148 No codec context. Returning false 2009-10-18 09:47:18.151 No codec context. Returning false 2009-10-18 09:47:18.155 No codec context. Returning false 2009-10-18 09:47:18.157 No codec context. Returning false 2009-10-18 09:47:18.158 AFD: HandleStreamChange(): streams_changed 0xb283a28 -- stream count 2 2009-10-18 09:47:18.158 AFD: Stream #0, has id 0x1e0 codec id MPEG2VIDEO, type Video, bitrate 9800000 at 0xb285cd0 2009-10-18 09:47:18.161 VidOutVDPAU: UpdatePauseFrame() UUUUUUUUUUuUULAAA 2009-10-18 09:47:18.162 VDP: Accepting: cmp(> 0 0) dec(vdpau) cpus(0) rend(vdpau) osd(vdpau) osdfade(enabled) deint(none,none) filt() 2009-10-18 09:47:18.162 VDP: LoadBestPreferences(2048x2048, 0) 2009-10-18 09:47:18.162 VDP: LoadBestPreferences(2048x2048, 60) 2009-10-18 09:47:18.162 VDP: LoadBestPreferences(720x480, 60) 2009-10-18 09:47:18.166 VDP: Accepting: cmp(> 0 0) dec(vdpau) cpus(0) rend(vdpau) osd(vdpau) osdfade(enabled) deint(none,none) filt() 2009-10-18 09:47:18.167 VDP: LoadBestPreferences(2048x2048, 0) 2009-10-18 09:47:18.167 VDP: LoadBestPreferences(2048x2048, 60) 2009-10-18 09:47:18.167 VDP: LoadBestPreferences(720x480, 60) 2009-10-18 09:47:18.170 NVP(0): progressive frame seen after 2 interlaced frames 2009-10-18 09:47:18.170 VDP: GetFilteredDeint() : vdpau -> 'none' 2009-10-18 09:47:18.170 Disabled deinterlacing 2009-10-18 09:47:18.172 VDP: Accepting: cmp(> 0 0) dec(vdpau) cpus(0) rend(vdpau) osd(vdpau) osdfade(enabled) deint(none,none) filt() 2009-10-18 09:47:18.172 VDP: LoadBestPreferences(2048x2048, 0) 2009-10-18 09:47:18.172 VDP: LoadBestPreferences(2048x2048, 60) 2009-10-18 09:47:18.172 VDP: LoadBestPreferences(720x480, 60) 2009-10-18 09:47:18.175 Using 1 CPUs for decoding 2009-10-18 09:47:18.175 AFD: Warning, video codec 0xb283140 id(MPEG2VIDEO) type (Video) already open. 2009-10-18 09:47:18.175 AFD: InitVideoCodec() 0xb283140 id(MPEG2VIDEO) type (Video). 2009-10-18 09:47:18.178 VDP: Accepting: cmp(> 0 0) dec(vdpau) cpus(0) rend(vdpau) osd(vdpau) osdfade(enabled) deint(none,none) filt() 2009-10-18 09:47:18.179 VDP: LoadBestPreferences(2048x2048, 0) 2009-10-18 09:47:18.179 VDP: LoadBestPreferences(2048x2048, 60) 2009-10-18 09:47:18.179 VDP: LoadBestPreferences(720x480, 60) 2009-10-18 09:47:18.179 VidOutVDPAU: InputChanged(720,480,1.77778) 'MPEG2 VDPAU'->'MPEG2 VDPAU' 2009-10-18 09:47:18.179 Display Rect left: 0, top: 0, width: 1818, height: 1021, aspect: 1.77778 2009-10-18 09:47:18.179 Video Rect left: 0, top: 0, width: 720, height: 480, aspect: 1.77778 2009-10-18 09:47:18.179 Display Rect left: 0, top: 0, width: 1818, height: 1021, aspect: 1.77778 2009-10-18 09:47:18.179 Video Rect left: 0, top: 0, width: 720, height: 480, aspect: 1.77778 2009-10-18 09:47:18.179 VDP: LoadBestPreferences(720x480, 29.97) 2009-10-18 09:47:18.179 NVP(0): Forcing decode extra audio option on (Video method requires it). 2009-10-18 09:47:18.460 NVP(0): ClearAfterSeek(1) 2009-10-18 09:47:18.461 VidOutVDPAU: ClearAfterSeek() 2009-10-18 09:47:18.461 VidOutVDPAU: DiscardFrames(0) 2009-10-18 09:47:18.539 VDPAU: Using 2 output surfaces (max 4) 2009-10-18 09:47:18.620 VideoBuffers::DiscardFrames(0): AAAAAAAAAAuAALAAA 2009-10-18 09:47:18.620 VideoBuffers::DiscardFrames(0): AAAAAAAAAAaAALAAA -- done 2009-10-18 09:47:18.620 VidOutVDPAU: DiscardFrames() 3: AAAAAAAAAAaAALAAA -- done() 2009-10-18 09:47:18.620 NVP(0): LoadFilters(''..) -> 0x0 2009-10-18 09:47:18.620 detectInterlace(Detect Scan, Progressive Scan, 29.97, 480) ->Interlaced Scan 2009-10-18 09:47:18.621 VDP: GetFilteredDeint() : vdpau -> 'none' 2009-10-18 09:47:18.621 Failed to enable deinterlacing 2009-10-18 09:47:18.621 AFD: Using vdpau for video decoding 2009-10-18 09:47:18.621 AFD: Looking for decoder for MPEG2VIDEO 2009-10-18 09:47:18.621 AFD: Stream #1, has id 0x80 codec id AC3, type Audio, bitrate 0 at 0xbeef5e0 2009-10-18 09:47:18.621 AFD: codec AC3 has 0 channels 2009-10-18 09:47:18.621 AFD: Looking for decoder for AC3 2009-10-18 09:47:18.622 AFD: Opened codec 0xc9dac50, id(AC3) type(Audio) 2009-10-18 09:47:18.622 RingBuf(/mnt/video/Movies/Blades of Glory/BLADES_OF_GLORY_16X9.iso): CalcReadAheadThresh(9800 KB) -> threshhold(64 KB) min read(32 KB) blk size(256 KB) 2009-10-18 09:47:18.627 Opening audio device '/dev/dsp'. ch 2(2) sr 48000 2009-10-18 09:47:18.627 Opening OSS audio device '/dev/dsp'. 2009-10-18 09:47:18.634 NVP(0): Enabling Audio 2009-10-18 09:47:18.653 VidOutVDPAU: UpdatePauseFrame() UUULAuAAAAAAAUUUu 2009-10-18 09:47:18.676 NVP(0): progressive frame seen after 2 interlaced frames 2009-10-18 09:47:18.676 VDP: GetFilteredDeint() : vdpau -> 'none' 2009-10-18 09:47:18.676 Disabled deinterlacing 2009-10-18 09:47:18.719 NVP(0): Video is 3.41665 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:18.753 NVP(0): Video is 5.9192 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:18.786 NVP(0): Video is 7.93098 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:18.819 NVP(0): Video is 9.43979 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:18.853 NVP(0): Video is 10.6988 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:18.886 NVP(0): Video is 11.6356 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:18.919 NVP(0): Video is 12.4655 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:18.953 NVP(0): Video is 13.0955 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:18.986 NVP(0): Video is 13.6953 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:19.019 NVP(0): Video is 14.1377 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:19.053 NVP(0): Video is 14.5893 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:19.086 NVP(0): Video is 14.9281 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:19.119 NVP(0): Video is 15.1896 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:19.153 NVP(0): Video is 15.5132 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:19.186 NVP(0): Video is 15.7483 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:19.219 NVP(0): Video is 16.0521 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:19.253 NVP(0): Video is 16.2874 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:19.286 NVP(0): Video is 16.5837 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:19.319 NVP(0): Video is 16.806 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:19.353 NVP(0): Video is 17.1001 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:19.386 NVP(0): Video is 17.3131 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:19.419 NVP(0): Video is 17.6003 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:19.453 NVP(0): Video is 17.8232 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:19.486 NVP(0): Video is 18.1177 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:19.519 NVP(0): Video is 18.3311 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:19.553 NVP(0): Video is 18.6186 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:19.586 NVP(0): Video is 18.8341 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:19.619 NVP(0): Video is 19.1232 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:19.653 NVP(0): Video is 19.34 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:19.686 NVP(0): Video is 19.6225 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:19.719 NVP(0): Video is 19.8418 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:19.753 NVP(0): Video is 20.1337 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:19.786 NVP(0): Video is 20.3451 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:19.820 NVP(0): Video is 20.6311 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:19.853 NVP(0): Video is 20.8455 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:19.886 NVP(0): Video is 21.1338 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:19.920 NVP(0): Video is 21.3499 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:19.953 NVP(0): Video is 21.6394 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:19.986 NVP(0): Video is 21.849 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:20.020 NVP(0): Video is 22.1336 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:20.053 NVP(0): Video is 22.3546 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:20.086 NVP(0): Video is 22.6477 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:20.120 NVP(0): Video is 22.86 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:20.153 NVP(0): Video is 23.1466 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:20.186 NVP(0): Video is 23.354 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:20.220 NVP(0): Video is 23.637 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:20.253 NVP(0): Video is 23.8567 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:20.286 NVP(0): Video is 24.1489 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:20.320 NVP(0): Video is 24.3605 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:20.353 NVP(0): Video is 24.6466 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:20.386 NVP(0): Video is 24.8612 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:20.420 NVP(0): Video is 25.1496 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:20.453 NVP(0): Video is 25.3658 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:20.486 NVP(0): Video is 25.6478 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:20.520 NVP(0): Video is 25.8669 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:20.553 NVP(0): Video is 26.1585 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:20.586 NVP(0): Video is 26.3697 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:20.620 NVP(0): Video is 26.6555 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:20.653 NVP(0): Video is 26.8699 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:20.686 NVP(0): Video is 27.1505 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:20.720 NVP(0): Video is 27.3685 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:20.753 NVP(0): Video is 27.6594 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:20.786 NVP(0): Video is 27.87 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:20.820 NVP(0): Video is 28.1554 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:20.853 NVP(0): Video is 28.3769 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:20.886 NVP(0): Video is 28.6704 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:20.920 NVP(0): Video is 28.8831 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:20.953 NVP(0): Video is 29.1699 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:20.986 NVP(0): Video is 29.3776 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:21.020 NVP(0): Video is 29.6607 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:21.053 NVP(0): Video is 29.8805 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:21.086 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:21.120 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:21.153 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:21.186 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:21.220 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:21.253 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:21.286 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:21.320 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:21.353 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:21.386 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:21.420 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:21.453 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:21.486 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:21.520 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:21.553 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:21.586 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 'video_output' mean = '34332.97', std. dev. = '25273.60', fps = '29.13' 2009-10-18 09:47:21.620 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:21.653 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:21.686 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:21.720 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:21.753 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:21.786 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:21.820 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:21.853 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:21.886 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:21.920 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:21.953 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:21.986 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:22.020 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:22.053 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:22.086 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:22.120 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:22.153 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:22.186 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:22.220 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:22.253 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:22.286 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:22.320 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:22.353 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:22.386 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:22.420 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:22.453 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:22.486 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:22.520 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:22.553 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:22.586 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:22.620 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:22.653 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:22.686 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:22.720 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:22.753 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:22.786 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:22.820 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:22.853 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:22.886 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:22.920 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:22.953 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:22.986 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:23.020 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:23.053 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:23.086 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:23.120 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:23.153 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:23.186 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:23.220 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:23.253 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:23.286 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:23.320 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:23.353 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:23.386 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:23.420 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:23.453 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:23.486 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:23.520 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:23.553 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:23.586 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:23.620 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:23.653 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:23.686 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:23.720 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:23.753 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:23.786 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:23.820 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:23.853 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:23.886 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:23.920 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:23.953 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:23.986 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:24.020 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:24.053 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:24.086 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:24.120 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:24.153 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:24.186 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:24.220 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:24.253 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:24.286 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:24.320 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:24.353 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:24.386 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:24.420 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:24.453 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:24.487 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:24.520 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:24.553 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:24.587 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:24.620 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:24.653 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:24.687 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:24.720 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:24.753 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:24.787 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:24.820 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:24.853 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:24.887 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:24.920 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 'video_output' mean = '33333.12', std. dev. = '20.83', fps = '30.00' 2009-10-18 09:47:24.953 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:24.987 NVP(0): Video is 30 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:25.020 NVP(0): Video is 26.452 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:25.053 NVP(0): Video is 20.5133 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:25.087 NVP(0): Video is 16.0593 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:25.120 NVP(0): Video is 12.7188 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:25.153 NVP(0): Video is 10.2135 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:25.187 NVP(0): Video is 8.33441 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:25.220 NVP(0): Video is 6.92513 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:25.253 NVP(0): Video is 5.86819 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:25.287 NVP(0): Video is 5.07547 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:25.320 NVP(0): Video is 4.48094 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:25.353 NVP(0): Video is 4.03504 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:25.387 NVP(0): Video is 3.70059 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:25.420 NVP(0): Video is 3.44977 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:25.453 NVP(0): Video is 3.26164 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:25.487 NVP(0): Video is 3.12057 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:25.520 NVP(0): Video is 3.01475 frames ahead of audio, doubling video frame interval to slow down. 2009-10-18 09:47:27.256 TV: SetActive(0,w/o OSD) 0 -> 0 -- begin 2009-10-18 09:47:27.257 TV: SetActive(0,w/o OSD) 0 -> 0 -- end 2009-10-18 09:47:27.297 TV: HandleStateChange(0) -- begin 2009-10-18 09:47:27.297 TV: Attempting to change from Watching DVD to None 2009-10-18 09:47:27.297 TV: StopStuff() for player ctx 0 -- begin 2009-10-18 09:47:27.298 TV: SetActive(0,w/o OSD) 0 -> 0 -- begin 2009-10-18 09:47:27.298 TV: SetActive(0,w/o OSD) 0 -> 0 -- end 2009-10-18 09:47:27.298 TV: StopStuff() -- get dvd player out of still frame or wait status 2009-10-18 09:47:27.298 TV: StopStuff(): stopping ring buffer 2009-10-18 09:47:27.298 TV: StopStuff(): stopping player 2009-10-18 09:47:27.298 TV: StopStuff() -- end 2009-10-18 09:47:27.298 TV: Changing from Watching DVD to None 2009-10-18 09:47:27.298 TV: HandleStateChange(0) -- end 2009-10-18 09:47:27.298 TV: StartTV -- process events end 2009-10-18 09:47:27.298 TV: StartTV -- process events 2 begin 2009-10-18 09:47:27.299 TV: StartTV -- process events 2 end 2009-10-18 09:47:27.300 TV::~TV() -- begin 2009-10-18 09:47:27.301 NVP(0): Exited decoder loop. 2009-10-18 09:47:27.303 ~OpenGLVideoSync() -- closing opengl vsync 2009-10-18 09:47:27.304 GLCtx: Deleting OpenGL Resources 2009-10-18 09:47:27.304 GLCtx: Unmapping gl window 2009-10-18 09:47:27.305 GLCtx: Destroying gl window 2009-10-18 09:47:27.305 GLCtx: Destroying glx context 2009-10-18 09:47:27.309 GLCtx: Closing display 2009-10-18 09:47:27.309 VidOutVDPAU: DiscardFrames(1) 2009-10-18 09:47:27.309 VideoBuffers::DiscardFrames(1): AUAUUUUUUUUuUUuLU 2009-10-18 09:47:27.309 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAA -- done() 2009-10-18 09:47:27.310 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAA -- done 2009-10-18 09:47:27.310 VidOutVDPAU: DiscardFrames() 3: AAAAAAAAAAAAAAAAA -- done() 2009-10-18 09:47:27.355 TV::~TV() -- lock 2009-10-18 09:47:27.788 TV::~TV() -- end 2009-10-18 09:47:27.788 TV: StartTV -- end 2009-10-18 09:47:35.700 Loading window theme from /usr/share/mythtv/themes/MythCenter-wide/video-ui.xml 2009-10-18 09:47:35.701 Loading window theme from /usr/share/mythtv/themes/default-wide/video-ui.xml 2009-10-18 09:47:38.230 TV: StartTV() -- begin 2009-10-18 09:47:38.231 TV: ctor 2009-10-18 09:47:38.271 TV: DrawUnusedRects() -- begin 2009-10-18 09:47:38.271 TV: DrawUnusedRects() -- end 2009-10-18 09:47:38.271 TV: tv->Playback() -- begin 2009-10-18 09:47:38.277 TV: tv->Playback() -- end 2009-10-18 09:47:38.277 TV: StartTV -- process events begin 2009-10-18 09:47:38.365 TV: HandleStateChange(0) -- begin 2009-10-18 09:47:38.366 TV: Attempting to change from None to Watching DVD 2009-10-18 09:47:38.366 RingBuf(dvd:/mnt/video/Movies/Bourne Movies/THE_BOURNE_IDENTITY.iso): OpenFile(dvd:/mnt/video/Movies/Bourne Movies/THE_BOURNE_IDENTITY.iso, 12) 2009-10-18 09:47:38.366 OpenFile() trying DVD at /mnt/video/Movies/Bourne Movies/THE_BOURNE_IDENTITY.iso libdvdnav: Using dvdnav version svnR1169 libdvdread: Using libdvdcss version 1.2.10 for DVD access libdvdnav: DVD Title: CDROM libdvdnav: DVD Serial Number: 473F4F9500000000 libdvdnav: DVD Title (Alternative): libdvdnav: Unable to find map file '/root/.dvdnav/CDROM.map' libdvdnav: DVD disk reports itself with Region mask 0x00fe0000. Regions: 1 libdvdread: Attempting to retrieve all CSS keys libdvdread: This can take a _long_ time, please be patient libdvdread: Get key for /VIDEO_TS/VIDEO_TS.VOB at 0x0000014d libdvdread: Elapsed time 0 libdvdread: Get key for /VIDEO_TS/VTS_01_0.VOB at 0x0000048a libdvdread: Elapsed time 0 libdvdread: Get key for /VIDEO_TS/VTS_01_1.VOB at 0x0000b4be libdvdread: Elapsed time 0 libdvdread: Get key for /VIDEO_TS/VTS_02_0.VOB at 0x002cb17c libdvdread: Elapsed time 0 libdvdread: Get key for /VIDEO_TS/VTS_02_1.VOB at 0x002cb180 libdvdread: Elapsed time 0 libdvdread: Get key for /VIDEO_TS/VTS_03_0.VOB at 0x002fb6de libdvdread: Elapsed time 0 libdvdread: Get key for /VIDEO_TS/VTS_03_1.VOB at 0x002fb6e2 libdvdread: Elapsed time 0 libdvdread: Get key for /VIDEO_TS/VTS_04_0.VOB at 0x00319372 libdvdread: Elapsed time 0 libdvdread: Get key for /VIDEO_TS/VTS_04_1.VOB at 0x00319376 libdvdread: Elapsed time 0 libdvdread: Get key for /VIDEO_TS/VTS_05_0.VOB at 0x003a7f4a libdvdread: Elapsed time 1 libdvdread: Get key for /VIDEO_TS/VTS_05_1.VOB at 0x003a7f4e libdvdread: Elapsed time 0 libdvdread: Get key for /VIDEO_TS/VTS_06_0.VOB at 0x003c282c libdvdread: Elapsed time 0 libdvdread: Get key for /VIDEO_TS/VTS_06_1.VOB at 0x003c2830 libdvdread: Elapsed time 0 libdvdread: Get key for /VIDEO_TS/VTS_07_0.VOB at 0x003c284e libdvdread: Elapsed time 0 libdvdread: Get key for /VIDEO_TS/VTS_07_1.VOB at 0x003c2a64 libdvdread: Elapsed time 0 libdvdread: Get key for /VIDEO_TS/VTS_08_0.VOB at 0x003ce0e2 libdvdread: Elapsed time 0 libdvdread: Get key for /VIDEO_TS/VTS_08_1.VOB at 0x003ce0e6 libdvdread: Elapsed time 0 libdvdread: Get key for /VIDEO_TS/VTS_09_0.VOB at 0x003cf473 libdvdread: Elapsed time 0 libdvdread: Get key for /VIDEO_TS/VTS_09_1.VOB at 0x003cf477 libdvdread: Elapsed time 0 libdvdread: Get key for /VIDEO_TS/VTS_10_0.VOB at 0x003cf6a2 libdvdread: Elapsed time 0 libdvdread: Get key for /VIDEO_TS/VTS_10_1.VOB at 0x003cf6a6 libdvdread: Elapsed time 0 libdvdread: Found 10 VTS's libdvdread: Elapsed time 1 2009-10-18 09:47:40.002 Opened DVD device at /mnt/video/Movies/Bourne Movies/THE_BOURNE_IDENTITY.iso 2009-10-18 09:47:40.002 There are 32 titles on the disk 2009-10-18 09:47:40.003 Title 0 has 0 parts. 2009-10-18 09:47:40.003 Title 1 has 21 parts. 2009-10-18 09:47:40.003 Title 2 has 21 parts. 2009-10-18 09:47:40.003 Title 3 has 2 parts. 2009-10-18 09:47:40.003 Title 4 has 2 parts. 2009-10-18 09:47:40.003 Title 5 has 2 parts. 2009-10-18 09:47:40.003 Title 6 has 2 parts. 2009-10-18 09:47:40.003 Title 7 has 4 parts. 2009-10-18 09:47:40.003 Title 8 has 2 parts. 2009-10-18 09:47:40.003 Title 9 has 2 parts. 2009-10-18 09:47:40.003 Title 10 has 2 parts. 2009-10-18 09:47:40.003 Title 11 has 2 parts. 2009-10-18 09:47:40.004 Title 12 has 5 parts. 2009-10-18 09:47:40.004 Title 13 has 2 parts. 2009-10-18 09:47:40.004 Title 14 has 2 parts. 2009-10-18 09:47:40.004 Title 15 has 2 parts. 2009-10-18 09:47:40.004 Title 16 has 2 parts. 2009-10-18 09:47:40.004 Title 17 has 2 parts. 2009-10-18 09:47:40.004 Title 18 has 2 parts. 2009-10-18 09:47:40.004 Title 19 has 2 parts. 2009-10-18 09:47:40.004 Title 20 has 2 parts. 2009-10-18 09:47:40.004 Title 21 has 2 parts. 2009-10-18 09:47:40.004 Title 22 has 2 parts. 2009-10-18 09:47:40.004 Title 23 has 1 parts. 2009-10-18 09:47:40.004 Title 24 has 1 parts. 2009-10-18 09:47:40.004 Title 25 has 1 parts. 2009-10-18 09:47:40.005 Title 26 has 2 parts. 2009-10-18 09:47:40.005 Title 27 has 2 parts. 2009-10-18 09:47:40.005 Title 28 has 2 parts. 2009-10-18 09:47:40.005 Title 29 has 2 parts. 2009-10-18 09:47:40.005 Title 30 has 2 parts. 2009-10-18 09:47:40.005 Title 31 has 2 parts. 2009-10-18 09:47:40.006 DVD Serial Number 473F4F95000000 2009-10-18 09:47:40.006 RingBuf(/mnt/video/Movies/Bourne Movies/THE_BOURNE_IDENTITY.iso): CalcReadAheadThresh(4000 KB) -> threshhold(64 KB) min read(32 KB) blk size(64 KB) 2009-10-18 09:47:40.056 TV: StartPlayer(0, Watching DVD, main) -- begin 2009-10-18 09:47:40.061 FilterManager, Error: Failed to load filter symbol: '/usr/lib/mythtv/filters/libconvert.so' /usr/lib/mythtv/filters/libconvert.so: undefined symbol: filter_table 2009-10-18 09:47:40.061 FilterManager, Warning: Failed to load filter library: /usr/lib/mythtv/filters/libconvert.so 2009-10-18 09:47:40.086 DVDNAV_HIGHLIGHT: display==1, palette==0, sx==0, sy==0, ex==0, ey==0, pts==0, buttonN==1 2009-10-18 09:47:40.087 DVDNAV_VTS_CHANGE: old_vtsN==0, new_vtsN==-1, aspect: 3, perm: 1 2009-10-18 09:47:40.087 DVDNAV_CELL_CHANGE: pg_length == 270000, pgc_length == 270000, cell_start == 0, pg_start == 0, title == 0, part == 0 titleParts 0 2009-10-18 09:47:40.087 DVDNAV_SPU_CLUT_CHANGE happened. 2009-10-18 09:47:40.087 DVDNAV_SPU_STREAM_CHANGE: physical_wide==0, physical_letterbox==0, physical_pan_scan==0, current_track==0 2009-10-18 09:47:40.087 DVDNAV_AUDIO_STREAM_CHANGE: Current Active Stream 0 2009-10-18 09:47:40.105 DVDNAV_HOP_CHANNEL happened. 2009-10-18 09:47:40.106 DVDNAV_CELL_CHANGE: pg_length == 270000, pgc_length == 270000, cell_start == 0, pg_start == 0, title == 0, part == 0 titleParts 0 2009-10-18 09:47:40.106 DVDNAV_SPU_CLUT_CHANGE happened. 2009-10-18 09:47:40.106 DVDNAV_SPU_STREAM_CHANGE: physical_wide==0, physical_letterbox==0, physical_pan_scan==0, current_track==0 2009-10-18 09:47:40.106 DVDNAV_AUDIO_STREAM_CHANGE: Current Active Stream 0 2009-10-18 09:47:40.107 AFD: Stream #0, has id 0x1e0 codec id MPEG2VIDEO, type Video, bitrate 8000000 at 0xb28a600 2009-10-18 09:47:40.111 VDP: Accepting: cmp(> 0 0) dec(vdpau) cpus(0) rend(vdpau) osd(vdpau) osdfade(enabled) deint(none,none) filt() 2009-10-18 09:47:40.111 VDP: LoadBestPreferences(2048x2048, 0) 2009-10-18 09:47:40.111 VDP: LoadBestPreferences(2048x2048, 60) 2009-10-18 09:47:40.111 VDP: LoadBestPreferences(720x480, 60) 2009-10-18 09:47:40.114 VDP: Accepting: cmp(> 0 0) dec(vdpau) cpus(0) rend(vdpau) osd(vdpau) osdfade(enabled) deint(none,none) filt() 2009-10-18 09:47:40.114 VDP: LoadBestPreferences(2048x2048, 0) 2009-10-18 09:47:40.114 VDP: LoadBestPreferences(2048x2048, 60) 2009-10-18 09:47:40.115 VDP: LoadBestPreferences(720x480, 60) 2009-10-18 09:47:40.118 VDP: Accepting: cmp(> 0 0) dec(vdpau) cpus(0) rend(vdpau) osd(vdpau) osdfade(enabled) deint(none,none) filt() 2009-10-18 09:47:40.118 VDP: LoadBestPreferences(2048x2048, 0) 2009-10-18 09:47:40.118 VDP: LoadBestPreferences(2048x2048, 60) 2009-10-18 09:47:40.118 VDP: LoadBestPreferences(720x480, 60) 2009-10-18 09:47:40.120 Using 1 CPUs for decoding 2009-10-18 09:47:40.120 AFD: InitVideoCodec() 0xb28a7c0 id(MPEG2VIDEO) type (Video). 2009-10-18 09:47:40.120 detectInterlace(Detect Scan, Interlaced Scan, 59.9401, 480) ->Progressive Scan 2009-10-18 09:47:40.120 AFD: Using vdpau for video decoding 2009-10-18 09:47:40.120 AFD: Looking for decoder for MPEG2VIDEO 2009-10-18 09:47:40.120 AFD: Opened codec 0xb28a7c0, id(MPEG2VIDEO) type(Video) 2009-10-18 09:47:40.120 RingBuf(/mnt/video/Movies/Bourne Movies/THE_BOURNE_IDENTITY.iso): CalcReadAheadThresh(8000 KB) -> threshhold(64 KB) min read(32 KB) blk size(128 KB) 2009-10-18 09:47:40.120 NVP(1): Disabling Audio, params(-1,-1,-1) 2009-10-18 09:47:40.121 No codec context. Returning false 2009-10-18 09:47:40.121 Dec: Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0) 2009-10-18 09:47:40.121 DVD Frame Rate 29.97 2009-10-18 09:47:40.121 Position map filled from DB to: 89 2009-10-18 09:47:40.121 Dec: SyncPositionMap prerecorded, from DB: 1 entries 2009-10-18 09:47:40.121 Dec: SyncPositionMap, new totframes: 89, new length: 3, posMap size: 1 2009-10-18 09:47:40.121 AFD: Position map found 2009-10-18 09:47:40.121 AFD: Successfully opened decoder for file: "/mnt/video/Movies/Bourne Movies/THE_BOURNE_IDENTITY.iso". novideo(0) 2009-10-18 09:47:40.128 VideoOutput: Allowed renderers: vdpau 2009-10-18 09:47:40.129 VideoOutput: Allowed renderers (filt: vdpau): vdpau 2009-10-18 09:47:40.132 VDP: Accepting: cmp(> 0 0) dec(vdpau) cpus(0) rend(vdpau) osd(vdpau) osdfade(enabled) deint(none,none) filt() 2009-10-18 09:47:40.132 VDP: LoadBestPreferences(2048x2048, 0) 2009-10-18 09:47:40.132 VDP: LoadBestPreferences(2048x2048, 60) 2009-10-18 09:47:40.132 VDP: LoadBestPreferences(720x480, 60) 2009-10-18 09:47:40.132 VideoOutput: Preferred renderer: vdpau 2009-10-18 09:47:40.132 VideoOutput: Trying video renderer: 'vdpau' 2009-10-18 09:47:40.145 VDP: Accepting: cmp(> 0 0) dec(vdpau) cpus(0) rend(vdpau) osd(vdpau) osdfade(enabled) deint(none,none) filt() 2009-10-18 09:47:40.145 VDP: LoadBestPreferences(2048x2048, 0) 2009-10-18 09:47:40.145 VDP: LoadBestPreferences(2048x2048, 60) 2009-10-18 09:47:40.152 VideoOutWindow::SetPIPState. pip_state: 0] 2009-10-18 09:47:40.152 Display Rect left: 0, top: 56, width: 1818, height: 908, aspect: 1.33333 2009-10-18 09:47:40.152 Video Rect left: 0, top: 0, width: 720, height: 480, aspect: 1.5 2009-10-18 09:47:40.152 VDP: LoadBestPreferences(720x480, 60) 2009-10-18 09:47:40.152 Display Rect left: 0, top: 56, width: 1818, height: 908, aspect: 1.33333 2009-10-18 09:47:40.152 Video Rect left: 0, top: 0, width: 720, height: 480, aspect: 1.5 2009-10-18 09:47:40.152 VDP: SetVideoRenderer(vdpau) 2009-10-18 09:47:40.152 VDP: SetVideoRender(vdpau) == GetVideoRenderer() 2009-10-18 09:47:40.153 VidOutVDPAU: VDPAU Colorkey: 0x20202 (depth 24) 2009-10-18 09:47:40.155 VideoOutput: Pixel dimensions: Screen 1920x1080, window 1818x1021 2009-10-18 09:47:40.155 VideoOutput: Actual display dimensions: 827x465 mm Aspect: 1.77849 2009-10-18 09:47:40.155 VideoOutput: Estimated window dimensions: 783x439 mm Aspect: 1.7836 2009-10-18 09:47:40.191 VDPAU Error: Error at util-vdpau.cpp:871 (#23, The system does not have enough resources to complete the requested operation at this time.) 2009-10-18 09:47:40.191 VDPAU Error: Failed to create output surface. 2009-10-18 09:47:40.191 VidOutVDPAU Error: Failed to initialise VDPAU 2009-10-18 09:47:40.191 VidOutVDPAU: DiscardFrames(1) 2009-10-18 09:47:40.191 VideoBuffers::DiscardFrames(1): 2009-10-18 09:47:40.191 VideoBuffers::DiscardFrames(): -- done() 2009-10-18 09:47:40.191 VideoBuffers::DiscardFrames(1): -- done 2009-10-18 09:47:40.191 VidOutVDPAU: DiscardFrames() 3: -- done() 2009-10-18 09:47:40.208 VidOutVDPAU: DiscardFrames(1) 2009-10-18 09:47:40.208 VideoBuffers::DiscardFrames(1): 2009-10-18 09:47:40.208 VideoBuffers::DiscardFrames(): -- done() 2009-10-18 09:47:40.208 VideoBuffers::DiscardFrames(1): -- done 2009-10-18 09:47:40.208 VidOutVDPAU: DiscardFrames() 3: -- done() 2009-10-18 09:47:40.208 VideoOutput, Error: Not compiled with any useable video output method. 2009-10-18 09:47:40.208 NVP(1), Error: Couldn't create VideoOutput instance. Exiting.. 2009-10-18 09:47:40.209 Unable to initialize video. 2009-10-18 09:48:00.165 playCtx, Error: StartDecoderThread() Failed to startdecoder 2009-10-18 09:48:00.165 TV: StartPlayer(0, Watching DVD, main) -- end error 2009-10-18 09:48:00.167 TV: HandleStateChange(0) -- end 2009-10-18 09:48:00.168 TV: StartTV -- process events end 2009-10-18 09:48:00.168 TV: StartTV -- process events 2 begin 2009-10-18 09:48:00.169 TV: StartTV -- process events 2 end 2009-10-18 09:48:00.170 TV::~TV() -- begin 2009-10-18 09:48:00.221 TV::~TV() -- lock