Print

Please login or register.
1 Hour 1 Day 1 Week 1 Month Forever
Login with username, password and session length

[ixne]

Hey all,

I've been having an intermittent problem with freezing when streaming from my Windows box to a dedicated headless debian server.  It's been happening since upgrading to MC27 and has been very difficult to pin down. It might have occurred with MC26 but I was changing my setup around a lot and could have been buried in the shuffle.

My windows system is running 27.0.79.  My linux system is debian installed on an ancient but reliable Macmini 2,1 and is running 27.0.84, connected to a DAC which is in turn connected to my main system.  Both machines are connected directly to my router via lan. I use a library local to my windows box to send both mp3 and FLAC files to the linux box.  After anywhere from 30 minutes to hours, playback will stop mid-song and the linux MC instance will (partially) freeze. This will happen in mediaserver mode or if I just load an MC instance from the linux desktop.

On the linux side, If I log in via VNCserver I see that the song progress bar is frozen and all toolbar elements are nonresponsive, although I can scroll the main window.

On the windows side, nothing happens unless I select the linux player from the sidebar.  The program then freezes for ~5 seconds with spinning pinwheel. The linux player then drops from the list and reappears a second later.  If I keep clicking on it, same thing happens ad infinitum.  There doesn't seem to be any pattern with the kind of media or file played, if I kill the program and restart the same file or playlist will work just fine.

The only relevant log instance on the linux side I can find is:

74467635: 140050033444608: Reader: CLinuxINetReader::Open: Start
74467636: 140050033444608: Reader: CLinuxINetReader::OpenRange: Partial Content Request = 0
74467636: 140050033444608: Reader: CLinuxINetReader::OpenRange:  Opening URL, Position 0
74467638: 140050033444608: Reader: CLinuxINetReader::OpenRange:  Elapsed MS 0.9175, initial number of headers = 2, number of loops = 0
74467638: 140050033444608: Reader: CLinuxINetReader::OpenRange:  Open Succeeded. Elapsed MS 0.9867, number of headers = 2, number of loops = 0
74467638: 140050033444608: Reader: CLinuxINetReader::OpenRange:  Content-Length = -1, Content-Range = , Content-Type = , Content-Encoding =
74467638: 140050033444608: Reader: CLinuxINetReader::OpenRange:  Range request length not found or derived. Content-Length = -1
74467638: 140050033444608: Reader: CLinuxINetReader::Open: Finish (2 ms)
74467638: 140050033444608: Reader: CLinuxINetReader::GetLength: Current Length -1
74467638: 140050033444608: Reader: CLinuxINetReader::fill_buffer: Exiting after fill: still_running 0 buffer filled 0, wanted 65536
74467638: 140050033444608: Reader: CLinuxINetReader::Read: Empty read, requested 65536 bytes!
74467638: 140050033444608: Reader: CLinuxINetReader::Cancel: Start
74467638: 140050033444608: Reader: CLinuxINetReader::Cancel: Finish (0 ms)
74467639: 140050033444608: Reader: CLinuxINetReader::Close: This 7f6034057f20, CleanClose 5

On the windows side, the corresponding log error seems to be (debllama below is my linux box):

