[mythtv-users] Can't skip forward on remote frontend while recording is still in progress - RemoteEncoder::SendReceiveStringList(): Response too short

Stephen Robertson stephengrobertson at gmail.com
Mon May 10 20:49:10 UTC 2010


Hi all,

I'm trying to set up a new frontend only machine using one of the little
revo R3610.  I'm having a problem where whenever I try to skip forward or
back during a recording that is still in progress then playback stops(see
log below) if I leave it alone then playback seems to be fine. Strangely if
I wait until the recording is complete then I can skip forward and back with
no problems.  It is a wired network connection between the two.

Any help would be greatfully received.

Thanks
Stephen

Frontend:(Ubuntu repositories)
MythTV Version   : 24158
MythTV Branch    : branches/release-0-23-fixes
Network Protocol : 56
Library API      : 0.23.20100314-1
QT Version       : 4.6.2
Options compiled in:
 linux debug using_oss using_alsa using_pulse using_jack using_pulseoutput
using_backend using_dvb using_firewire using_frontend
using_glx_proc_addr_arb using_hdhomerun using_hdpvr using_iptv using_ivtv
using_joystick_menu using_libudev using_lirc using_mheg using_opengl_video
using_opengl_vsync using_qtdbus using_qtwebkit using_v4l using_x11
using_xrandr using_xv using_xvmc using_xvmc_vld using_xvmcw
using_bindings_perl using_bindings_python using_opengl using_vdpau
using_ffmpeg_threads using_libavc_5_3 using_live using_mheg


Backend:(compiled from SVN)
MythTV Version   : 24355
MythTV Branch    : branches/release-0-23-fixes
Network Protocol : 56
Library API      : 0.23.20100429-1
QT Version       : 4.5.0
Options compiled in:
 linux release using_oss using_alsa using_backend using_dvb using_frontend
using_hdhomerun using_hdpvr using_iptv using_ivtv using_joystick_menu
using_lirc using_mheg using_opengl_video using_opengl_vsync using_qtdbus
using_qtwebkit using_v4l using_x11 using_xrandr using_bindings_perl
using_bindings_python using_opengl using_vdpau using_ffmpeg_threads
using_live using_mheg

2010-05-10 21:38:47.683 TV: Attempting to change from None to
WatchingRecording
2010-05-10 21:38:47.688 RingBuf(myth://
192.168.1.100:6543/1003_20100510210000.mpg): OpenFile(myth://
192.168.1.100:6543/1003_20100510210000.mpg, 12)
2010-05-10 21:38:47.750 RingBuf(myth://
192.168.1.100:6543/1003_20100510210000.mpg): CalcReadAheadThresh(0 KB)
             -> threshhold(64 KB) min read(0 KB) blk size(32 KB)
