mythcommflag Run from Command Line Never Exits

Have a MythTV related problem? Ask for help from other MythTV users here.

Moderator: Forum Moderators

Post Reply
nello
Junior
Posts: 27
Joined: Sun Mar 08, 2015 7:33 pm
United States of America

mythcommflag Run from Command Line Never Exits

Post by nello » Mon Jan 11, 2016 4:20 am

Running mythcommflag from the command line starts but doesn't exit for some recordings ... even when running in verbose mode there are no errors and this is all I get:

Code: Select all

/usr/local/bin/mythcommflag --chanid 1021 --starttime 20160110150100 --logpath /var/log/mythtv  -v
2016-01-10 21:30:24.782101 C  mythcommflag version:  [5a5e1cd] www.mythtv.org
2016-01-10 21:30:24.782369 C  Qt version: compile: 4.8.6, runtime: 4.8.7
MythTV Commercial Flagger, flagging commercials for:
    CBS News Sunday Morning

Even when mythcommflag is only rebuilding the seektable it still doesn't finish:

Code: Select all

root@mythtv:/var/log/mythtv # /usr/local/bin/mythcommflag --chanid 1021 --starttime 20160110150100  --rebuild  --logpath /var/log/mythtv -v 
2016-01-10 21:35:58.151641 C  mythcommflag version:  [5a5e1cd] www.mythtv.org
2016-01-10 21:35:58.151908 C  Qt version: compile: 4.8.6, runtime: 4.8.7
MythTV Commercial Flagger, building seek table for:
    CBS News Sunday Morning
Rebuild started at Sun Jan 10 21:35:58 2016

Can anyone suggest where I should be looking to debug whatever mythcommflag is stuck on?

Thank you for reading.

- nello

User avatar
bill6502
Developer
Posts: 1377
Joined: Fri Feb 07, 2014 5:28 pm
United States of America

Re: mythcommflag Run from Command Line Never Exits

Post by bill6502 » Mon Jan 11, 2016 5:16 am

Try something like this: mythcommflag --logpath /tmp --chanid 2632 --starttime 20151216040000 -v commflag --loglevel debug
and look at the log in /tmp, not what prints to the screen.

nello
Junior
Posts: 27
Joined: Sun Mar 08, 2015 7:33 pm
United States of America

Re: mythcommflag Run from Command Line Never Exits

Post by nello » Mon Jan 11, 2016 3:30 pm

@bill6502: Thank you for your suggestion.

I ran this command:

Code: Select all

/usr/local/bin/mythcommflag --chanid 1021 --starttime 20160110150100 -v --logpath /var/log/mythtv --loglevel debug
Terminal output was the same as before.

Nothing in the log file jumps out at me except for the fact that it hangs:

Code: Select all

