[mythtv-users] mythvidexport was working now errors out

[William Jacoby]

On 9/13/2013 6:11 AM, Raymond Wagner wrote:
> On Sep 13, 2013, at 0:05, William Jacoby<bonelifer at gmail.com>  wrote:
>
>>  From my mythbackend.log:
>>
>> Sep 12 21:41:10 william-mythtv mythbackend[21022]: I UserJob_4495 jobqueue.cpp:2404 (DoUserJobThread) JobQueue: Started Export for Reba:"Mommy Nearest" recorded from channel 12113 at 2013-09-12T14:26:00
>> Sep 12 21:41:10 william-mythtv mythbackend[21022]: E UserJob_4495 jobqueue.cpp:2439 (DoUserJobThread) JobQueue: User Job '/usr/bin/mythvidexport.py /var/lib/mythtv/recordings/12113_20130912142600.nuv' failed.
>>
>> It was working before. Possible permissions error on the folders, I had had trouble moving the files from where it stores them to where I store them. So I ran something like this:
>> sudo chown -Rf william:william /var/lib/mythtv/videos/
>> sudo chmod -Rf 0777 /var/lib/mythtv/videos/
>>
>> Not sure if this started happening afterwards or before.I now think I should of set mythtv in smb.conf instead. These are just guesses on my part.
> I really need a backtrace, or at least some kind of logging, to have an idea why it might have failed.  If you're using the "%VERBOSEMODE%" flag, the job queue will pass in the necessary variables to have the script log where the rest of MythTV logs.  Otherwise, you may need to run the command manually from the terminal.
>
> One thing that does stand out, are you just running the script with the absolute file name of the recording?
> _______________________________________________
> mythtv-users mailing list
> mythtv-users at mythtv.org
> http://www.mythtv.org/mailman/listinfo/mythtv-users
>
Not sure how to do a backtrace.  I'm running it via userjob, with:
mythvidexport.py %JOBID%

 From the commandline it works if I feed the JOBID found in the 
mythbackend.log file.

With it set to:
/usr/bin/mythvidexport.py %VERBOSEMODE% %JOBID%

I get this:
Sep 13 15:24:35 william-mythtv mythbackend[29949]: I MetadataDownload 
metadatadownload.cpp:222 (runGrabber) Running Grabber: 
/usr/share/mythtv/metadata/Movie/tmdb.py -l en -M Galaxy Quest
Sep 13 15:24:36 william-mythtv mythbackend[29949]: I MetadataDownload 
metadatadownload.cpp:222 (runGrabber) Running Grabber: 
/usr/share/mythtv/metadata/Television/ttvdb.py -l en -M Galaxy Quest
Sep 13 15:24:36 william-mythtv mythbackend[29949]: I MetadataDownload 
metadatadownload.cpp:222 (runGrabber) Running Grabber: 
/usr/share/mythtv/metadata/Movie/tmdb.py -l en -D 926
Sep 13 15:24:37 william-mythtv mythbackend[29949]: I MetadataDownload 
metadatadownload.cpp:144 (run) Returning Metadata Results: Galaxy Quest 0 0
Sep 13 15:24:39 william-mythtv mythbackend[29949]: I ProcessRequest 
mainserver.cpp:1360 (HandleAnnounce) MainServer::ANN Monitor
Sep 13 15:24:39 william-mythtv mythbackend[29949]: I ProcessRequest 
mainserver.cpp:1362 (HandleAnnounce) adding: william-mythtv as a client 
(events: 2)
Sep 13 15:24:39 william-mythtv mythbackend[29949]: I ProcessRequest 
mainserver.cpp:1360 (HandleAnnounce) MainServer::ANN Monitor
Sep 13 15:24:39 william-mythtv mythbackend[29949]: I ProcessRequest 
mainserver.cpp:1362 (HandleAnnounce) adding: william-mythtv as a client 
(events: 2)
Sep 13 15:24:39 william-mythtv mythbackend[29949]: I Scheduler 
scheduler.cpp:2033 (HandleReschedule) Reschedule requested for id -1.
Sep 13 15:24:42 william-mythtv mythbackend[29949]: I Scheduler 
scheduler.cpp:2093 (HandleReschedule) Scheduled 630 items in 3.1 = 1.10 
match + 1.96 place
Sep 13 15:24:42 william-mythtv mythbackend[29949]: I ProcessRequest 
mainserver.cpp:1360 (HandleAnnounce) MainServer::ANN Monitor
Sep 13 15:24:42 william-mythtv mythbackend[29949]: I ProcessRequest 
mainserver.cpp:1362 (HandleAnnounce) adding: william-mythtv as a client 
(events: 2)
Sep 13 15:24:42 william-mythtv mythbackend[29949]: I ProcessRequest 
mainserver.cpp:1360 (HandleAnnounce) MainServer::ANN Monitor
Sep 13 15:24:42 william-mythtv mythbackend[29949]: I ProcessRequest 
mainserver.cpp:1362 (HandleAnnounce) adding: william-mythtv as a client 
(events: 2)
Sep 13 15:24:43 william-mythtv mythbackend[29949]: I UserJob_4524 
jobqueue.cpp:2404 (DoUserJobThread) JobQueue: Started Export for "Galaxy 
Quest" recorded from channel 12112 at 2013-09-12T08:56:00
Sep 13 15:24:45 william-mythtv mythbackend[29949]: I MetadataDownload 
metadatadownload.cpp:222 (runGrabber) Running Grabber: 
/usr/share/mythtv/metadata/Movie/tmdb.py -l en -M Galaxy Quest
Sep 13 15:24:45 william-mythtv mythbackend[29949]: I MetadataDownload 
metadatadownload.cpp:222 (runGrabber) Running Grabber: 
/usr/share/mythtv/metadata/Television/ttvdb.py -l en -M Galaxy Quest
Sep 13 15:24:45 william-mythtv mythbackend[29949]: I MetadataDownload 
metadatadownload.cpp:222 (runGrabber) Running Grabber: 
/usr/share/mythtv/metadata/Movie/tmdb.py -l en -D 926
Sep 13 15:24:46 william-mythtv mythbackend[29949]: I MetadataDownload 
metadatadownload.cpp:144 (run) Returning Metadata Results: Galaxy Quest 0 0
Sep 13 15:24:53 william-mythtv mythbackend[29949]: E UserJob_4524 
jobqueue.cpp:2439 (DoUserJobThread) JobQueue: User Job 
'/usr/bin/mythvidexport.py  --verbose general --loglevel info --syslog 
local7 4524' failed.


Taking JOBID: 4524  from that and running commandline it works and 
produces this:

william at william-mythtv:~$ mythvidexport.py 4524
2013-09-13 15:28:53.064381 I [30692] mythvidexport.py Using recording -- 
Galaxy Quest -
2013-09-13 15:28:53.090670 I [30692] mythvidexport.py Attempting Movie 
export.
2013-09-13 15:28:53.513544 I [30692] mythvidexport.py Importing content 
from -- 926
2013-09-13 15:28:53.937363 I [30692] mythvidexport.py Import complete
2013-09-13 15:28:53.939074 I [30692] mythvidexport.py Copying 
myth://Default@william-mythtv/12112_20130912085600.nuv to 
myth://Videos@william-mythtv/Movies/Galaxy Quest.nuv
2013-09-13 15:29:12.084101 I [30692] mythvidexport.py Transfer Complete 
-- 18 seconds elapsed
william at william-mythtv:~$