2010-05-10 21:38:47.815 MythContext: Connecting to backend server:
127.0.0.1:6543 (try 1 of 1)
2010-05-10 21:38:47.816 Using protocol version 56
2010-05-10 21:38:47.852 TV: StartPlayer(0, WatchingRecording, main) -- begin
2010-05-10 21:38:47.852 TV: Elapsed time since TV constructor was called:
385 ms
2010-05-10 21:38:48.128 AFD: Stream #0, has id 0x200 codec id MPEG2VIDEO,
type Video, bitrate 15000000 at 0xa5fe3c90
2010-05-10 21:38:48.144 VDP: Accepting: cmp(>= 0 720) dec(vdpau) cpus(1)
rend(vdpau) osd(vdpau) osdfade(enabled)
deint(vdpaubasicdoublerate,vdpaubasic) filt(vdpaucolorspace=auto)
2010-05-10 21:38:48.145 VDP: Accepting: cmp(> 0 0) dec(vdpau) cpus(1)
rend(vdpau) osd(vdpau) osdfade(enabled)
deint(vdpauadvanceddoublerate,vdpauadvanced) filt(vdpaucolorspace=auto)
2010-05-10 21:38:48.145 VDP: LoadBestPreferences(2048x2048, 0)
2010-05-10 21:38:48.145 VDP: LoadBestPreferences(2048x2048, 60)
2010-05-10 21:38:48.145 VDP: LoadBestPreferences(720x576, 60)
2010-05-10 21:38:48.162 VDP: Accepting: cmp(>= 0 720) dec(vdpau) cpus(1)
rend(vdpau) osd(vdpau) osdfade(enabled)
deint(vdpaubasicdoublerate,vdpaubasic) filt(vdpaucolorspace=auto)
2010-05-10 21:38:48.163 VDP: Accepting: cmp(> 0 0) dec(vdpau) cpus(1)
rend(vdpau) osd(vdpau) osdfade(enabled)
deint(vdpauadvanceddoublerate,vdpauadvanced) filt(vdpaucolorspace=auto)
2010-05-10 21:38:48.163 VDP: LoadBestPreferences(2048x2048, 0)
2010-05-10 21:38:48.163 VDP: LoadBestPreferences(2048x2048, 60)
2010-05-10 21:38:48.164 VDP: LoadBestPreferences(720x576, 60)
2010-05-10 21:38:48.175 VDP: Accepting: cmp(>= 0 720) dec(vdpau) cpus(1)
rend(vdpau) osd(vdpau) osdfade(enabled)
deint(vdpaubasicdoublerate,vdpaubasic) filt(vdpaucolorspace=auto)
2010-05-10 21:38:48.176 VDP: Accepting: cmp(> 0 0) dec(vdpau) cpus(1)
rend(vdpau) osd(vdpau) osdfade(enabled)
deint(vdpauadvanceddoublerate,vdpauadvanced) filt(vdpaucolorspace=auto)
2010-05-10 21:38:48.176 VDP: LoadBestPreferences(2048x2048, 0)
2010-05-10 21:38:48.176 VDP: LoadBestPreferences(2048x2048, 60)
2010-05-10 21:38:48.176 VDP: LoadBestPreferences(720x576, 60)
2010-05-10 21:38:48.185 Using 1 CPUs for decoding
2010-05-10 21:38:48.186 AFD: InitVideoCodec() 0xa5fe2f70 id(MPEG2VIDEO) type
(Video).
2010-05-10 21:38:48.186 detectInterlace(Detect Scan, Interlaced Scan, 25,
576) ->Interlaced Scan
2010-05-10 21:38:48.187 AFD: Using vdpau for video decoding
2010-05-10 21:38:48.187 AFD: Looking for decoder for MPEG2VIDEO
2010-05-10 21:38:48.187 AFD: Opened codec 0xa5fe2f70, id(MPEG2VIDEO)
type(Video)
2010-05-10 21:38:48.187 AFD: Stream #1, has id 0x28a codec id MP2, type
Audio, bitrate 192000 at 0xa5fe33a0
2010-05-10 21:38:48.188 AFD: codec MP2 has 2 channels
2010-05-10 21:38:48.188 AFD: Looking for decoder for MP2
2010-05-10 21:38:48.188 AFD: Opened codec 0xa5fdffb0, id(MP2) type(Audio)
2010-05-10 21:38:48.188 AFD: Stream #2, has id 0x294 codec id MP2, type
Audio, bitrate 64000 at 0xa5fe0360
2010-05-10 21:38:48.188 AFD: codec MP2 has 1 channels
2010-05-10 21:38:48.188 AFD: Looking for decoder for MP2
2010-05-10 21:38:48.189 AFD: Opened codec 0xa5fe0530, id(MP2) type(Audio)
2010-05-10 21:38:48.189 AFD: Stream #3, has id 0x402 codec id DVB_SUBTITLE,
type Subtitle, bitrate 0 at 0xa5fe0ae0
2010-05-10 21:38:48.189 AFD: subtitle codec (Subtitle)
2010-05-10 21:38:48.189 AFD: Looking for decoder for DVB_SUBTITLE
2010-05-10 21:38:48.189 AFD: Opened codec 0xa5a6f140, id(DVB_SUBTITLE)
type(Subtitle)
2010-05-10 21:38:48.189 AFD: Subtitle track #1 is A/V stream #3 and is in
the English language(6647399).
2010-05-10 21:38:48.189 AFD: Stream #4, has id 0x853 codec id DSMCC_B, type
Data, bitrate 0 at 0xa5fe0cc0
2010-05-10 21:38:48.190 AFD: data codec (Data)
2010-05-10 21:38:48.190 AFD: Stream #5, has id 0x91b codec id DSMCC_B, type
Data, bitrate 0 at 0xa5a718f0
2010-05-10 21:38:48.190 AFD: data codec (Data)
2010-05-10 21:38:48.190 AFD: Stream #6, has id 0x91c codec id DSMCC_B, type
Data, bitrate 0 at 0xa5a72e90
2010-05-10 21:38:48.190 AFD: data codec (Data)
2010-05-10 21:38:48.193 RingBuf(myth://
192.168.1.100:6543/1003_20100510210000.mpg): CalcReadAheadThresh(0 KB)
             -> threshhold(64 KB) min read(0 KB) blk size(32 KB)
