id,summary,reporter,owner,description,type,status,priority,milestone,component,version,severity,resolution,keywords,cc,mlocked 10574,"Error, timed out after 7000 ms. Unable to change channels in 0.25",iwelcomespam@…,,"I just upgraded to the new 0.25/fixes release and I'm unable to change channels. The front-end always crashes when changing channels with a socket timeout error. When I downgrade to 0.24/fixes I don't see this problem. Here's my FE log {{{ 2012-04-10 10:50:53.499667 C mythfrontend version: master [v0.25-rc-182-gcffed2b] www.mythtv.org 2012-04-10 10:50:53.499697 N Enabled verbose msgs: general 2012-04-10 10:50:53.499732 N Setting Log Level to LOG_INFO 2012-04-10 10:50:53.499808 I Added logging to the console 2012-04-10 10:50:53.499848 I Added syslogging to facility local7 2012-04-10 10:50:53.499859 I Added database logging to table logging 2012-04-10 10:50:53.499965 N Setting up SIGHUP handler 2012-04-10 10:50:53.500113 N Using runtime prefix = /usr 2012-04-10 10:50:53.500135 N Using configuration directory = /home/w/.mythtv 2012-04-10 10:50:53.500328 I Assumed character encoding: en_US.UTF-8 2012-04-10 10:50:53.501244 N Empty LocalHostName. 2012-04-10 10:50:53.501254 I Using localhost value of w 2012-04-10 10:50:53.501499 I Testing network connectivity to '192.168.2.24' 2012-04-10 10:50:53.503041 I Starting IO manager (write) 2012-04-10 10:50:53.503272 I Starting process signal handler 2012-04-10 10:50:53.503285 I Starting IO manager (read) 2012-04-10 10:50:53.503684 I Starting process manager 2012-04-10 10:50:53.703086 E Could not open settings file /home/w/.mythtv/config.xml for writing 2012-04-10 10:50:53.707074 N Setting QT default locale to en_US 2012-04-10 10:50:53.707093 I Current locale en_US 2012-04-10 10:50:53.707161 N Reading locale defaults from /usr/share/mythtv//locales/en_us.xml 2012-04-10 10:50:53.853963 I ScreenSaverX11Private: XScreenSaver support enabled 2012-04-10 10:50:53.854820 I ScreenSaverX11Private: DPMS is disabled. 2012-04-10 10:50:53.875078 N Desktop video mode: 1920x1080 60.000 Hz 2012-04-10 10:50:55.269402 I Listening on TCP 127.0.0.1:6547 2012-04-10 10:50:55.269557 I Listening on TCP 192.168.2.77:6547 2012-04-10 10:50:55.269713 I Listening on TCP [0:0:0:0:0:0:0:1]:6547 2012-04-10 10:50:55.269877 I Listening on TCP [2002:453e:9325:1234:201:80ff:fe76:f453]:6547 2012-04-10 10:50:55.271408 E Could not open settings file /home/w/.mythtv/config.xml for writing 2012-04-10 10:50:55.898961 E RAOP Conn: Failed to read key from: /home/w/.mythtv/RAOPKey.rsa 2012-04-10 10:50:55.898986 E RAOP Device: Aborting startup - no key found. 2012-04-10 10:50:55.906287 I Loading en_us translation for module mythfrontend 2012-04-10 10:50:55.929352 I LIRC: Successfully initialized '/dev/lircd' using '/home/w/.mythtv/lircrc' config 2012-04-10 10:50:55.929477 E JoystickMenuThread: Joystick disabled - Failed to read /home/w/.mythtv/joystickmenurc 2012-04-10 10:50:55.931072 I Binding to UDP 127.0.0.1:6948 2012-04-10 10:50:55.931175 I Binding to UDP 192.168.2.77:6948 2012-04-10 10:50:55.931306 I Binding to UDP [0:0:0:0:0:0:0:1]:6948 2012-04-10 10:50:55.931434 I Binding to UDP [2002:453e:9325:1234:201:80ff:fe76:f453]:6948 2012-04-10 10:50:55.931525 I Binding to UDP 192.168.2.255:6948 2012-04-10 10:50:56.011639 I Using Frameless Window 2012-04-10 10:50:56.011777 I Using Full Screen Window 2012-04-10 10:50:56.247626 I Using the Qt painter 2012-04-10 10:50:56.382642 I Current MythTV Schema Version (DBSchemaVer): 1299 2012-04-10 10:50:56.556891 W ThemeInfo: Unable to open themeinfo.xml for /usr/share/mythtv/themes/BlackCurves-OSD/themeinfo.xml 2012-04-10 10:50:56.556915 E ThemeInfo: The theme (/usr/share/mythtv/themes/BlackCurves-OSD) is missing a themeinfo.xml file. 2012-04-10 10:50:56.558173 W ThemeInfo: Unable to open themeinfo.xml for /usr/share/mythtv/themes/Gray-OSD/themeinfo.xml 2012-04-10 10:50:56.558191 E ThemeInfo: The theme (/usr/share/mythtv/themes/Gray-OSD) is missing a themeinfo.xml file. 2012-04-10 10:50:56.951636 N Registering Internal as a media playback plugin. 2012-04-10 10:50:57.002876 W No plugins directory /usr/lib/mythtv/plugins 2012-04-10 10:50:57.095759 N Found mainmenu.xml for theme 'MythCenter-wide' 2012-04-10 10:50:57.184577 I MythCoreContext: Connecting to backend server: 192.168.2.24:6543 (try 1 of 1) 2012-04-10 10:50:57.192503 I Using protocol version 72 2012-04-10 10:50:57.354155 I Bonjour: Service registration complete: name 'Mythfrontend on w' type '_mythfrontend._tcp.' domain: 'local.' 2012-04-10 10:51:02.672008 I TV: Creating TV object 2012-04-10 10:51:02.703303 N Suspending idle timer 2012-04-10 10:51:02.711733 I TV: Created TvPlayWindow. 2012-04-10 10:51:02.797712 I TV: Attempting to change from None to WatchingLiveTV 2012-04-10 10:51:02.797768 I MythCoreContext: Connecting to backend server: 192.168.2.24:6543 (try 1 of 1) 2012-04-10 10:51:02.799057 I Using protocol version 72 2012-04-10 10:51:02.811800 N TV: Spawning LiveTV Recorder -- begin 2012-04-10 10:51:03.013844 N TV: Spawning LiveTV Recorder -- end 2012-04-10 10:51:03.027035 E SG(Default): Unable to find any Storage Group Directories. Using hardcoded default value of '/mnt/store' 2012-04-10 10:51:03.033629 I TV: playbackURL(myth://192.168.2.24:6543/1632_20120410105102.mpg) cardtype(DUMMY) 2012-04-10 10:51:03.354744 I Pulse: PulseAudio suspend OK 2012-04-10 10:51:03.377323 N AudioPlayer: Enabling Audio 2012-04-10 10:51:03.416525 I VideoOutputXv: XVideo Adaptor Name: 'Intel(R) Textured Video' 2012-04-10 10:51:03.466197 I OSD: Base theme size: 1280x720 2012-04-10 10:51:03.466232 I OSD: Scaling factors: 0.5625x0.8 2012-04-10 10:51:03.513545 I OSD: Base theme size: 1280x720 2012-04-10 10:51:03.513568 I OSD: Scaling factors: 0.5625x0.8 2012-04-10 10:51:03.543305 I Player(0): Video timing method: DRM 2012-04-10 10:51:03.559769 I TV: Created player. 2012-04-10 10:51:03.559829 I TV: Changing from None to WatchingLiveTV 2012-04-10 10:51:03.559853 I TV: State is LiveTV & mctx == ctx 2012-04-10 10:51:03.562138 I TV: UpdateOSDInput done 2012-04-10 10:51:03.562163 I TV: UpdateLCD done 2012-04-10 10:51:03.563086 I TV: ITVRestart done 2012-04-10 10:51:03.577761 I TV: Main UI disabled. 2012-04-10 10:51:03.578327 I TV: Entering main playback loop. 2012-04-10 10:51:03.619748 E SG(Default): Unable to find any Storage Group Directories. Using hardcoded default value of '/mnt/store' 2012-04-10 10:51:03.625924 I Using protocol version 72 2012-04-10 10:51:03.627204 I Using protocol version 72 Running DIL (3.22.0) Version DtsDeviceOpen: Opening HW in mode 0 Scaling command param 0x0,ctx_scal:0x0 2012-04-10 10:51:05.488169 I CrystalHD: Device: BCM70015 2012-04-10 10:51:05.488201 I CrystalHD: Library : 22.0.5635 2012-04-10 10:51:05.488215 I CrystalHD: Driver : 10.0.3204448419 2012-04-10 10:51:05.488227 I CrystalHD: Firmware: 60.39.3036836803 2012-04-10 10:51:05.780992 I VideoOutputXv: XVideo Adaptor Name: 'Intel(R) Textured Video' 2012-04-10 10:51:05.864864 I OSD: Base theme size: 1280x720 2012-04-10 10:51:05.864893 I OSD: Scaling factors: 1x1 2012-04-10 10:51:05.913782 I AFD: Opened codec 0xffffffffaf469e50, id(H264) type(Video) 2012-04-10 10:51:05.913800 I AFD: codec AC3 has 6 channels 2012-04-10 10:51:05.914280 I AFD: Opened codec 0xffffffffa8168550, id(AC3) type(Audio) 2012-04-10 10:51:05.976530 I AO: Opening audio device 'hw:CARD=Intel,DEV=0' ch 2(6) sr 48000 sf signed 32 bit reenc 0 2012-04-10 10:51:05.991815 E ALSA: no playback control PCM found on mixer device default 2012-04-10 10:51:05.991831 E ALSA: Unable to open audio mixer. Volume control disabled 2012-04-10 10:51:06.045470 N AFD: Resetting byte context eof (livetv 1 was eof 0) 2012-04-10 10:51:06.372198 N Player(0): Waited 105ms for video buffers AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAP 2012-04-10 10:51:06.419689 I CrystalHD: Decoder reported format change. 2012-04-10 10:51:06.488721 N Player(0): Waited 221ms for video buffers UUUAAAAAAAAAAAAAAAAAAAAAAAAAAAAP 2012-04-10 10:51:06.587365 I OSD: Base theme size: 1280x720 2012-04-10 10:51:06.587412 I OSD: Scaling factors: 1x1 2012-04-10 10:51:06.662554 I VideoOutput: Created YV12 OSD. (TV plays fine at this point in HD) ... .. (switching to any other channel) ... 2012-04-10 10:51:44.903092 E MythSocket(8d16cd0:49): readStringList: Error, timed out after 7000 ms. 2012-04-10 10:51:44.903147 E RemoteEncoder::SendReceiveStringList(): No response. 2012-04-10 10:51:44.911317 E LiveTVChain(live-w-2012-04-10T10:51:02): SwitchTo() not switching to current 2012-04-10 10:51:44.920468 E Player(0): Unknown recorder error, exiting decoder 2012-04-10 10:51:44.951521 I TV: Attempting to change from WatchingLiveTV to None 2012-04-10 10:51:45.206999 I Pulse: PulseAudio resume OK 2012-04-10 10:51:45.207757 I MythCoreContext: Connecting to backend server: 192.168.2.24:6543 (try 1 of 1) 2012-04-10 10:51:45.209075 I Using protocol version 72 2012-04-10 10:51:52.210917 E MythSocket(92e42a8:49): readStringList: Error, timed out after 7000 ms. 2012-04-10 10:51:52.210976 E RemoteEncoder::SendReceiveStringList(): No response. 2012-04-10 10:51:52.211031 I TV: Changing from WatchingLiveTV to None 2012-04-10 10:51:52.211094 I MythCoreContext: Connecting to backend server: 192.168.2.24:6543 (try 1 of 1) 2012-04-10 10:51:52.212366 I Using protocol version 72 2012-04-10 10:51:52.213213 I TV: Exiting main playback loop. 2012-04-10 10:51:52.229322 I TV: Attempting to change from None to WatchingLiveTV 2012-04-10 10:51:52.229356 I MythCoreContext: Connecting to backend server: 192.168.2.24:6543 (try 1 of 1) 2012-04-10 10:51:52.230331 I Using protocol version 72 2012-04-10 10:51:52.240500 N TV: Spawning LiveTV Recorder -- begin 2012-04-10 10:51:59.242367 E MythSocket(ffffffffa5354e08:49): readStringList: Error, timed out after 7000 ms. 2012-04-10 10:51:59.242447 E RemoteEncoder::SendReceiveStringList(): No response. 2012-04-10 10:51:59.242484 N TV: Spawning LiveTV Recorder -- end 2012-04-10 10:51:59.243712 E GetEntryAt(-1) failed. 2012-04-10 10:51:59.243742 E It appears that your backend may be misconfigured. Check your backend logs to determine whether your capture cards, lineups, channels, or storage configuration are reporting errors. This issue is commonly caused by failing to complete all setup steps properly. You may wish to review the documentation for mythtv-setup. 2012-04-10 10:51:59.243854 E EntryToProgram(0@Wed Dec 31 16:00:00 1969) failed to get pginfo 2012-04-10 10:51:59.243877 E TV: HandleStateChange(): LiveTV not successfully started 2012-04-10 10:51:59.243941 E TV: Invalid Remote Encoder 2012-04-10 10:51:59.243954 E TV: LiveTV not successfully started 2012-04-10 10:51:59.244034 I TV: Main UI disabled. 2012-04-10 10:51:59.244048 I TV: Entering main playback loop. 2012-04-10 10:51:59.247061 I TV: Exiting main playback loop. DtsAllocIoctlData Error 2012-04-10 10:51:59.432875 N Suspending idle timer }}} I'm running Ubuntu 11.10 for both front-end and backend systems connected via gigabit network. uname -a Linux w 3.0.0-17-generic-pae #30-Ubuntu SMP Thu Mar 8 17:53:35 UTC 2012 i686 i686 i386 GNU/Linux ",Bug Report - Hang/Deadlock,closed,minor,unknown,MythTV - General,0.25-fixes,low,Duplicate,,,1