Opened 10 years ago

Closed 10 years ago

#6894 closed defect (duplicate)

MythSocket Errors when remove recording or auto expire.

Reported by: degrotepappa@… Owned by: Isaac Richards
Priority: minor Milestone: 0.22
Component: MythTV - General Version: head
Severity: medium Keywords:
Cc: Ticket locked: no

Description (last modified by sphery)

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@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.

Change History (2)

comment:1 Changed 10 years ago by sphery

Description: modified (diff)

See, also, #6516

comment:2 Changed 10 years ago by danielk

Resolution: duplicate
Status: newclosed

dup #6516

Note: See TracTickets for help on using tickets.