Headphone Post processing Issues On Windows Server 2016

skydiverscott commented 4 years ago

I am running Headphones from PYTHON source on Windows Server 2016. I have headphones installs in d:\headphones\

Installed: ffmpeg version N-94405-g43891ea8ab and in my windows system path.
Headphones is set to re-encode flac to m4a file format.
Files are downloaded via NZBGET into d:\NZBGet\DL\complete\Music\
Using NSSM to run pythonw.exe d:\headphones\headphones.py as a service running as local system

Settings in headphone are set as follows: Re-encode downloads during postprocessing - Yes Only re-encode lossless files (.flac) - Yes Delete original lossless files after encoding m- Yes Encoder - ffmpeg Enable multi-core - No Multi-core count - 0 Audio Properties Format: m4a VBR/CBR: vbr Quality: 0 Sampling: 44.1kHz Advanced Encoding Options Arguments: -y -acodec alac -vn Extension: m4a Path to encoder: d:\ffmpeg\bin\ffmpeg.exe

I am getting this error when it tried to encode the files: `2019-08-13 23:47:52	ERROR	One or more files failed to encode. Ensure you have the latest version of ffmpeg installed.
2019-08-13 23:47:52	ERROR	Encoder raised an exception.Traceback (most recent call last):File "D:\headphones\headphones\music_encoder.py", line 257, in command_mapreturn command(args)File "D:\headphones\headphones\music_encoder.py", line 360, in commandstderr=subprocess.PIPE)File "D:\Python27\lib\subprocess.py", line 394, in init*errread, errwrite)File "D:\Python27\lib\subprocess.py", line 644, in _execute_childstartupinfo)WindowsError: [Error 2] The system cannot find the file specified
2019-08-13 23:47:52	INFO	Encoding D:\headphones\temp\headphones1979 Santana - Marathon (flac)@hp@_p9vav0\headphones\11. Santana - Hard Times.flac...`

Any suggestions here?

raymondjstone commented 4 years ago

Perhaps try the version of ffmpeg that was released before the last headphones version, could be a change after the last headphones one has changed something. not setup ffmpeg myself so just guessing, could also be that the strange filename is causing some issues, perhaps with a regex pattern or something like that

skydiverscott commented 4 years ago

Thanks. I will try an earlier version of ffmpeg. This is happening with all files so character pattern is probably not the issue.

On Aug 14, 2019, at 10:42 AM, Raymond Stone notifications@github.com<mailto:notifications@github.com> wrote:

Perhaps try the version of ffmpeg that was released before the last headphones version, could be a change after the last headphones one has changed something. not setup ffmpeg myself so just guessing, could also be that the strange filename is causing some issues, perhaps with a regex pattern or something like that

— You are receiving this because you authored the thread. Reply to this email directly, view it on GitHubhttps://github.com/rembo10/headphones/issues/3219?email_source=notifications&email_token=AAPBA5T367M5NZJVQEAHY43QEQ7YBA5CNFSM4ILRGSF2YY3PNVWWK3TUL52HS4DFVREXG43VMVBW63LNMVXHJKTDN5WW2ZLOORPWSZGOD4JR4PA#issuecomment-521346620, or mute the threadhttps://github.com/notifications/unsubscribe-auth/AAPBA5WPILMVMTE2EWLMJT3QEQ7YBANCNFSM4ILRGSFQ.

skydiverscott commented 4 years ago

Which previous version of FFMPEG would you recommend?

skydiverscott commented 4 years ago

I tried several older versions with no change in results. I also tried several other folders. Could this be an issue with the encoding settings I have?

AdeHub commented 4 years ago

is ffmpeg.exe definitely in this location?

Path to encoder: d:\ffmpeg\bin\ffmpeg.exe

Does it work manually from a command line?

skydiverscott commented 4 years ago

FFMPEG is definitely in the location specified. I also have this directory in my path, so it is executable from anywhere on my system.

So I ran this from the commandline: ffmpeg.exe -i "01-Let It Rock.flac" -y -acodec alac -vn "01-Let It Rock.m4a" and it completed succesfully.

So this looks like an issue in my headphones install or config...

raymondjstone commented 4 years ago

I have not tried it but I would guess that thus

Path to encoder: d:\ffmpeg\bin\ffmpeg.exe

is wrong, that is not the path

Path to encoder: d:\ffmpeg\bin\

would be the path

skydiverscott commented 4 years ago

This is copy/paste of my directory:

D:\>dir ffmpeg.exe /s
 Volume in drive D is Data
 Volume Serial Number is E410-4E98

 Directory of D:\FFMPEG\bin

07/29/2019  04:16 AM        65,814,528 ffmpeg.exe
               1 File(s)     65,814,528 bytes

raymondjstone commented 4 years ago

and do you have the setting as the DIRECTORY path, and ffmpeg set in the drop down as the encoder choice.?

skydiverscott commented 4 years ago

Here are my settings Headphones - Settings - Brave

raymondjstone commented 4 years ago

you still do not have the PATH TO set correctly, PATHS should not have the exe name, and if it does it makes no sense to have the encoder drop down list at all

skydiverscott commented 4 years ago

Gotcha... Does the path need the trailing backslash?

raymondjstone commented 4 years ago

not sure how it's been coded but best to keep it in initially and see

raymondjstone commented 4 years ago

set it up onb my machine and you do actually need ffmeg.exe on the path, but apart from that mine runs fine using the latest stable release (static build). I do not however have the additional arguments you have set

skydiverscott commented 4 years ago

Thank you... it is still failing.

I tried d:\ffmpeg\bin\ and d:\ffmpeg\bin

I cannot turn on debug logging. That may be the what is needed to figure out what i am getting these fails. When I try to turn on debug logging this is what I get:

500 Internal Server Error
The server encountered an unexpected condition which prevented it from fulfilling the request.

Traceback (most recent call last):
  File "lib/cherrypy\_cprequest.py", line 670, in respond
    response.body = self.handler()
  File "lib/cherrypy\lib\encoding.py", line 217, in __call__
    self.body = self.oldhandler(*args, **kwargs)
  File "lib/cherrypy\_cpdispatch.py", line 61, in __call__
    return self.callable(*self.args, **self.kwargs)
  File "D:\headphones\headphones\webserve.py", line 956, in toggleVerbose
    log_dir=headphones.CONFIG.LOG_DIR, verbose=headphones.VERBOSE)
  File "D:\headphones\headphones\logger.py", line 135, in initLogger
    handler.flush()
  File "D:\Python27\lib\logging\__init__.py", line 852, in flush
    self.stream.flush()
IOError: [Errno 9] Bad file descriptor
Powered by CherryPy 3.6.0

raymondjstone commented 4 years ago

if you run headphones in a console window then a lot of logging messages appear in that which may help

skydiverscott commented 4 years ago

I figured out how to manually start headphones with debug from commandline..,. So progress on that front. Here is the new error logs:

2019-08-17 10:21:05 | ERROR | Encoder raised an exception.
Traceback (most recent call last):
File "D:\headphones\headphones\music_encoder.py", line 257, in command_map
return command(*args)
File "D:\headphones\headphones\music_encoder.py", line 360, in command
stderr=subprocess.PIPE)
File "D:\Python27\lib\subprocess.py", line 394, in __init__errread, errwrite)
File "D:\Python27\lib\subprocess.py", line 644, in _execute_childstartupinfo)WindowsError: [Error 5] Access is denied
-- | -- | --
2019-08-17 10:21:05 | DEBUG | d:\ffmpeg\bin\ -i d:\NZBGet\DL\complete\Music\Bon_Jovi-Have_A_Nice_Day-CD-FLAC-2005-BOCKSCAR\06-bon_jovi-bells_of_freedom.flac -y -acodec alac -vn d:\NZBGet\DL\complete\Music\Bon_Jovi-Have_A_Nice_Day-CD-FLAC-2005-BOCKSCAR\temp\06-bon_jovi-bells_of_freedom.m4a