root@mythtv:/var/log/mythtv # cat mythcommflag.20160111151741.41676.log
2016-01-11 09:17:41.951200 C [41676/100176] thread_unknown mythcommandlineparser.cpp:2595 (ConfigureLogging) - mythcommflag version:  [5a5e1cd] www.mythtv.org
2016-01-11 09:17:41.951467 C [41676/100176] thread_unknown mythcommandlineparser.cpp:2597 (ConfigureLogging) - Qt version: compile: 4.8.6, runtime: 4.8.7
2016-01-11 09:17:41.951487 N [41676/100176] thread_unknown mythcommandlineparser.cpp:2599 (ConfigureLogging) - Enabled verbose msgs:  general
2016-01-11 09:17:41.951680 N [41676/100176] thread_unknown logging.cpp:914 (logStart) - Setting Log Level to LOG_DEBUG
2016-01-11 09:17:41.953226 I [41676/100176] thread_unknown signalhandling.cpp:194 (SetHandlerPrivate) - Setup Interrupt: 2 handler
2016-01-11 09:17:41.953239 I [41676/100176] thread_unknown signalhandling.cpp:194 (SetHandlerPrivate) - Setup Terminated: 15 handler
2016-01-11 09:17:41.953248 I [41676/100176] thread_unknown signalhandling.cpp:194 (SetHandlerPrivate) - Setup Segmentation fault: 11 handler
2016-01-11 09:17:41.953257 I [41676/100176] thread_unknown signalhandling.cpp:194 (SetHandlerPrivate) - Setup Abort trap: 6 handler
2016-01-11 09:17:41.953265 I [41676/100176] thread_unknown signalhandling.cpp:194 (SetHandlerPrivate) - Setup Bus error: 10 handler
2016-01-11 09:17:41.953273 I [41676/100176] thread_unknown signalhandling.cpp:194 (SetHandlerPrivate) - Setup Floating point exception: 8 handler
2016-01-11 09:17:41.953282 I [41676/100176] thread_unknown signalhandling.cpp:194 (SetHandlerPrivate) - Setup Illegal instruction: 4 handler
2016-01-11 09:17:41.953295 I [41676/100176] thread_unknown signalhandling.cpp:194 (SetHandlerPrivate) - Setup Unknown signal: 65 handler
2016-01-11 09:17:41.953368 N [41676/100176] thread_unknown mythdirs.cpp:55 (InitializeMythDirs) - Using runtime prefix = /usr/local
2016-01-11 09:17:41.953388 N [41676/100176] thread_unknown mythdirs.cpp:68 (InitializeMythDirs) - Using configuration directory = /var/lib/mythtv/.mythtv
2016-01-11 09:17:41.954003 I [41676/100176] CoreContext mythcorecontext.cpp:249 (Init) - Assumed character encoding: en_US.UTF-8
2016-01-11 09:17:41.955113 N [41676/100176] CoreContext mythcontext.cpp:504 (LoadDatabaseSettings) - Empty LocalHostName.
2016-01-11 09:17:41.955146 I [41676/100176] CoreContext mythcontext.cpp:512 (LoadDatabaseSettings) - Using localhost value of mythtv
2016-01-11 09:17:41.984803 D [41676/100176] CoreContext mythcontext.cpp:432 (FindDatabase) - FindDatabase() - Success!
2016-01-11 09:17:41.986158 I [41676/101027] Logger logging.cpp:315 (run) - Added logging to the console
2016-01-11 09:17:41.994387 N [41676/100176] CoreContext mythcorecontext.cpp:1272 (InitLocale) - Setting QT default locale to en_US
2016-01-11 09:17:41.994530 I [41676/100176] CoreContext mythcorecontext.cpp:1305 (SaveLocaleDefaults) - Current locale en_US
2016-01-11 09:17:41.994757 N [41676/100176] CoreContext mythlocale.cpp:121 (LoadDefaultsFromXML) - Reading locale defaults from /usr/local/share/mythtv//locales/en_us.xml
2016-01-11 09:17:42.004873 I [41676/100176] CoreContext mythtranslation.cpp:65 (load) - Loading en_us translation for module mythfrontend
2016-01-11 09:17:42.316321 I [41676/101027] Logger logging.cpp:474 (initialTimeout) - Added logging to mythlogserver at TCP:35327
2016-01-11 09:17:43.738624 I [41676/101911] SendMessage mythcorecontext.cpp:418 (ConnectCommandSocket) - MythCoreContext: Connecting to backend server: 10.10.49.13:6543 (try 1 of 1)
2016-01-11 09:17:43.839161 I [41676/101911] SendMessage mythcorecontext.cpp:1185 (CheckProtoVersion) - Using protocol version 77
2016-01-11 09:17:44.572794 I [41676/100176] CoreContext avformatdecoder.cpp:1898 (ScanStreams) - AFD: codec AC3 has 6 channels
2016-01-11 09:17:44.573277 I [41676/100176] CoreContext avformatdecoder.cpp:2378 (OpenAVCodec) - AFD: Opened codec 0x81242d700, id(AC3) type(Audio)
2016-01-11 09:17:44.637658 I [41676/100176] CoreContext avformatdecoder.cpp:2378 (OpenAVCodec) - AFD: Opened codec 0x81242d200, id(H264) type(Video)
root@mythtv:/var/log/mythtv # cat mythcommflag.20160111151741.41676.log
2016-01-11 09:17:41.951200 C [41676/100176] thread_unknown mythcommandlineparser.cpp:2595 (ConfigureLogging) - mythcommflag version:  [5a5e1cd] www.mythtv.org
2016-01-11 09:17:41.951467 C [41676/100176] thread_unknown mythcommandlineparser.cpp:2597 (ConfigureLogging) - Qt version: compile: 4.8.6, runtime: 4.8.7
2016-01-11 09:17:41.951487 N [41676/100176] thread_unknown mythcommandlineparser.cpp:2599 (ConfigureLogging) - Enabled verbose msgs:  general
2016-01-11 09:17:41.951680 N [41676/100176] thread_unknown logging.cpp:914 (logStart) - Setting Log Level to LOG_DEBUG
2016-01-11 09:17:41.953226 I [41676/100176] thread_unknown signalhandling.cpp:194 (SetHandlerPrivate) - Setup Interrupt: 2 handler
2016-01-11 09:17:41.953239 I [41676/100176] thread_unknown signalhandling.cpp:194 (SetHandlerPrivate) - Setup Terminated: 15 handler
2016-01-11 09:17:41.953248 I [41676/100176] thread_unknown signalhandling.cpp:194 (SetHandlerPrivate) - Setup Segmentation fault: 11 handler
2016-01-11 09:17:41.953257 I [41676/100176] thread_unknown signalhandling.cpp:194 (SetHandlerPrivate) - Setup Abort trap: 6 handler
2016-01-11 09:17:41.953265 I [41676/100176] thread_unknown signalhandling.cpp:194 (SetHandlerPrivate) - Setup Bus error: 10 handler
2016-01-11 09:17:41.953273 I [41676/100176] thread_unknown signalhandling.cpp:194 (SetHandlerPrivate) - Setup Floating point exception: 8 handler
2016-01-11 09:17:41.953282 I [41676/100176] thread_unknown signalhandling.cpp:194 (SetHandlerPrivate) - Setup Illegal instruction: 4 handler
2016-01-11 09:17:41.953295 I [41676/100176] thread_unknown signalhandling.cpp:194 (SetHandlerPrivate) - Setup Unknown signal: 65 handler
2016-01-11 09:17:41.953368 N [41676/100176] thread_unknown mythdirs.cpp:55 (InitializeMythDirs) - Using runtime prefix = /usr/local
2016-01-11 09:17:41.953388 N [41676/100176] thread_unknown mythdirs.cpp:68 (InitializeMythDirs) - Using configuration directory = /var/lib/mythtv/.mythtv
2016-01-11 09:17:41.954003 I [41676/100176] CoreContext mythcorecontext.cpp:249 (Init) - Assumed character encoding: en_US.UTF-8
2016-01-11 09:17:41.955113 N [41676/100176] CoreContext mythcontext.cpp:504 (LoadDatabaseSettings) - Empty LocalHostName.
2016-01-11 09:17:41.955146 I [41676/100176] CoreContext mythcontext.cpp:512 (LoadDatabaseSettings) - Using localhost value of mythtv
2016-01-11 09:17:41.984803 D [41676/100176] CoreContext mythcontext.cpp:432 (FindDatabase) - FindDatabase() - Success!
2016-01-11 09:17:41.986158 I [41676/101027] Logger logging.cpp:315 (run) - Added logging to the console
2016-01-11 09:17:41.994387 N [41676/100176] CoreContext mythcorecontext.cpp:1272 (InitLocale) - Setting QT default locale to en_US
2016-01-11 09:17:41.994530 I [41676/100176] CoreContext mythcorecontext.cpp:1305 (SaveLocaleDefaults) - Current locale en_US
2016-01-11 09:17:41.994757 N [41676/100176] CoreContext mythlocale.cpp:121 (LoadDefaultsFromXML) - Reading locale defaults from /usr/local/share/mythtv//locales/en_us.xml
2016-01-11 09:17:42.004873 I [41676/100176] CoreContext mythtranslation.cpp:65 (load) - Loading en_us translation for module mythfrontend
2016-01-11 09:17:42.316321 I [41676/101027] Logger logging.cpp:474 (initialTimeout) - Added logging to mythlogserver at TCP:35327
2016-01-11 09:17:43.738624 I [41676/101911] SendMessage mythcorecontext.cpp:418 (ConnectCommandSocket) - MythCoreContext: Connecting to backend server: 10.10.49.13:6543 (try 1 of 1)
2016-01-11 09:17:43.839161 I [41676/101911] SendMessage mythcorecontext.cpp:1185 (CheckProtoVersion) - Using protocol version 77
2016-01-11 09:17:44.572794 I [41676/100176] CoreContext avformatdecoder.cpp:1898 (ScanStreams) - AFD: codec AC3 has 6 channels
2016-01-11 09:17:44.573277 I [41676/100176] CoreContext avformatdecoder.cpp:2378 (OpenAVCodec) - AFD: Opened codec 0x81242d700, id(AC3) type(Audio)
2016-01-11 09:17:44.637658 I [41676/100176] CoreContext avformatdecoder.cpp:2378 (OpenAVCodec) - AFD: Opened codec 0x81242d200, id(H264) type(Video)
root@mythtv:/var/log/mythtv # 
What am I missing?

