@bill6502
Thank you for pointing out that I missed a parameter. Yes,I have MythTV configured to use the mythtv user.
Here's the command you suggested along with the terminal output:
Code: Select all
root@mythtv:/ # su mythtv
$ /usr/local/bin/mythcommflag --chanid 1021 --starttime 20160110150100 -v commflag,audio,playback --logpath /var/log/mythtv --loglevel debug
MythTV Commercial Flagger, flagging commercials for:
CBS News Sunday Morning
2016-01-11 10:41:44.399023 C mythcommflag version: [5a5e1cd] www.mythtv.org
2016-01-11 10:41:44.399351 C Qt version: compile: 4.8.6, runtime: 4.8.7
2016-01-11 10:41:51.932237 E MythSocket(81910e200:51): ReadStringList: Error, timed out after 7000 ms.
2016-01-11 10:41:51.935442 E MythCoreContext: Timeout connecting event socket to master backend
Those two errors at the end of the terminal output look like they are worth investigating. But, permissions look good:
Code: Select all
$ ls -al /media/tv/1021_20160110150100.mpg
-rw-r--r-- 1 mythtv mythtv 769164776 Jan 10 09:30 /media/tv/1021_20160110150100.mpg
And, here's the log file:
Code: Select all
$ cat /var/log/mythtv/mythcommflag.20160111164144.45695.log
2016-01-11 10:41:44.399023 C [45695/100788] thread_unknown mythcommandlineparser.cpp:2595 (ConfigureLogging) - mythcommflag version: [5a5e1cd] www.mythtv.org
2016-01-11 10:41:44.399351 C [45695/100788] thread_unknown mythcommandlineparser.cpp:2597 (ConfigureLogging) - Qt version: compile: 4.8.6, runtime: 4.8.7
2016-01-11 10:41:44.399371 N [45695/100788] thread_unknown mythcommandlineparser.cpp:2599 (ConfigureLogging) - Enabled verbose msgs: general commflag audio playback
2016-01-11 10:41:44.399570 N [45695/100788] thread_unknown logging.cpp:914 (logStart) - Setting Log Level to LOG_DEBUG
2016-01-11 10:41:44.401200 I [45695/100788] thread_unknown signalhandling.cpp:194 (SetHandlerPrivate) - Setup Interrupt: 2 handler
2016-01-11 10:41:44.401213 I [45695/100788] thread_unknown signalhandling.cpp:194 (SetHandlerPrivate) - Setup Terminated: 15 handler
2016-01-11 10:41:44.401223 I [45695/100788] thread_unknown signalhandling.cpp:194 (SetHandlerPrivate) - Setup Segmentation fault: 11 handler
2016-01-11 10:41:44.401231 I [45695/100788] thread_unknown signalhandling.cpp:194 (SetHandlerPrivate) - Setup Abort trap: 6 handler
2016-01-11 10:41:44.401239 I [45695/100788] thread_unknown signalhandling.cpp:194 (SetHandlerPrivate) - Setup Bus error: 10 handler
2016-01-11 10:41:44.401248 I [45695/100788] thread_unknown signalhandling.cpp:194 (SetHandlerPrivate) - Setup Floating point exception: 8 handler
2016-01-11 10:41:44.401256 I [45695/100788] thread_unknown signalhandling.cpp:194 (SetHandlerPrivate) - Setup Illegal instruction: 4 handler
2016-01-11 10:41:44.401271 I [45695/100788] thread_unknown signalhandling.cpp:194 (SetHandlerPrivate) - Setup Unknown signal: 65 handler
2016-01-11 10:41:44.401346 N [45695/100788] thread_unknown mythdirs.cpp:55 (InitializeMythDirs) - Using runtime prefix = /usr/local
2016-01-11 10:41:44.401367 N [45695/100788] thread_unknown mythdirs.cpp:68 (InitializeMythDirs) - Using configuration directory = /var/lib/mythtv/.mythtv
2016-01-11 10:41:44.401978 I [45695/100788] CoreContext mythcorecontext.cpp:249 (Init) - Assumed character encoding: en_US.UTF-8
2016-01-11 10:41:44.403097 N [45695/100788] CoreContext mythcontext.cpp:504 (LoadDatabaseSettings) - Empty LocalHostName.
2016-01-11 10:41:44.403131 I [45695/100788] CoreContext mythcontext.cpp:512 (LoadDatabaseSettings) - Using localhost value of mythtv
2016-01-11 10:41:44.431476 D [45695/100788] CoreContext mythcontext.cpp:432 (FindDatabase) - FindDatabase() - Success!
2016-01-11 10:41:44.435863 N [45695/100788] CoreContext mythcorecontext.cpp:1272 (InitLocale) - Setting QT default locale to en_US
2016-01-11 10:41:44.436004 I [45695/100788] CoreContext mythcorecontext.cpp:1305 (SaveLocaleDefaults) - Current locale en_US
2016-01-11 10:41:44.436236 N [45695/100788] CoreContext mythlocale.cpp:121 (LoadDefaultsFromXML) - Reading locale defaults from /usr/local/share/mythtv//locales/en_us.xml
2016-01-11 10:41:44.446390 I [45695/100788] CoreContext mythtranslation.cpp:65 (load) - Loading en_us translation for module mythfrontend
2016-01-11 10:41:44.451388 I [45695/100788] CoreContext main.cpp:824 (FlagCommercials) - Using method: 7 from channel 1021
2016-01-11 10:41:44.454456 I [45695/100788] CoreContext fileringbuffer.cpp:183 (OpenFile) - FileRingBuf(/media/tv/1021_20160110150100.mpg): OpenFile(/media/tv/1021_20160110150100.mpg, 2000 ms)
2016-01-11 10:41:44.476201 I [45695/100739] Logger logging.cpp:315 (run) - Added logging to the console
2016-01-11 10:41:44.479644 D [45695/100788] CoreContext filtermanager.cpp:177 (LoadFilterLib) - FilterManager: filters[adjust] = 0x8126af820
2016-01-11 10:41:44.480311 D [45695/100788] CoreContext filtermanager.cpp:177 (LoadFilterLib) - FilterManager: filters[bobdeint] = 0x8126af4c0
2016-01-11 10:41:44.480433 D [45695/100788] CoreContext filtermanager.cpp:177 (LoadFilterLib) - FilterManager: filters[crop] = 0x8126af5e0
2016-01-11 10:41:44.480555 D [45695/100788] CoreContext filtermanager.cpp:177 (LoadFilterLib) - FilterManager: filters[denoise3d] = 0x8126afd30
2016-01-11 10:41:44.480659 D [45695/100788] CoreContext filtermanager.cpp:177 (LoadFilterLib) - FilterManager: filters[fieldorderdoubleprocessdeint] = 0x8126afdf0
2016-01-11 10:41:44.480758 D [45695/100788] CoreContext filtermanager.cpp:177 (LoadFilterLib) - FilterManager: filters[forceyv12] = 0x8126afeb0
2016-01-11 10:41:44.480949 D [45695/100788] CoreContext filtermanager.cpp:177 (LoadFilterLib) - FilterManager: filters[forceyuv422p] = 0x8126aff40
2016-01-11 10:41:44.480960 D [45695/100788] CoreContext filtermanager.cpp:177 (LoadFilterLib) - FilterManager: filters[forcergb24] = 0x814fff040
2016-01-11 10:41:44.480969 D [45695/100788] CoreContext filtermanager.cpp:177 (LoadFilterLib) - FilterManager: filters[forceargb32] = 0x814fff100
2016-01-11 10:41:44.481153 D [45695/100788] CoreContext filtermanager.cpp:177 (LoadFilterLib) - FilterManager: filters[greedyhdeint] = 0x814fff1f0
2016-01-11 10:41:44.481163 D [45695/100788] CoreContext filtermanager.cpp:177 (LoadFilterLib) - FilterManager: filters[greedyhdoubleprocessdeint] = 0x814fff250
2016-01-11 10:41:44.481289 D [45695/100788] CoreContext filtermanager.cpp:177 (LoadFilterLib) - FilterManager: filters[invert] = 0x814fff2e0
2016-01-11 10:41:44.481392 D [45695/100788] CoreContext filtermanager.cpp:177 (LoadFilterLib) - FilterManager: filters[ivtc] = 0x814fff400
2016-01-11 10:41:44.481558 D [45695/100788] CoreContext filtermanager.cpp:177 (LoadFilterLib) - FilterManager: filters[kerneldeint] = 0x814fff4f0
2016-01-11 10:41:44.481568 D [45695/100788] CoreContext filtermanager.cpp:177 (LoadFilterLib) - FilterManager: filters[kerneldoubleprocessdeint] = 0x814fff580
2016-01-11 10:41:44.481737 D [45695/100788] CoreContext filtermanager.cpp:177 (LoadFilterLib) - FilterManager: filters[linearblend] = 0x814fff640
2016-01-11 10:41:44.481866 D [45695/100788] CoreContext filtermanager.cpp:177 (LoadFilterLib) - FilterManager: filters[onefield] = 0x814fff730
2016-01-11 10:41:44.482263 D [45695/100788] CoreContext filtermanager.cpp:177 (LoadFilterLib) - FilterManager: filters[postprocess] = 0x814fff820
2016-01-11 10:41:44.482374 D [45695/100788] CoreContext filtermanager.cpp:177 (LoadFilterLib) - FilterManager: filters[quickdnr] = 0x814fff8e0
2016-01-11 10:41:44.482484 D [45695/100788] CoreContext filtermanager.cpp:177 (LoadFilterLib) - FilterManager: filters[vflip] = 0x814fff9d0
2016-01-11 10:41:44.482685 D [45695/100788] CoreContext filtermanager.cpp:177 (LoadFilterLib) - FilterManager: filters[yadifdeint] = 0x814fffaf0
2016-01-11 10:41:44.482695 D [45695/100788] CoreContext filtermanager.cpp:177 (LoadFilterLib) - FilterManager: filters[yadifdoubleprocessdeint] = 0x814fffb80
2016-01-11 10:41:44.505223 I [45695/100788] CoreContext main.cpp:578 (DoFlagCommercials) - mythcommflag sending COMMFLAG_START notification
2016-01-11 10:41:44.513269 D [45695/100788] CoreContext avformatdecoder.cpp:360 (AvFormatDecoder) - AFD: PlayerFlags: 0x3010b
2016-01-11 10:41:44.514720 I [45695/100788] CoreContext avformatdecoder.cpp:860 (InitByteContext) - AFD: Buffer size: 32768, streamed 0
2016-01-11 10:41:44.640072 I [45695/100753] SendMessage mythcorecontext.cpp:418 (ConnectCommandSocket) - MythCoreContext: Connecting to backend server: 10.10.49.13:6543 (try 1 of 1)
2016-01-11 10:41:44.782414 I [45695/100753] SendMessage mythcorecontext.cpp:1185 (CheckProtoVersion) - Using protocol version 77
2016-01-11 10:41:44.783335 I [45695/100739] Logger logging.cpp:474 (initialTimeout) - Added logging to mythlogserver at TCP:35327
2016-01-11 10:41:45.006445 I [45695/100788] CoreContext avformatdecoder.cpp:1856 (ScanStreams) - AFD: Stream #0, has id 0x31 codec id H264, type Video, bitrate 0 at 0x8124c3a00
2016-01-11 10:41:45.006468 I [45695/100788] CoreContext avformatdecoder.cpp:1856 (ScanStreams) - AFD: Stream #1, has id 0x33 codec id AC3, type Audio, bitrate 384000 at 0x8124c4000
2016-01-11 10:41:45.006475 I [45695/100788] CoreContext avformatdecoder.cpp:1898 (ScanStreams) - AFD: codec AC3 has 6 channels
2016-01-11 10:41:45.006480 I [45695/100788] CoreContext avformatdecoder.cpp:1955 (ScanStreams) - AFD: Looking for decoder for AC3
2016-01-11 10:41:45.006847 I [45695/100788] CoreContext avformatdecoder.cpp:2378 (OpenAVCodec) - AFD: Opened codec 0x812651800, id(AC3) type(Audio)
2016-01-11 10:41:45.013260 I [45695/100788] CoreContext avformatdecoder.cpp:2086 (ScanStreams) - AFD: Audio Track #1, of type (Normal) is A/V stream #1 (id=0x33) and has 6 channels in the English language(6647399).
2016-01-11 10:41:45.013269 I [45695/100788] CoreContext avformatdecoder.cpp:2097 (ScanStreams) - AFD: Trying to select best video track
2016-01-11 10:41:45.013296 I [45695/100788] CoreContext avformatdecoder.cpp:2133 (ScanStreams) - AFD: Selected track #0 (id 0x31 codec id H264, type Video, bitrate 500000 at 0x8124c3a00)
2016-01-11 10:41:45.014461 I [45695/100788] CoreContext videodisplayprofile.cpp:1547 (init_statics) - VDP: decoder<->render support: ffmpeg null xlib xshm xv-blit opengl opengl-lite vdpau nullvdpau
2016-01-11 10:41:45.014470 I [45695/100788] CoreContext videodisplayprofile.cpp:1547 (init_statics) - VDP: decoder<->render support: vdpau vdpau nullvdpau
2016-01-11 10:41:45.018340 N [45695/100788] CoreContext videodisplayprofile.cpp:450 (LoadDB) - VDP: Ignoring profile item 7 (renderer quartz-blit is not supported w/decoder ffmpeg (supported: null,xlib,xshm,xv-blit,opengl,opengl-lite,vdpau,nullvdpau))
2016-01-11 10:41:45.018433 N [45695/100788] CoreContext videodisplayprofile.cpp:467 (LoadDB) - VDP: Ignoring profile item 8 (renderer quartz-blit is not supported w/decoder ffmpeg (supported: null,xlib,xshm,xv-blit,opengl,opengl-lite,vdpau,nullvdpau))
2016-01-11 10:41:45.019190 I [45695/100788] CoreContext videodisplayprofile.cpp:237 (VideoDisplayProfile) - VDP: Accepting: cmp(>= 1280 720) dec(ffmpeg) cpus(1) skiploop(enabled) rend(xv-blit) osd(softblend) osdfade(disabled) deint(linearblend,linearblend) filt()
2016-01-11 10:41:45.019259 I [45695/100788] CoreContext videodisplayprofile.cpp:237 (VideoDisplayProfile) - VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) skiploop(enabled) rend(xv-blit) osd(softblend) osdfade(enabled) deint(greedyhdoubleprocessdeint,kerneldeint) filt()
2016-01-11 10:41:45.019285 I [45695/100788] CoreContext videodisplayprofile.cpp:406 (LoadBestPreferences) - VDP: LoadBestPreferences(2048x2048, 0)
2016-01-11 10:41:45.019329 I [45695/100788] CoreContext videodisplayprofile.cpp:406 (LoadBestPreferences) - VDP: LoadBestPreferences(2048x2048, 60)
2016-01-11 10:41:45.019442 I [45695/100788] CoreContext videodisplayprofile.cpp:406 (LoadBestPreferences) - VDP: LoadBestPreferences(1280x540, 60)
2016-01-11 10:41:45.019572 I [45695/100788] CoreContext avformatdecoder.cpp:2262 (ScanStreams) - AFD: Using 1 CPUs for decoding
2016-01-11 10:41:45.019589 I [45695/100788] CoreContext avformatdecoder.cpp:1335 (InitVideoCodec) - AFD: InitVideoCodec() 0x812651300 id(H264) type (Video).
2016-01-11 10:41:45.019630 I [45695/100788] CoreContext avformatdecoder.cpp:1247 (normalized_fps) - AFD: Selected FPS is 29.97 (avg 29.97 codec 29.97 container 90000 estimated 29.97)
2016-01-11 10:41:45.019722 I [45695/100788] CoreContext mythplayer.cpp:1748 (SetFrameInterval) - Player(0): SetFrameInterval ps:1 scan:3
2016-01-11 10:41:45.019741 I [45695/100788] CoreContext mythplayer.cpp:670 (detectInterlace) - Player(0): detectInterlace(Detect Scan, Interlaced Scan, 29.97, 540) ->Interlaced Scan
2016-01-11 10:41:45.020269 I [45695/100788] CoreContext avformatdecoder.cpp:2274 (ScanStreams) - AFD: Using ffmpeg for video decoding
2016-01-11 10:41:45.020570 I [45695/100788] CoreContext avformatdecoder.cpp:2378 (OpenAVCodec) - AFD: Opened codec 0x812651300, id(H264) type(Video)
2016-01-11 10:41:45.021092 I [45695/100788] CoreContext avformatdecoder.cpp:4301 (AutoSelectAudioTrack) - AFD: Selected track 1: English AC3 5.1ch (A/V Stream #1)
2016-01-11 10:41:45.021110 I [45695/100788] CoreContext avformatdecoder.cpp:5017 (SetupAudioStream) - AFD: Audio data is planar
2016-01-11 10:41:45.021133 I [45695/100788] CoreContext avformatdecoder.cpp:5059 (SetupAudioStream) - AFD: Initializing audio parms from audio track #1
2016-01-11 10:41:45.021162 I [45695/100788] CoreContext avformatdecoder.cpp:5065 (SetupAudioStream) - AFD: Audio format changed
from id(NONE) -1Hz -1ch -1bps (profile 0) to id( AC3) 48000Hz 6ch 32bps (profile 0)
2016-01-11 10:41:45.021223 I [45695/100788] CoreContext decoderbase.cpp:1117 (AutoSelectTrack) - Dec: Selected track #1 (type 2) in the Unknown language(0)
2016-01-11 10:41:45.021240 I [45695/100788] CoreContext decoderbase.cpp:348 (SyncPositionMap) - Dec: Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
2016-01-11 10:41:45.022810 I [45695/100788] CoreContext decoderbase.cpp:391 (SyncPositionMap) - Dec: SyncPositionMap prerecorded, from DB: 0 entries
2016-01-11 10:41:51.932237 E [45695/100735] MythSocketThread(-1) mythsocket.cpp:790 (ReadStringListReal) - MythSocket(81910e200:51): ReadStringList: Error, timed out after 7000 ms.
2016-01-11 10:41:51.933145 N [45695/100735] MythSocketThread(-1) mythcorecontext.cpp:1138 (connectionClosed) - Event socket closed. No connection to the backend.
2016-01-11 10:41:51.935442 E [45695/100753] SendMessage mythcorecontext.cpp:530 (ConnectEventSocket) - MythCoreContext: Timeout connecting event socket to master backend
2016-01-11 10:41:51.973544 I [45695/100765] SendMessage mythcorecontext.cpp:418 (ConnectCommandSocket) - MythCoreContext: Connecting to backend server: 10.10.49.13:6543 (try 1 of 1)
$
Unfortunately, I have no idea what it is trying to telll me.
- nello