Looking at the log file above, the commandline echo doesn't include ffpmeg.exe

raymondjstone commented 4 years ago

as I mentioned earlier you were right to have ffmpeg on the 'path' after all, I got the same message until I added it

skydiverscott commented 4 years ago

When I manually add the ffmpeg.exe to the commandline I get the error trying to write to a non-existent directory.

D:\>d:\ffmpeg\bin\ffmpeg.exe -i d:\NZBGet\DL\complete\Music\Bon_Jovi-Have_A_Nice_Day-CD-FLAC-2005-BOCKSCAR\06-bon_jovi-bells_of_freedom.flac -y -acodec alac -vn d:\NZBGet\DL\complete\Music\Bon_Jovi-Have_A_Nice_Day-CD-FLAC-2005-BOCKSCAR\temp\06-bon_jovi-bells_of_freedom.m4a
ffmpeg version N-94405-g43891ea8ab Copyright (c) 2000-2019 the FFmpeg developers
  built with gcc 9.1.1 (GCC) 20190716
  configuration: --enable-gpl --enable-version3 --enable-sdl2 --enable-fontconfig --enable-gnutls --enable-iconv --enable-libass --enable-libdav1d --enable-libbluray --enable-libfreetype --enable-libmp3lame --enable-libopencore-amrnb --enable-libopencore-amrwb --enable-libopenjpeg --enable-libopus --enable-libshine --enable-libsnappy --enable-libsoxr --enable-libtheora --enable-libtwolame --enable-libvpx --enable-libwavpack --enable-libwebp --enable-libx264 --enable-libx265 --enable-libxml2 --enable-libzimg --enable-lzma --enable-zlib --enable-gmp --enable-libvidstab --enable-libvorbis --enable-libvo-amrwbenc --enable-libmysofa --enable-libspeex --enable-libxvid --enable-libaom --enable-libmfx --enable-amf --enable-ffnvcodec --enable-cuvid --enable-d3d11va --enable-nvenc --enable-nvdec --enable-dxva2 --enable-avisynth --enable-libopenmpt
  libavutil      56. 32.100 / 56. 32.100
  libavcodec     58. 55.100 / 58. 55.100
  libavformat    58. 30.100 / 58. 30.100
  libavdevice    58.  9.100 / 58.  9.100
  libavfilter     7. 58.100 /  7. 58.100
  libswscale      5.  6.100 /  5.  6.100
  libswresample   3.  6.100 /  3.  6.100
  libpostproc    55.  6.100 / 55.  6.100
Input #0, flac, from 'd:\NZBGet\DL\complete\Music\Bon_Jovi-Have_A_Nice_Day-CD-FLAC-2005-BOCKSCAR\06-bon_jovi-bells_of_freedom.flac':
  Metadata:
    title           : Bells Of Freedom
    album           : Have A Nice Day
    track           : 6
    Language        : English
    Rip Date        : 2013-06-02
    Date            : 2005
    Retail Date     : 2005-09-20
    Media           : CD (LP)
    Encoder         : FLAC 1.2.1
    Release Type    : Normal release
    Genre           : Rock
    ORGANIZATION    : Universal
    Artist          : Bon Jovi
  Duration: 00:04:55.92, start: 0.000000, bitrate: 1035 kb/s
    Stream #0:0: Audio: flac, 44100 Hz, stereo, s16
d:\NZBGet\DL\complete\Music\Bon_Jovi-Have_A_Nice_Day-CD-FLAC-2005-BOCKSCAR\temp\06-bon_jovi-bells_of_freedom.m4a: No such file or directory

I designated the headphones temp directory (see config screenshot up the thread) but it looks like it is trying to write out to a temp folder inside the directory being processed. It appears that headphones cannot create this directory.

raymondjstone commented 4 years ago

for some reason it seems to be adding in temp to the final location shown

try removing you extra args just to see what happens, the message underneath then says that using them will ignore all options above, not clear if that includes the temp directories specified in the right hand column

raymondjstone commented 4 years ago

you args are the issue I think, the -vn one should have a directory path, at least it does in mine where I don't specify any args

D:\Media\ffmpeg-4.2-win64-static\bin\ffmpeg.exe -i "D:\Media\headphones-temp\xxxxl.flac" -ar 44100 -ab 192k -y -ac 2 -vn "D:\Media\headphones-temp\xxx.mp3"

skydiverscott commented 4 years ago

Removing the args didn't help:

2019-08-17 11:06:23 ERROR   One or more files failed to encode. Ensure you have the latest version of ffmpeg installed.
2019-08-17 11:06:23 ERROR   Encoder raised an exception.
Traceback (most recent call last):
File "D:\headphones\headphones\music_encoder.py", line 257, in command_map
return command(*args)
File "D:\headphones\headphones\music_encoder.py", line 360, in command
stderr=subprocess.PIPE)
File "D:\Python27\lib\subprocess.py", line 394, in __init__
errread, errwrite)
File "D:\Python27\lib\subprocess.py", line 644, in _execute_child
startupinfo)
WindowsError: [Error 5] Access is denied
2019-08-17 11:06:23 DEBUG   d:\ffmpeg\bin\ -i d:\NZBGet\DL\complete\Music\Bon_Jovi-Have_A_Nice_Day-CD-FLAC-2005-BOCKSCAR\13-bon_jovi-who_says_you_cant_go_home_(duet_with_jennifer_nettles_of_sugarland).flac -strict experimental -aq 0 -y -ac 2 -vn d:\NZBGet\DL\complete\Music\Bon_Jovi-Have_A_Nice_Day-CD-FLAC-2005-BOCKSCAR\temp\13-bon_jovi-who_says_you_cant_go_home_(duet_with_jennifer_nettles_of_sugarland).m4a
2019-08-17 11:06:23 INFO    Encoding d:\NZBGet\DL\complete\Music\Bon_Jovi-Have_A_Nice_Day-CD-FLAC-2005-BOCKSCAR\13-bon_jovi-who_says_you_cant_go_home_(duet_with_jennifer_nettles_of_sugarland).flac...

Changing my arguments to: -y -acodec alac -vn d:\headphones\temp\ Still has same error. Here is the adjusted command line in headphones log: d:\ffmpeg\bin\ -i d:\NZBGet\DL\complete\Music\Bon_Jovi-Have_A_Nice_Day-CD-FLAC-2005-BOCKSCAR\01-bon_jovi-have_a_nice_day.flac -y -acodec alac -vn d:\headphones\temp\ d:\NZBGet\DL\complete\Music\Bon_Jovi-Have_A_Nice_Day-CD-FLAC-2005-BOCKSCAR\temp\01-bon_jovi-have_a_nice_day.m4a

It is still trying to write to the same folder as the source file. It is pulling this from somewhere. ANy ideas?

raymondjstone commented 4 years ago

mine used the temp directory I specified in the right hand column, does your exist?

skydiverscott commented 4 years ago

Yes. I double checked and d:\headphones\temp (this is what is st on the the right side of the advanced page) exists. Not that it should matter in windows but I also checked the named directory case

skydiverscott commented 4 years ago

There has got to be another setting in headphones where this is getting puled from

skydiverscott commented 4 years ago

Not sure if any settings here could be causing this: Headphones - Settings 2 - Brave

raymondjstone commented 4 years ago

The only other settings I think would be on the post process page

skydiverscott commented 4 years ago

@raymondjstone could you screenshot the directories set in the right side of the advanced settings page?

raymondjstone commented 4 years ago

skydiverscott commented 4 years ago

Thanks. When i was looking at my config.ini file, the value where the temp processing directory is named keep_torrent_files_dir , so I think this setting is not what is being use to process downloaded files.

So manually created the temp folder in the directory to be processed and ran the ffmpeg command from the command line and the file gets re-encoded successfully.

Then I emptied the temp folder of any files, and attempted to force process this folder in headphones while I watched the to be processed folder.

