[mythtv-commits] Ticket #1977: mythsocket tries to use unconnected socket to talk to slave backend
MythTV
mythtv at cvs.mythtv.org
Wed Jun 21 04:54:49 UTC 2006
#1977: mythsocket tries to use unconnected socket to talk to slave backend
------------------------------+---------------------------------------------
Reporter: jppoet at gmail.com | Owner: ijr
Type: defect | Status: new
Priority: minor | Milestone:
Component: mythtv | Version:
Severity: medium |
------------------------------+---------------------------------------------
My master backend and slave backend have been running fine. I shutdown
the slave for the day.
At the time I shut down the slave, I see this in the master backend log:
========================================================================================
2006-06-20 09:04:57.546 MythSocket(84b8cc0:12): UpRef: 2
2006-06-20 09:04:57.365 Reschedule requested for id -1.
2006-06-20 09:04:57.573 MythSocket(84b05a8:18): socket closed
2006-06-20 09:04:57.609 MythSocket(84b05a8:18): state change Connected ->
Idle
2006-06-20 09:04:57.650 MythSocket(84b05a8:-1): cb->connectionClosed()
2006-06-20 09:04:57.581 write -> 12 50
BACKEND_MESSAGE[]:[]CLEAR_SETTINGS_CACHE[]:[]empty
2006-06-20 09:04:57.661 MythSocket(84b8cc0:12): DownRef: 1
2006-06-20 09:04:58.895 MythSocket(84b8cc0:12): UpRef: 2
2006-06-20 09:04:58.902 write -> 12 16 QUERY_FREE_SPACE
2006-06-20 09:05:28.910 MythSocket(84b8cc0:12): readStringList: Error,
timeout.
2006-06-20 09:05:28.914 MythSocket(84b8cc0:12): state change Connected ->
Idle
2006-06-20 09:05:28.918 MythSocket(84b8cc0:-1): DownRef: 1
2006-06-20 09:05:28.922 decodeLongLong() called with offset >= list size.
2006-06-20 09:05:28.926 decodeLongLong() called with offset >= list size.
2006-06-20 09:05:28.930 MythSocket(84b8cc0:-1): UpRef: 2
2006-06-20 09:05:28.934 MythSocket(84b8cc0:-1): writeStringList: Error,
called with unconnected socket.
2006-06-20 09:05:28.938 MythSocket(84b8cc0:-1): readStringList: Error,
called with unconnected socket.
2006-06-20 09:05:28.942 MythSocket(84b8cc0:-1): DownRef: 1
2006-06-20 09:05:28.946 decodeLongLong() called with offset >= list size.
2006-06-20 09:05:28.950 decodeLongLong() called with offset >= list size.
2006-06-20 09:05:28.954 MythSocket(84b8cc0:-1): UpRef: 2
2006-06-20 09:05:28.958 MythSocket(84b8cc0:-1): writeStringList: Error,
called with unconnected socket.
2006-06-20 09:05:28.962 MythSocket(84b8cc0:-1): readStringList: Error,
called with unconnected socket.
2006-06-20 09:05:28.966 MythSocket(84b8cc0:-1): DownRef: 1
2006-06-20 09:05:28.970 decodeLongLong() called with offset >= list size.
2006-06-20 09:05:28.974 decodeLongLong() called with offset >= list size.
2006-06-20 09:05:28.978 MythSocket(84b8cc0:-1): UpRef: 2
2006-06-20 09:05:28.982 MythSocket(84b8cc0:-1): writeStringList: Error,
called with unconnected socket.
2006-06-20 09:05:28.986 MythSocket(84b8cc0:-1): readStringList: Error,
called with unconnected socket.
2006-06-20 09:05:28.990 MythSocket(84b8cc0:-1): DownRef: 1
2006-06-20 09:05:28.994 decodeLongLong() called with offset >= list size.
2006-06-20 09:05:28.998 decodeLongLong() called with offset >= list size.
2006-06-20 09:05:31.606 Scheduled 399 items in 4.1 = 1.53 match + 2.60
place
========================================================================================
When I brought the slave back online, I see:
========================================================================================
2006-06-20 18:45:23.517 MythSocket(82e0a58:17): new socket
2006-06-20 18:45:23.522 MythSocket(82e0a58:17): setSocket: 12
2006-06-20 18:45:23.525 MythSocket(82e0a58:12): state change Idle ->
Connected
2006-06-20 18:45:23.529 MythSocket(82e0a58:12): UpRef: 1
2006-06-20 18:45:23.585 MythSocket(82e0a58:12): socket is readable
2006-06-20 18:45:23.589 MythSocket(82e0a58:12): cb->readyRead()
2006-06-20 18:45:23.594 MythSocket(82e0a58:12): UpRef: 2
2006-06-20 18:45:23.598 read <- 12 1232 ANN SlaveBackend cobalt
192.168.2.51[]:[][]:[][]:[][]:[][]:[][]:[...
2006-06-20 18:45:23.602 adding: cobalt as a slave backend server
2006-06-20 18:45:23.606 write -> 12 2 OK
2006-06-20 18:45:23.606 Reschedule requested for id 0.
2006-06-20 18:45:23.610 MythSocket(82e0a58:12): DownRef: 1
2006-06-20 18:45:23.614 MythSocket(82e0a58:12): UpRef: 2
2006-06-20 18:45:23.621 write -> 12 16 QUERY_FREE_SPACE
2006-06-20 18:45:23.701 read <- 12 37
0[]:[]1562701824[]:[]0[]:[]1358073856
2006-06-20 18:45:23.706 MythSocket(82e0a58:12): DownRef: 1
2006-06-20 18:45:23.710 MythSocket(82e0a58:12): UpRef: 2
2006-06-20 18:45:23.714 write -> 12 16 QUERY_FREE_SPACE
2006-06-20 18:45:23.760 read <- 12 37
0[]:[]1562701824[]:[]0[]:[]1358073856
2006-06-20 18:45:23.766 MythSocket(82e0a58:12): DownRef: 1
2006-06-20 18:45:23.770 MythSocket(82e0a58:12): UpRef: 2
2006-06-20 18:45:23.774 write -> 12 16 QUERY_FREE_SPACE
2006-06-20 18:45:23.827 read <- 12 37
0[]:[]1562701824[]:[]0[]:[]1358073856
2006-06-20 18:45:23.830 MythSocket(82e0a58:12): DownRef: 1
2006-06-20 18:45:23.834 MythSocket(82e0a58:12): UpRef: 2
2006-06-20 18:45:23.837 write -> 12 16 QUERY_FREE_SPACE
2006-06-20 18:45:23.885 read <- 12 37
0[]:[]1562701824[]:[]0[]:[]1358073856
2006-06-20 18:45:23.890 MythSocket(82e0a58:12): DownRef: 1
2006-06-20 18:45:26.400 Scheduled 389 items in 2.5 = 0.01 match + 2.50
place
2006-06-20 18:45:26.407 MythSocket(816f2e0:13): UpRef: 2
2006-06-20 18:45:26.410 MythSocket(816f2e0:13): DownRef: 1
2006-06-20 18:45:26.414 MythSocket(816e7e0:16): UpRef: 2
2006-06-20 18:45:26.418 write -> 16 45
BACKEND_MESSAGE[]:[]SCHEDULE_CHANGE[]:[]empty
2006-06-20 18:45:26.422 MythSocket(816e7e0:16): DownRef: 1
2006-06-20 18:45:26.426 MythSocket(84b8cc0:-1): UpRef: 2
2006-06-20 18:45:26.430 MythSocket(84b8cc0:-1): DownRef: 1
2006-06-20 18:45:26.434 MythSocket(82e0a58:12): UpRef: 2
2006-06-20 18:45:26.438 MythSocket(82e0a58:12): DownRef: 1
2006-06-20 18:45:29.613 MythSocket(82e0a58:12): UpRef: 2
2006-06-20 18:45:29.618 write -> 12 16 QUERY_FREE_SPACE
2006-06-20 18:45:29.692 read <- 12 37
0[]:[]1562701824[]:[]0[]:[]1358073856
2006-06-20 18:45:29.698 MythSocket(82e0a58:12): DownRef: 1
2006-06-20 18:45:29.702 MythSocket(82e0a58:12): UpRef: 2
2006-06-20 18:45:29.706 write -> 12 16 QUERY_FREE_SPACE
2006-06-20 18:45:29.750 read <- 12 37
0[]:[]1562701824[]:[]0[]:[]1358073856
2006-06-20 18:45:29.754 MythSocket(82e0a58:12): DownRef: 1
2006-06-20 18:45:29.758 MythSocket(82e0a58:12): UpRef: 2
2006-06-20 18:45:29.762 write -> 12 16 QUERY_FREE_SPACE
2006-06-20 18:45:29.800 read <- 12 37
0[]:[]1562701824[]:[]0[]:[]1358073856
2006-06-20 18:45:29.806 MythSocket(82e0a58:12): DownRef: 1
2006-06-20 18:45:29.810 MythSocket(82e0a58:12): UpRef: 2
2006-06-20 18:45:29.814 write -> 12 16 QUERY_FREE_SPACE
2006-06-20 18:45:29.839 read <- 12 37
0[]:[]1562701824[]:[]0[]:[]1358073856
2006-06-20 18:45:29.842 MythSocket(82e0a58:12): DownRef: 1
2006-06-20 18:45:29.846 AutoExpire: Found 3 recorders w/max rate of 349
MiB/min
2006-06-20 18:45:29.851 AutoExpire: Required Free Space: 2.7 GB w/freq: 5
min
========================================================================================
When I then try to play a show which lives on the slave:
========================================================================================
2006-06-20 22:51:56.051 MythSocket(82be4e0:17): socket is readable
2006-06-20 22:51:56.094 MythSocket(82be4e0:17): cb->readyRead()
2006-06-20 22:51:56.099 MythSocket(82be4e0:17): UpRef: 2
2006-06-20 22:51:56.107 read <- 17 536
QUERY_CHECKFILE[]:[]Nature[]:[]The Vanishing Lions[]:[]The decrea...
2006-06-20 22:51:56.115 MythSocket(84b8cc0:-1): UpRef: 2
2006-06-20 22:51:56.123 MythSocket(84b8cc0:-1): writeStringList: Error,
called with unconnected socket.
2006-06-20 22:51:56.131 MythSocket(84b8cc0:-1): readStringList: Error,
called with unconnected socket.
2006-06-20 22:51:56.139 MythSocket(84b8cc0:-1): DownRef: 1
2006-06-20 22:51:56.147 write -> 17 1 0
2006-06-20 22:51:56.155 MythSocket(82be4e0:17): DownRef: 1
========================================================================================
So, the master tries to use the original socket to "play" a show off the
backend, but it is long gone.
John
--
Ticket URL: <http://svn.mythtv.org/trac/ticket/1977>
MythTV <http://www.mythtv.org/>
MythTV
More information about the mythtv-commits
mailing list