Unexpected error: EBUSY: resource busy or locked

[thiagoeec]

When trying to check some books, I'm getting this error of 'resource busy or locked'. It always happens with images (jpeg ang gif, so far). When I rerun ACE (without deleting the 'data' folder), then it completes
the check without errors.

Ace version: 1.0.2
Node Version: v11.4.0
OS: Windows 10 x64 (version 1809)

ace -V -o report "Test.epub"
verbose: Ace 1.0.2, Node v11.4.0, Windows_NT 10.0.17763
verbose: Options: cwd=C:\Users\go_th\Desktop, outdir=report, tmpdir=undefined, verbose=true, silent=false, jobId=
info:    Processing Test.epub
verbose: Extracting EPUB
info:    Parsing EPUB
verbose: at location 'C:\Users\go_th\AppData\Local\Temp\tmp-9316p6iJJgr9wCgX'
info:    Analyzing accessibility metadata
info:    Checking package...
info:    - OEBPS\content.opf: 4 issues found
info:    Checking documents...
verbose: - Processing Text/CoverImage.xhtml
verbose: - Processing Text/38_estrategias_epub.xhtml
verbose: - Processing Text/Section0001.xhtml
verbose: - Processing Text/Section0002.xhtml
verbose: Converting aXe results to ace for Text/CoverImage.xhtml
info:    - Text/CoverImage.xhtml: 1 issues found
verbose: - Processing Text/Section0003.xhtml
verbose: Converting aXe results to ace for Text/38_estrategias_epub.xhtml
info:    - Text/38_estrategias_epub.xhtml: 1 issues found
verbose: - Processing Text/Dialet_Erist.xhtml
verbose: Converting aXe results to ace for Text/Section0001.xhtml
info:    - Text/Section0001.xhtml: 1 issues found
verbose: - Processing Text/Section0005.xhtml
verbose: Converting aXe results to ace for Text/Section0002.xhtml
info:    - Text/Section0002.xhtml: 1 issues found
verbose: - Processing Text/A_base_toda.xhtml
verbose: Converting aXe results to ace for Text/Dialet_Erist.xhtml
info:    - Text/Dialet_Erist.xhtml: 1 issues found
verbose: - Processing Text/Ilustra_01.xhtml
verbose: Converting aXe results to ace for Text/A_base_toda.xhtml
info:    - Text/A_base_toda.xhtml: 1 issues found
verbose: - Processing Text/Section0008.xhtml
verbose: Converting aXe results to ace for Text/Ilustra_01.xhtml
info:    - Text/Ilustra_01.xhtml: 1 issues found
verbose: - Processing Text/As_38_estrat.xhtml
verbose: Converting aXe results to ace for Text/Section0003.xhtml
info:    - Text/Section0003.xhtml: 1 issues found
verbose: - Processing Text/Estrat_1.xhtml
verbose: Converting aXe results to ace for Text/Section0005.xhtml
info:    - Text/Section0005.xhtml: 1 issues found
verbose: - Processing Text/Section0011.xhtml
verbose: Converting aXe results to ace for Text/As_38_estrat.xhtml
info:    - Text/As_38_estrat.xhtml: 1 issues found
verbose: - Processing Text/Section0012.xhtml
verbose: Converting aXe results to ace for Text/Section0008.xhtml
info:    - Text/Section0008.xhtml: 1 issues found
verbose: - Processing Text/Section0013.xhtml
verbose: Converting aXe results to ace for Text/Estrat_1.xhtml
info:    - Text/Estrat_1.xhtml: 1 issues found
verbose: - Processing Text/Section0014.xhtml
verbose: Converting aXe results to ace for Text/Section0011.xhtml
info:    - Text/Section0011.xhtml: 1 issues found
verbose: - Processing Text/Section0015.xhtml
verbose: Converting aXe results to ace for Text/Section0013.xhtml
info:    - Text/Section0013.xhtml: 1 issues found
verbose: - Processing Text/Section0016.xhtml
verbose: Converting aXe results to ace for Text/Section0012.xhtml
info:    - Text/Section0012.xhtml: 1 issues found
verbose: - Processing Text/Section0017.xhtml
verbose: Converting aXe results to ace for Text/Section0014.xhtml
info:    - Text/Section0014.xhtml: 1 issues found
verbose: - Processing Text/Section0018.xhtml
verbose: Converting aXe results to ace for Text/Section0015.xhtml
info:    - Text/Section0015.xhtml: 1 issues found
verbose: - Processing Text/Estrat_10.xhtml
verbose: Converting aXe results to ace for Text/Section0016.xhtml
info:    - Text/Section0016.xhtml: 1 issues found
verbose: - Processing Text/Section0020.xhtml
verbose: Converting aXe results to ace for Text/Section0017.xhtml
info:    - Text/Section0017.xhtml: 1 issues found
verbose: - Processing Text/Section0021.xhtml
verbose: Converting aXe results to ace for Text/Section0018.xhtml
info:    - Text/Section0018.xhtml: 1 issues found
verbose: - Processing Text/Section0022.xhtml
verbose: Converting aXe results to ace for Text/Estrat_10.xhtml
info:    - Text/Estrat_10.xhtml: 1 issues found
verbose: - Processing Text/Section0023.xhtml
verbose: Converting aXe results to ace for Text/Section0020.xhtml
info:    - Text/Section0020.xhtml: 1 issues found
verbose: - Processing Text/Section0024.xhtml
verbose: Converting aXe results to ace for Text/Section0021.xhtml
info:    - Text/Section0021.xhtml: 1 issues found
verbose: - Processing Text/Section0025.xhtml
verbose: Converting aXe results to ace for Text/Section0022.xhtml
info:    - Text/Section0022.xhtml: 1 issues found
verbose: - Processing Text/Section0026.xhtml
verbose: Converting aXe results to ace for Text/Section0023.xhtml
info:    - Text/Section0023.xhtml: 1 issues found
verbose: - Processing Text/Section0027.xhtml
verbose: Converting aXe results to ace for Text/Section0024.xhtml
info:    - Text/Section0024.xhtml: 1 issues found
verbose: - Processing Text/Ilustra_18.xhtml
verbose: Converting aXe results to ace for Text/Section0025.xhtml
info:    - Text/Section0025.xhtml: 1 issues found
verbose: - Processing Text/Section0029.xhtml
verbose: Converting aXe results to ace for Text/Section0026.xhtml
info:    - Text/Section0026.xhtml: 1 issues found
verbose: - Processing Text/Section0030.xhtml
verbose: Converting aXe results to ace for Text/Section0027.xhtml
info:    - Text/Section0027.xhtml: 1 issues found
verbose: - Processing Text/Section0031.xhtml
verbose: Converting aXe results to ace for Text/Ilustra_18.xhtml
info:    - Text/Ilustra_18.xhtml: 1 issues found
verbose: - Processing Text/Section0032.xhtml
verbose: Converting aXe results to ace for Text/Section0029.xhtml
info:    - Text/Section0029.xhtml: 1 issues found
verbose: - Processing Text/Section0033.xhtml
verbose: Converting aXe results to ace for Text/Section0030.xhtml
info:    - Text/Section0030.xhtml: 1 issues found
verbose: - Processing Text/Section0034.xhtml
verbose: Converting aXe results to ace for Text/Section0031.xhtml
info:    - Text/Section0031.xhtml: 1 issues found
verbose: - Processing Text/Section0035.xhtml
verbose: Converting aXe results to ace for Text/Section0032.xhtml
info:    - Text/Section0032.xhtml: 1 issues found
verbose: - Processing Text/Ilustra_25.xhtml
verbose: Converting aXe results to ace for Text/Section0033.xhtml
info:    - Text/Section0033.xhtml: 1 issues found
verbose: - Processing Text/Section0037.xhtml
verbose: Converting aXe results to ace for Text/Section0034.xhtml
info:    - Text/Section0034.xhtml: 1 issues found
verbose: - Processing Text/Section0038.xhtml
verbose: Converting aXe results to ace for Text/Ilustra_25.xhtml
info:    - Text/Ilustra_25.xhtml: 1 issues found
verbose: - Processing Text/Section0039.xhtml
verbose: Converting aXe results to ace for Text/Section0035.xhtml
info:    - Text/Section0035.xhtml: 1 issues found
verbose: - Processing Text/Section0040.xhtml
verbose: Converting aXe results to ace for Text/Section0037.xhtml
info:    - Text/Section0037.xhtml: 1 issues found
verbose: - Processing Text/Section0041.xhtml
verbose: Converting aXe results to ace for Text/Section0038.xhtml
info:    - Text/Section0038.xhtml: 1 issues found
verbose: - Processing Text/Section0056.xhtml
verbose: Converting aXe results to ace for Text/Section0040.xhtml
info:    - Text/Section0040.xhtml: 1 issues found
verbose: - Processing Text/Section0042.xhtml
verbose: Converting aXe results to ace for Text/Section0039.xhtml
info:    - Text/Section0039.xhtml: 1 issues found
verbose: - Processing Text/Section0043.xhtml
verbose: Converting aXe results to ace for Text/Section0041.xhtml
info:    - Text/Section0041.xhtml: 1 issues found
verbose: - Processing Text/Ilustra_32.xhtml
verbose: Converting aXe results to ace for Text/Ilustra_32.xhtml
info:    - Text/Ilustra_32.xhtml: 1 issues found
verbose: - Processing Text/Section0045.xhtml
verbose: Converting aXe results to ace for Text/Section0056.xhtml
info:    - Text/Section0056.xhtml: 1 issues found
verbose: - Processing Text/Ilustra_33.xhtml
verbose: Converting aXe results to ace for Text/Section0042.xhtml
info:    - Text/Section0042.xhtml: 1 issues found
verbose: - Processing Text/Section0047.xhtml
verbose: Converting aXe results to ace for Text/Section0043.xhtml
info:    - Text/Section0043.xhtml: 1 issues found
verbose: - Processing Text/Section0048.xhtml
verbose: Converting aXe results to ace for Text/Section0045.xhtml
info:    - Text/Section0045.xhtml: 1 issues found
verbose: - Processing Text/Ilustra_35.xhtml
verbose: Converting aXe results to ace for Text/Ilustra_33.xhtml
info:    - Text/Ilustra_33.xhtml: 1 issues found
verbose: - Processing Text/Section0050.xhtml
verbose: Converting aXe results to ace for Text/Section0047.xhtml
info:    - Text/Section0047.xhtml: 1 issues found
verbose: - Processing Text/Section0051.xhtml
verbose: Converting aXe results to ace for Text/Ilustra_35.xhtml
info:    - Text/Ilustra_35.xhtml: 1 issues found
verbose: - Processing Text/Section0052.xhtml
verbose: Converting aXe results to ace for Text/Section0048.xhtml
info:    - Text/Section0048.xhtml: 1 issues found
verbose: - Processing Text/Apendice.xhtml
verbose: Converting aXe results to ace for Text/Section0051.xhtml
info:    - Text/Section0051.xhtml: 1 issues found
verbose: - Processing Text/Section0054.xhtml
verbose: Converting aXe results to ace for Text/Section0050.xhtml
info:    - Text/Section0050.xhtml: 1 issues found
verbose: - Processing Text/Section0055.xhtml
verbose: Converting aXe results to ace for Text/Apendice.xhtml
info:    - Text/Apendice.xhtml: 1 issues found
verbose: - Processing Text/Autor.xhtml
verbose: Converting aXe results to ace for Text/Section0052.xhtml
info:    - Text/Section0052.xhtml: 1 issues found
verbose: - Processing Text/Creditos.xhtml
verbose: Converting aXe results to ace for Text/Autor.xhtml
info:    - Text/Autor.xhtml: 1 issues found
verbose: - Processing Text/Literat_Alema.xhtml
verbose: Converting aXe results to ace for Text/Section0055.xhtml
info:    - Text/Section0055.xhtml: 1 issues found
verbose: Converting aXe results to ace for Text/Section0054.xhtml
info:    - Text/Section0054.xhtml: 1 issues found
verbose: Converting aXe results to ace for Text/Creditos.xhtml
info:    - Text/Creditos.xhtml: 1 issues found
verbose: Converting aXe results to ace for Text/Literat_Alema.xhtml
info:    - Text/Literat_Alema.xhtml: 1 issues found
info:    Consolidating results...
info:    Copying data
error:   Unexpected error: EBUSY: resource busy or locked, copyfile 'C:\Users\go_th\AppData\Local\Temp\tmp-9316p6iJJgr9wCgX\OEBPS\Images\vinheta.gif' -> 'C:\Users\go_th\Desktop\report\data\OEBPS\Images\vinheta.gif'
debug:   Error: EBUSY: resource busy or locked, copyfile 'C:\Users\go_th\AppData\Local\Temp\tmp-9316p6iJJgr9wCgX\OEBPS\Images\vinheta.gif' -> 'C:\Users\go_th\Desktop\report\data\OEBPS\Images\vinheta.gif'
info:    Closing logs.