3841651: 7804: Sharing Plugins: CHTTPListenerWorker::HandleConnection: Start
3841651: 7804: Sharing Plugins: CHTTPListenerWorker::HandleRequest: UDP: 192.168.1.148: : http://127.0.0.1
3841651: 7804: Sharing Plugins: CHTTPListenerWorker::HandleConnection: Finish (0 ms)
3841651: 3848: Sharing Plugins: CUDPSocketReader::Accept: Start
3841651: 3848: Sharing Plugins: CUDPSocketReader::Accept: Description: CListenerBase::CListenerBase [UDP]
3841751: 3848: Sharing Plugins: CUDPSocketReader::Accept: Finish (99 ms)
3841751: 7916: Sharing Plugins: CHTTPListenerWorker::HandleConnection: Start
3841751: 7916: Sharing Plugins: CHTTPListenerWorker::HandleRequest: UDP: 192.168.1.148: : http://127.0.0.1
3841751: 7916: Sharing Plugins: CHTTPListenerWorker::HandleConnection: Finish (0 ms)
3841751: 3848: Sharing Plugins: CUDPSocketReader::Accept: Start
3841751: 3848: Sharing Plugins: CUDPSocketReader::Accept: Description: CListenerBase::CListenerBase [UDP]
3846234: 4620: Reader: CWinINetReader::Open: ConnectToURL failed, timed out (6000 ms), URL=http://192.168.1.148:52101/AVTransport/control
3846234: 4620: Reader: CWinINetReader::Close: Start
3846234: 12828: Reader: CWinINetReader::DownloadFromHTTPURL: Canceled (checked after HttpSendRequest)
3846234: 12828: Reader: CWinINetReader::DownloadFromHTTPURL: Finish (6077 ms)
3846234: 12828: Reader: CWinINetReader::Thread: Finish (6077 ms)
3846265: 4620: Reader: CWinINetReader::Close: Finish (30 ms)
3846265: 4620: Reader: CWinINetReader::Open: Finish (6108 ms)
3846265: 4620: General: CDLNADeviceServiceCaller::CallServiceFunction: DLNA function call failed or timeout! url = http://192.168.1.148:52101/AVTransport/control function = GetTransportInfo execution time = 6108.14 ms of 6000 ms.
3846265: 4620: Sharing Plugins: CDLNADeviceConnectionStatus::NotifyCommunicatedWithDevice: Error communicating with device: debllama
3846265: 4620: Sharing Plugins: CDLNADeviceConnectionStatus::SetConnected: Start
3846265: 4620: Sharing Plugins: CDLNADeviceConnectionStatus::SetConnected: Changing connected to 0 for device: debllama
3846265: 4620: Sharing Plugins: CDLNADeviceConnectionStatus::SetConnected: Finish (0 ms)
3846265: 4620: Playback: CPlayerZoneDisplayInfoUpdateThread::Thread: Finish (6108 ms)
3846281: 8636: Playback: CPlayerZoneDisplayInfoUpdateThread::Destructor: Finish (6123 ms)
3846281: 8636: Playback: CPlayerZoneDisplayInfoUpdateThreadPause::~CPlayerZoneDisplayInfoUpdateThreadPause: Start
3846281: 8636: Playback: CPlayerZoneDisplayInfoUpdateThreadPause::~CPlayerZoneDisplayInfoUpdateThreadPause: Finish (0 ms)
3846281: 8636: Playback: CPlayerZone::Stop: Finish (6123 ms)
3846281: 13908: Playback: CPlayerZoneDisplayInfoUpdateThread::Thread: Start
3846281: 13908: Playback: CPlayerZoneDisplayInfoUpdateThread::Thread: Zone: debllama (id: 10007)


Any thoughts?

[bob]

Make sure you have the latest build on the mini. The response time of >6 secs to the DLNA control function is way excessive.

[ixne]

Check.  In fact, after trying for a while to further isolate the problem (somewhat difficult for an intermittent issue), I decided to make a clean install.  That completely fixed the problem...for three days.  It has slowly crept back and becomes more and more frequent over time.  I've had to take a break but will no doubt attack it again when I have more energy.

[bob]

Check.  In fact, after trying for a while to further isolate the problem (somewhat difficult for an intermittent issue), I decided to make a clean install.  That completely fixed the problem...for three days.  It has slowly crept back and becomes more and more frequent over time.  I've had to take a break but will no doubt attack it again when I have more energy.

It sounds like something is eating your memory.
Try a top command on the mini to see.
You can sort by memory use and cpu use with top.

[ixne]

For those of you out there who may be struggling with this issue I think I found a solution. I gave up trying to decipher the log, lived with the problem for a while, then after messing with about every remotely relevant options setting I seemed to have solved the issue by changing to polling mode.

Right click on the remote device -> DNLA Controller options -> Ignore Transfer Events (use polling mode)

Not sure if it's a bug or just a quirk of how my system is set up, but it seems to have fixed the problem.  I'd be interested in knowing more on transfer events and polling mode if someone has a word to say about it, but it's just academic at this point.