I saw the temp folder get deleted and then recreated multiple times as headphones prcoessed each flac file ending without the temp folder.

So this shows that at lest the creation of the tempo folder is not being stopped due to file system ACL's. It is almost like

headphones is deleting the temp folder
running the ffmpeg command
Failing and logging the error
creating the folder
Looking in the folder to move the file and not finding anything, deleting the folder
starting the next file

I am only guessing this from observing the file folders getting created and the resulting missing temp folder.

I have not looked at the code directly. I wouldn't know where to look.

skydiverscott commented 4 years ago

@AdeHub could you provide any insights here?

skydiverscott commented 4 years ago

Looking to see if anyone can help figure this out. Debug logs have been useful but not able to determine the root cause here.

I was wondering if this could be due to perhaps the directory may have been renamed from the original snatched name. I am testing this using the "Post-Process Single Folder" function which I believe skips the snatched requirement

skydiverscott commented 4 years ago

I am still stuck with this same issue... I would like to get the piling up albums post-processed by HerapPhne this weekend. Is there anyone here that would be willing to help be track what is causing these errors?

raymondjstone commented 4 years ago

It's a mystery, I have just reprocessed by entire library to enforce the consistent naming conventions and in general it worked fine and recoded the flac with no issues with much the same settings as you

AdeHub commented 4 years ago

@skydiverscott can you post the latest debug logs

skydiverscott commented 4 years ago

Here is where the errors begin. I can provide earlier parts of the logs is you need. 21-Sep-2019 09:39:41 - INFO :: Thread-14 : Starting post-processing for: Bon Jovi - 7800° Fahrenheit 21-Sep-2019 09:39:41 - INFO :: Thread-14 : Encoding d:\NZBGet\DL\complete\Music\Bon Jovi - 1985 - 7800 Fahrenheit\01. Bon Jovi - In And Out Of Love.flac... 21-Sep-2019 09:39:41 - DEBUG :: Thread-14 : d:\ffmpeg\bin\ -i "d:\NZBGet\DL\complete\Music\Bon Jovi - 1985 - 7800 Fahrenheit\01. Bon Jovi - In And Out Of Love.flac" -y -acodec alac -vn "d:\NZBGet\DL\complete\Music\Bon Jovi - 1985 - 7800 Fahrenheit\temp\01. Bon Jovi - In And Out Of Love.m4a" 21-Sep-2019 09:39:41 - ERROR :: Thread-14 : Encoder raised an exception. Traceback (most recent call last): File "d:\headphones\headphones\music_encoder.py", line 257, in command_map return command(*args) File "d:\headphones\headphones\music_encoder.py", line 360, in command stderr=subprocess.PIPE) File "d:\Python27\lib\subprocess.py", line 394, in __init__ errread, errwrite) File "d:\Python27\lib\subprocess.py", line 644, in _execute_child startupinfo) WindowsError: [Error 5] Access is denied

skydiverscott commented 4 years ago

I opened a command prompt and attempted to run the commandline from the logs. The commandline from the log file doesn't run because it is missing the ffmpeg.exe as part of the path: D:\>d:\ffmpeg\bin\ffmpeg.exe -i "d:\NZBGet\DL\complete\Music\Bon Jovi - 1985 - 7800 Fahrenheit\01. Bon Jovi - In And Out Of Love.flac" -y -acodec alac -vn

When I add the exe like below this is my output: D:\>d:\ffmpeg\bin\ffmpeg.exe -i "d:\NZBGet\DL\complete\Music\Bon Jovi - 1985 - 7800 Fahrenheit\01. Bon Jovi - In And Out Of Love.flac" -y -acodec alac -vn ffmpeg version N-94405-g43891ea8ab Copyright (c) 2000-2019 the FFmpeg developers built with gcc 9.1.1 (GCC) 20190716 configuration: --enable-gpl --enable-version3 --enable-sdl2 --enable-fontconfig --enable-gnutls --enable-iconv --enable-libass --enable-libdav1d --enable-libbluray --enable-libfreetype --enable-libmp3lame --enable-libopencore-amrnb --enable-libopencore-amrwb --enable-libopenjpeg --enable-libopus --enable-libshine --enable-libsnappy --enable-libsoxr --enable-libtheora --enable-libtwolame --enable-libvpx --enable-libwavpack --enable-libwebp --enable-libx264 --enable-libx265 --enable-libxml2 --enable-libzimg --enable-lzma --enable-zlib --enable-gmp --enable-libvidstab --enable-libvorbis --enable-libvo-amrwbenc --enable-libmysofa --enable-libspeex --enable-libxvid --enable-libaom --enable-libmfx --enable-amf --enable-ffnvcodec --enable-cuvid --enable-d3d11va --enable-nvenc --enable-nvdec --enable-dxva2 --enable-avisynth --enable-libopenmpt libavutil 56. 32.100 / 56. 32.100 libavcodec 58. 55.100 / 58. 55.100 libavformat 58. 30.100 / 58. 30.100 libavdevice 58. 9.100 / 58. 9.100 libavfilter 7. 58.100 / 7. 58.100 libswscale 5. 6.100 / 5. 6.100 libswresample 3. 6.100 / 3. 6.100 libpostproc 55. 6.100 / 55. 6.100 Trailing options were found on the commandline. Input #0, flac, from 'd:\NZBGet\DL\complete\Music\Bon Jovi - 1985 - 7800 Fahrenheit\01. Bon Jovi - In And Out Of Love.flac': Metadata: NOTES : Medieval CUE Splitter (www.medieval.it) TITLE : In And Out Of Love ARTIST : Bon Jovi ALBUM : 7800 Fahrenheit [28PD-521] DATE : 1985 GENRE : Hard Rock track : 01 ALBUM ARTIST : Bon Jovi Duration: 00:04:24.89, start: 0.000000, bitrate: 961 kb/s Stream #0:0: Audio: flac, 44100 Hz, stereo, s16 Stream #0:1: Video: mjpeg (Baseline), yuvj420p(pc, bt470bg/unknown/unknown), 900x900 [SAR 300:300 DAR 1:1], 90k tbr, 90k tbn, 90k tbc (attached pic) Metadata: comment : Cover (front) At least one output file must be specified

I am assuming the verbose logging is only showing part of the actual command being executed. I believe this missing information may possible point to where the Windows ACL error is being thrown.

I am logged in running the command prompt as an administrator and windows defender has the ffmpeg.exe, python.exe and pythonw.exe whitlisted

The user I am running headphones.py as has r/w to both the d: an i: drives

skydiverscott commented 4 years ago