If you guys need, I could send the input file so you can reproduce the problem. I just need another way to send it beside here, since it is copyrighted material. This happened on several books, but none of the are on public domain.

[rdeltour]

Thanks for the report. This looks like the same issue as #192, which we’re currently trying to debug.

I think we do have sample files, although the error apparently can't always be systematically reproduced, which makes the debugging harder 😉. I'll let you know if we need more test files, or maybe if you can try a test build when a fix is ready!

[thiagoeec]

Oh, sorry for the duplicate. The title of #192 did not seem to be related to me.

Ok. If you need test files, I can provide. When the new build come out, let me know and I'll test it!

[rdeltour]

Oh, sorry for the duplicate. The title of #192 did not seem to be related to me.

No problem! 😄

Ok. If you need test files, I can provide. When the new build come out, let me know and I'll test it!

OK, will do. Thanks!

[nicolegaines]

Hi. I received this same error this morning. Please let me know if there is any progress on resolving this issue. In case it helps, I am including the log file for my issue below.
Ace 1.0.2
Node 10.15.1
Windows 10 Pro Version 10.0.17134 Build 17134

Ace log

{"level":"info","message":"   Processing 9781616480080_EPUB.epub","timestamp":"2019-01-31T15:37:40.512Z"}
{"level":"info","message":"   Parsing EPUB","timestamp":"2019-01-31T15:37:50.482Z"}
{"level":"info","message":"   Analyzing accessibility metadata","timestamp":"2019-01-31T15:37:50.835Z"}
{"level":"info","message":"   Checking package...","timestamp":"2019-01-31T15:37:50.836Z"}
{"level":"info","message":"   - OEBPS\\content.opf: No issues found","timestamp":"2019-01-31T15:37:50.837Z"}
{"level":"info","message":"   Checking documents...","timestamp":"2019-01-31T15:37:53.387Z"}
{"level":"info","message":"   - xhtml/cover.xhtml: No issues found","timestamp":"2019-01-31T15:37:54.406Z"}
{"level":"info","message":"   - xhtml/title_page.xhtml: No issues found","timestamp":"2019-01-31T15:37:54.413Z"}
{"level":"info","message":"   - xhtml/copyright.xhtml: No issues found","timestamp":"2019-01-31T15:37:54.420Z"}
{"level":"info","message":"   - xhtml/contents.xhtml: No issues found","timestamp":"2019-01-31T15:37:54.492Z"}
{"level":"info","message":"   - xhtml/foreword.xhtml: No issues found","timestamp":"2019-01-31T15:37:54.963Z"}
{"level":"info","message":"   - xhtml/acknowledgments.xhtml: No issues found","timestamp":"2019-01-31T15:37:55.042Z"}
{"level":"info","message":"   - xhtml/contributors.xhtml: No issues found","timestamp":"2019-01-31T15:37:55.090Z"}
{"level":"info","message":"   - xhtml/introduction.xhtml: No issues found","timestamp":"2019-01-31T15:37:56.287Z"}
{"level":"info","message":"   - xhtml/chapter_2.xhtml: No issues found","timestamp":"2019-01-31T15:37:56.964Z"}
{"level":"info","message":"   - xhtml/chapter_1.xhtml: No issues found","timestamp":"2019-01-31T15:37:57.794Z"}
{"level":"info","message":"   - xhtml/chapter_5.xhtml: No issues found","timestamp":"2019-01-31T15:37:58.936Z"}
{"level":"info","message":"   - xhtml/chapter_3.xhtml: No issues found","timestamp":"2019-01-31T15:38:00.481Z"}
{"level":"info","message":"   - xhtml/chapter_6.xhtml: No issues found","timestamp":"2019-01-31T15:38:00.989Z"}
{"level":"info","message":"   - xhtml/backad.xhtml: No issues found","timestamp":"2019-01-31T15:38:01.302Z"}
{"level":"info","message":"   - xhtml/chapter_7.xhtml: No issues found","timestamp":"2019-01-31T15:38:01.876Z"}
{"level":"info","message":"   - xhtml/index.xhtml: No issues found","timestamp":"2019-01-31T15:38:03.771Z"}
{"level":"info","message":"   - xhtml/chapter_4.xhtml: No issues found","timestamp":"2019-01-31T15:38:16.690Z"}
{"level":"info","message":"   Consolidating results...","timestamp":"2019-01-31T15:38:17.119Z"}
{"level":"info","message":"   Copying data","timestamp":"2019-01-31T15:38:17.122Z"}
{"level":"error","message":"  Unexpected error: EBUSY: resource busy or locked, copyfile 'C:\\Users\\ngaines\\AppData\\Local\\Temp\\tmp-105362SSFZS4lbAVL\\OEBPS\\images\\afb-lieberman-rule.jpg' -> 'C:\\Users\\ngaines\\Desktop\\results5\\data\\OEBPS\\images\\afb-lieberman-rule.jpg'","timestamp":"2019-01-31T15:38:17.509Z"}
{"level":"info","message":"   Closing logs.","timestamp":"2019-01-31T15:38:17.513Z"}

Thanks!
Nicole

[rdeltour]

Hi Nicole, thanks for the report.

This issue is still under investigation; it's not easy to reproduce. We have a workaround pending, to ignore the issue and keep on processing, although it's not ideal and doesn't replace a proper bug fix. We'd like to spend a bit more time on this before releasing the workaround.