[mythtv-commits] Ticket #6894: MythSocket Errors when remove recording or auto expire.

MythTV mythtv at cvs.mythtv.org
Sun Aug 23 14:43:27 UTC 2009


#6894: MythSocket Errors when remove recording or auto expire.
------------------------------------+---------------------------------------
 Reporter:  degrotepappa at gmail.com  |       Owner:  ijr 
     Type:  defect                  |      Status:  new 
 Priority:  minor                   |   Milestone:  0.22
Component:  MythTV - General        |     Version:  head
 Severity:  medium                  |     Mlocked:  0   
------------------------------------+---------------------------------------
 Using TRUNK Version: 21446

 When I remove manualy a recording or a file from live-tv or when a file
 expired, I got these errors in the mythbackend log:

 009-08-23 16:15:35.412 MythSocket(904e70:-1): writeStringList: Error,
 called with unconnected socket.
 2009-08-23 16:15:35.414 MythSocket(a75850:-1): writeStringList: Error,
 called with unconnected socket.
 2009-08-23 16:15:35.414 MythSocket(8f5430:-1): writeStringList: Error,
 called with unconnected socket.
 2009-08-23 16:15:35.415 MythSocket(a2fe60:-1): writeStringList: Error,
 called with unconnected socket.
 2009-08-23 16:15:35.416 MythSocket(7fb21c042210:-1): writeStringList:
 Error, called with unconnected socket.
 2009-08-23 16:15:35.417 MythSocket(7fb21c04f060:-1): writeStringList:
 Error, called with unconnected socket.
 2009-08-23 16:15:35.418 MythSocket(7fb21c081560:-1): writeStringList:
 Error, called with unconnected socket.
 2009-08-23 16:15:35.419 MythSocket(a58d70:-1): writeStringList: Error,
 called with unconnected socket.
 2009-08-23 16:15:35.420 MythSocket(9093d0:-1): writeStringList: Error,
 called with unconnected socket.
 2009-08-23 16:15:35.420 MythSocket(a53200:-1): writeStringList: Error,
 called with unconnected socket.
 2009-08-23 16:15:35.421 MythSocket(a37fc0:-1): writeStringList: Error,
 called with unconnected socket.
 2009-08-23 16:15:35.422 MythSocket(923c00:-1): writeStringList: Error,
 called with unconnected socket.


 Log with -v all:

 2009-08-23 16:27:27.725 adding: Quad6600 as a client (events: 1)
 2009-08-23 16:27:27.725 MythSocket(7ff464007890:47): write -> 47 2
 OK
 2009-08-23 16:27:27.725 MythSocket(7ff464007890:47): writeBlock(0x9125416,
 10)
 2009-08-23 16:27:27.725 MythSocket(7ff464007890:47): DownRef: 1
 2009-08-23 16:27:27.725 MythSocket(7ff464007890:47): socket is readable
 2009-08-23 16:27:27.725 MythSocket(7ff464007890:47): calling
 m_cb->readyRead()
 2009-08-23 16:27:27.725 MythSocket(7ff464007890:47): UpRef: 2
 2009-08-23 16:27:27.725 MythSocket(7ff464007890:47): readBlock(0x9678184,
 8) called
 2009-08-23 16:27:27.725 MythSocket(7ff464007890:47): readBlock(0x9159304,
 450) called
 2009-08-23 16:27:27.725 MythSocket(7ff464007890:47): read  <- 47 450
 DELETE_RECORDING[]:[]Onbekend[]:[][]:[][]:[][]:[]1110[]:[]110[]:[]100%NL[]:[]100%NL[]:[]myth://192.168.1.101:6543/1110_20090823161424.mpg[]:[]0[]:[]13322244[]:[]1251036864[]:[]1251037800[]:[]0[]:[]0[]:[]0[]:[]Quad6600[]:[]0[]:[]0[]:[]0[]:[]0[]:[]-3[]:[]0[]:[]0[]:[]0[]:[]0[]:[]1251036864[]:[]1251037380[]:[]0[]:[]4[]:[]LiveTV[]:[]0[]:[][]:[][]:[][]:[]1251036864[]:[]0,000000[]:[][]:[]0[]:[]Default[]:[]0[]:[]0[]:[]LiveTV[]:[]0[]:[]0[]:[]0[]:[]0[]:[]0
 2009-08-23 16:27:27.726 MSqlQuery::exec() "SELECT data FROM settings WHERE
 value = 'AutoExpireInsteadOfDelete' AND hostname = 'Quad6600' ;"
 2009-08-23 16:27:27.727 MSqlQuery::exec() "SELECT data FROM settings WHERE
 value = 'AutoExpireInsteadOfDelete' AND hostname IS NULL;"
 2009-08-23 16:27:27.727 MSqlQuery::exec() "SELECT basename FROM recorded
 WHERE chanid = '1110' AND       starttime = '2009-08-23T16:14:24';"
 2009-08-23 16:27:27.728 MSqlQuery::exec() "SELECT DISTINCT dirname FROM
 storagegroup WHERE groupname = 'LiveTV'"
 2009-08-23 16:27:27.728 SG(LiveTV): FindRecordingFile: Searching for
 '1110_20090823161424.mpg'
 2009-08-23 16:27:27.729 SG(LiveTV): FindRecordingDir: Checking '/mythtv'
 for '/mythtv/1110_20090823161424.mpg'
 2009-08-23 16:27:27.729 SG(LiveTV): FindRecordingFile: Found
 '/mythtv/1110_20090823161424.mpg'
 2009-08-23 16:27:27.729 ProgramInfo: GetPlaybackURL: File is local:
 '/mythtv/1110_20090823161424.mpg'
 2009-08-23 16:27:27.729 MSqlQuery::exec() "UPDATE recorded SET
 deletepending = '1' WHERE chanid = '1110' AND starttime =
 '2009-08-23T16:14:24' ;"
 2009-08-23 16:27:27.729 MythSocket(7ff464007890:47): write -> 47 2
 -1
 2009-08-23 16:27:27.729 MythSocket(7ff464007890:47): writeBlock(0x9678184,
 10)
 2009-08-23 16:27:27.730 MythEvent: RECORDING_LIST_CHANGE DELETE 1110
 2009-08-23T16:14:24
 2009-08-23 16:27:27.730 MythSocket(909140:41): UpRef: 2
 2009-08-23 16:27:27.730 MythSocket(909140:41): DownRef: 1
 2009-08-23 16:27:27.730 MythSocket(7ff464007730:46): UpRef: 2
 2009-08-23 16:27:27.730 MythSocket(7ff464007730:46): write -> 46 83
 BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE DELETE 1110
 2009-08-23T16:14:24[]:[]empty
 2009-08-23 16:27:27.730 MythSocket(7ff464007730:46):
 writeBlock(0x140687626500552, 91)
 2009-08-23 16:27:27.730 MythSocket(7ff464007730:46): DownRef: 1
 2009-08-23 16:27:27.730 MythSocket(7ff46401e3d0:-1): UpRef: 2
 2009-08-23 16:27:27.730 MythSocket(7ff46401e3d0:-1): writeStringList:
 Error, called with unconnected socket.
 2009-08-23 16:27:27.730 MythSocket(7ff46401e3d0:-1): DownRef: 1
 2009-08-23 16:27:27.730 MythSocket(7ff464006d50:-1): UpRef: 2
 2009-08-23 16:27:27.730 MythSocket(7ff464006d50:-1): writeStringList:
 Error, called with unconnected socket.
 2009-08-23 16:27:27.730 MythSocket(7ff464006d50:-1): DownRef: 1
 2009-08-23 16:27:27.730 MythSocket(7ff464005f40:-1): UpRef: 2
 2009-08-23 16:27:27.730 MythSocket(7ff464005f40:-1): writeStringList:
 Error, called with unconnected socket.
 2009-08-23 16:27:27.730 MythSocket(7ff464005f40:-1): DownRef: 1
 2009-08-23 16:27:27.730 MythSocket(7ff464008ba0:-1): UpRef: 2
 2009-08-23 16:27:27.730 MythSocket(7ff464008ba0:-1): writeStringList:
 Error, called with unconnected socket.
 2009-08-23 16:27:27.730 MythSocket(7ff464008ba0:-1): DownRef: 1
 2009-08-23 16:27:27.730 MythSocket(7ff464006f70:-1): UpRef: 2
 2009-08-23 16:27:27.731 MythSocket(7ff464006f70:-1): writeStringList:
 Error, called with unconnected socket.
 2009-08-23 16:27:27.731 MythSocket(7ff464006f70:-1): DownRef: 1
 2009-08-23 16:27:27.731 MythSocket(7ff464007890:47): UpRef: 3
 2009-08-23 16:27:27.731 MythSocket(7ff464007890:47): write -> 47 83
 BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE DELETE 1110
 2009-08-23T16:14:24[]:[]empty
 2009-08-23 16:27:27.731 MythSocket(7ff464007890:47):
 writeBlock(0x140687626500552, 91)
 2009-08-23 16:27:27.731 MythSocket(7ff464007890:47): DownRef: 2
 2009-08-23 16:27:27.731 MythSocket(7ff464007890:47): socket is readable
 2009-08-23 16:27:27.731 MythSocket(7ff464007890:47): calling
 m_cb->readyRead()
 2009-08-23 16:27:27.731 MythSocket(7ff464007890:47): UpRef: 3
 2009-08-23 16:27:27.731 MythSocket(7ff464007890:47): readBlock(0x8340440,
 8) called
 2009-08-23 16:27:27.731 MythSocket(7ff464007890:47): readBlock(0x8963560,
 4) called
 2009-08-23 16:27:27.731 MythSocket(7ff464007890:47): read  <- 47 4
 DONE
 2009-08-23 16:27:27.731 MythSocket(7ff464007890:47): state change
 Connected -> Idle
 2009-08-23 16:27:27.731 MSocketDevice::close: Closed socket 47
 2009-08-23 16:27:27.731 MythSocket(7ff464007890:-1): DownRef: 2
 2009-08-23 16:27:27.732 MythSocket(7ff464007890:-1): DownRef: 1
 2009-08-23 16:27:30.731 MSqlQuery::exec() "SELECT
 recorded.chanid,starttime,endtime,title,
 subtitle,description,channel.channum,
 channel.callsign,channel.name,channel.commmethod,
 channel.outputfilters,seriesid,programid,filesize,
 lastmodified,stars,previouslyshown,originalairdate,
 hostname,recordid,transcoder,playgroup,
 recorded.recpriority,progstart,progend,basename,recgroup, storagegroup
 FROM recorded LEFT JOIN channel ON recorded.chanid = channel.chanid WHERE
 recorded.chanid = '1110' AND starttime = '2009-08-23T16:14:24' ;"
 2009-08-23 16:27:30.732 MSqlQuery::exec() "SELECT commflagged, cutlist,
 autoexpire, editing, bookmark, watched, preserve FROM recorded LEFT JOIN
 recordedprogram ON (recorded.chanid = recordedprogram.chanid AND
 recorded.progstart = recordedprogram.starttime) WHERE recorded.chanid =
 '1110' AND recorded.starttime = '2009-08-23T16:14:24' ;"
 2009-08-23 16:27:30.733 MSqlQuery::exec() "SELECT audioprop+0,
 videoprop+0, subtitletypes+0 FROM recorded LEFT JOIN recordedprogram ON
 (recorded.chanid = recordedprogram.chanid AND recorded.progstart =
 recordedprogram.starttime) WHERE recorded.chanid = '1110' AND
 recorded.starttime = '2009-08-23T16:14:24' ;"
 2009-08-23 16:27:30.733 MSqlQuery::exec() "UPDATE jobqueue SET status =
 '320' WHERE chanid = '1110' AND starttime = '2009-08-23T16:14:24' AND
 status = '1';"
 2009-08-23 16:27:30.734 MSqlQuery::exec() "UPDATE jobqueue SET cmds = '4'
 WHERE chanid = '1110' AND starttime = '2009-08-23T16:14:24' AND status <>
 '320';"
 2009-08-23 16:27:30.735 MSqlQuery::exec() "SELECT id FROM jobqueue WHERE
 chanid = '1110' and starttime = '2009-08-23T16:14:24' AND status NOT IN
 ('272','288','304','320');"
 2009-08-23 16:27:30.735 MSqlQuery::exec() "DELETE FROM jobqueue WHERE
 chanid = '1110' AND starttime = '2009-08-23T16:14:24';"
 2009-08-23 16:27:30.736 MSqlQuery::exec() "SELECT data FROM settings WHERE
 value = 'DeletesFollowLinks' AND hostname = 'Quad6600' ;"
 2009-08-23 16:27:30.737 MSqlQuery::exec() "SELECT data FROM settings WHERE
 value = 'DeletesFollowLinks' AND hostname IS NULL;"
 2009-08-23 16:27:30.737 MSqlQuery::exec() "SELECT data FROM settings WHERE
 value = 'TruncateDeletesSlowly' AND hostname = 'Quad6600' ;"
 2009-08-23 16:27:30.737 About to unlink/delete file:
 '/mythtv/1110_20090823161424.mpg'
 2009-08-23 16:27:30.740 About to delete file:
 /mythtv/1110_20090823161424.mpg.64.100x75.png
 2009-08-23 16:27:30.740 About to delete file:
 /mythtv/1110_20090823161424.mpg.64.png
 2009-08-23 16:27:30.741 MSqlQuery::exec() "SELECT basename, hostname,
 storagegroup FROM recordedfile WHERE chanid = '1110' AND starttime =
 '2009-08-23T16:14:24';"
 2009-08-23 16:27:30.742 MSqlQuery::exec() "DELETE FROM recorded WHERE
 chanid = '1110' AND title = 'Onbekend' AND starttime =
 '2009-08-23T16:14:24';"
 2009-08-23 16:27:31.742 MythEvent: RECORDING_LIST_CHANGE DELETE 1110
 2009-08-23T16:14:24
 2009-08-23 16:27:31.742 MythSocket(909140:41): UpRef: 2
 2009-08-23 16:27:31.742 MythSocket(909140:41): DownRef: 1
 2009-08-23 16:27:31.742 MythSocket(7ff464007730:46): UpRef: 2
 2009-08-23 16:27:31.742 MythSocket(7ff464007730:46): write -> 46 83
 BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE DELETE 1110
 2009-08-23T16:14:24[]:[]empty
 2009-08-23 16:27:31.742 MythSocket(7ff464007730:46):
 writeBlock(0x140687626500552, 91)
 2009-08-23 16:27:31.742 MythSocket(7ff464007730:46): DownRef: 1
 2009-08-23 16:27:31.742 MythSocket(7ff46401e3d0:-1): UpRef: 2
 2009-08-23 16:27:31.742 MythSocket(7ff46401e3d0:-1): writeStringList:
 Error, called with unconnected socket.
 2009-08-23 16:27:31.742 MythSocket(7ff46401e3d0:-1): DownRef: 1
 2009-08-23 16:27:31.742 MythSocket(7ff464006d50:-1): UpRef: 2
 2009-08-23 16:27:31.742 MythSocket(7ff464006d50:-1): writeStringList:
 Error, called with unconnected socket.
 2009-08-23 16:27:31.743 MythSocket(7ff464006d50:-1): DownRef: 1
 2009-08-23 16:27:31.743 MythSocket(7ff464005f40:-1): UpRef: 2
 2009-08-23 16:27:31.743 MythSocket(7ff464005f40:-1): writeStringList:
 Error, called with unconnected socket.
 2009-08-23 16:27:31.743 MythSocket(7ff464005f40:-1): DownRef: 1
 2009-08-23 16:27:31.743 MythSocket(7ff464008ba0:-1): UpRef: 2
 2009-08-23 16:27:31.743 MythSocket(7ff464008ba0:-1): writeStringList:
 Error, called with unconnected socket.
 2009-08-23 16:27:31.743 MythSocket(7ff464008ba0:-1): DownRef: 1
 2009-08-23 16:27:31.743 MythSocket(7ff464006f70:-1): UpRef: 2
 2009-08-23 16:27:31.743 MythSocket(7ff464006f70:-1): writeStringList:
 Error, called with unconnected socket.
 2009-08-23 16:27:31.743 MythSocket(7ff464006f70:-1): DownRef: 1
 2009-08-23 16:27:31.743 MythSocket(7ff464007890:-1): UpRef: 2
 2009-08-23 16:27:31.743 MythSocket(7ff464007890:-1): writeStringList:
 Error, called with unconnected socket.
 2009-08-23 16:27:31.743 MythSocket(7ff464007890:-1): DownRef: 1

 Above error came up when I remove some LiveTV recording with MythWeb, but
 for example, when I listen to DVB-T radio, every 30 minutes I got those
 errors to:

 2009-08-23 14:00:02.295 MythSocket(7ffdf0178f00:-1): writeStringList:
 Error, called with unconnected socket.
 2009-08-23 14:00:02.295 MythSocket(7ffdf0179390:-1): writeStringList:
 Error, called with unconnected socket.
 2009-08-23 14:00:02.296 MythSocket(7ffdf0178ba0:-1): writeStringList:
 Error, called with unconnected socket.
 2009-08-23 14:00:02.297 MythSocket(7ffdf0004620:-1): writeStringList:
 Error, called with unconnected socket.
 2009-08-23 14:00:02.298 MythSocket(7ffdf0152a80:-1): writeStringList:
 Error, called with unconnected socket.
 2009-08-23 14:00:03.890 AFD: codec MP2 has 2 channels
 2009-08-23 14:00:03.891 AFD: Opened codec 0x83ff00, id(MP2) type(Audio)
 2009-08-23 14:00:03.935 Preview: Grabbed preview
 '/mythtv/1110_20090823133000.mpg' 640x480 at 1200s
 2009-08-23 14:04:04.168 AutoExpire: CalcParams(): Max required Free Space:
 3.0 GB w/freq: 15 min
 2009-08-23 14:19:04.212 AutoExpire: CalcParams(): Max required Free Space:
 3.0 GB w/freq: 15 min
 2009-08-23 14:30:00.281 Finished recording Onbekend: channel 1110
 2009-08-23 14:30:00.370 AutoExpire: CalcParams(): Max required Free Space:
 3.0 GB w/freq: 15 min
 2009-08-23 14:30:00.656 MythSocket(7ffdf0004870:-1): writeStringList:
 Error, called with unconnected socket.
 2009-08-23 14:30:00.868 MythSocket(7ffdf0177f00:-1): writeStringList:
 Error, called with unconnected socket.
 2009-08-23 14:30:00.918 MythSocket(7ffdf0179620:-1): writeStringList:
 Error, called with unconnected socket.
 2009-08-23 14:30:01.054 MythSocket(7ffdf0178f00:-1): writeStringList:
 Error, called with unconnected socket.

 I'm using OpenSUSE 11.1, KDE 4.3, QT 4.5.2.
 First I thought there was too much IO on the MySQL drive so I moved the
 database to another drive (EXT3) wich stores only movies so there is not
 much IO. The storage disk is formatted with XFS.

-- 
Ticket URL: <http://svn.mythtv.org/trac/ticket/6894>
MythTV <http://www.mythtv.org/>
MythTV


More information about the mythtv-commits mailing list