And here is when I add the output file name (I path'd it to the same folder as the input): D:\>d:\ffmpeg\bin\ffmpeg.exe -i "d:\NZBGet\DL\complete\Music\Bon Jovi - 1985 - 7800 Fahrenheit\01. Bon Jovi - In And Out Of Love.flac" -y -acodec alac -vn "d:\NZBGet\DL\complete\Music\Bon Jovi - 1985 - 7800 Fahrenheit\01. Bon Jovi - In And Out Of Love.m4a" ffmpeg version N-94405-g43891ea8ab Copyright (c) 2000-2019 the FFmpeg developers built with gcc 9.1.1 (GCC) 20190716 configuration: --enable-gpl --enable-version3 --enable-sdl2 --enable-fontconfig --enable-gnutls --enable-iconv --enable-libass --enable-libdav1d --enable-libbluray --enable-libfreetype --enable-libmp3lame --enable-libopencore-amrnb --enable-libopencore-amrwb --enable-libopenjpeg --enable-libopus --enable-libshine --enable-libsnappy --enable-libsoxr --enable-libtheora --enable-libtwolame --enable-libvpx --enable-libwavpack --enable-libwebp --enable-libx264 --enable-libx265 --enable-libxml2 --enable-libzimg --enable-lzma --enable-zlib --enable-gmp --enable-libvidstab --enable-libvorbis --enable-libvo-amrwbenc --enable-libmysofa --enable-libspeex --enable-libxvid --enable-libaom --enable-libmfx --enable-amf --enable-ffnvcodec --enable-cuvid --enable-d3d11va --enable-nvenc --enable-nvdec --enable-dxva2 --enable-avisynth --enable-libopenmpt libavutil 56. 32.100 / 56. 32.100 libavcodec 58. 55.100 / 58. 55.100 libavformat 58. 30.100 / 58. 30.100 libavdevice 58. 9.100 / 58. 9.100 libavfilter 7. 58.100 / 7. 58.100 libswscale 5. 6.100 / 5. 6.100 libswresample 3. 6.100 / 3. 6.100 libpostproc 55. 6.100 / 55. 6.100 Input #0, flac, from 'd:\NZBGet\DL\complete\Music\Bon Jovi - 1985 - 7800 Fahrenheit\01. Bon Jovi - In And Out Of Love.flac': Metadata: NOTES : Medieval CUE Splitter (www.medieval.it) TITLE : In And Out Of Love ARTIST : Bon Jovi ALBUM : 7800 Fahrenheit [28PD-521] DATE : 1985 GENRE : Hard Rock track : 01 ALBUM ARTIST : Bon Jovi Duration: 00:04:24.89, start: 0.000000, bitrate: 961 kb/s Stream #0:0: Audio: flac, 44100 Hz, stereo, s16 Stream #0:1: Video: mjpeg (Baseline), yuvj420p(pc, bt470bg/unknown/unknown), 900x900 [SAR 300:300 DAR 1:1], 90k tbr, 90k tbn, 90k tbc (attached pic) Metadata: comment : Cover (front) Stream mapping: Stream #0:0 -> #0:0 (flac (native) -> alac (native)) Press [q] to stop, [?] for help Output #0, ipod, to 'd:\NZBGet\DL\complete\Music\Bon Jovi - 1985 - 7800 Fahrenheit\01. Bon Jovi - In And Out Of Love.m4a': Metadata: NOTES : Medieval CUE Splitter (www.medieval.it) TITLE : In And Out Of Love ARTIST : Bon Jovi ALBUM : 7800 Fahrenheit [28PD-521] DATE : 1985 GENRE : Hard Rock track : 01 ALBUM ARTIST : Bon Jovi encoder : Lavf58.30.100 Stream #0:0: Audio: alac (alac / 0x63616C61), 44100 Hz, stereo, s16p, 128 kb/s Metadata: encoder : Lavc58.55.100 alac size= 31589kB time=00:04:24.89 bitrate= 976.9kbits/s speed= 149x video:0kB audio:31576kB subtitle:0kB other streams:0kB global headers:0kB muxing overhead: 0.039500%

You will notice that it completes, so this isn't an issue with the ffmpeg version.

raymondjstone commented 4 years ago

given you are processing a single track from an album it seems strange that the cue splitter is running, try removing any cue files from the processing folder and giving it a go again

skydiverscott commented 4 years ago

I am actually processing a folder. I am only providing a single file in the logs.

skydiverscott commented 4 years ago

I rename the cue file and re-processed the folder. Still fails. Here are the logs (I included logs for the whole album): 21-Sep-2019 10:21:56 - DEBUG :: Thread-26 : Matching metadata artist: bon jovi with artist name: bon jovi 21-Sep-2019 10:21:56 - DEBUG :: Thread-26 : Matching metadata album: 7800 fahrenheit [28pd-521] with album name: 7800{degrees} fahrenheit 21-Sep-2019 10:21:56 - DEBUG :: Thread-26 : Matching metadata artist: bon jovi with artist name: bon jovi 21-Sep-2019 10:21:56 - DEBUG :: Thread-26 : Matching metadata album: 7800 fahrenheit [28pd-521] with album name: 7800{degrees} fahrenheit 21-Sep-2019 10:21:56 - DEBUG :: Thread-26 : Matching metadata artist: bon jovi with artist name: bon jovi 21-Sep-2019 10:21:56 - DEBUG :: Thread-26 : Matching metadata album: 7800 fahrenheit [28pd-521] with album name: 7800{degrees} fahrenheit 21-Sep-2019 10:21:56 - DEBUG :: Thread-26 : Matching metadata artist: bon jovi with artist name: bon jovi 21-Sep-2019 10:21:56 - DEBUG :: Thread-26 : Matching metadata album: 7800 fahrenheit [28pd-521] with album name: 7800{degrees} fahrenheit 21-Sep-2019 10:21:56 - DEBUG :: Thread-26 : Matching metadata artist: bon jovi with artist name: bon jovi 21-Sep-2019 10:21:56 - DEBUG :: Thread-26 : Matching metadata album: 7800 fahrenheit [28pd-521] with album name: 7800{degrees} fahrenheit 21-Sep-2019 10:21:56 - DEBUG :: Thread-26 : Matching metadata artist: bon jovi with artist name: bon jovi 21-Sep-2019 10:21:56 - DEBUG :: Thread-26 : Matching metadata album: 7800 fahrenheit [28pd-521] with album name: 7800{degrees} fahrenheit 21-Sep-2019 10:21:56 - DEBUG :: Thread-26 : Matching metadata artist: bon jovi with artist name: bon jovi 21-Sep-2019 10:21:56 - DEBUG :: Thread-26 : Matching metadata album: 7800 fahrenheit [28pd-521] with album name: 7800{degrees} fahrenheit 21-Sep-2019 10:21:56 - DEBUG :: Thread-26 : Matching metadata artist: bon jovi with artist name: bon jovi 21-Sep-2019 10:21:56 - DEBUG :: Thread-26 : Matching metadata album: 7800 fahrenheit [28pd-521] with album name: 7800{degrees} fahrenheit 21-Sep-2019 10:21:56 - DEBUG :: Thread-26 : Matching metadata artist: bon jovi with artist name: bon jovi 21-Sep-2019 10:21:56 - DEBUG :: Thread-26 : Matching metadata album: 7800 fahrenheit [28pd-521] with album name: 7800{degrees} fahrenheit 21-Sep-2019 10:21:56 - DEBUG :: Thread-26 : Matching metadata artist: bon jovi with artist name: bon jovi 21-Sep-2019 10:21:56 - DEBUG :: Thread-26 : Matching metadata album: 7800 fahrenheit [28pd-521] with album name: 7800{degrees} fahrenheit 21-Sep-2019 10:21:56 - DEBUG :: Thread-26 : Metadata check failed. Verifying filenames... 21-Sep-2019 10:21:56 - DEBUG :: Thread-26 : Checking if track title: silent night is in file name: d:\nzbget\dl\complete\music\bon jovi 1985 7800 fahrenheit\01 bon jovi in and out of love 21-Sep-2019 10:21:56 - DEBUG :: Thread-26 : Checking if track title: tokyo road is in file name: d:\nzbget\dl\complete\music\bon jovi 1985 7800 fahrenheit\01 bon jovi in and out of love 21-Sep-2019 10:21:56 - DEBUG :: Thread-26 : Checking if track title: price of love is in file name: d:\nzbget\dl\complete\music\bon jovi 1985 7800 fahrenheit\01 bon jovi in and out of love 21-Sep-2019 10:21:56 - DEBUG :: Thread-26 : Checking if track title: king of the mountain is in file name: d:\nzbget\dl\complete\music\bon jovi 1985 7800 fahrenheit\01 bon jovi in and out of love 21-Sep-2019 10:21:56 - DEBUG :: Thread-26 : Checking if track title: secret dreams is in file name: d:\nzbget\dl\complete\music\bon jovi 1985 7800 fahrenheit\01 bon jovi in and out of love 21-Sep-2019 10:21:56 - DEBUG :: Thread-26 : Checking if track title: in and out of love is in file name: d:\nzbget\dl\complete\music\bon jovi 1985 7800 fahrenheit\01 bon jovi in and out of love 21-Sep-2019 10:21:56 - INFO :: Thread-26 : Starting post-processing for: Bon Jovi - 7800° Fahrenheit 21-Sep-2019 10:21:56 - INFO :: Thread-26 : Encoding d:\NZBGet\DL\complete\Music\Bon Jovi - 1985 - 7800 Fahrenheit\01. Bon Jovi - In And Out Of Love.flac... 21-Sep-2019 10:21:56 - DEBUG :: Thread-26 : d:\ffmpeg\bin\ -i "d:\NZBGet\DL\complete\Music\Bon Jovi - 1985 - 7800 Fahrenheit\01. Bon Jovi - In And Out Of Love.flac" -y -acodec alac -vn "d:\NZBGet\DL\complete\Music\Bon Jovi - 1985 - 7800 Fahrenheit\temp\01. Bon Jovi - In And Out Of Love.m4a" 21-Sep-2019 10:21:56 - ERROR :: Thread-26 : Encoder raised an exception. Traceback (most recent call last): File "d:\headphones\headphones\music_encoder.py", line 257, in command_map return command(*args) File "d:\headphones\headphones\music_encoder.py", line 360, in command stderr=subprocess.PIPE) File "d:\Python27\lib\subprocess.py", line 394, in __init__ errread, errwrite) File "d:\Python27\lib\subprocess.py", line 644, in _execute_child startupinfo) WindowsError: [Error 5] Access is denied 21-Sep-2019 10:21:56 - INFO :: Thread-26 : Encoding d:\NZBGet\DL\complete\Music\Bon Jovi - 1985 - 7800 Fahrenheit\02. Bon Jovi - The Price Of Love.flac... 21-Sep-2019 10:21:56 - DEBUG :: Thread-26 : d:\ffmpeg\bin\ -i "d:\NZBGet\DL\complete\Music\Bon Jovi - 1985 - 7800 Fahrenheit\02. Bon Jovi - The Price Of Love.flac" -y -acodec alac -vn "d:\NZBGet\DL\complete\Music\Bon Jovi - 1985 - 7800 Fahrenheit\temp\02. Bon Jovi - The Price Of Love.m4a" 21-Sep-2019 10:21:56 - ERROR :: Thread-26 : Encoder raised an exception. Traceback (most recent call last): File "d:\headphones\headphones\music_encoder.py", line 257, in command_map return command(*args) File "d:\headphones\headphones\music_encoder.py", line 360, in command stderr=subprocess.PIPE) File "d:\Python27\lib\subprocess.py", line 394, in __init__ errread, errwrite) File "d:\Python27\lib\subprocess.py", line 644, in _execute_child startupinfo) WindowsError: [Error 5] Access is denied 21-Sep-2019 10:21:56 - INFO :: Thread-26 : Encoding d:\NZBGet\DL\complete\Music\Bon Jovi - 1985 - 7800 Fahrenheit\03. Bon Jovi - Only Lonely.flac... 21-Sep-2019 10:21:56 - DEBUG :: Thread-26 : d:\ffmpeg\bin\ -i "d:\NZBGet\DL\complete\Music\Bon Jovi - 1985 - 7800 Fahrenheit\03. Bon Jovi - Only Lonely.flac" -y -acodec alac -vn "d:\NZBGet\DL\complete\Music\Bon Jovi - 1985 - 7800 Fahrenheit\temp\03. Bon Jovi - Only Lonely.m4a" 21-Sep-2019 10:21:56 - ERROR :: Thread-26 : Encoder raised an exception. Traceback (most recent call last): File "d:\headphones\headphones\music_encoder.py", line 257, in command_map return command(*args) File "d:\headphones\headphones\music_encoder.py", line 360, in command stderr=subprocess.PIPE) File "d:\Python27\lib\subprocess.py", line 394, in __init__ errread, errwrite) File "d:\Python27\lib\subprocess.py", line 644, in _execute_child startupinfo) WindowsError: [Error 5] Access is denied 21-Sep-2019 10:21:56 - INFO :: Thread-26 : Encoding d:\NZBGet\DL\complete\Music\Bon Jovi - 1985 - 7800 Fahrenheit\04. Bon Jovi - King Of The Mountain.flac... 21-Sep-2019 10:21:56 - DEBUG :: Thread-26 : d:\ffmpeg\bin\ -i "d:\NZBGet\DL\complete\Music\Bon Jovi - 1985 - 7800 Fahrenheit\04. Bon Jovi - King Of The Mountain.flac" -y -acodec alac -vn "d:\NZBGet\DL\complete\Music\Bon Jovi - 1985 - 7800 Fahrenheit\temp\04. Bon Jovi - King Of The Mountain.m4a" 21-Sep-2019 10:21:56 - ERROR :: Thread-26 : Encoder raised an exception. Traceback (most recent call last): File "d:\headphones\headphones\music_encoder.py", line 257, in command_map return command(*args) File "d:\headphones\headphones\music_encoder.py", line 360, in command stderr=subprocess.PIPE) File "d:\Python27\lib\subprocess.py", line 394, in __init__ errread, errwrite) File "d:\Python27\lib\subprocess.py", line 644, in _execute_child startupinfo) WindowsError: [Error 5] Access is denied 21-Sep-2019 10:21:56 - INFO :: Thread-26 : Encoding d:\NZBGet\DL\complete\Music\Bon Jovi - 1985 - 7800 Fahrenheit\05. Bon Jovi - Silent Night.flac... 21-Sep-2019 10:21:56 - DEBUG :: Thread-26 : d:\ffmpeg\bin\ -i "d:\NZBGet\DL\complete\Music\Bon Jovi - 1985 - 7800 Fahrenheit\05. Bon Jovi - Silent Night.flac" -y -acodec alac -vn "d:\NZBGet\DL\complete\Music\Bon Jovi - 1985 - 7800 Fahrenheit\temp\05. Bon Jovi - Silent Night.m4a" 21-Sep-2019 10:21:56 - ERROR :: Thread-26 : Encoder raised an exception. Traceback (most recent call last): File "d:\headphones\headphones\music_encoder.py", line 257, in command_map return command(*args) File "d:\headphones\headphones\music_encoder.py", line 360, in command stderr=subprocess.PIPE) File "d:\Python27\lib\subprocess.py", line 394, in __init__ errread, errwrite) File "d:\Python27\lib\subprocess.py", line 644, in _execute_child startupinfo) WindowsError: [Error 5] Access is denied 21-Sep-2019 10:21:57 - INFO :: Thread-26 : Encoding d:\NZBGet\DL\complete\Music\Bon Jovi - 1985 - 7800 Fahrenheit\06. Bon Jovi - Tokyo Road.flac... 21-Sep-2019 10:21:57 - DEBUG :: Thread-26 : d:\ffmpeg\bin\ -i "d:\NZBGet\DL\complete\Music\Bon Jovi - 1985 - 7800 Fahrenheit\06. Bon Jovi - Tokyo Road.flac" -y -acodec alac -vn "d:\NZBGet\DL\complete\Music\Bon Jovi - 1985 - 7800 Fahrenheit\temp\06. Bon Jovi - Tokyo Road.m4a" 21-Sep-2019 10:21:57 - ERROR :: Thread-26 : Encoder raised an exception. Traceback (most recent call last): File "d:\headphones\headphones\music_encoder.py", line 257, in command_map return command(*args) File "d:\headphones\headphones\music_encoder.py", line 360, in command stderr=subprocess.PIPE) File "d:\Python27\lib\subprocess.py", line 394, in __init__ errread, errwrite) File "d:\Python27\lib\subprocess.py", line 644, in _execute_child startupinfo) WindowsError: [Error 5] Access is denied 21-Sep-2019 10:21:57 - INFO :: Thread-26 : Encoding d:\NZBGet\DL\complete\Music\Bon Jovi - 1985 - 7800 Fahrenheit\07. Bon Jovi - The Hardest Part Is The Night.flac... 21-Sep-2019 10:21:57 - DEBUG :: Thread-26 : d:\ffmpeg\bin\ -i "d:\NZBGet\DL\complete\Music\Bon Jovi - 1985 - 7800 Fahrenheit\07. Bon Jovi - The Hardest Part Is The Night.flac" -y -acodec alac -vn "d:\NZBGet\DL\complete\Music\Bon Jovi - 1985 - 7800 Fahrenheit\temp\07. Bon Jovi - The Hardest Part Is The Night.m4a" 21-Sep-2019 10:21:57 - ERROR :: Thread-26 : Encoder raised an exception. Traceback (most recent call last): File "d:\headphones\headphones\music_encoder.py", line 257, in command_map return command(*args) File "d:\headphones\headphones\music_encoder.py", line 360, in command stderr=subprocess.PIPE) File "d:\Python27\lib\subprocess.py", line 394, in __init__ errread, errwrite) File "d:\Python27\lib\subprocess.py", line 644, in _execute_child startupinfo) WindowsError: [Error 5] Access is denied 21-Sep-2019 10:21:57 - INFO :: Thread-26 : Encoding d:\NZBGet\DL\complete\Music\Bon Jovi - 1985 - 7800 Fahrenheit\08. Bon Jovi - Always Run To You.flac... 21-Sep-2019 10:21:57 - DEBUG :: Thread-26 : d:\ffmpeg\bin\ -i "d:\NZBGet\DL\complete\Music\Bon Jovi - 1985 - 7800 Fahrenheit\08. Bon Jovi - Always Run To You.flac" -y -acodec alac -vn "d:\NZBGet\DL\complete\Music\Bon Jovi - 1985 - 7800 Fahrenheit\temp\08. Bon Jovi - Always Run To You.m4a" 21-Sep-2019 10:21:57 - ERROR :: Thread-26 : Encoder raised an exception. Traceback (most recent call last): File "d:\headphones\headphones\music_encoder.py", line 257, in command_map return command(*args) File "d:\headphones\headphones\music_encoder.py", line 360, in command stderr=subprocess.PIPE) File "d:\Python27\lib\subprocess.py", line 394, in __init__ errread, errwrite) File "d:\Python27\lib\subprocess.py", line 644, in _execute_child startupinfo) WindowsError: [Error 5] Access is denied 21-Sep-2019 10:21:57 - INFO :: Thread-26 : Encoding d:\NZBGet\DL\complete\Music\Bon Jovi - 1985 - 7800 Fahrenheit\09. Bon Jovi - (I Don't Wanna Fall) To The Fire.flac... 21-Sep-2019 10:21:57 - DEBUG :: Thread-26 : d:\ffmpeg\bin\ -i "d:\NZBGet\DL\complete\Music\Bon Jovi - 1985 - 7800 Fahrenheit\09. Bon Jovi - (I Don't Wanna Fall) To The Fire.flac" -y -acodec alac -vn "d:\NZBGet\DL\complete\Music\Bon Jovi - 1985 - 7800 Fahrenheit\temp\09. Bon Jovi - (I Don't Wanna Fall) To The Fire.m4a" 21-Sep-2019 10:21:57 - ERROR :: Thread-26 : Encoder raised an exception. Traceback (most recent call last): File "d:\headphones\headphones\music_encoder.py", line 257, in command_map return command(*args) File "d:\headphones\headphones\music_encoder.py", line 360, in command stderr=subprocess.PIPE) File "d:\Python27\lib\subprocess.py", line 394, in __init__ errread, errwrite) File "d:\Python27\lib\subprocess.py", line 644, in _execute_child startupinfo) WindowsError: [Error 5] Access is denied 21-Sep-2019 10:21:57 - INFO :: Thread-26 : Encoding d:\NZBGet\DL\complete\Music\Bon Jovi - 1985 - 7800 Fahrenheit\10. Bon Jovi - Secret Dreams.flac... 21-Sep-2019 10:21:57 - DEBUG :: Thread-26 : d:\ffmpeg\bin\ -i "d:\NZBGet\DL\complete\Music\Bon Jovi - 1985 - 7800 Fahrenheit\10. Bon Jovi - Secret Dreams.flac" -y -acodec alac -vn "d:\NZBGet\DL\complete\Music\Bon Jovi - 1985 - 7800 Fahrenheit\temp\10. Bon Jovi - Secret Dreams.m4a" 21-Sep-2019 10:21:57 - ERROR :: Thread-26 : Encoder raised an exception. Traceback (most recent call last): File "d:\headphones\headphones\music_encoder.py", line 257, in command_map return command(*args) File "d:\headphones\headphones\music_encoder.py", line 360, in command stderr=subprocess.PIPE) File "d:\Python27\lib\subprocess.py", line 394, in __init__ errread, errwrite) File "d:\Python27\lib\subprocess.py", line 644, in _execute_child startupinfo) WindowsError: [Error 5] Access is denied 21-Sep-2019 10:21:57 - ERROR :: Thread-26 : One or more files failed to encode. Ensure you have the latest version of ffmpeg installed.

raymondjstone commented 4 years ago

Cue file splitting is where teh album is a single file, yours is not, it is file per track so cue files should not be getting used

raymondjstone commented 4 years ago

what option do you have set for original files - keep or delete, and have you checked that the original files and folder is not read only??

skydiverscott commented 4 years ago

Delete. If you look at the manually run command, I wrote the output file back to the original folder, so it is r/w also: ` D:\NZBGet\DL\complete\Music>cacls "Bon Jovi - 1985 - 7800 Fahrenheit" D:\NZBGet\DL\complete\Music\Bon Jovi - 1985 - 7800 Fahrenheit BUILTIN\Administrators:(OI)(CI)F NT AUTHORITY\SYSTEM:(OI)(CI)F BUILTIN\Administrators:F CREATOR OWNER:(OI)(CI)(IO)F BUILTIN\Users:(OI)(CI)R BUILTIN\Users:(CI)(special access:) FILE_APPEND_DATA

                                                          BUILTIN\Users:(CI)(special access:)
                                                                            FILE_WRITE_DATA

`

skydiverscott commented 4 years ago

I re-processed the single folder choosing to keep the original files. Same result: 21-Sep-2019 10:31:55 - DEBUG :: Thread-28 : Matching metadata artist: bon jovi with artist name: bon jovi 21-Sep-2019 10:31:55 - DEBUG :: Thread-28 : Matching metadata album: 7800 fahrenheit [28pd-521] with album name: 7800{degrees} fahrenheit 21-Sep-2019 10:31:55 - DEBUG :: Thread-28 : Metadata check failed. Verifying filenames... 21-Sep-2019 10:31:55 - DEBUG :: Thread-28 : Checking if track title: silent night is in file name: d:\nzbget\dl\complete\music\bon jovi 1985 7800 fahrenheit\01 bon jovi in and out of love 21-Sep-2019 10:31:55 - DEBUG :: Thread-28 : Checking if track title: tokyo road is in file name: d:\nzbget\dl\complete\music\bon jovi 1985 7800 fahrenheit\01 bon jovi in and out of love 21-Sep-2019 10:31:55 - DEBUG :: Thread-28 : Checking if track title: price of love is in file name: d:\nzbget\dl\complete\music\bon jovi 1985 7800 fahrenheit\01 bon jovi in and out of love 21-Sep-2019 10:31:55 - DEBUG :: Thread-28 : Checking if track title: king of the mountain is in file name: d:\nzbget\dl\complete\music\bon jovi 1985 7800 fahrenheit\01 bon jovi in and out of love 21-Sep-2019 10:31:55 - DEBUG :: Thread-28 : Checking if track title: secret dreams is in file name: d:\nzbget\dl\complete\music\bon jovi 1985 7800 fahrenheit\01 bon jovi in and out of love 21-Sep-2019 10:31:55 - DEBUG :: Thread-28 : Checking if track title: in and out of love is in file name: d:\nzbget\dl\complete\music\bon jovi 1985 7800 fahrenheit\01 bon jovi in and out of love 21-Sep-2019 10:31:55 - INFO :: Thread-28 : Starting post-processing for: Bon Jovi - 7800o Fahrenheit 21-Sep-2019 10:31:55 - INFO :: Thread-28 : Preparing to copy to a temporary directory for post processing: d:\NZBGet\DL\complete\Music\Bon Jovi - 1985 - 7800 Fahrenheit\ 21-Sep-2019 10:31:55 - INFO :: Thread-28 : Copying files to d:\headphones\temp\headphones_Bon Jovi - 1985 - 7800 Fahrenheit_@hp@_eb5ixr\headphones 21-Sep-2019 10:32:04 - INFO :: Thread-28 : Encoding d:\headphones\temp\headphones_Bon Jovi - 1985 - 7800 Fahrenheit_@hp@_eb5ixr\headphones\01. Bon Jovi - In And Out Of Love.flac... 21-Sep-2019 10:32:04 - DEBUG :: Thread-28 : d:\ffmpeg\bin\ -i "d:\headphones\temp\headphones_Bon Jovi - 1985 - 7800 Fahrenheit_@hp@_eb5ixr\headphones\01. Bon Jovi - In And Out Of Love.flac" -y -acodec alac -vn "d:\headphones\temp\headphones_Bon Jovi - 1985 - 7800 Fahrenheit_@hp@_eb5ixr\headphones\temp\01. Bon Jovi - In And Out Of Love.m4a" 21-Sep-2019 10:32:04 - ERROR :: Thread-28 : Encoder raised an exception. Traceback (most recent call last): File "d:\headphones\headphones\music_encoder.py", line 257, in command_map return command(*args) File "d:\headphones\headphones\music_encoder.py", line 360, in command stderr=subprocess.PIPE) File "d:\Python27\lib\subprocess.py", line 394, in __init__ errread, errwrite) File "d:\Python27\lib\subprocess.py", line 644, in _execute_child startupinfo) WindowsError: [Error 5] Access is denied 21-Sep-2019 10:32:04 - INFO :: Thread-28 : Encoding d:\headphones\temp\headphones_Bon Jovi - 1985 - 7800 Fahrenheit_@hp@_eb5ixr\headphones\02. Bon Jovi - The Price Of Love.flac... 21-Sep-2019 10:32:04 - DEBUG :: Thread-28 : d:\ffmpeg\bin\ -i "d:\headphones\temp\headphones_Bon Jovi - 1985 - 7800 Fahrenheit_@hp@_eb5ixr\headphones\02. Bon Jovi - The Price Of Love.flac" -y -acodec alac -vn "d:\headphones\temp\headphones_Bon Jovi - 1985 - 7800 Fahrenheit_@hp@_eb5ixr\headphones\temp\02. Bon Jovi - The Price Of Love.m4a" 21-Sep-2019 10:32:04 - ERROR :: Thread-28 : Encoder raised an exception. Traceback (most recent call last): File "d:\headphones\headphones\music_encoder.py", line 257, in command_map return command(*args) File "d:\headphones\headphones\music_encoder.py", line 360, in command stderr=subprocess.PIPE) File "d:\Python27\lib\subprocess.py", line 394, in __init__ errread, errwrite) File "d:\Python27\lib\subprocess.py", line 644, in _execute_child startupinfo) WindowsError: [Error 5] Access is denied 21-Sep-2019 10:32:04 - INFO :: Thread-28 : Encoding d:\headphones\temp\headphones_Bon Jovi - 1985 - 7800 Fahrenheit_@hp@_eb5ixr\headphones\03. Bon Jovi - Only Lonely.flac... 21-Sep-2019 10:32:04 - DEBUG :: Thread-28 : d:\ffmpeg\bin\ -i "d:\headphones\temp\headphones_Bon Jovi - 1985 - 7800 Fahrenheit_@hp@_eb5ixr\headphones\03. Bon Jovi - Only Lonely.flac" -y -acodec alac -vn "d:\headphones\temp\headphones_Bon Jovi - 1985 - 7800 Fahrenheit_@hp@_eb5ixr\headphones\temp\03. Bon Jovi - Only Lonely.m4a" 21-Sep-2019 10:32:04 - ERROR :: Thread-28 : Encoder raised an exception. Traceback (most recent call last): File "d:\headphones\headphones\music_encoder.py", line 257, in command_map return command(*args) File "d:\headphones\headphones\music_encoder.py", line 360, in command stderr=subprocess.PIPE) File "d:\Python27\lib\subprocess.py", line 394, in __init__ errread, errwrite) File "d:\Python27\lib\subprocess.py", line 644, in _execute_child startupinfo) WindowsError: [Error 5] Access is denied 21-Sep-2019 10:32:04 - INFO :: Thread-28 : Encoding d:\headphones\temp\headphones_Bon Jovi - 1985 - 7800 Fahrenheit_@hp@_eb5ixr\headphones\04. Bon Jovi - King Of The Mountain.flac... 21-Sep-2019 10:32:04 - DEBUG :: Thread-28 : d:\ffmpeg\bin\ -i "d:\headphones\temp\headphones_Bon Jovi - 1985 - 7800 Fahrenheit_@hp@_eb5ixr\headphones\04. Bon Jovi - King Of The Mountain.flac" -y -acodec alac -vn "d:\headphones\temp\headphones_Bon Jovi - 1985 - 7800 Fahrenheit_@hp@_eb5ixr\headphones\temp\04. Bon Jovi - King Of The Mountain.m4a" 21-Sep-2019 10:32:04 - ERROR :: Thread-28 : Encoder raised an exception. Traceback (most recent call last): File "d:\headphones\headphones\music_encoder.py", line 257, in command_map return command(*args) File "d:\headphones\headphones\music_encoder.py", line 360, in command stderr=subprocess.PIPE) File "d:\Python27\lib\subprocess.py", line 394, in __init__ errread, errwrite) File "d:\Python27\lib\subprocess.py", line 644, in _execute_child startupinfo) WindowsError: [Error 5] Access is denied 21-Sep-2019 10:32:04 - INFO :: Thread-28 : Encoding d:\headphones\temp\headphones_Bon Jovi - 1985 - 7800 Fahrenheit_@hp@_eb5ixr\headphones\05. Bon Jovi - Silent Night.flac... 21-Sep-2019 10:32:04 - DEBUG :: Thread-28 : d:\ffmpeg\bin\ -i "d:\headphones\temp\headphones_Bon Jovi - 1985 - 7800 Fahrenheit_@hp@_eb5ixr\headphones\05. Bon Jovi - Silent Night.flac" -y -acodec alac -vn "d:\headphones\temp\headphones_Bon Jovi - 1985 - 7800 Fahrenheit_@hp@_eb5ixr\headphones\temp\05. Bon Jovi - Silent Night.m4a" 21-Sep-2019 10:32:04 - ERROR :: Thread-28 : Encoder raised an exception. Traceback (most recent call last): File "d:\headphones\headphones\music_encoder.py", line 257, in command_map return command(*args) File "d:\headphones\headphones\music_encoder.py", line 360, in command stderr=subprocess.PIPE) File "d:\Python27\lib\subprocess.py", line 394, in __init__ errread, errwrite) File "d:\Python27\lib\subprocess.py", line 644, in _execute_child startupinfo) WindowsError: [Error 5] Access is denied 21-Sep-2019 10:32:04 - INFO :: Thread-28 : Encoding d:\headphones\temp\headphones_Bon Jovi - 1985 - 7800 Fahrenheit_@hp@_eb5ixr\headphones\06. Bon Jovi - Tokyo Road.flac... 21-Sep-2019 10:32:04 - DEBUG :: Thread-28 : d:\ffmpeg\bin\ -i "d:\headphones\temp\headphones_Bon Jovi - 1985 - 7800 Fahrenheit_@hp@_eb5ixr\headphones\06. Bon Jovi - Tokyo Road.flac" -y -acodec alac -vn "d:\headphones\temp\headphones_Bon Jovi - 1985 - 7800 Fahrenheit_@hp@_eb5ixr\headphones\temp\06. Bon Jovi - Tokyo Road.m4a" 21-Sep-2019 10:32:04 - ERROR :: Thread-28 : Encoder raised an exception. Traceback (most recent call last): File "d:\headphones\headphones\music_encoder.py", line 257, in command_map return command(*args) File "d:\headphones\headphones\music_encoder.py", line 360, in command stderr=subprocess.PIPE) File "d:\Python27\lib\subprocess.py", line 394, in __init__ errread, errwrite) File "d:\Python27\lib\subprocess.py", line 644, in _execute_child startupinfo) WindowsError: [Error 5] Access is denied 21-Sep-2019 10:32:04 - INFO :: Thread-28 : Encoding d:\headphones\temp\headphones_Bon Jovi - 1985 - 7800 Fahrenheit_@hp@_eb5ixr\headphones\07. Bon Jovi - The Hardest Part Is The Night.flac... 21-Sep-2019 10:32:04 - DEBUG :: Thread-28 : d:\ffmpeg\bin\ -i "d:\headphones\temp\headphones_Bon Jovi - 1985 - 7800 Fahrenheit_@hp@_eb5ixr\headphones\07. Bon Jovi - The Hardest Part Is The Night.flac" -y -acodec alac -vn "d:\headphones\temp\headphones_Bon Jovi - 1985 - 7800 Fahrenheit_@hp@_eb5ixr\headphones\temp\07. Bon Jovi - The Hardest Part Is The Night.m4a" 21-Sep-2019 10:32:04 - ERROR :: Thread-28 : Encoder raised an exception. Traceback (most recent call last): File "d:\headphones\headphones\music_encoder.py", line 257, in command_map return command(*args) File "d:\headphones\headphones\music_encoder.py", line 360, in command stderr=subprocess.PIPE) File "d:\Python27\lib\subprocess.py", line 394, in __init__ errread, errwrite) File "d:\Python27\lib\subprocess.py", line 644, in _execute_child startupinfo) WindowsError: [Error 5] Access is denied 21-Sep-2019 10:32:04 - INFO :: Thread-28 : Encoding d:\headphones\temp\headphones_Bon Jovi - 1985 - 7800 Fahrenheit_@hp@_eb5ixr\headphones\08. Bon Jovi - Always Run To You.flac... 21-Sep-2019 10:32:04 - DEBUG :: Thread-28 : d:\ffmpeg\bin\ -i "d:\headphones\temp\headphones_Bon Jovi - 1985 - 7800 Fahrenheit_@hp@_eb5ixr\headphones\08. Bon Jovi - Always Run To You.flac" -y -acodec alac -vn "d:\headphones\temp\headphones_Bon Jovi - 1985 - 7800 Fahrenheit_@hp@_eb5ixr\headphones\temp\08. Bon Jovi - Always Run To You.m4a" 21-Sep-2019 10:32:04 - ERROR :: Thread-28 : Encoder raised an exception. Traceback (most recent call last): File "d:\headphones\headphones\music_encoder.py", line 257, in command_map return command(*args) File "d:\headphones\headphones\music_encoder.py", line 360, in command stderr=subprocess.PIPE) File "d:\Python27\lib\subprocess.py", line 394, in __init__ errread, errwrite) File "d:\Python27\lib\subprocess.py", line 644, in _execute_child startupinfo) WindowsError: [Error 5] Access is denied 21-Sep-2019 10:32:04 - INFO :: Thread-28 : Encoding d:\headphones\temp\headphones_Bon Jovi - 1985 - 7800 Fahrenheit_@hp@_eb5ixr\headphones\09. Bon Jovi - (I Don't Wanna Fall) To The Fire.flac... 21-Sep-2019 10:32:04 - DEBUG :: Thread-28 : d:\ffmpeg\bin\ -i "d:\headphones\temp\headphones_Bon Jovi - 1985 - 7800 Fahrenheit_@hp@_eb5ixr\headphones\09. Bon Jovi - (I Don't Wanna Fall) To The Fire.flac" -y -acodec alac -vn "d:\headphones\temp\headphones_Bon Jovi - 1985 - 7800 Fahrenheit_@hp@_eb5ixr\headphones\temp\09. Bon Jovi - (I Don't Wanna Fall) To The Fire.m4a" 21-Sep-2019 10:32:04 - ERROR :: Thread-28 : Encoder raised an exception. Traceback (most recent call last): File "d:\headphones\headphones\music_encoder.py", line 257, in command_map return command(*args) File "d:\headphones\headphones\music_encoder.py", line 360, in command stderr=subprocess.PIPE) File "d:\Python27\lib\subprocess.py", line 394, in __init__ errread, errwrite) File "d:\Python27\lib\subprocess.py", line 644, in _execute_child startupinfo) WindowsError: [Error 5] Access is denied 21-Sep-2019 10:32:04 - INFO :: Thread-28 : Encoding d:\headphones\temp\headphones_Bon Jovi - 1985 - 7800 Fahrenheit_@hp@_eb5ixr\headphones\10. Bon Jovi - Secret Dreams.flac... 21-Sep-2019 10:32:04 - DEBUG :: Thread-28 : d:\ffmpeg\bin\ -i "d:\headphones\temp\headphones_Bon Jovi - 1985 - 7800 Fahrenheit_@hp@_eb5ixr\headphones\10. Bon Jovi - Secret Dreams.flac" -y -acodec alac -vn "d:\headphones\temp\headphones_Bon Jovi - 1985 - 7800 Fahrenheit_@hp@_eb5ixr\headphones\temp\10. Bon Jovi - Secret Dreams.m4a" 21-Sep-2019 10:32:04 - ERROR :: Thread-28 : Encoder raised an exception. Traceback (most recent call last): File "d:\headphones\headphones\music_encoder.py", line 257, in command_map return command(*args) File "d:\headphones\headphones\music_encoder.py", line 360, in command stderr=subprocess.PIPE) File "d:\Python27\lib\subprocess.py", line 394, in __init__ errread, errwrite) File "d:\Python27\lib\subprocess.py", line 644, in _execute_child startupinfo) WindowsError: [Error 5] Access is denied 21-Sep-2019 10:32:04 - ERROR :: Thread-28 : One or more files failed to encode. Ensure you have the latest version of ffmpeg installed.

raymondjstone commented 4 years ago

anything in the windows event viewer log?

skydiverscott commented 4 years ago

Which hive am I looking for?

raymondjstone commented 4 years ago

check the all but the applications one would be the most likely.

skydiverscott commented 4 years ago

Nothing obvious in the Windows Event Logs..

I mentioned higher up in this thread this observation:

So manually created the temp folder in the directory to be processed and ran the ffmpeg command from the command line and the file gets re-encoded successfully.

Then I emptied the temp folder of any files, and attempted to force process this folder in headphones while I watched the to be processed folder.

I saw the temp folder get deleted and then recreated multiple times as headphones processed each flac file ending without the temp folder.

So this shows that at least the creation of the temp folder is not being stopped due to file system ACL's. It is almost like

headphones is deleting the temp folder running the ffmpeg command Failing and logging the error creating the folder Looking in the folder to move the file and not finding anything, deleting the folder starting the next file I am only guessing this from observing the file folders getting created and the resulting missing temp folder.

I have not looked at the code directly. I wouldn't know where to look. This behavior occurs when I manually post process a single folder and choose to delete the original files.

When I manually add the temp folder then choose to keep the original files the manually created temp folder remains after running the force folder processing option in headphones.

raymondjstone commented 4 years ago

The only difference I can see from your setup and mine (other than you using m4a) is that your on W server 2016 and I am running headphones on W10. Looking like issues with S2016 perhaps. not sure what additional security hoops that added over older server versions,

rembo10 / headphones

Headphone Post processing Issues On Windows Server 2016 #3219