2006-03-25 15:04:38.898 New DB connection, total: 2
2006-03-25 15:04:38.900 Connected to database 'mythconverg' at host: mythserver
2006-03-25 15:04:38.901 MSqlQuery: SELECT data FROM settings WHERE value  = 'CustomFilters' AND hostname = 'mythserver' ;
2006-03-25 15:04:38.903 MSqlQuery: SELECT data FROM settings WHERE value  = 'UseOutputPictureControls' AND hostname = 'mythserver' ;
2006-03-25 15:04:38.904 MSqlQuery: SELECT data FROM settings WHERE value  = 'SmartChannelChange' AND hostname = 'mythserver' ;
2006-03-25 15:04:38.906 MSqlQuery: SELECT data FROM settings WHERE value  = 'IndividualMuteControl' AND hostname = 'mythserver' ;
2006-03-25 15:04:38.907 MSqlQuery: SELECT data FROM settings WHERE value  = 'UseArrowAccels' AND hostname = 'mythserver' ;
2006-03-25 15:04:38.909 MSqlQuery: SELECT data FROM settings WHERE value  = 'PersistentBrowseMode' AND hostname = 'mythserver' ;
2006-03-25 15:04:38.910 MSqlQuery: SELECT data FROM settings WHERE value  = 'OSDGeneralTimeout' AND hostname = 'mythserver' ;
2006-03-25 15:04:38.912 MSqlQuery: SELECT data FROM settings WHERE value  = 'OSDProgramInfoTimeout' AND hostname = 'mythserver' ;
2006-03-25 15:04:38.913 MSqlQuery: SELECT data FROM settings WHERE value  = 'AutoCommercialSkip' AND hostname = 'mythserver' ;
2006-03-25 15:04:38.915 MSqlQuery: SELECT data FROM settings WHERE value  = 'TryUnflaggedSkip' AND hostname = 'mythserver' ;
2006-03-25 15:04:38.916 MSqlQuery: SELECT data FROM settings WHERE value = 'TryUnflaggedSkip' AND hostname IS NULL;
2006-03-25 15:04:38.917 MSqlQuery: SELECT data FROM settings WHERE value  = 'SmartForward' AND hostname = 'mythserver' ;
2006-03-25 15:04:38.919 MSqlQuery: SELECT data FROM settings WHERE value  = 'StickyKeys' AND hostname = 'mythserver' ;
2006-03-25 15:04:38.921 MSqlQuery: SELECT data FROM settings WHERE value  = 'FFRewReposTime' AND hostname = 'mythserver' ;
2006-03-25 15:04:38.922 MSqlQuery: SELECT data FROM settings WHERE value  = 'FFRewReverse' AND hostname = 'mythserver' ;
2006-03-25 15:04:38.924 MSqlQuery: SELECT data FROM settings WHERE value  = 'FFRewSpeed0' AND hostname = 'mythserver' ;
2006-03-25 15:04:38.925 MSqlQuery: SELECT data FROM settings WHERE value = 'FFRewSpeed0' AND hostname IS NULL;
2006-03-25 15:04:38.926 MSqlQuery: SELECT data FROM settings WHERE value  = 'FFRewSpeed1' AND hostname = 'mythserver' ;
2006-03-25 15:04:38.928 MSqlQuery: SELECT data FROM settings WHERE value = 'FFRewSpeed1' AND hostname IS NULL;
2006-03-25 15:04:38.929 MSqlQuery: SELECT data FROM settings WHERE value  = 'FFRewSpeed2' AND hostname = 'mythserver' ;
2006-03-25 15:04:38.930 MSqlQuery: SELECT data FROM settings WHERE value = 'FFRewSpeed2' AND hostname IS NULL;
2006-03-25 15:04:38.932 MSqlQuery: SELECT data FROM settings WHERE value  = 'FFRewSpeed3' AND hostname = 'mythserver' ;
2006-03-25 15:04:38.933 MSqlQuery: SELECT data FROM settings WHERE value = 'FFRewSpeed3' AND hostname IS NULL;
2006-03-25 15:04:38.934 MSqlQuery: SELECT data FROM settings WHERE value  = 'FFRewSpeed4' AND hostname = 'mythserver' ;
2006-03-25 15:04:38.935 MSqlQuery: SELECT data FROM settings WHERE value = 'FFRewSpeed4' AND hostname IS NULL;
2006-03-25 15:04:38.937 MSqlQuery: SELECT data FROM settings WHERE value  = 'FFRewSpeed5' AND hostname = 'mythserver' ;
2006-03-25 15:04:38.938 MSqlQuery: SELECT data FROM settings WHERE value = 'FFRewSpeed5' AND hostname IS NULL;
2006-03-25 15:04:38.939 MSqlQuery: SELECT data FROM settings WHERE value  = 'FFRewSpeed6' AND hostname = 'mythserver' ;
2006-03-25 15:04:38.940 MSqlQuery: SELECT data FROM settings WHERE value = 'FFRewSpeed6' AND hostname IS NULL;
2006-03-25 15:04:38.942 MSqlQuery: SELECT data FROM settings WHERE value  = 'FFRewSpeed7' AND hostname = 'mythserver' ;
2006-03-25 15:04:38.943 MSqlQuery: SELECT data FROM settings WHERE value = 'FFRewSpeed7' AND hostname IS NULL;
2006-03-25 15:04:38.944 MSqlQuery: SELECT data FROM settings WHERE value  = 'VbiFormat' AND hostname = 'mythserver' ;
2006-03-25 15:04:38.946 MSqlQuery: SELECT data FROM settings WHERE value = 'VbiFormat' AND hostname IS NULL;
2006-03-25 15:04:38.947 MSqlQuery: SELECT data FROM settings WHERE value  = 'GuiSizeForTV' AND hostname = 'mythserver' ;
2006-03-25 15:04:38.949 MSqlQuery: SELECT data FROM settings WHERE value  = 'UseVideoModes' AND hostname = 'mythserver' ;
2006-03-25 15:04:38.950 MSqlQuery: SELECT data FROM settings WHERE value  = 'GuiResolution' AND hostname = 'mythserver' ;
2006-03-25 15:04:38.951 MSqlQuery: SELECT data FROM settings WHERE value = 'GuiResolution' AND hostname IS NULL;
2006-03-25 15:04:38.953 MSqlQuery: SELECT data FROM settings WHERE value  = 'GuiWidth' AND hostname = 'mythserver' ;
2006-03-25 15:04:38.954 MSqlQuery: SELECT data FROM settings WHERE value  = 'GuiHeight' AND hostname = 'mythserver' ;
2006-03-25 15:04:38.967 MSqlQuery: SELECT data FROM settings WHERE value  = 'MasterServerIP' AND hostname = 'mythserver' ;
2006-03-25 15:04:38.968 MSqlQuery: SELECT data FROM settings WHERE value = 'MasterServerIP' AND hostname IS NULL;
2006-03-25 15:04:38.969 MSqlQuery: SELECT data FROM settings WHERE value  = 'MasterServerPort' AND hostname = 'mythserver' ;
2006-03-25 15:04:38.971 MSqlQuery: SELECT data FROM settings WHERE value = 'MasterServerPort' AND hostname IS NULL;
2006-03-25 15:04:38.972 MSqlQuery: SELECT data FROM settings WHERE value  = 'WOLbackendReconnectWaitTime' AND hostname = 'mythserver' ;
2006-03-25 15:04:38.973 MSqlQuery: SELECT data FROM settings WHERE value = 'WOLbackendReconnectWaitTime' AND hostname IS NULL;
2006-03-25 15:04:38.975 MSqlQuery: SELECT data FROM settings WHERE value  = 'WOLbackendConnectRetry' AND hostname = 'mythserver' ;
2006-03-25 15:04:38.976 MSqlQuery: SELECT data FROM settings WHERE value = 'WOLbackendConnectRetry' AND hostname IS NULL;
2006-03-25 15:04:38.977 Connecting to backend server: 192.168.0.250:6543 (try 1 of 5)
2006-03-25 15:04:38.977 write -> 12 21      MYTH_PROTO_VERSION 26
2006-03-25 15:04:38.986 read  <- 12 13      ACCEPT[]:[]26
2006-03-25 15:04:38.987 Using protocol version 26
2006-03-25 15:04:38.987 write -> 12 24      ANN Monitor mythserver 0
2006-03-25 15:04:38.994 read  <- 12 2       OK
2006-03-25 15:04:39.002 write -> 12 29      GET_NEXT_FREE_RECORDER[]:[]-1
2006-03-25 15:04:39.012 read  <- 12 28      2[]:[]192.168.0.250[]:[]6543
2006-03-25 15:04:39.014 MSqlQuery: SELECT name, skipahead FROM playgroup WHERE (name = 'Default' OR name = 'Default')       AND skipahead <> 0 ORDER BY name = 'Default';
2006-03-25 15:04:39.015 TV: Attempting to change from None to WatchingLiveTV
2006-03-25 15:04:39.016 MSqlQuery: SELECT name, skipback FROM playgroup WHERE (name = 'Default' OR name = 'Default')       AND skipback <> 0 ORDER BY name = 'Default';
2006-03-25 15:04:39.017 write -> 14 21      MYTH_PROTO_VERSION 26
2006-03-25 15:04:39.018 MSqlQuery: SELECT name, jump FROM playgroup WHERE (name = 'Default' OR name = 'Default')       AND jump <> 0 ORDER BY name = 'Default';
2006-03-25 15:04:39.019 MSqlQuery: SELECT name, timestretch FROM playgroup WHERE (name = 'Default' OR name = 'Default')       AND timestretch <> 0 ORDER BY name = 'Default';
2006-03-25 15:04:39.021 MSqlQuery: SELECT data FROM settings WHERE value  = 'WatchTVGuide' AND hostname = 'mythserver' ;
2006-03-25 15:04:39.022 write -> 13 24      ANN Monitor mythserver 1
2006-03-25 15:04:39.038 read  <- 14 13      ACCEPT[]:[]26
2006-03-25 15:04:39.038 Using protocol version 26
2006-03-25 15:04:39.039 write -> 14 25      ANN Playback mythserver 0
2006-03-25 15:04:39.039 read  <- 13 2       OK
2006-03-25 15:04:39.046 read  <- 14 2       OK
2006-03-25 15:04:39.046 write -> 14 79      QUERY_RECORDER 2[]:[]SPAWN_LIVETV[]:[]live-mythserver-2006-03-25T15:04:39[]:[]0
2006-03-25 15:04:39.350 read  <- 13 51      BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty
2006-03-25 15:04:39.364 read  <- 14 2       ok
2006-03-25 15:04:39.366 MSqlQuery: SELECT chanid, starttime, endtime, discontinuity, chainpos, hostprefix, cardtype, channame, input FROM tvchain WHERE chainid = 'live-mythserver-2006-03-25T15:04:39' ORDER BY chainpos;
2006-03-25 15:04:39.367 LiveTVChain(live-mythserver-2006-03-25T15:04:39): ReloadAll(): Added new recording
2006-03-25 15:04:39.369 MSqlQuery: SELECT recorded.chanid,starttime,endtime,title, subtitle,description,channel.channum, channel.callsign,channel.name,channel.commfree, channel.outputfilters,seriesid,programid,filesize, lastmodified,stars,previouslyshown,originalairdate, hostname,recordid,transcoder,playgroup, recorded.recpriority,progstart,progend,basename,recgroup FROM recorded LEFT JOIN channel ON recorded.chanid = channel.chanid WHERE recorded.chanid = '1057' AND starttime = '2006-03-25T15:04:39' ;
2006-03-25 15:04:39.372 MSqlQuery: SELECT commflagged, cutlist, autoexpire, editing, bookmark FROM recorded WHERE chanid = '1057' AND starttime = '2006-03-25T15:04:39' ;
2006-03-25 15:04:39.375 MSqlQuery: SELECT data FROM settings WHERE value = 'RecordFilePrefix' AND hostname = 'mythserver' ;
2006-03-25 15:04:39.375 RingBuf(/mnt/store//1057_20060325150439.mpg): OpenFile(/mnt/store//1057_20060325150439.mpg, 12)
2006-03-25 15:04:39.375 RingBuf(/mnt/store//1057_20060325150439.mpg): Invalid file (fd 15) when opening '/mnt/store//1057_20060325150439.mpg'. 12 retries remaining.
2006-03-25 15:04:39.387 read  <- 13 85      BACKEND_MESSAGE[]:[]LIVETV_CHAIN UPDATE live-mythserver-2006-03-25T15:04:39[]:[]empty
2006-03-25 15:04:39.878 RingBuf(/mnt/store//1057_20060325150439.mpg): Invalid file (fd 15) when opening '/mnt/store//1057_20060325150439.mpg'. 11 retries remaining.
2006-03-25 15:04:40.382 RingBuf(/mnt/store//1057_20060325150439.mpg): Invalid file (fd 15) when opening '/mnt/store//1057_20060325150439.mpg'. 10 retries remaining.
2006-03-25 15:04:40.886 RingBuf(/mnt/store//1057_20060325150439.mpg): Invalid file (fd 15) when opening '/mnt/store//1057_20060325150439.mpg'. 9 retries remaining.
2006-03-25 15:04:41.390 RingBuf(/mnt/store//1057_20060325150439.mpg): Invalid file (fd 15) when opening '/mnt/store//1057_20060325150439.mpg'. 8 retries remaining.
2006-03-25 15:04:41.894 RingBuf(/mnt/store//1057_20060325150439.mpg): Invalid file (fd 15) when opening '/mnt/store//1057_20060325150439.mpg'. 7 retries remaining.
2006-03-25 15:04:42.398 RingBuf(/mnt/store//1057_20060325150439.mpg): Invalid file (fd 15) when opening '/mnt/store//1057_20060325150439.mpg'. 6 retries remaining.
2006-03-25 15:04:42.902 RingBuf(/mnt/store//1057_20060325150439.mpg): Invalid file (fd 15) when opening '/mnt/store//1057_20060325150439.mpg'. 5 retries remaining.
2006-03-25 15:04:43.406 RingBuf(/mnt/store//1057_20060325150439.mpg): Invalid file (fd 15) when opening '/mnt/store//1057_20060325150439.mpg'. 4 retries remaining.
2006-03-25 15:04:43.910 RingBuf(/mnt/store//1057_20060325150439.mpg): Invalid file (fd 15) when opening '/mnt/store//1057_20060325150439.mpg'. 3 retries remaining.
2006-03-25 15:04:44.414 RingBuf(/mnt/store//1057_20060325150439.mpg): Invalid file (fd 15) when opening '/mnt/store//1057_20060325150439.mpg'. 2 retries remaining.
2006-03-25 15:04:44.918 RingBuf(/mnt/store//1057_20060325150439.mpg): Invalid file (fd 15) when opening '/mnt/store//1057_20060325150439.mpg'. 1 retries remaining.
2006-03-25 15:04:45.422 RingBuf(/mnt/store//1057_20060325150439.mpg): Invalid file (fd 15) when opening '/mnt/store//1057_20060325150439.mpg'. 0 retries remaining.
2006-03-25 15:04:45.926 write -> 14 33      QUERY_RECORDER 2[]:[]IS_RECORDING
2006-03-25 15:04:45.928 Disable DPMS
2006-03-25 15:04:45.935 read  <- 14 1       1
2006-03-25 15:04:45.935 write -> 14 33      QUERY_RECORDER 2[]:[]IS_RECORDING
2006-03-25 15:04:45.942 read  <- 14 1       1
2006-03-25 15:04:45.943 TV: StartRecorder(): took 17 ms to start recorder.
2006-03-25 15:04:45.944 MSqlQuery: SELECT channel_timeout, cardtype FROM capturecard WHERE cardid = 2
2006-03-25 15:04:45.945 TV: GetLockTimeout(2): Set lock timeout to 4294967295 ms
2006-03-25 15:04:45.945 write -> 14 34      QUERY_RECORDER 2[]:[]GET_FRAMERATE
2006-03-25 15:04:45.951 read  <- 14 5       29.97
2006-03-25 15:04:45.959 MSqlQuery: DELETE FROM inuseprograms WHERE chanid = '1057' AND starttime = '2006-03-25T15:04:39' AND hostname = 'mythserver' AND recusage = 'player' ;
2006-03-25 15:04:45.960 MSqlQuery: INSERT INTO inuseprograms  (chanid, starttime, recusage, hostname, lastupdatetime)  VALUES  ('1057', '2006-03-25T15:04:39', 'player', 'mythserver', '2006-03-25T15:04:45');
2006-03-25 15:04:45.961 write -> 12 33      MESSAGE[]:[]RECORDING_LIST_CHANGE
2006-03-25 15:04:45.966 read  <- 12 2       OK
2006-03-25 15:04:45.969 MSqlQuery: SELECT data FROM settings WHERE value  = 'CommRewindAmount' AND hostname = 'mythserver' ;
2006-03-25 15:04:45.970 MSqlQuery: SELECT data FROM settings WHERE value  = 'CommNotifyAmount' AND hostname = 'mythserver' ;
2006-03-25 15:04:45.972 MSqlQuery: SELECT data FROM settings WHERE value  = 'Deinterlace' AND hostname = 'mythserver' ;
2006-03-25 15:04:45.974 MSqlQuery: SELECT data FROM settings WHERE value  = 'DecodeExtraAudio' AND hostname = 'mythserver' ;
2006-03-25 15:04:45.976 MSqlQuery: SELECT data FROM settings WHERE value  = 'VBIpageNr' AND hostname = 'mythserver' ;
2006-03-25 15:04:45.977 MSqlQuery: SELECT data FROM settings WHERE value = 'VBIpageNr' AND hostname IS NULL;
2006-03-25 15:04:45.979 MSqlQuery: SELECT data FROM settings WHERE value  = 'AudioSampleRate' AND hostname = 'mythserver' ;
2006-03-25 15:04:45.980 MSqlQuery: SELECT data FROM settings WHERE value = 'AudioSampleRate' AND hostname IS NULL;
2006-03-25 15:04:45.982 MSqlQuery: SELECT data FROM settings WHERE value  = 'AudioOutputDevice' AND hostname = 'mythserver' ;
2006-03-25 15:04:45.983 read  <- 13 51      BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty
2006-03-25 15:04:45.984 MSqlQuery: SELECT data FROM settings WHERE value  = 'ExactSeeking' AND hostname = 'mythserver' ;
2006-03-25 15:04:45.986 MSqlQuery: SELECT data FROM settings WHERE value  = 'DefaultCCMode' AND hostname = 'mythserver' ;
2006-03-25 15:04:45.986 TV: Output filters for this channel are: ''
2006-03-25 15:04:45.988 MSqlQuery: SELECT data FROM settings WHERE value  = 'UDPNotifyPort' AND hostname = 'mythserver' ;
2006-03-25 15:04:45.989 NVP::OpenFile(): Error, file not found: /mnt/store//1057_20060325150439.mpg
2006-03-25 15:04:45.990 TV: StartPlayer(): took 1 ms to start player.
2006-03-25 15:04:45.990 TV Error: StartPlayer(): NVP is not playing after 20000 msec
2006-03-25 15:04:45.990 TV: StopStuff() -- begin
2006-03-25 15:04:45.991 TV: StopStuff(): stopping ring buffer[s]
2006-03-25 15:04:45.991 TV: StopStuff(): stopping player[s] (1/2)
2006-03-25 15:04:45.991 TV: StopStuff(): stopping recorder[s]
2006-03-25 15:04:45.991 write -> 14 32      QUERY_RECORDER 2[]:[]STOP_LIVETV
2006-03-25 15:04:46.014 read  <- 13 48      BACKEND_MESSAGE[]:[]DONE_RECORDING 2 0[]:[]empty
2006-03-25 15:04:50.114 read  <- 14 2       ok
2006-03-25 15:04:50.115 TV: StopStuff(): stopping player[s] (2/2)
2006-03-25 15:04:50.117 MSqlQuery: DELETE FROM inuseprograms WHERE chanid = '1057' AND starttime = '2006-03-25T15:04:39' AND hostname = 'mythserver' AND recusage = 'player' ;
2006-03-25 15:04:50.117 write -> 12 33      MESSAGE[]:[]RECORDING_LIST_CHANGE
2006-03-25 15:04:50.123 read  <- 12 2       OK
2006-03-25 15:04:50.124 read  <- 13 85      BACKEND_MESSAGE[]:[]LIVETV_CHAIN UPDATE live-mythserver-2006-03-25T15:04:39[]:[]empty
2006-03-25 15:04:50.130 read  <- 13 51      BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty
2006-03-25 15:04:50.138 read  <- 13 51      BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty
2006-03-25 15:04:50.141 MSqlQuery: DELETE FROM tvchain WHERE chainid = 'live-mythserver-2006-03-25T15:04:39' ;
2006-03-25 15:04:50.141 TV: StopStuff() -- end
2006-03-25 15:04:50.141 TV Error: LiveTV not successfully started
2006-03-25 15:04:50.155 Enable DPMS