[mythtv-users] Can't read first 2048

Mike Carron jmcarron at starstream.net
Sat Jan 24 23:39:43 UTC 2015 

On 01/24/2015 12:37 PM, Mike B wrote:
> I'm not sure what's changed, but one day I could no longer watch live 
> TV on my frontend.  When I try to watch live TV, I get an error 
> message saying, "Cannot read first 2048 bytes".  When I click on the 
> OK button on that message, the frontend locks up.   I can watch liveTV 
> on my backend just fine.  The backend records shows and plays those 
> recorded shows without trouble.
>
> I don't know if it is at all related, but there are also a couple of 
> videos on my backend that I cannot watch.  Most videos I can watch 
> without problem.  I can watch them all on the backend just fine.
>
> I'm including the terminal output from the frontend.  I captured this 
> a few weeks ago, but only now am getting to asking about it. I checked 
> the backend logs and there were no entries that corresponded to these 
> events (none on the same day even).  When I captured this output, I 
> had tried to watch a video (the movie LeMans) first.  It gave the 
> "cannot read the first 2048" error (a transparent pop-up in the 
> upper-right corner like what you get when the backend isn't 
> available), and wouldn't play.  I then tried to watch live TV.  For 
> both the video and live TV I get RingBuffer errors.
>
> $ mythfrontend
> 2015-01-03 13:42:11.753608 I  Setup Interrupt handler
> 2015-01-03 13:42:11.753637 I  Setup Terminated handler
> 2015-01-03 13:42:11.753647 I  Setup Segmentation fault handler
> 2015-01-03 13:42:11.753657 I  Setup Aborted handler
> 2015-01-03 13:42:11.753665 I  Setup Bus error handler
> 2015-01-03 13:42:11.753675 I  Setup Floating point exception handler
> 2015-01-03 13:42:11.753684 I  Setup Illegal instruction handler
> 2015-01-03 13:42:11.753694 I  Setup Real-time signal 0 handler
> 2015-01-03 13:42:11.753706 I  Setup User defined signal 1 handler
> 2015-01-03 13:42:11.753715 I  Setup User defined signal 2 handler
> 2015-01-03 13:42:11.753846 C  mythfrontend version: fixes/0.27 
> [v0.27-193-g8ee257c] www.mythtv.org
> 2015-01-03 13:42:11.753855 C  Qt version: compile: 4.8.6, runtime: 4.8.6
> 2015-01-03 13:42:11.753860 N  Enabled verbose msgs:  general
> 2015-01-03 13:42:11.753876 N  Setting Log Level to LOG_INFO
> 2015-01-03 13:42:11.764580 I  Added logging to the console
> 2015-01-03 13:42:11.764553 N  Using runtime prefix = /usr
> 2015-01-03 13:42:11.764631 N  Using configuration directory = 
> /home/tonka/.mythtv
> 2015-01-03 13:42:11.764717 I  Assumed character encoding: en_US.UTF-8
> 2015-01-03 13:42:11.765198 N  Empty LocalHostName.
> 2015-01-03 13:42:11.765209 I  Using localhost value of htpc-frontend
> 2015-01-03 13:42:11.765248 I  Testing network connectivity to '10.6.2.51'
> 2015-01-03 13:42:11.766543 I  Starting process manager
> 2015-01-03 13:42:11.766757 I  Starting IO manager (read)
> 2015-01-03 13:42:11.773558 I  Starting process signal handler
> 2015-01-03 13:42:11.774217 I  Starting IO manager (write)
> 2015-01-03 13:42:11.867178 I  New Client:  (#1)
> 2015-01-03 13:42:11.867227 I  Added syslogging
> 2015-01-03 13:42:11.919596 N  Setting QT default locale to en_UM
> 2015-01-03 13:42:11.919811 I  Current locale en_UM
> 2015-01-03 13:42:11.919912 E  No locale defaults file for en_UM, skipping
> 2015-01-03 13:42:11.980582 I  ScreenSaverX11Private: XScreenSaver 
> support enabled
> 2015-01-03 13:42:11.981539 I  ScreenSaverX11Private: DPMS is disabled.
> 2015-01-03 13:42:12.003056 N  Desktop video mode: 1920x1080 60.000 Hz
> 2015-01-03 13:42:12.079013 I  Listening on TCP 127.0.0.1:6547
> 2015-01-03 13:42:12.079202 I  Listening on TCP 10.6.2.52:6547
> 2015-01-03 13:42:12.079361 I  Listening on TCP [::1]:6547
> 2015-01-03 13:42:12.079528 I  Listening on TCP 
> [fe80::6a05:caff:fe2a:b43a%eth0]:6547
> 2015-01-03 13:42:12.939738 I  Connecting to lcd server: 127.0.0.1:6545 
> (try 1 of 10)
> 2015-01-03 13:42:12.942669 I  Loading en_us translation for module 
> mythfrontend
> 2015-01-03 13:42:12.962984 I  LIRC: Successfully initialized 
> '/dev/lircd' using '/home/tonka/.mythtv/lircrc' config
> 2015-01-03 13:42:12.963177 E  JoystickMenuThread: Joystick disabled - 
> Failed to read /home/tonka/.mythtv/joystickmenurc
> 2015-01-03 13:42:12.963232 I  UDPListener: Enabling
> 2015-01-03 13:42:12.964669 I  Binding to UDP 127.0.0.1:6948
> 2015-01-03 13:42:12.964780 I  Binding to UDP 10.6.2.52:6948
> 2015-01-03 13:42:12.964942 I  Binding to UDP [::1]:6948
> 2015-01-03 13:42:12.965096 I  Binding to UDP 
> [fe80::6a05:caff:fe2a:b43a%eth0]:6948
> 2015-01-03 13:42:12.965232 I  Binding to UDP 10.6.2.255:6948
> 2015-01-03 13:42:13.047042 I  Using Frameless Window
> 2015-01-03 13:42:13.047147 I  Using Full Screen Window
> 2015-01-03 13:42:13.053886 I  Using the Qt painter
> 2015-01-03 13:42:13.348678 I  MythCoreContext: Connecting to backend 
> server: 10.6.2.51:6543 (try 1 of 1)
> 2015-01-03 13:42:13.357877 I  Using protocol version 77
> 2015-01-03 13:42:13.495016 I  MythUIWebBrowser: Loading css from - 
> file:///usr/share/mythtv/themes/default/htmls/mythbrowser.css
> 2015-01-03 13:42:13.495682 E  MythUIWebBrowser: failed to find our 
> parent screen
> 2015-01-03 13:42:13.497385 I  MythUIWebBrowser: enabling plugins
> 2015-01-03 13:42:13.536562 I  Current MythTV Schema Version 
> (DBSchemaVer): 1317
> 2015-01-03 13:42:13.617565 W  ThemeInfo: Unable to open themeinfo.xml 
> for /usr/share/mythtv/themes/BlackCurves-OSD/themeinfo.xml
> 2015-01-03 13:42:13.617586 E  ThemeInfo: The theme 
> (/usr/share/mythtv/themes/BlackCurves-OSD) is missing a themeinfo.xml 
> file.
> 2015-01-03 13:42:13.618377 W  ThemeInfo: Unable to open themeinfo.xml 
> for /usr/share/mythtv/themes/Gray-OSD/themeinfo.xml
> 2015-01-03 13:42:13.618388 E  ThemeInfo: The theme 
> (/usr/share/mythtv/themes/Gray-OSD) is missing a themeinfo.xml file.
> 2015-01-03 13:42:13.957794 N  Registering Internal as a media playback 
> plugin.
> 2015-01-03 13:42:14.076378 I  Loading en_us translation for module 
> mytharchive
> 2015-01-03 13:42:14.083669 N  Registering WebBrowser as a media 
> playback plugin.
> 2015-01-03 13:42:14.083755 I  Loading en_us translation for module 
> mythbrowser
> 2015-01-03 13:42:14.120082 I  Loading en_us translation for module 
> mythgallery
> 2015-01-03 13:42:14.128899 I  Loading en_us translation for module 
> mythgame
> 2015-01-03 13:42:14.136057 I  Current MythMusic Schema Version 
> (MusicDBSchemaVer): 1020
> 2015-01-03 13:42:14.164766 I  Loading en_us translation for module 
> mythmusic
> 2015-01-03 13:42:14.169016 I  Loading en_us translation for module 
> mythnetvision
> 2015-01-03 13:42:14.175013 I  Loading en_us translation for module 
> mythnews
> 2015-01-03 13:42:14.183112 I  Loading en_us translation for module 
> mythweather
> 2015-01-03 13:42:14.185409 I  Listening on TCP 127.0.0.1:6546
> 2015-01-03 13:42:14.185489 I  Listening on TCP 10.6.2.52:6546
> 2015-01-03 13:42:14.185586 I  Listening on TCP [::1]:6546
> 2015-01-03 13:42:14.185673 I  Listening on TCP 
> [fe80::6a05:caff:fe2a:b43a%eth0]:6546
> 2015-01-03 13:42:14.265635 N  Found mainmenu.xml for theme 'Mythbuntu'
> 2015-01-03 13:42:14.273643 I  Registering HouseKeeperTask 
> 'HardwareProfiler'.
> 2015-01-03 13:42:14.278791 I  Starting HouseKeeper.
> 2015-01-03 13:42:14.646839 I  Bonjour: Service registration complete: 
> name 'Mythfrontend on htpc-frontend' type '_mythfrontend._tcp.' 
> domain: 'local.'
> 2015-01-03 13:42:34.909405 N  Suspending idle timer
> 2015-01-03 13:42:34.915392 I  TV: Creating TV object
> 2015-01-03 13:42:35.005405 N  Suspending idle timer
> 2015-01-03 13:42:35.012742 I  TV: Created TvPlayWindow.
> 2015-01-03 13:42:35.092277 I  TV: Attempting to change from None to 
> WatchingVideo
> 2015-01-03 13:42:35.454255 I  Pulse: PulseAudio suspend OK
> 2015-01-03 13:42:35.529623 N  AudioPlayer: Enabling Audio
> 2015-01-03 13:42:45.577838 W 
> RingBuf(myth://Videos@10.6.2.51:6543/Movies/Le Mans/Le Mans.mkv): 
> Peek() requested 2048 bytes, but only returning 0
> 2015-01-03 13:42:45.577927 E  Player(0): OpenFile(): Could not read 
> first 2048 bytes of 'myth://Videos@10.6.2.51:6543/Movies/Le Mans/Le 
> Mans.mkv'
> 2015-01-03 13:42:45.578002 E  Player(0): Unable to open video file.
> 2015-01-03 13:42:45.578023 E  playCtx: StartPlaying() Failed to start 
> player
> 2015-01-03 13:42:45.614398 I  TV: Main UI disabled.
> 2015-01-03 13:42:45.614729 I  TV: Entering main playback loop.
> 2015-01-03 13:42:45.640981 I  TV: Exiting main playback loop.
> 2015-01-03 13:42:45.874522 I  Pulse: PulseAudio resume OK
> 2015-01-03 13:42:45.885733 N  Resuming idle timer
> 2015-01-03 13:42:45.885841 N  Resuming idle timer
> 2015-01-03 13:43:21.322417 I  TV: Creating TV object
> 2015-01-03 13:43:21.392612 N  Suspending idle timer
> 2015-01-03 13:43:21.400665 I  TV: Created TvPlayWindow.
> 2015-01-03 13:43:21.514143 I  TV: Attempting to change from None to 
> WatchingLiveTV
> 2015-01-03 13:43:21.516886 I  MythCoreContext: Connecting to backend 
> server: 10.6.2.51:6543 (try 1 of 1)
> 2015-01-03 13:43:21.546840 N  TV: Spawning LiveTV Recorder -- begin
> 2015-01-03 13:43:21.684745 N  TV: Spawning LiveTV Recorder -- end
> 2015-01-03 13:43:21.697249 I  TV: 
> playbackURL(myth://10.6.2.51:6543/1063_20150103194321.mpg) 
> cardtype(DUMMY)
> 2015-01-03 13:43:21.892824 I  Pulse: PulseAudio suspend OK
> 2015-01-03 13:43:21.929837 N  AudioPlayer: Enabling Audio
> 2015-01-03 13:43:22.664889 I  VDPAU: Created 2 output surfaces.
> 2015-01-03 13:43:22.664921 I  VDPAU: Version 1
> 2015-01-03 13:43:22.664929 I  VDPAU: Information NVIDIA VDPAU Driver 
> Shared Library  331.38  Wed Jan  8 19:13:15 PST 2014
> 2015-01-03 13:43:22.665051 I  VDPAU: Created VDPAU render device 
> 1920x1080
> 2015-01-03 13:43:22.903960 I  Player(1): Video timing method: USleep 
> with busy wait
> 2015-01-03 13:43:22.904466 I  TV: Created player.
> 2015-01-03 13:43:22.904513 I  TV: Changing from None to WatchingLiveTV
> 2015-01-03 13:43:22.904522 I  TV: State is LiveTV & mctx == ctx
> 2015-01-03 13:43:22.907104 I  TV: UpdateOSDInput done
> 2015-01-03 13:43:22.907123 I  TV: UpdateLCD done
> 2015-01-03 13:43:22.907675 I  TV: ITVRestart done
> 2015-01-03 13:43:22.908643 I  TV: Main UI disabled.
> 2015-01-03 13:43:22.908662 I  Using Idle Timer. 3 minutes
> 2015-01-03 13:43:22.993108 I  TV: Entering main playback loop.
> 2015-01-03 13:43:23.363233 I  VDPAU: Added 2 output surfaces (total 4, 
> max 4)
> 2015-01-03 13:44:15.271333 W 
> RingBuf(myth://10.6.2.51:6543/1063_20150103194412.mpg): Taking too 
> long to be allowed to read..
> 2015-01-03 13:44:16.271483 W 
> RingBuf(myth://10.6.2.51:6543/1063_20150103194412.mpg): Taking too 
> long to be allowed to read..
> 2015-01-03 13:44:17.271625 W 
> RingBuf(myth://10.6.2.51:6543/1063_20150103194412.mpg): Taking too 
> long to be allowed to read..
> 2015-01-03 13:44:18.271791 W 
> RingBuf(myth://10.6.2.51:6543/1063_20150103194412.mpg): Taking too 
> long to be allowed to read..
> 2015-01-03 13:44:19.271954 W 
> RingBuf(myth://10.6.2.51:6543/1063_20150103194412.mpg): Taking too 
> long to be allowed to read..
> 2015-01-03 13:44:20.272107 W 
> RingBuf(myth://10.6.2.51:6543/1063_20150103194412.mpg): Taking too 
> long to be allowed to read..
> 2015-01-03 13:44:21.272258 W 
> RingBuf(myth://10.6.2.51:6543/1063_20150103194412.mpg): Taking too 
> long to be allowed to read..
> 2015-01-03 13:44:22.272405 W 
> RingBuf(myth://10.6.2.51:6543/1063_20150103194412.mpg): Taking too 
> long to be allowed to read..
> 2015-01-03 13:44:23.272556 W 
> RingBuf(myth://10.6.2.51:6543/1063_20150103194412.mpg): Taking too 
> long to be allowed to read..
> 2015-01-03 13:44:23.272586 E 
> RingBuf(myth://10.6.2.51:6543/1063_20150103194412.mpg): Took more than 
> 10 seconds to be allowed to read, aborting.
> 2015-01-03 13:44:23.318451 W 
> RingBuf(myth://10.6.2.51:6543/1063_20150103194412.mpg): Peek() 
> requested 2048 bytes, but only returning 0
> 2015-01-03 13:44:23.318503 E  Player(1): OpenFile(): Could not read 
> first 2048 bytes of 'myth://10.6.2.51:6543/1063_20150103194412.mpg'
> 2015-01-03 13:44:23.318550 E  Player(1): Error opening jump program file
> 2015-01-03 13:44:23.318566 E  Player(1): JumpToProgram failed.
> 2015-01-03 13:44:23.318597 E  Player(1): Unknown recorder error, 
> exiting decoder
> 2015-01-03 13:44:23.363902 I  TV: Attempting to change from 
> WatchingLiveTV to None
> 2015-01-03 13:44:23.369672 W  MythPainter: 28 images not yet 
> de-allocated.
> 2015-01-03 13:44:23.369748 I  VDPAU Painter: Clearing VDPAU painter 
> cache.
> 2015-01-03 13:44:23.644506 I  Pulse: PulseAudio resume OK
> 2015-01-03 13:44:23.700337 I  TV: Changing from WatchingLiveTV to None
> 2015-01-03 13:44:23.700904 I  TV: Exiting main playback loop.
> 2015-01-03 13:44:23.711069 I  TV: Attempting to change from None to 
> WatchingLiveTV
> 2015-01-03 13:44:23.711628 I  MythCoreContext: Connecting to backend 
> server: 10.6.2.51:6543 (try 1 of 1)
> 2015-01-03 13:44:23.730091 N  TV: Spawning LiveTV Recorder -- begin
> 2015-01-03 13:44:23.864431 N  TV: Spawning LiveTV Recorder -- end
> 2015-01-03 13:44:23.876379 I  TV: 
> playbackURL(myth://10.6.2.51:6543/1063_20150103194423.mpg) 
> cardtype(DUMMY)
> 2015-01-03 13:44:23.880732 E  TV: LiveTV not successfully started
> 2015-01-03 13:44:23.881346 I  TV: Main UI disabled.
> 2015-01-03 13:44:23.881382 I  Using Idle Timer. 3 minutes
> 2015-01-03 13:44:23.979243 I  TV: Entering main playback loop.
> 2015-01-03 13:44:24.020365 I  TV: Exiting main playback loop.
> 2015-01-03 13:44:24.110886 N  Resuming idle timer
> Killed
>
> Does anybody have any suggestions on how to troubleshoot this?
>
> Mike B
> _______________________________________________
>
***
I see the same error message whenever I try to watch an in-progress 
recording. Once the recording finishes I can watch with no problem. The 
problem began when I moved the MythTV storage directories, except for 
LiveTV to an NAS. The NAS should be plenty fast but the problem occurs 
regardless of any other network activity. The NAS has 16gb of memory and 
most of that is employed as cache according to top. The only relevant 
log entry I could see in the backend log was a ringbuffer "taking too 
long to be allowed to read." Is there a known problem trying to watch an 
in-progress recording when the storage directory is not on the backend 
proper?

mike

More information about the mythtv-users mailing list