2010-05-10 21:38:48.263 Opening audio device 'default'. ch 2(2) sr 48000
(reenc 0)
2010-05-10 21:38:48.265 Opening ALSA audio device 'default'.
ALSA lib control.c:902:(snd_ctl_open_noupdate) Invalid CTL /dev/mixer
2010-05-10 21:38:48.306 AudioOutput Warning: Mixer attach error -2: No such
file or directory
            Check Mixer Name in Setup: '/dev/mixer'
2010-05-10 21:38:48.318 Dec: Selected track #1 in the English
language(6647399)
2010-05-10 21:38:48.318 Dec: Trying to select track (w/lang)
2010-05-10 21:38:48.318 Dec: Selecting first track
2010-05-10 21:38:48.319 Dec: Selected track #1 in the Unknown language(0)
2010-05-10 21:38:48.319 Dec: Resyncing position map. posmapStarted = 0
livetv(0) watchingRec(1)
2010-05-10 21:38:48.414 Position map filled from DB to: 57816
2010-05-10 21:38:48.415 Dec: SyncPositionMap watchingrecording, from DB:
2124 entries
2010-05-10 21:38:48.416 NVP(0): Filling position map from 57817 to end
2010-05-10 21:38:48.474 Dec: Position map filled from Encoder to: 57816
2010-05-10 21:38:48.474 Dec: SyncPositionMap watchingrecording total: 2124
entries
2010-05-10 21:38:48.474 Dec: SyncPositionMap, new totframes: 57816, new
length: 2312, posMap size: 2124
2010-05-10 21:38:48.475 AFD: Partial position map found
2010-05-10 21:38:48.475 AFD: Successfully opened decoder for file: "myth://
192.168.1.100:6543/1003_20100510210000.mpg". novideo(0)
2010-05-10 21:38:48.482 VideoOutput: Allowed renderers: vdpau
2010-05-10 21:38:48.482 VideoOutput: Allowed renderers (filt: vdpau): vdpau
2010-05-10 21:38:48.488 VDP: Accepting: cmp(>= 0 720) dec(vdpau) cpus(1)
rend(vdpau) osd(vdpau) osdfade(enabled)
deint(vdpaubasicdoublerate,vdpaubasic) filt(vdpaucolorspace=auto)
2010-05-10 21:38:48.489 VDP: Accepting: cmp(> 0 0) dec(vdpau) cpus(1)
rend(vdpau) osd(vdpau) osdfade(enabled)
deint(vdpauadvanceddoublerate,vdpauadvanced) filt(vdpaucolorspace=auto)
2010-05-10 21:38:48.489 VDP: LoadBestPreferences(2048x2048, 0)
2010-05-10 21:38:48.489 VDP: LoadBestPreferences(2048x2048, 60)
2010-05-10 21:38:48.489 VDP: LoadBestPreferences(720x576, 60)
2010-05-10 21:38:48.490 VideoOutput: Preferred renderer: vdpau
2010-05-10 21:38:48.490 VideoOutput: Trying video renderer: 'vdpau'
2010-05-10 21:38:48.511 VDP: Accepting: cmp(>= 0 720) dec(vdpau) cpus(1)
rend(vdpau) osd(vdpau) osdfade(enabled)
deint(vdpaubasicdoublerate,vdpaubasic) filt(vdpaucolorspace=auto)
2010-05-10 21:38:48.511 VDP: Accepting: cmp(> 0 0) dec(vdpau) cpus(1)
rend(vdpau) osd(vdpau) osdfade(enabled)
deint(vdpauadvanceddoublerate,vdpauadvanced) filt(vdpaucolorspace=auto)
2010-05-10 21:38:48.512 VDP: LoadBestPreferences(2048x2048, 0)
2010-05-10 21:38:48.512 VDP: LoadBestPreferences(2048x2048, 60)
2010-05-10 21:38:48.529 VideoOutWindow::SetPIPState. pip_state: 0]
2010-05-10 21:38:48.529 Display Rect  left: 0, top: 150, width: 1600,
height: 900, aspect: 1.33333
2010-05-10 21:38:48.529 Video Rect    left: 0, top: 0, width: 720, height:
576, aspect: 1.77778
2010-05-10 21:38:48.530 VDP: LoadBestPreferences(720x576, 60)
2010-05-10 21:38:48.530 Display Rect  left: 0, top: 150, width: 1600,
height: 900, aspect: 1.33333
2010-05-10 21:38:48.530 Video Rect    left: 0, top: 0, width: 720, height:
576, aspect: 1.77778
2010-05-10 21:38:48.531 VDP: SetVideoRenderer(vdpau)
2010-05-10 21:38:48.531 VDP: SetVideoRender(vdpau) == GetVideoRenderer()
2010-05-10 21:38:48.534 VideoOutput: Pixel dimensions: Screen 1600x1200,
window 1600x1200
2010-05-10 21:38:48.534 VideoOutput: Actual display dimensions: 363x290 mm
Aspect: 1.25172
2010-05-10 21:38:48.534 VideoOutput: Estimated window dimensions: 363x290
mm  Aspect: 1.25172
2010-05-10 21:38:48.666 VDPAU: Created 2 output surfaces.
2010-05-10 21:38:48.666 VDPAU: Set colorkey to 0x20202
2010-05-10 21:38:48.666 VDPAU: Version 1
2010-05-10 21:38:48.666 VDPAU: Information NVIDIA VDPAU Driver Shared
Library  195.36.15  Thu Mar 11 23:42:13 PST 2010
2010-05-10 21:38:48.667 VDPAU: HQ Scaling not supported.
2010-05-10 21:38:48.667 VDPAU: MPEG4 hardware acceleration not supported.
2010-05-10 21:38:48.667 VDPAU: Created VDPAU render device 1600x1200
2010-05-10 21:38:48.712 VidOutVDPAU: Created VDPAU osd (1600x1200)
2010-05-10 21:38:48.840 VidOutVDPAU: PictureAttributes: Brightness,
Contrast, Colour, Hue
2010-05-10 21:38:48.849 VidOutVDPAU: Using ITU BT.601 colorspace
2010-05-10 21:38:48.849 Display Rect  left: 0, top: 177, width: 1600,
height: 845, aspect: 1.25172
2010-05-10 21:38:48.849 Video Rect    left: 0, top: 0, width: 720, height:
576, aspect: 1.77778
2010-05-10 21:38:48.850 VidOutVDPAU: Created VDPAU context (GPU decode)
2010-05-10 21:38:48.854 Over/underscan. V: 0, H: 0
2010-05-10 21:38:48.855 Display Rect  left: 0, top: 177, width: 1600,
height: 845, aspect: 1.25172
2010-05-10 21:38:48.855 Video Rect    left: 0, top: 0, width: 720, height:
576, aspect: 1.77778
2010-05-10 21:38:48.855 VDP: LoadBestPreferences(720x576, 25)
2010-05-10 21:38:48.855 NVP(0): Forcing decode extra audio option on (Video
method requires it).
2010-05-10 21:38:48.857 FilterManager: GetFilterInfo(convert) returning: 0x0
2010-05-10 21:38:48.858 NVP(0): LoadFilters('vdpaucolorspace=auto'..) -> 0x0
2010-05-10 21:38:48.862 OSD Theme Dimensions W: 640 H: 480
2010-05-10 21:38:49.084 NVP(0): ClearAfterSeek(1)
2010-05-10 21:38:49.084 playCtx: StartDecoderThread(): took 605 ms to start
player.
2010-05-10 21:38:49.084 VidOutVDPAU: ClearAfterSeek()
2010-05-10 21:38:49.084 VidOutVDPAU: DiscardFrames(0)
2010-05-10 21:38:49.084 TV: StartPlayer(0, WatchingRecording, main) -- end
ok
2010-05-10 21:38:49.084 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAA
2010-05-10 21:38:49.085 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAA --
done
2010-05-10 21:38:49.085 VidOutVDPAU: DiscardFrames() 3: AAAAAAAAAAAAAAAAA --
done()
2010-05-10 21:38:49.085 TV: Changing from None to WatchingRecording
2010-05-10 21:38:49.088 Realtime priority would require SUID as root.
2010-05-10 21:38:49.089 VDP: GetFilteredDeint() : vdpau ->
'vdpauadvanceddoublerate'
2010-05-10 21:38:49.108 VidOutVDPAU: Enabled deinterlacing.
2010-05-10 21:38:49.111 DRMVideoSync: Could not open device /dev/dri/card0,
No such file or directory
2010-05-10 21:38:49.111 OpenGLVideoSync()
2010-05-10 21:38:49.127 TV: HandleStateChange(0) -- end
2010-05-10 21:38:49.194 ScreenSaverX11Private: DPMS Deactivated 1
2010-05-10 21:38:49.195 ScreenSaverX11Private: ResetTimer -- begin
2010-05-10 21:38:49.195 ScreenSaverX11Private: StopTimer
2010-05-10 21:38:49.195 VidOutVDPAU: Created VDPAU decoder (2 ref frames)
2010-05-10 21:38:49.197 ScreenSaverX11Private: StartTimer
2010-05-10 21:38:49.197 ScreenSaverX11Private: ResetTimer -- end
2010-05-10 21:38:49.197 Detect Letterbox: The source is not a supported
frame format (was 11)
2010-05-10 21:38:49.215 Forcing GLX version to 1.2 (orig 1.4)
2010-05-10 21:38:49.285 GLCtx: Created OpenGL window.
2010-05-10 21:38:49.285 GLCtx: Created GLX context.
2010-05-10 21:38:49.285 GLCtx: GLX Version: 1.2
2010-05-10 21:38:49.285 GLCtx: Direct rendering: Yes
2010-05-10 21:38:49.339 GLCtx: OpenGL vendor  : NVIDIA Corporation
2010-05-10 21:38:49.340 GLCtx: OpenGL renderer: ION/PCI/SSE2
2010-05-10 21:38:49.340 GLCtx: OpenGL version : 3.2.0 NVIDIA 195.36.15
2010-05-10 21:38:49.340 GLCtx: Max texture size: 8192 x 8192
2010-05-10 21:38:49.340 GLCtx: Max texture units: 4
2010-05-10 21:38:49.341 Set video sync frame interval to 40000
2010-05-10 21:38:49.373 Video timing method: SGI OpenGL
2010-05-10 21:38:49.374 Refresh rate: 13333, frame interval: 40000
2010-05-10 21:38:49.378 Set video sync frame interval to 40000
2010-05-10 21:38:49.378 Enabled deinterlacing
2010-05-10 21:38:49.381 VDPAU: Added 2 output surfaces (total 4, max 4)
'video_output' mean = '39997.67', std. dev. = '186.38', fps = '25.00'
'video_output' mean = '39997.76', std. dev. = '1649.68', fps = '25.00'
2010-05-10 21:39:00.026 TV: DoNVPSeek() -- begin
2010-05-10 21:39:00.026 TV: DoNVPSeek() -- ff
2010-05-10 21:39:00.053 RemoteEncoder::SendReceiveStringList(): Response too
short
2010-05-10 21:39:00.053 RemoteEncoder(2), Error: GetFramesWritten() --
network error
2010-05-10 21:39:00.054 TV: DoNVPSeek() -- end
2010-05-10 21:39:00.054 MythContext: Connecting to backend server:
127.0.0.1:6543 (try 1 of 1)
2010-05-10 21:39:00.054 UpdateOSDSeekMessage(Skip Ahead, 2)
2010-05-10 21:39:00.056 Using protocol version 56
2010-05-10 21:39:00.087 RemoteEncoder::SendReceiveStringList(): Response too
short
2010-05-10 21:39:00.088 RemoteEncoder(2), Error: GetFramesWritten() --
network error
2010-05-10 21:39:00.088 MythContext: Connecting to backend server:
127.0.0.1:6543 (try 1 of 1)
2010-05-10 21:39:00.090 Using protocol version 56
2010-05-10 21:39:00.090 NVP(0), Error: Unknown recorder error, exiting
decoder
2010-05-10 21:39:00.090 NVP(0): Exited decoder loop.
2010-05-10 21:39:00.119 ~OpenGLVideoSync() -- closing opengl vsync
2010-05-10 21:39:00.120 RemoteEncoder::SendReceiveStringList(): Response too
short
2010-05-10 21:39:00.120 GLCtx: Deleting OpenGL Resources
2010-05-10 21:39:00.121 RemoteEncoder(2), Error: GetFramesWritten() --
network error
2010-05-10 21:39:00.122 GLCtx: Unmapping gl window
2010-05-10 21:39:00.122 GLCtx: Destroying gl window
2010-05-10 21:39:00.122 GLCtx: Destroying glx context
2010-05-10 21:39:00.131 GLCtx: Closing display
2010-05-10 21:39:00.144 VidOutVDPAU: DiscardFrames(1)
2010-05-10 21:39:00.144 VideoBuffers::DiscardFrames(1): UUUuuULDAAUDDUUUU
2010-05-10 21:39:00.144 VideoBuffers::DiscardFrames(): AAAAAAADAAADDAAAA --
done()
2010-05-10 21:39:00.145 VideoBuffers::DiscardFrames(1): AAAAAAADAAADDAAAA --
done
2010-05-10 21:39:00.145 VidOutVDPAU: DiscardFrames() 3: AAAAAAADAAADDAAAA --
done()
2010-05-10 21:39:00.163 TV: HandleStateChange(0) -- begin
2010-05-10 21:39:00.164 TV: Attempting to change from WatchingRecording to
None
2010-05-10 21:39:00.164 TV: StopStuff() for player ctx 0 -- begin
2010-05-10 21:39:00.164 TV: SetActive(0,w/o OSD) 0 -> 0 -- begin
2010-05-10 21:39:00.165 TV: SetActive(0,w/o OSD) 0 -> 0 -- end
2010-05-10 21:39:00.165 TV: StopStuff(): stopping ring buffer
2010-05-10 21:39:00.207 TV: StopStuff(): stopping player
2010-05-10 21:39:00.207 TV: StopStuff() -- end
2010-05-10 21:39:00.208 TV: Changing from WatchingRecording to None
2010-05-10 21:39:00.208 MythContext: Connecting to backend server:
127.0.0.1:6543 (try 1 of 1)
2010-05-10 21:39:00.210 Using protocol version 56
2010-05-10 21:39:00.242 TV: HandleStateChange(0) -- end
2010-05-10 21:39:00.275 ScreenSaverX11Private: DPMS Reactivated 1
2010-05-10 21:39:00.275 ScreenSaverX11Private: StopTimer
2010-05-10 21:39:00.275 TV: HandleStateChange(0) -- begin
2010-05-10 21:39:00.275 TV: Attempting to change from None to None
2010-05-10 21:39:00.287 TV: HandleStateChange(0) -- end
2010-05-10 21:39:00.287 TV: StartTV -- process events end
2010-05-10 21:39:00.287 TV: StartTV -- process events 2 begin
2010-05-10 21:39:00.288 TV: StartTV -- process events 2 end
2010-05-10 21:39:00.289 TV::~TV() -- begin
2010-05-10 21:39:00.351 TV::~TV() -- lock
2010-05-10 21:39:00.381 TV::~TV() -- end
2010-05-10 21:39:00.395 TV: StartTV -- end
2010-05-10 21:39:00.850 PlaybackBoxHelper: Not requesting preview as it is
already being generated
2010-05-10 21:39:00.880 PlaybackBoxHelper: Not requesting preview as it is
already being generated
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://mythtv.org/pipermail/mythtv-users/attachments/20100510/0c9ad9b7/attachment.htm>


More information about the mythtv-users mailing list