It must be obvious; that's why it eludes me!

- nello

User avatar
bill6502
Developer
Posts: 1377
Joined: Fri Feb 07, 2014 5:28 pm
United States of America

Re: mythcommflag Run from Command Line Never Exits

Post by bill6502 » Mon Jan 11, 2016 4:36 pm

Hi,

Eludes me too. But you missed the commflag after the -v (to get more logging.) Try for
even more with -v commflag,audio,playback (because the last messages are in the av
code (semi-educated guess here.)

Based on the Subject: are you saying that when mythcommflag runs automatically (e.g.
by mythbackend after a recording completes) that it works OK? If so, then I see you're
running it as the root user. Running anything MythTV as root is never recommended. Try
running it as yourself - of course you need the proper ~/.mythtv/config.xml file.

Does your mythbackend run as root too? Or do you use (the recommended --user mythtv
flag?) [Or start it with something that changes the user some other way, e.g. systemd]

nello
Junior
Posts: 27
Joined: Sun Mar 08, 2015 7:33 pm
United States of America

Re: mythcommflag Run from Command Line Never Exits

Post by nello » Mon Jan 11, 2016 4:57 pm

@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

User avatar
bill6502
Developer
Posts: 1377
Joined: Fri Feb 07, 2014 5:28 pm
United States of America

Re: mythcommflag Run from Command Line Never Exits

Post by bill6502 » Tue Jan 12, 2016 12:56 am

Bad news is I'm stumped too. The backend communications seems to go away in the
above. What I see next is this:

Code: Select all

2016-01-11 11:59:49.797182 I [11687/11687] CoreContext decoderbase.cpp:389 (SyncPositionMap) - Dec: SyncPositionMap prerecorded, from DB: 3955 entries
2016-01-11 11:59:49.797187 I [11687/11687] CoreContext decoderbase.cpp:427 (SyncPositionMap) - Dec: SyncPositionMap, new totframes: 59325, new length: 1979, posMap size: 3955
2016-01-11 11:59:49.797413 I [11687/11687] CoreContext avformatdecoder.cpp:1347 (OpenFile) - AFD: Position map found
2016-01-11 11:59:49.797418 I [11687/11687] CoreContext avformatdecoder.cpp:1352 (OpenFile) - AFD: Successfully opened decoder for file: "/srv/mythtv-3/recordings/2632_20151216040000.ts". novideo(0)
2016-01-11 11:59:49.797760 I [11687/11687] CoreContext deletemap.cpp:847 (TrackerReset) - DelMap: Tracker next CUT_START: 17050
2016-01-11 11:59:49.797867 I [11687/11687] CoreContext deletemap.cpp:847 (TrackerReset) - DelMap: Tracker next CUT_START: 17050
2016-01-11 11:59:49.798149 I [11687/11687] CoreContext ClassicCommDetector.cpp:223 (Init) - Commercial Detection initialized: width = 480, height = 270, fps = 29.97, method = 11
2016-01-11 11:59:49.798152 I [11687/11687] CoreContext ClassicCommDetector.cpp:253 (Init) - Using Sample Spacing of 4 horizontal & 4 vertical pixels.
I didn't see your response to the question about commflag running automatically when the backend
finishes a recording. Does it work when the backend runs it???

nello
Junior
Posts: 27
Joined: Sun Mar 08, 2015 7:33 pm
United States of America

Re: mythcommflag Run from Command Line Never Exits

Post by nello » Mon Jan 18, 2016 5:32 pm

I didn't see your response to the question about commflag running automatically when the backend
finishes a recording. Does it work when the backend runs it???
@ bill6502

I don't think that I'm running mythcommflag when the backend finishes a recording; how can I tell other than looking at the X11 setup (/usr/local/bin/mythtv-setup) screens, links to which are below?

There is NOT checkbox in "Allow commercial-detection jobs"
http://imagebin.ca/v/2Tu7rpkGtjC0

There is NOT checkbox in "Start auto-commercial-detection jobs when the recording starts"
http://imagebin.ca/v/2Tu8EcqOxZ4u

Is there a log I should be looking at to see whether mythcommflag is being run by the backend?

Thank you for your help.

- nello

PS: Sorry for not getting back to you earlier. I've been busy replacing a failed drive in my desktop computer and working on taxes. :cry:

wesnewell
Senior
Posts: 512
Joined: Mon Jun 23, 2014 6:54 pm
Location: Wylie TX, USA
United States of America

Re: mythcommflag Run from Command Line Never Exits

Post by wesnewell » Mon Jan 18, 2016 6:01 pm

/var/log/mythtv/mythcommflag.log
BE/FE-Asrock AB350 Pro AMD Ryzen 3 2200G, 6 atsc tuners. Frontends-GF8200's,,AMD Athlon II's. Mythtv user since 2005.

nello
Junior
Posts: 27
Joined: Sun Mar 08, 2015 7:33 pm
United States of America

Re: mythcommflag Run from Command Line Never Exits

Post by nello » Sun Mar 13, 2016 4:12 am

bill6502 wrote:Bad news is I'm stumped too …
Well, I don't know why but commflag started working again, or at least not going off into la-la land.

But, of course, now I have other questions, which I've posted in a this new thread:

viewtopic.php?f=36&t=1368

Thank you.

- nello

Post Reply