2006-03-25, 11:41 PM
I have noticed that, since upgrading to 96.12, when I am listening to Net Radio through the MVP I will intermittently get a spontaneous reboot.
The latest occurrence of this was just a few minutes ago. I was listening to a Net Radio shoutcast station and also watching a picture slide show using "Picture Library".
I have captured an pasted a log segment below, but before we go there I also wanted to state that there have been many times since updating to 96.12 where I would turn on my MVP and it would stay at the initial screen "Contact Servers ... " for a couple minutes until I just got tired of waiting and would go down to my computer and shut down the Media MVP Server and then restart it to get the MVP to respond.
While this sometimes happened with the MVP in the previous version (95.14), it was only very occasionally, whereas with 96.12 it seems to happen at least once per day.
In any event, here is the log snippet for what I described in the first paragraph above. Another thing I will point out about this event is that when the MVP did come back on line, nothing except the menu functions worked. If I tried to play Net Radio or a Video it would not play anything. I ultimately had to manually restart the MVP Server on the PC.
The latest occurrence of this was just a few minutes ago. I was listening to a Net Radio shoutcast station and also watching a picture slide show using "Picture Library".
I have captured an pasted a log segment below, but before we go there I also wanted to state that there have been many times since updating to 96.12 where I would turn on my MVP and it would stay at the initial screen "Contact Servers ... " for a couple minutes until I just got tired of waiting and would go down to my computer and shut down the Media MVP Server and then restart it to get the MVP to respond.
While this sometimes happened with the MVP in the previous version (95.14), it was only very occasionally, whereas with 96.12 it seems to happen at least once per day.
In any event, here is the log snippet for what I described in the first paragraph above. Another thing I will point out about this event is that when the MVP did come back on line, nothing except the menu functions worked. If I tried to play Net Radio or a Video it would not play anything. I ultimately had to manually restart the MVP Server on the PC.
Quote:3/25/2006 4:24:06 PM.312 VERBOSE [1004] MVP request processed
3/25/2006 4:24:06 PM.406 VERBOSE [1005] Request 2000 bytes (file size:29163520 file location: 28970000)
3/25/2006 4:24:06 PM.437 VERBOSE [1004] MVP data read: 1 (9)
3/25/2006 4:24:06 PM.437 VERBOSE [1004] MVP request processed
3/25/2006 4:24:06 PM.531 VERBOSE [1005] Request 2000 bytes (file size:29163520 file location: 28972000)
3/25/2006 4:24:06 PM.562 VERBOSE [1004] MVP data read: 1 (8)
3/25/2006 4:24:06 PM.562 VERBOSE [1004] Responding to ping
3/25/2006 4:24:06 PM.562 VERBOSE [1004] getValue cached value: /settings/DisableHauppaugeMVPScreenSaver : true
3/25/2006 4:24:06 PM.562 VERBOSE [1004] frame sent
3/25/2006 4:24:06 PM.562 VERBOSE [1004] MVP request processed
3/25/2006 4:24:06 PM.562 VERBOSE [1004] MVP data read: 1 (0)
3/25/2006 4:24:06 PM.562 VERBOSE [1004] MVP request processed
3/25/2006 4:24:06 PM.562 VERBOSE [1004] MVP data read: 1 (0)
3/25/2006 4:24:06 PM.562 VERBOSE [1004] MVP request processed
3/25/2006 4:24:06 PM.562 VERBOSE [1004] MVP data read: 1 (0)
3/25/2006 4:24:06 PM.562 VERBOSE [1004] MVP request processed
3/25/2006 4:24:06 PM.562 VERBOSE [1004] MVP data read: 1 (1)
3/25/2006 4:24:06 PM.562 VERBOSE [1004] MVP request processed
3/25/2006 4:24:06 PM.562 VERBOSE [1004] MVP data read: 1 (186)
3/25/2006 4:24:06 PM.562 VERBOSE [1004] MVP request processed
3/25/2006 4:24:06 PM.562 VERBOSE [1004] MVP data read: 1 (27)
3/25/2006 4:24:06 PM.562 VERBOSE [1004] MVP request processed
3/25/2006 4:24:06 PM.562 VERBOSE [1004] MVP data read: 1 (176)
3/25/2006 4:24:06 PM.562 VERBOSE [1004] MVP request processed
3/25/2006 4:24:06 PM.562 VERBOSE [1004] MVP data read: 1 (0)
3/25/2006 4:24:06 PM.562 VERBOSE [1004] MVP request processed
3/25/2006 4:24:06 PM.562 VERBOSE [1004] MVP data read: 1 (0)
3/25/2006 4:24:06 PM.562 VERBOSE [1004] MVP request processed
3/25/2006 4:24:06 PM.671 VERBOSE [1005] Request 2000 bytes (file size:29163520 file location: 28974000)
3/25/2006 4:24:06 PM.703 VERBOSE [1004] MVP data read: 1 (9)
3/25/2006 4:24:06 PM.703 VERBOSE [1004] MVP request processed
3/25/2006 4:24:06 PM.796 VERBOSE [1005] Request 2000 bytes (file size:29163520 file location: 28976000)
3/25/2006 4:24:06 PM.828 VERBOSE [1004] MVP data read: 1 (9)
3/25/2006 4:24:06 PM.828 VERBOSE [1004] MVP request processed
3/25/2006 4:24:06 PM.921 VERBOSE [1005] Request 2000 bytes (file size:29163520 file location: 28978000)
3/25/2006 4:24:06 PM.953 VERBOSE [1004] MVP data read: 1 (9)
3/25/2006 4:24:06 PM.953 VERBOSE [1004] MVP request processed
3/25/2006 4:24:07 PM.015 VERBOSE [1005] Request 2000 bytes (file size:29163520 file location: 28980000)
3/25/2006 4:24:07 PM.046 VERBOSE [1004] MVP data read: 1 (9)
3/25/2006 4:24:07 PM.046 VERBOSE [1004] MVP request processed
3/25/2006 4:24:07 PM.156 VERBOSE [1005] Request 2000 bytes (file size:29163520 file location: 28982000)
3/25/2006 4:24:07 PM.171 VERBOSE [1006] frame sent
3/25/2006 4:24:07 PM.187 VERBOSE [1004] MVP data read: 1 (9)
3/25/2006 4:24:07 PM.187 VERBOSE [1004] MVP request processed
3/25/2006 4:24:13 PM.078 ERROR [1006] MVP Timer Refresh error: An existing connection was forcibly closed by the remote host
3/25/2006 4:24:13 PM.078 ERROR [1006] at System.Net.Sockets.Socket.Send(Byte[] buffer, Int32 offset, Int32 size, SocketFlags socketFlags)
at System.Net.Sockets.Socket.Send(Byte[] buffer)
at xae10db17d26ba033.xbb1566157f5f8b1f.xe2575a347ffe125c(Boolean x61447f3b8bf39c72)
at xae10db17d26ba033.xbb1566157f5f8b1f.xb1cbc922a07ef39b(Object xdf2e3583f942db7b, EventArgs xc4f45905cb1fc7ba)
3/25/2006 4:24:27 PM.218 VERBOSE [1004] MVP data taking too long, connection must have been dropped!
3/25/2006 4:24:27 PM.218 VERBOSE [1004] eventText=
3/25/2006 4:24:27 PM.218 VERBOSE [1004] type= MVPDisconnected
3/25/2006 4:24:27 PM.218 ERROR [1004] MVPWorkerThread - Connection lost, waiting a new one
3/25/2006 4:24:27 PM.218 VERBOSE [1004] eventText=
3/25/2006 4:24:27 PM.218 VERBOSE [1004] type= CurrentChannelName
3/25/2006 4:24:27 PM.218 VERBOSE [1004] eventText=
3/25/2006 4:24:27 PM.218 VERBOSE [1004] type= CurrentVideoTrack
3/25/2006 4:24:27 PM.218 VERBOSE [1005] MVP media connection recycle requested
3/25/2006 4:24:27 PM.218 VERBOSE [1005] eventText=
3/25/2006 4:24:27 PM.218 VERBOSE [1005] type= CurrentAudioTrack
3/25/2006 4:24:27 PM.218 VERBOSE [1005] current audio track!
3/25/2006 4:24:27 PM.218 VERBOSE [1005] eventText=
3/25/2006 4:24:27 PM.218 VERBOSE [1005] type= MusicStopping
3/25/2006 4:24:27 PM.218 VERBOSE [1005] music stopping
3/25/2006 4:24:27 PM.343 VERBOSE [1004] MVP Control thread waiting connection
3/25/2006 4:24:27 PM.343 VERBOSE [1024] Closing shoutcast stream (stopStreaming requested)
3/25/2006 4:24:31 PM.687 VERBOSE [1007] Received MVP Service Locator request
3/25/2006 4:24:31 PM.687 VERBOSE [1007] Parsing MVP Service Locator request
3/25/2006 4:24:31 PM.687 VERBOSE [1007] About construct MVP Service Locator request
3/25/2006 4:24:31 PM.687 VERBOSE [1007] Sending MVP Service Locator response to 6A01A8C0:16882
3/25/2006 4:24:32 PM.140 INFO [1004] MVP Control thread got connection
3/25/2006 4:24:32 PM.140 INFO [1004] MVP mac address: 000DFE0059A1
3/25/2006 4:24:32 PM.140 VERBOSE [1004] MVP initConnection()
3/25/2006 4:24:32 PM.140 VERBOSE [1004] MVP initConnection() - protocol version sent
3/25/2006 4:24:32 PM.140 VERBOSE [1004] MVP initConnection() - protocol version ack: RFB 003.003
3/25/2006 4:24:32 PM.390 VERBOSE [1004] frame sent
3/25/2006 4:24:32 PM.562 VERBOSE [1004] eventText=
3/25/2006 4:24:32 PM.562 VERBOSE [1004] type= MVPConnected