Unit tests on windows #15

jnehring · 2015-08-03T08:57:16Z

Today I ran the unit tests of e-publishing while working on freme-project/Broker#44. I use a windows 8 machine. It showed some build errors, before and after updating to spring boot 1.2.5. On our Jenkins the unit tests went fine on Ubuntu so I believe these problems are platform dependent.

My maven console output looks like this:

[INFO] Scanning for projects...
[INFO] 
[INFO] Using the builder org.apache.maven.lifecycle.internal.builder.singlethreaded.SingleThreadedBuilder with a thread count of 1
[INFO]                                                                         
[INFO] ------------------------------------------------------------------------
[INFO] Building e-Publishing 0.3-SNAPSHOT
[INFO] ------------------------------------------------------------------------
[INFO] 
[INFO] --- maven-resources-plugin:2.6:resources (default-resources) @ e-publishing ---
[INFO] Using 'UTF-8' encoding to copy filtered resources.
[INFO] Copying 3 resources
[INFO] 
[INFO] --- maven-compiler-plugin:3.1:compile (default-compile) @ e-publishing ---
[INFO] Nothing to compile - all classes are up to date
[INFO] 
[INFO] --- maven-resources-plugin:2.6:testResources (default-testResources) @ e-publishing ---
[INFO] Using 'UTF-8' encoding to copy filtered resources.
[INFO] Copying 3 resources
[INFO] 
[INFO] --- maven-compiler-plugin:3.1:testCompile (default-testCompile) @ e-publishing ---
[INFO] Nothing to compile - all classes are up to date
[INFO] 
[INFO] --- maven-surefire-plugin:2.12.4:test (default-test) @ e-publishing ---
[INFO] Surefire report directory: C:\Users\jnehring\git\e-Publishing\target\surefire-reports

-------------------------------------------------------
 T E S T S
-------------------------------------------------------
Running eu.freme.eservices.epublishing.webservice.EPUBCreationTest
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591379096\00.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591379096\01.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591379096\02.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591379096\03.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591379096\04.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591379096\05.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591379096\06.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591379096\07.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591379096\08.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591379096\09.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591379096\10.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591379096\11.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591379096\12.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591379096\13.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591379096\cover.jpeg
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591379096\style.css
SLF4J: Class path contains multiple SLF4J bindings.
SLF4J: Found binding in [jar:file:/C:/Users/jnehring/.m2/repository/org/slf4j/slf4j-log4j12/1.7.11/slf4j-log4j12-1.7.11.jar!/org/slf4j/impl/StaticLoggerBinder.class]
SLF4J: Found binding in [jar:file:/C:/Users/jnehring/.m2/repository/org/apache/logging/log4j/log4j-slf4j-impl/2.2/log4j-slf4j-impl-2.2.jar!/org/slf4j/impl/StaticLoggerBinder.class]
SLF4J: Found binding in [jar:file:/C:/Users/jnehring/.m2/repository/org/slf4j/slf4j-simple/1.6.1/slf4j-simple-1.6.1.jar!/org/slf4j/impl/StaticLoggerBinder.class]
SLF4J: See http://www.slf4j.org/codes.html#multiple_bindings for an explanation.
SLF4J: Actual binding is of type [org.slf4j.impl.Log4jLoggerFactory]
Writing result to C:\Users\jnehring\AppData\Local\Temp\alice5246346492366064066.epub
2015-08-03 10:43:03 [ERROR] nl.siegmann.epublib.epub.PackageDocumentReader.readCover(PackageDocumentReader.java:363) Cover resource image_2 not found
2015-08-03 10:43:03 [ERROR] nl.siegmann.epublib.epub.PackageDocumentReader.readSpine(PackageDocumentReader.java:232) resource with id 'item_1' not found
2015-08-03 10:43:03 [ERROR] nl.siegmann.epublib.epub.PackageDocumentReader.readSpine(PackageDocumentReader.java:232) resource with id 'item_2' not found
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591383103\00.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591383103\01.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591383103\02.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591383103\03.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591383103\04.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591383103\05.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591383103\06.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591383103\07.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591383103\08.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591383103\09.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591383103\10.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591383103\11.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591383103\12.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591383103\13.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591383103\cover.jpeg
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591383103\style.css
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591383746\00.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591383746\01.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591383746\02.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591383746\03.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591383746\04.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591383746\05.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591383746\06.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591383746\07.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591383746\08.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591383746\09.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591383746\10.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591383746\11.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591383746\12.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591383746\13.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591383746\cover.jpeg
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591383746\style.css
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591384286\00.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591384286\01.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591384286\02.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591384286\03.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591384286\04.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591384286\05.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591384286\06.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591384286\07.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591384286\08.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591384286\09.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591384286\10.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591384286\11.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591384286\12.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591384286\13.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591384286\cover.jpeg
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591384286\style.css
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591384708\00.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591384708\01.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591384708\02.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591384708\03.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591384708\04.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591384708\05.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591384708\06.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591384708\07.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591384708\08.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591384708\09.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591384708\10.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591384708\11.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591384708\12.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591384708\13.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591384708\cover.jpeg
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591384708\style.css
Tests run: 7, Failures: 0, Errors: 4, Skipped: 0, Time elapsed: 6.738 sec <<< FAILURE!
testWithoutAuthor(eu.freme.eservices.epublishing.webservice.EPUBCreationTest)  Time elapsed: 0.645 sec  <<< ERROR!
java.io.IOException: Unable to delete file: C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591383103\cover.jpeg
    at org.apache.commons.io.FileUtils.forceDelete(FileUtils.java:2279)
    at org.apache.commons.io.FileUtils.cleanDirectory(FileUtils.java:1653)
    at org.apache.commons.io.FileUtils.deleteDirectory(FileUtils.java:1535)
    at eu.freme.eservices.epublishing.EPublishingService.createEPUB(EPublishingService.java:49)
    at eu.freme.eservices.epublishing.webservice.EPUBCreationTest.testWithoutAuthor(EPUBCreationTest.java:64)
    at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
    at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
    at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
    at java.lang.reflect.Method.invoke(Method.java:606)
    at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:44)
    at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15)
    at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:41)
    at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:20)
    at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:76)
    at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:50)
    at org.junit.runners.ParentRunner$3.run(ParentRunner.java:193)
    at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:52)
    at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:191)
    at org.junit.runners.ParentRunner.access$000(ParentRunner.java:42)
    at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:184)
    at org.junit.runners.ParentRunner.run(ParentRunner.java:236)
    at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:252)
    at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:141)
    at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:112)
    at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
    at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
    at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
    at java.lang.reflect.Method.invoke(Method.java:606)
    at org.apache.maven.surefire.util.ReflectionUtils.invokeMethodWithArray(ReflectionUtils.java:189)
    at org.apache.maven.surefire.booter.ProviderFactory$ProviderProxy.invoke(ProviderFactory.java:165)
    at org.apache.maven.surefire.booter.ProviderFactory.invokeProvider(ProviderFactory.java:85)
    at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:115)
    at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:75)

testCoverImage(eu.freme.eservices.epublishing.webservice.EPUBCreationTest)  Time elapsed: 0.536 sec  <<< ERROR!
java.io.IOException: Unable to delete file: C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591383746\cover.jpeg
    at org.apache.commons.io.FileUtils.forceDelete(FileUtils.java:2279)
    at org.apache.commons.io.FileUtils.cleanDirectory(FileUtils.java:1653)
    at org.apache.commons.io.FileUtils.deleteDirectory(FileUtils.java:1535)
    at eu.freme.eservices.epublishing.EPublishingService.createEPUB(EPublishingService.java:49)
    at eu.freme.eservices.epublishing.webservice.EPUBCreationTest.testCoverImage(EPUBCreationTest.java:140)
    at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
    at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
    at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
    at java.lang.reflect.Method.invoke(Method.java:606)
    at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:44)
    at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15)
    at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:41)
    at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:20)
    at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:76)
    at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:50)
    at org.junit.runners.ParentRunner$3.run(ParentRunner.java:193)
    at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:52)
    at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:191)
    at org.junit.runners.ParentRunner.access$000(ParentRunner.java:42)
    at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:184)
    at org.junit.runners.ParentRunner.run(ParentRunner.java:236)
    at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:252)
    at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:141)
    at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:112)
    at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
    at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
    at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
    at java.lang.reflect.Method.invoke(Method.java:606)
    at org.apache.maven.surefire.util.ReflectionUtils.invokeMethodWithArray(ReflectionUtils.java:189)
    at org.apache.maven.surefire.booter.ProviderFactory$ProviderProxy.invoke(ProviderFactory.java:165)
    at org.apache.maven.surefire.booter.ProviderFactory.invokeProvider(ProviderFactory.java:85)
    at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:115)
    at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:75)

testTitles(eu.freme.eservices.epublishing.webservice.EPUBCreationTest)  Time elapsed: 0.422 sec  <<< ERROR!
java.io.IOException: Unable to delete file: C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591384286\cover.jpeg
    at org.apache.commons.io.FileUtils.forceDelete(FileUtils.java:2279)
    at org.apache.commons.io.FileUtils.cleanDirectory(FileUtils.java:1653)
    at org.apache.commons.io.FileUtils.deleteDirectory(FileUtils.java:1535)
    at eu.freme.eservices.epublishing.EPublishingService.createEPUB(EPublishingService.java:49)
    at eu.freme.eservices.epublishing.webservice.EPUBCreationTest.testTitles(EPUBCreationTest.java:93)
    at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
    at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
    at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
    at java.lang.reflect.Method.invoke(Method.java:606)
    at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:44)
    at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15)
    at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:41)
    at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:20)
    at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:76)
    at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:50)
    at org.junit.runners.ParentRunner$3.run(ParentRunner.java:193)
    at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:52)
    at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:191)
    at org.junit.runners.ParentRunner.access$000(ParentRunner.java:42)
    at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:184)
    at org.junit.runners.ParentRunner.run(ParentRunner.java:236)
    at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:252)
    at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:141)
    at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:112)
    at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
    at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
    at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
    at java.lang.reflect.Method.invoke(Method.java:606)
    at org.apache.maven.surefire.util.ReflectionUtils.invokeMethodWithArray(ReflectionUtils.java:189)
    at org.apache.maven.surefire.booter.ProviderFactory$ProviderProxy.invoke(ProviderFactory.java:165)
    at org.apache.maven.surefire.booter.ProviderFactory.invokeProvider(ProviderFactory.java:85)
    at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:115)
    at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:75)

testAuthors(eu.freme.eservices.epublishing.webservice.EPUBCreationTest)  Time elapsed: 0.496 sec  <<< ERROR!
java.io.IOException: Unable to delete file: C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591384708\cover.jpeg
    at org.apache.commons.io.FileUtils.forceDelete(FileUtils.java:2279)
    at org.apache.commons.io.FileUtils.cleanDirectory(FileUtils.java:1653)
    at org.apache.commons.io.FileUtils.deleteDirectory(FileUtils.java:1535)
    at eu.freme.eservices.epublishing.EPublishingService.createEPUB(EPublishingService.java:49)
    at eu.freme.eservices.epublishing.webservice.EPUBCreationTest.testAuthors(EPUBCreationTest.java:116)
    at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
    at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
    at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
    at java.lang.reflect.Method.invoke(Method.java:606)
    at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:44)
    at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15)
    at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:41)
    at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:20)
    at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:76)
    at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:50)
    at org.junit.runners.ParentRunner$3.run(ParentRunner.java:193)
    at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:52)
    at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:191)
    at org.junit.runners.ParentRunner.access$000(ParentRunner.java:42)
    at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:184)
    at org.junit.runners.ParentRunner.run(ParentRunner.java:236)
    at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:252)
    at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:141)
    at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:112)
    at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
    at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
    at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
    at java.lang.reflect.Method.invoke(Method.java:606)
    at org.apache.maven.surefire.util.ReflectionUtils.invokeMethodWithArray(ReflectionUtils.java:189)
    at org.apache.maven.surefire.booter.ProviderFactory$ProviderProxy.invoke(ProviderFactory.java:165)
    at org.apache.maven.surefire.booter.ProviderFactory.invokeProvider(ProviderFactory.java:85)
    at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:115)
    at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:75)

Running eu.freme.eservices.epublishing.webservice.EPubTest

  .   ____          _            __ _ _
 /\\ / ___'_ __ _ _(_)_ __  __ _ \ \ \ \
( ( )\___ | '_ | '_| | '_ \/ _` | \ \ \ \
 \\/  ___)| |_)| | | | | || (_| |  ) ) ) )
  '  |____| .__|_| |_|_| |_\__, | / / / /
 =========|_|==============|___/=/_/_/_/
 :: Spring Boot ::        (v1.2.3.RELEASE)

2015-08-03 10:43:09 [INFO] org.springframework.boot.StartupInfoLogger.logStarting(StartupInfoLogger.java:47) Starting ForkedBooter v2.12.4 on jan with PID 15256 (C:\Users\jnehring\.m2\repository\org\apache\maven\surefire\surefire-booter\2.12.4\surefire-booter-2.12.4.jar started by jnehring in C:\Users\jnehring\git\e-Publishing)
2015-08-03 10:43:09 [INFO] org.springframework.context.support.AbstractApplicationContext.prepareRefresh(AbstractApplicationContext.java:510) Refreshing org.springframework.boot.context.embedded.AnnotationConfigEmbeddedWebApplicationContext@3129a6: startup date [Mon Aug 03 10:43:09 CEST 2015]; root of context hierarchy
2015-08-03 10:43:12 [INFO] org.springframework.beans.factory.support.DefaultListableBeanFactory.registerBeanDefinition(DefaultListableBeanFactory.java:822) Overriding bean definition for bean 'beanNameViewResolver': replacing [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=org.springframework.boot.autoconfigure.web.ErrorMvcAutoConfiguration$WhitelabelErrorViewConfiguration; factoryMethodName=beanNameViewResolver; initMethodName=null; destroyMethodName=(inferred); defined in class path resource [org/springframework/boot/autoconfigure/web/ErrorMvcAutoConfiguration$WhitelabelErrorViewConfiguration.class]] with [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=org.springframework.boot.autoconfigure.web.WebMvcAutoConfiguration$WebMvcAutoConfigurationAdapter; factoryMethodName=beanNameViewResolver; initMethodName=null; destroyMethodName=(inferred); defined in class path resource [org/springframework/boot/autoconfigure/web/WebMvcAutoConfiguration$WebMvcAutoConfigurationAdapter.class]]
2015-08-03 10:43:13 [INFO] org.springframework.beans.factory.annotation.AutowiredAnnotationBeanPostProcessor.<init>(AutowiredAnnotationBeanPostProcessor.java:153) JSR-330 'javax.inject.Inject' annotation found and supported for autowiring
2015-08-03 10:43:14 [INFO] org.hibernate.validator.internal.util.Version.<clinit>(Version.java:27) HV000001: Hibernate Validator 5.1.3.Final
2015-08-03 10:43:15 [INFO] org.springframework.boot.context.embedded.tomcat.TomcatEmbeddedServletContainer.initialize(TomcatEmbeddedServletContainer.java:79) Tomcat initialized with port(s): 8080 (http)
2015-08-03 10:43:16 [INFO] org.apache.juli.logging.DirectJDKLog.log(DirectJDKLog.java:180) Starting service Tomcat
2015-08-03 10:43:16 [INFO] org.apache.juli.logging.DirectJDKLog.log(DirectJDKLog.java:180) Starting Servlet Engine: Apache Tomcat/8.0.20
2015-08-03 10:43:16 [INFO] org.apache.juli.logging.DirectJDKLog.log(DirectJDKLog.java:180) Initializing Spring embedded WebApplicationContext
2015-08-03 10:43:16 [INFO] org.springframework.boot.context.embedded.EmbeddedWebApplicationContext.prepareEmbeddedWebApplicationContext(EmbeddedWebApplicationContext.java:268) Root WebApplicationContext: initialization completed in 7394 ms
2015-08-03 10:43:20 [INFO] org.springframework.boot.context.embedded.ServletRegistrationBean.onStartup(ServletRegistrationBean.java:188) Mapping servlet: 'dispatcherServlet' to [/]
2015-08-03 10:43:20 [INFO] org.springframework.boot.context.embedded.FilterRegistrationBean.configure(FilterRegistrationBean.java:286) Mapping filter: 'characterEncodingFilter' to: [/*]
2015-08-03 10:43:20 [INFO] org.springframework.boot.context.embedded.FilterRegistrationBean.configure(FilterRegistrationBean.java:286) Mapping filter: 'hiddenHttpMethodFilter' to: [/*]
2015-08-03 10:43:20 [INFO] org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerAdapter.initControllerAdviceCache(RequestMappingHandlerAdapter.java:517) Looking for @ControllerAdvice: org.springframework.boot.context.embedded.AnnotationConfigEmbeddedWebApplicationContext@3129a6: startup date [Mon Aug 03 10:43:09 CEST 2015]; root of context hierarchy
2015-08-03 10:43:20 [INFO] org.springframework.web.servlet.handler.AbstractHandlerMethodMapping.registerHandlerMethod(AbstractHandlerMethodMapping.java:217) Mapped "{[/e-publishing/html],methods=[POST],params=[],headers=[],consumes=[],produces=[],custom=[]}" onto public org.springframework.http.ResponseEntity<[B> eu.freme.eservices.epublishing.ServiceRestController.htmlToEPub(org.springframework.web.multipart.MultipartFile,java.lang.String) throws java.io.IOException,eu.freme.eservices.epublishing.exception.InvalidZipException,eu.freme.eservices.epublishing.exception.EPubCreationException
2015-08-03 10:43:20 [INFO] org.springframework.web.servlet.handler.AbstractHandlerMethodMapping.registerHandlerMethod(AbstractHandlerMethodMapping.java:217) Mapped "{[/error],methods=[],params=[],headers=[],consumes=[],produces=[text/html],custom=[]}" onto public org.springframework.web.servlet.ModelAndView org.springframework.boot.autoconfigure.web.BasicErrorController.errorHtml(javax.servlet.http.HttpServletRequest)
2015-08-03 10:43:20 [INFO] org.springframework.web.servlet.handler.AbstractHandlerMethodMapping.registerHandlerMethod(AbstractHandlerMethodMapping.java:217) Mapped "{[/error],methods=[],params=[],headers=[],consumes=[],produces=[],custom=[]}" onto public org.springframework.http.ResponseEntity<java.util.Map<java.lang.String, java.lang.Object>> org.springframework.boot.autoconfigure.web.BasicErrorController.error(javax.servlet.http.HttpServletRequest)
2015-08-03 10:43:20 [INFO] org.springframework.web.servlet.handler.AbstractUrlHandlerMapping.registerHandler(AbstractUrlHandlerMapping.java:314) Mapped URL path [/**] onto handler of type [class org.springframework.web.servlet.resource.ResourceHttpRequestHandler]
2015-08-03 10:43:20 [INFO] org.springframework.web.servlet.handler.AbstractUrlHandlerMapping.registerHandler(AbstractUrlHandlerMapping.java:314) Mapped URL path [/webjars/**] onto handler of type [class org.springframework.web.servlet.resource.ResourceHttpRequestHandler]
2015-08-03 10:43:21 [INFO] org.springframework.web.servlet.handler.AbstractUrlHandlerMapping.registerHandler(AbstractUrlHandlerMapping.java:314) Mapped URL path [/**/favicon.ico] onto handler of type [class org.springframework.web.servlet.resource.ResourceHttpRequestHandler]
2015-08-03 10:43:21 [INFO] org.springframework.jmx.export.MBeanExporter.afterSingletonsInstantiated(MBeanExporter.java:431) Registering beans for JMX exposure on startup
2015-08-03 10:43:21 [INFO] org.apache.juli.logging.DirectJDKLog.log(DirectJDKLog.java:180) Initializing ProtocolHandler ["http-nio-8080"]
2015-08-03 10:43:21 [INFO] org.apache.juli.logging.DirectJDKLog.log(DirectJDKLog.java:180) Starting ProtocolHandler ["http-nio-8080"]
2015-08-03 10:43:21 [INFO] org.apache.juli.logging.DirectJDKLog.log(DirectJDKLog.java:180) Using a shared selector for servlet write/read
2015-08-03 10:43:21 [INFO] org.springframework.boot.context.embedded.tomcat.TomcatEmbeddedServletContainer.start(TomcatEmbeddedServletContainer.java:159) Tomcat started on port(s): 8080 (http)
2015-08-03 10:43:21 [INFO] org.springframework.boot.StartupInfoLogger.logStarted(StartupInfoLogger.java:56) Started ForkedBooter in 15.667 seconds (JVM running for 24.148)
Converting src\test\resources\alice.zip to EPUB format.
{"titles":["Alice in Utopia"],"authors":["Joske Vermeulen","Marieke Vermalen"],"description":"Dit is een heel mooi boekske.","identifier":{"value":"urn:ean:1234-7956-1356-1123"}}
2015-08-03 10:43:25 [INFO] org.apache.juli.logging.DirectJDKLog.log(DirectJDKLog.java:180) Initializing Spring FrameworkServlet 'dispatcherServlet'
2015-08-03 10:43:25 [INFO] org.springframework.web.servlet.FrameworkServlet.initServletBean(FrameworkServlet.java:484) FrameworkServlet 'dispatcherServlet': initialization started
2015-08-03 10:43:25 [INFO] org.springframework.web.servlet.FrameworkServlet.initServletBean(FrameworkServlet.java:503) FrameworkServlet 'dispatcherServlet': initialization completed in 89 ms
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591405505\00.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591405505\01.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591405505\02.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591405505\03.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591405505\04.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591405505\05.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591405505\06.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591405505\07.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591405505\08.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591405505\09.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591405505\10.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591405505\11.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591405505\12.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591405505\13.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591405505\cover.jpeg
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591405505\style.css
Writing result to C:\Users\jnehring\AppData\Local\Temp\alice3106306652331423022.epub
2015-08-03 10:43:26 [ERROR] nl.siegmann.epublib.epub.PackageDocumentReader.readSpine(PackageDocumentReader.java:232) resource with id 'item_1' not found
2015-08-03 10:43:26 [ERROR] nl.siegmann.epublib.epub.PackageDocumentReader.readSpine(PackageDocumentReader.java:232) resource with id 'item_2' not found
2015-08-03 10:43:26 [ERROR] nl.siegmann.epublib.epub.PackageDocumentReader.readSpine(PackageDocumentReader.java:232) resource with id 'item_3' not found
2015-08-03 10:43:26 [ERROR] nl.siegmann.epublib.epub.PackageDocumentReader.readSpine(PackageDocumentReader.java:232) resource with id 'item_4' not found
2015-08-03 10:43:26 [ERROR] nl.siegmann.epublib.epub.PackageDocumentReader.readSpine(PackageDocumentReader.java:232) resource with id 'item_5' not found
2015-08-03 10:43:26 [ERROR] nl.siegmann.epublib.epub.PackageDocumentReader.readSpine(PackageDocumentReader.java:232) resource with id 'item_6' not found
2015-08-03 10:43:26 [ERROR] nl.siegmann.epublib.epub.PackageDocumentReader.readSpine(PackageDocumentReader.java:232) resource with id 'item_7' not found
2015-08-03 10:43:26 [ERROR] nl.siegmann.epublib.epub.PackageDocumentReader.readSpine(PackageDocumentReader.java:232) resource with id 'item_8' not found
2015-08-03 10:43:26 [ERROR] nl.siegmann.epublib.epub.PackageDocumentReader.readSpine(PackageDocumentReader.java:232) resource with id 'item_9' not found
2015-08-03 10:43:26 [ERROR] nl.siegmann.epublib.epub.PackageDocumentReader.readSpine(PackageDocumentReader.java:232) resource with id 'item_10' not found
2015-08-03 10:43:26 [ERROR] nl.siegmann.epublib.epub.PackageDocumentReader.readSpine(PackageDocumentReader.java:232) resource with id 'item_11' not found
2015-08-03 10:43:26 [ERROR] nl.siegmann.epublib.epub.PackageDocumentReader.readSpine(PackageDocumentReader.java:232) resource with id 'item_12' not found
2015-08-03 10:43:26 [ERROR] nl.siegmann.epublib.epub.PackageDocumentReader.readSpine(PackageDocumentReader.java:232) resource with id 'item_13' not found
2015-08-03 10:43:26 [ERROR] nl.siegmann.epublib.epub.PackageDocumentReader.readSpine(PackageDocumentReader.java:232) resource with id 'item_14' not found
[Joske Vermeulen, Marieke Vermalen]
2015-08-03 10:43:26 [INFO] org.springframework.context.support.AbstractApplicationContext.doClose(AbstractApplicationContext.java:862) Closing org.springframework.boot.context.embedded.AnnotationConfigEmbeddedWebApplicationContext@3129a6: startup date [Mon Aug 03 10:43:09 CEST 2015]; root of context hierarchy
2015-08-03 10:43:26 [INFO] org.springframework.jmx.export.MBeanExporter.destroy(MBeanExporter.java:449) Unregistering JMX-exposed beans on shutdown

  .   ____          _            __ _ _
 /\\ / ___'_ __ _ _(_)_ __  __ _ \ \ \ \
( ( )\___ | '_ | '_| | '_ \/ _` | \ \ \ \
 \\/  ___)| |_)| | | | | || (_| |  ) ) ) )
  '  |____| .__|_| |_|_| |_\__, | / / / /
 =========|_|==============|___/=/_/_/_/
 :: Spring Boot ::        (v1.2.3.RELEASE)

2015-08-03 10:43:26 [INFO] org.springframework.boot.StartupInfoLogger.logStarting(StartupInfoLogger.java:47) Starting ForkedBooter v2.12.4 on jan with PID 15256 (C:\Users\jnehring\.m2\repository\org\apache\maven\surefire\surefire-booter\2.12.4\surefire-booter-2.12.4.jar started by jnehring in C:\Users\jnehring\git\e-Publishing)
2015-08-03 10:43:26 [INFO] org.springframework.context.support.AbstractApplicationContext.prepareRefresh(AbstractApplicationContext.java:510) Refreshing org.springframework.boot.context.embedded.AnnotationConfigEmbeddedWebApplicationContext@2ae1c9: startup date [Mon Aug 03 10:43:26 CEST 2015]; root of context hierarchy
2015-08-03 10:43:27 [INFO] org.springframework.beans.factory.support.DefaultListableBeanFactory.registerBeanDefinition(DefaultListableBeanFactory.java:822) Overriding bean definition for bean 'beanNameViewResolver': replacing [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=org.springframework.boot.autoconfigure.web.ErrorMvcAutoConfiguration$WhitelabelErrorViewConfiguration; factoryMethodName=beanNameViewResolver; initMethodName=null; destroyMethodName=(inferred); defined in class path resource [org/springframework/boot/autoconfigure/web/ErrorMvcAutoConfiguration$WhitelabelErrorViewConfiguration.class]] with [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=org.springframework.boot.autoconfigure.web.WebMvcAutoConfiguration$WebMvcAutoConfigurationAdapter; factoryMethodName=beanNameViewResolver; initMethodName=null; destroyMethodName=(inferred); defined in class path resource [org/springframework/boot/autoconfigure/web/WebMvcAutoConfiguration$WebMvcAutoConfigurationAdapter.class]]
2015-08-03 10:43:27 [INFO] org.springframework.beans.factory.annotation.AutowiredAnnotationBeanPostProcessor.<init>(AutowiredAnnotationBeanPostProcessor.java:153) JSR-330 'javax.inject.Inject' annotation found and supported for autowiring
2015-08-03 10:43:27 [INFO] org.springframework.boot.context.embedded.tomcat.TomcatEmbeddedServletContainer.initialize(TomcatEmbeddedServletContainer.java:79) Tomcat initialized with port(s): 8080 (http)
2015-08-03 10:43:27 [INFO] org.apache.juli.logging.DirectJDKLog.log(DirectJDKLog.java:180) Starting service Tomcat
2015-08-03 10:43:27 [INFO] org.apache.juli.logging.DirectJDKLog.log(DirectJDKLog.java:180) Starting Servlet Engine: Apache Tomcat/8.0.20
2015-08-03 10:43:27 [INFO] org.apache.juli.logging.DirectJDKLog.log(DirectJDKLog.java:180) Initializing Spring embedded WebApplicationContext
2015-08-03 10:43:27 [INFO] org.springframework.boot.context.embedded.EmbeddedWebApplicationContext.prepareEmbeddedWebApplicationContext(EmbeddedWebApplicationContext.java:268) Root WebApplicationContext: initialization completed in 1063 ms
2015-08-03 10:43:27 [INFO] org.springframework.boot.context.embedded.ServletRegistrationBean.onStartup(ServletRegistrationBean.java:188) Mapping servlet: 'dispatcherServlet' to [/]
2015-08-03 10:43:27 [INFO] org.springframework.boot.context.embedded.FilterRegistrationBean.configure(FilterRegistrationBean.java:286) Mapping filter: 'characterEncodingFilter' to: [/*]
2015-08-03 10:43:27 [INFO] org.springframework.boot.context.embedded.FilterRegistrationBean.configure(FilterRegistrationBean.java:286) Mapping filter: 'hiddenHttpMethodFilter' to: [/*]
2015-08-03 10:43:28 [INFO] org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerAdapter.initControllerAdviceCache(RequestMappingHandlerAdapter.java:517) Looking for @ControllerAdvice: org.springframework.boot.context.embedded.AnnotationConfigEmbeddedWebApplicationContext@2ae1c9: startup date [Mon Aug 03 10:43:26 CEST 2015]; root of context hierarchy
2015-08-03 10:43:28 [INFO] org.springframework.web.servlet.handler.AbstractHandlerMethodMapping.registerHandlerMethod(AbstractHandlerMethodMapping.java:217) Mapped "{[/e-publishing/html],methods=[POST],params=[],headers=[],consumes=[],produces=[],custom=[]}" onto public org.springframework.http.ResponseEntity<[B> eu.freme.eservices.epublishing.ServiceRestController.htmlToEPub(org.springframework.web.multipart.MultipartFile,java.lang.String) throws java.io.IOException,eu.freme.eservices.epublishing.exception.InvalidZipException,eu.freme.eservices.epublishing.exception.EPubCreationException
2015-08-03 10:43:28 [INFO] org.springframework.web.servlet.handler.AbstractHandlerMethodMapping.registerHandlerMethod(AbstractHandlerMethodMapping.java:217) Mapped "{[/error],methods=[],params=[],headers=[],consumes=[],produces=[text/html],custom=[]}" onto public org.springframework.web.servlet.ModelAndView org.springframework.boot.autoconfigure.web.BasicErrorController.errorHtml(javax.servlet.http.HttpServletRequest)
2015-08-03 10:43:28 [INFO] org.springframework.web.servlet.handler.AbstractHandlerMethodMapping.registerHandlerMethod(AbstractHandlerMethodMapping.java:217) Mapped "{[/error],methods=[],params=[],headers=[],consumes=[],produces=[],custom=[]}" onto public org.springframework.http.ResponseEntity<java.util.Map<java.lang.String, java.lang.Object>> org.springframework.boot.autoconfigure.web.BasicErrorController.error(javax.servlet.http.HttpServletRequest)
2015-08-03 10:43:28 [INFO] org.springframework.web.servlet.handler.AbstractUrlHandlerMapping.registerHandler(AbstractUrlHandlerMapping.java:314) Mapped URL path [/**] onto handler of type [class org.springframework.web.servlet.resource.ResourceHttpRequestHandler]
2015-08-03 10:43:28 [INFO] org.springframework.web.servlet.handler.AbstractUrlHandlerMapping.registerHandler(AbstractUrlHandlerMapping.java:314) Mapped URL path [/webjars/**] onto handler of type [class org.springframework.web.servlet.resource.ResourceHttpRequestHandler]
2015-08-03 10:43:28 [INFO] org.springframework.web.servlet.handler.AbstractUrlHandlerMapping.registerHandler(AbstractUrlHandlerMapping.java:314) Mapped URL path [/**/favicon.ico] onto handler of type [class org.springframework.web.servlet.resource.ResourceHttpRequestHandler]
2015-08-03 10:43:28 [INFO] org.springframework.jmx.export.MBeanExporter.afterSingletonsInstantiated(MBeanExporter.java:431) Registering beans for JMX exposure on startup
2015-08-03 10:43:28 [INFO] org.apache.juli.logging.DirectJDKLog.log(DirectJDKLog.java:180) Initializing ProtocolHandler ["http-nio-8080"]
2015-08-03 10:43:28 [INFO] org.apache.juli.logging.DirectJDKLog.log(DirectJDKLog.java:180) Starting ProtocolHandler ["http-nio-8080"]
2015-08-03 10:43:28 [INFO] org.apache.juli.logging.DirectJDKLog.log(DirectJDKLog.java:180) Using a shared selector for servlet write/read
2015-08-03 10:43:28 [INFO] org.springframework.boot.context.embedded.tomcat.TomcatEmbeddedServletContainer.start(TomcatEmbeddedServletContainer.java:159) Tomcat started on port(s): 8080 (http)
2015-08-03 10:43:28 [INFO] org.springframework.boot.StartupInfoLogger.logStarted(StartupInfoLogger.java:56) Started ForkedBooter in 2.004 seconds (JVM running for 30.628)
Converting src\test\resources\alice.zip to EPUB format.
2015-08-03 10:43:28 [INFO] org.apache.juli.logging.DirectJDKLog.log(DirectJDKLog.java:180) Initializing Spring FrameworkServlet 'dispatcherServlet'
2015-08-03 10:43:28 [INFO] org.springframework.web.servlet.FrameworkServlet.initServletBean(FrameworkServlet.java:484) FrameworkServlet 'dispatcherServlet': initialization started
2015-08-03 10:43:28 [INFO] org.springframework.web.servlet.FrameworkServlet.initServletBean(FrameworkServlet.java:503) FrameworkServlet 'dispatcherServlet': initialization completed in 8 ms
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591408397\00.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591408397\01.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591408397\02.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591408397\03.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591408397\04.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591408397\05.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591408397\06.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591408397\07.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591408397\08.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591408397\09.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591408397\10.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591408397\11.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591408397\12.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591408397\13.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591408397\cover.jpeg
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591408397\style.css
2015-08-03 10:43:28 [ERROR] org.apache.juli.logging.DirectJDKLog.log(DirectJDKLog.java:182) Servlet.service() for servlet [dispatcherServlet] in context with path [] threw exception
java.io.IOException: Unable to delete file: C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591408397\09.xhtml
    at org.apache.commons.io.FileUtils.forceDelete(FileUtils.java:2279)
    at org.apache.commons.io.FileUtils.cleanDirectory(FileUtils.java:1653)
    at org.apache.commons.io.FileUtils.deleteDirectory(FileUtils.java:1535)
    at eu.freme.eservices.epublishing.EPublishingService.createEPUB(EPublishingService.java:49)
    at eu.freme.eservices.epublishing.ServiceRestController.htmlToEPub(ServiceRestController.java:37)
    at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
    at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
    at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
    at java.lang.reflect.Method.invoke(Method.java:606)
    at org.springframework.web.method.support.InvocableHandlerMethod.doInvoke(InvocableHandlerMethod.java:221)
    at org.springframework.web.method.support.InvocableHandlerMethod.invokeForRequest(InvocableHandlerMethod.java:137)
    at org.springframework.web.servlet.mvc.method.annotation.ServletInvocableHandlerMethod.invokeAndHandle(ServletInvocableHandlerMethod.java:110)
    at org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerAdapter.invokeHandleMethod(RequestMappingHandlerAdapter.java:776)
    at org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerAdapter.handleInternal(RequestMappingHandlerAdapter.java:705)
    at org.springframework.web.servlet.mvc.method.AbstractHandlerMethodAdapter.handle(AbstractHandlerMethodAdapter.java:85)
    at org.springframework.web.servlet.DispatcherServlet.doDispatch(DispatcherServlet.java:959)
    at org.springframework.web.servlet.DispatcherServlet.doService(DispatcherServlet.java:893)
    at org.springframework.web.servlet.FrameworkServlet.processRequest(FrameworkServlet.java:966)
    at org.springframework.web.servlet.FrameworkServlet.doPost(FrameworkServlet.java:868)
    at javax.servlet.http.HttpServlet.service(HttpServlet.java:644)
    at org.springframework.web.servlet.FrameworkServlet.service(FrameworkServlet.java:842)
    at javax.servlet.http.HttpServlet.service(HttpServlet.java:725)
    at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:291)
    at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
    at org.apache.tomcat.websocket.server.WsFilter.doFilter(WsFilter.java:52)
    at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:239)
    at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
    at org.springframework.web.filter.HiddenHttpMethodFilter.doFilterInternal(HiddenHttpMethodFilter.java:77)
    at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:107)
    at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:239)
    at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
    at org.springframework.web.filter.CharacterEncodingFilter.doFilterInternal(CharacterEncodingFilter.java:85)
    at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:107)
    at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:239)
    at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
    at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:219)
    at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:106)
    at org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:501)
    at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:142)
    at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:79)
    at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:88)
    at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:516)
    at org.apache.coyote.http11.AbstractHttp11Processor.process(AbstractHttp11Processor.java:1086)
    at org.apache.coyote.AbstractProtocol$AbstractConnectionHandler.process(AbstractProtocol.java:659)
    at org.apache.coyote.http11.Http11NioProtocol$Http11ConnectionHandler.process(Http11NioProtocol.java:223)
    at org.apache.tomcat.util.net.NioEndpoint$SocketProcessor.doRun(NioEndpoint.java:1558)
    at org.apache.tomcat.util.net.NioEndpoint$SocketProcessor.run(NioEndpoint.java:1515)
    at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
    at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
    at org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61)
    at java.lang.Thread.run(Thread.java:745)
Writing result to C:\Users\jnehring\AppData\Local\Temp\alice6670575336156318338.epub
2015-08-03 10:43:28 [ERROR] nl.siegmann.epublib.epub.EpubReader.processPackageResource(EpubReader.java:121) 
java.lang.NullPointerException
    at nl.siegmann.epublib.epub.PackageDocumentReader.read(PackageDocumentReader.java:51)
    at nl.siegmann.epublib.epub.EpubReader.processPackageResource(EpubReader.java:119)
    at nl.siegmann.epublib.epub.EpubReader.readEpub(EpubReader.java:97)
    at nl.siegmann.epublib.epub.EpubReader.readEpub(EpubReader.java:53)
    at nl.siegmann.epublib.epub.EpubReader.readEpub(EpubReader.java:37)
    at eu.freme.eservices.epublishing.webservice.EPubTest.TestSections(EPubTest.java:132)
    at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
    at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
    at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
    at java.lang.reflect.Method.invoke(Method.java:606)
    at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:44)
    at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15)
    at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:41)
    at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:20)
    at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:28)
    at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:31)
    at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:76)
    at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:50)
    at org.junit.runners.ParentRunner$3.run(ParentRunner.java:193)
    at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:52)
    at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:191)
    at org.junit.runners.ParentRunner.access$000(ParentRunner.java:42)
    at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:184)
    at org.junit.runners.ParentRunner.run(ParentRunner.java:236)
    at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:252)
    at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:141)
    at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:112)
    at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
    at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
    at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
    at java.lang.reflect.Method.invoke(Method.java:606)
    at org.apache.maven.surefire.util.ReflectionUtils.invokeMethodWithArray(ReflectionUtils.java:189)
    at org.apache.maven.surefire.booter.ProviderFactory$ProviderProxy.invoke(ProviderFactory.java:165)
    at org.apache.maven.surefire.booter.ProviderFactory.invokeProvider(ProviderFactory.java:85)
    at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:115)
    at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:75)
2015-08-03 10:43:28 [ERROR] nl.siegmann.epublib.epub.NCXDocument.read(NCXDocument.java:82) Book does not contain a table of contents file
2015-08-03 10:43:28 [INFO] org.springframework.context.support.AbstractApplicationContext.doClose(AbstractApplicationContext.java:862) Closing org.springframework.boot.context.embedded.AnnotationConfigEmbeddedWebApplicationContext@2ae1c9: startup date [Mon Aug 03 10:43:26 CEST 2015]; root of context hierarchy
2015-08-03 10:43:28 [INFO] org.springframework.jmx.export.MBeanExporter.destroy(MBeanExporter.java:449) Unregistering JMX-exposed beans on shutdown

  .   ____          _            __ _ _
 /\\ / ___'_ __ _ _(_)_ __  __ _ \ \ \ \
( ( )\___ | '_ | '_| | '_ \/ _` | \ \ \ \
 \\/  ___)| |_)| | | | | || (_| |  ) ) ) )
  '  |____| .__|_| |_|_| |_\__, | / / / /
 =========|_|==============|___/=/_/_/_/
 :: Spring Boot ::        (v1.2.3.RELEASE)

2015-08-03 10:43:29 [INFO] org.springframework.boot.StartupInfoLogger.logStarting(StartupInfoLogger.java:47) Starting ForkedBooter v2.12.4 on jan with PID 15256 (C:\Users\jnehring\.m2\repository\org\apache\maven\surefire\surefire-booter\2.12.4\surefire-booter-2.12.4.jar started by jnehring in C:\Users\jnehring\git\e-Publishing)
2015-08-03 10:43:29 [INFO] org.springframework.context.support.AbstractApplicationContext.prepareRefresh(AbstractApplicationContext.java:510) Refreshing org.springframework.boot.context.embedded.AnnotationConfigEmbeddedWebApplicationContext@14c1b4f: startup date [Mon Aug 03 10:43:29 CEST 2015]; root of context hierarchy
2015-08-03 10:43:30 [INFO] org.springframework.beans.factory.support.DefaultListableBeanFactory.registerBeanDefinition(DefaultListableBeanFactory.java:822) Overriding bean definition for bean 'beanNameViewResolver': replacing [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=org.springframework.boot.autoconfigure.web.ErrorMvcAutoConfiguration$WhitelabelErrorViewConfiguration; factoryMethodName=beanNameViewResolver; initMethodName=null; destroyMethodName=(inferred); defined in class path resource [org/springframework/boot/autoconfigure/web/ErrorMvcAutoConfiguration$WhitelabelErrorViewConfiguration.class]] with [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=org.springframework.boot.autoconfigure.web.WebMvcAutoConfiguration$WebMvcAutoConfigurationAdapter; factoryMethodName=beanNameViewResolver; initMethodName=null; destroyMethodName=(inferred); defined in class path resource [org/springframework/boot/autoconfigure/web/WebMvcAutoConfiguration$WebMvcAutoConfigurationAdapter.class]]
2015-08-03 10:43:30 [INFO] org.springframework.beans.factory.annotation.AutowiredAnnotationBeanPostProcessor.<init>(AutowiredAnnotationBeanPostProcessor.java:153) JSR-330 'javax.inject.Inject' annotation found and supported for autowiring
2015-08-03 10:43:30 [INFO] org.springframework.boot.context.embedded.tomcat.TomcatEmbeddedServletContainer.initialize(TomcatEmbeddedServletContainer.java:79) Tomcat initialized with port(s): 8080 (http)
2015-08-03 10:43:30 [INFO] org.apache.juli.logging.DirectJDKLog.log(DirectJDKLog.java:180) Starting service Tomcat
2015-08-03 10:43:30 [INFO] org.apache.juli.logging.DirectJDKLog.log(DirectJDKLog.java:180) Starting Servlet Engine: Apache Tomcat/8.0.20
2015-08-03 10:43:30 [INFO] org.apache.juli.logging.DirectJDKLog.log(DirectJDKLog.java:180) Initializing Spring embedded WebApplicationContext
2015-08-03 10:43:30 [INFO] org.springframework.boot.context.embedded.EmbeddedWebApplicationContext.prepareEmbeddedWebApplicationContext(EmbeddedWebApplicationContext.java:268) Root WebApplicationContext: initialization completed in 884 ms
2015-08-03 10:43:30 [INFO] org.springframework.boot.context.embedded.ServletRegistrationBean.onStartup(ServletRegistrationBean.java:188) Mapping servlet: 'dispatcherServlet' to [/]
2015-08-03 10:43:30 [INFO] org.springframework.boot.context.embedded.FilterRegistrationBean.configure(FilterRegistrationBean.java:286) Mapping filter: 'characterEncodingFilter' to: [/*]
2015-08-03 10:43:30 [INFO] org.springframework.boot.context.embedded.FilterRegistrationBean.configure(FilterRegistrationBean.java:286) Mapping filter: 'hiddenHttpMethodFilter' to: [/*]
2015-08-03 10:43:31 [INFO] org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerAdapter.initControllerAdviceCache(RequestMappingHandlerAdapter.java:517) Looking for @ControllerAdvice: org.springframework.boot.context.embedded.AnnotationConfigEmbeddedWebApplicationContext@14c1b4f: startup date [Mon Aug 03 10:43:29 CEST 2015]; root of context hierarchy
2015-08-03 10:43:31 [INFO] org.springframework.web.servlet.handler.AbstractHandlerMethodMapping.registerHandlerMethod(AbstractHandlerMethodMapping.java:217) Mapped "{[/e-publishing/html],methods=[POST],params=[],headers=[],consumes=[],produces=[],custom=[]}" onto public org.springframework.http.ResponseEntity<[B> eu.freme.eservices.epublishing.ServiceRestController.htmlToEPub(org.springframework.web.multipart.MultipartFile,java.lang.String) throws java.io.IOException,eu.freme.eservices.epublishing.exception.InvalidZipException,eu.freme.eservices.epublishing.exception.EPubCreationException
2015-08-03 10:43:31 [INFO] org.springframework.web.servlet.handler.AbstractHandlerMethodMapping.registerHandlerMethod(AbstractHandlerMethodMapping.java:217) Mapped "{[/error],methods=[],params=[],headers=[],consumes=[],produces=[text/html],custom=[]}" onto public org.springframework.web.servlet.ModelAndView org.springframework.boot.autoconfigure.web.BasicErrorController.errorHtml(javax.servlet.http.HttpServletRequest)
2015-08-03 10:43:31 [INFO] org.springframework.web.servlet.handler.AbstractHandlerMethodMapping.registerHandlerMethod(AbstractHandlerMethodMapping.java:217) Mapped "{[/error],methods=[],params=[],headers=[],consumes=[],produces=[],custom=[]}" onto public org.springframework.http.ResponseEntity<java.util.Map<java.lang.String, java.lang.Object>> org.springframework.boot.autoconfigure.web.BasicErrorController.error(javax.servlet.http.HttpServletRequest)
2015-08-03 10:43:31 [INFO] org.springframework.web.servlet.handler.AbstractUrlHandlerMapping.registerHandler(AbstractUrlHandlerMapping.java:314) Mapped URL path [/**] onto handler of type [class org.springframework.web.servlet.resource.ResourceHttpRequestHandler]
2015-08-03 10:43:31 [INFO] org.springframework.web.servlet.handler.AbstractUrlHandlerMapping.registerHandler(AbstractUrlHandlerMapping.java:314) Mapped URL path [/webjars/**] onto handler of type [class org.springframework.web.servlet.resource.ResourceHttpRequestHandler]
2015-08-03 10:43:31 [INFO] org.springframework.web.servlet.handler.AbstractUrlHandlerMapping.registerHandler(AbstractUrlHandlerMapping.java:314) Mapped URL path [/**/favicon.ico] onto handler of type [class org.springframework.web.servlet.resource.ResourceHttpRequestHandler]
2015-08-03 10:43:31 [INFO] org.springframework.jmx.export.MBeanExporter.afterSingletonsInstantiated(MBeanExporter.java:431) Registering beans for JMX exposure on startup
2015-08-03 10:43:31 [INFO] org.apache.juli.logging.DirectJDKLog.log(DirectJDKLog.java:180) Initializing ProtocolHandler ["http-nio-8080"]
2015-08-03 10:43:31 [INFO] org.apache.juli.logging.DirectJDKLog.log(DirectJDKLog.java:180) Starting ProtocolHandler ["http-nio-8080"]
2015-08-03 10:43:31 [INFO] org.apache.juli.logging.DirectJDKLog.log(DirectJDKLog.java:180) Using a shared selector for servlet write/read
2015-08-03 10:43:31 [INFO] org.springframework.boot.context.embedded.tomcat.TomcatEmbeddedServletContainer.start(TomcatEmbeddedServletContainer.java:159) Tomcat started on port(s): 8080 (http)
2015-08-03 10:43:31 [INFO] org.springframework.boot.StartupInfoLogger.logStarted(StartupInfoLogger.java:56) Started ForkedBooter in 2.358 seconds (JVM running for 33.753)
2015-08-03 10:43:31 [INFO] org.apache.juli.logging.DirectJDKLog.log(DirectJDKLog.java:180) Initializing Spring FrameworkServlet 'dispatcherServlet'
2015-08-03 10:43:31 [INFO] org.springframework.web.servlet.FrameworkServlet.initServletBean(FrameworkServlet.java:484) FrameworkServlet 'dispatcherServlet': initialization started
2015-08-03 10:43:31 [INFO] org.springframework.web.servlet.FrameworkServlet.initServletBean(FrameworkServlet.java:503) FrameworkServlet 'dispatcherServlet': initialization completed in 5 ms
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591411503\00.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591411503\01.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591411503\02.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591411503\03.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591411503\04.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591411503\05.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591411503\06.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591411503\07.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591411503\08.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591411503\09.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591411503\10.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591411503\11.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591411503\12.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591411503\13.xhtml
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591411503\cover.jpeg
file unzip : C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591411503\style.css
2015-08-03 10:43:32 [ERROR] org.apache.juli.logging.DirectJDKLog.log(DirectJDKLog.java:182) Servlet.service() for servlet [dispatcherServlet] in context with path [] threw exception
java.io.IOException: Unable to delete file: C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591411503\cover.jpeg
    at org.apache.commons.io.FileUtils.forceDelete(FileUtils.java:2279)
    at org.apache.commons.io.FileUtils.cleanDirectory(FileUtils.java:1653)
    at org.apache.commons.io.FileUtils.deleteDirectory(FileUtils.java:1535)
    at eu.freme.eservices.epublishing.EPublishingService.createEPUB(EPublishingService.java:49)
    at eu.freme.eservices.epublishing.ServiceRestController.htmlToEPub(ServiceRestController.java:37)
    at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
    at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
    at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
    at java.lang.reflect.Method.invoke(Method.java:606)
    at org.springframework.web.method.support.InvocableHandlerMethod.doInvoke(InvocableHandlerMethod.java:221)
    at org.springframework.web.method.support.InvocableHandlerMethod.invokeForRequest(InvocableHandlerMethod.java:137)
    at org.springframework.web.servlet.mvc.method.annotation.ServletInvocableHandlerMethod.invokeAndHandle(ServletInvocableHandlerMethod.java:110)
    at org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerAdapter.invokeHandleMethod(RequestMappingHandlerAdapter.java:776)
    at org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerAdapter.handleInternal(RequestMappingHandlerAdapter.java:705)
    at org.springframework.web.servlet.mvc.method.AbstractHandlerMethodAdapter.handle(AbstractHandlerMethodAdapter.java:85)
    at org.springframework.web.servlet.DispatcherServlet.doDispatch(DispatcherServlet.java:959)
    at org.springframework.web.servlet.DispatcherServlet.doService(DispatcherServlet.java:893)
    at org.springframework.web.servlet.FrameworkServlet.processRequest(FrameworkServlet.java:966)
    at org.springframework.web.servlet.FrameworkServlet.doPost(FrameworkServlet.java:868)
    at javax.servlet.http.HttpServlet.service(HttpServlet.java:644)
    at org.springframework.web.servlet.FrameworkServlet.service(FrameworkServlet.java:842)
    at javax.servlet.http.HttpServlet.service(HttpServlet.java:725)
    at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:291)
    at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
    at org.apache.tomcat.websocket.server.WsFilter.doFilter(WsFilter.java:52)
    at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:239)
    at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
    at org.springframework.web.filter.HiddenHttpMethodFilter.doFilterInternal(HiddenHttpMethodFilter.java:77)
    at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:107)
    at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:239)
    at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
    at org.springframework.web.filter.CharacterEncodingFilter.doFilterInternal(CharacterEncodingFilter.java:85)
    at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:107)
    at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:239)
    at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
    at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:219)
    at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:106)
    at org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:501)
    at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:142)
    at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:79)
    at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:88)
    at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:516)
    at org.apache.coyote.http11.AbstractHttp11Processor.process(AbstractHttp11Processor.java:1086)
    at org.apache.coyote.AbstractProtocol$AbstractConnectionHandler.process(AbstractProtocol.java:659)
    at org.apache.coyote.http11.Http11NioProtocol$Http11ConnectionHandler.process(Http11NioProtocol.java:223)
    at org.apache.tomcat.util.net.NioEndpoint$SocketProcessor.doRun(NioEndpoint.java:1558)
    at org.apache.tomcat.util.net.NioEndpoint$SocketProcessor.run(NioEndpoint.java:1515)
    at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
    at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
    at org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61)
    at java.lang.Thread.run(Thread.java:745)
2015-08-03 10:43:32 [ERROR] nl.siegmann.epublib.epub.EpubReader.processPackageResource(EpubReader.java:121) 
java.lang.NullPointerException
    at nl.siegmann.epublib.epub.PackageDocumentReader.read(PackageDocumentReader.java:51)
    at nl.siegmann.epublib.epub.EpubReader.processPackageResource(EpubReader.java:119)
    at nl.siegmann.epublib.epub.EpubReader.readEpub(EpubReader.java:97)
    at nl.siegmann.epublib.epub.EpubReader.readEpub(EpubReader.java:53)
    at nl.siegmann.epublib.epub.EpubReader.readEpub(EpubReader.java:37)
    at eu.freme.eservices.epublishing.webservice.EPubTest.TestCoverImage(EPubTest.java:190)
    at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
    at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
    at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
    at java.lang.reflect.Method.invoke(Method.java:606)
    at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:44)
    at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15)
    at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:41)
    at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:20)
    at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:28)
    at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:31)
    at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:76)
    at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:50)
    at org.junit.runners.ParentRunner$3.run(ParentRunner.java:193)
    at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:52)
    at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:191)
    at org.junit.runners.ParentRunner.access$000(ParentRunner.java:42)
    at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:184)
    at org.junit.runners.ParentRunner.run(ParentRunner.java:236)
    at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:252)
    at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:141)
    at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:112)
    at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
    at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
    at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
    at java.lang.reflect.Method.invoke(Method.java:606)
    at org.apache.maven.surefire.util.ReflectionUtils.invokeMethodWithArray(ReflectionUtils.java:189)
    at org.apache.maven.surefire.booter.ProviderFactory$ProviderProxy.invoke(ProviderFactory.java:165)
    at org.apache.maven.surefire.booter.ProviderFactory.invokeProvider(ProviderFactory.java:85)
    at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:115)
    at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:75)
2015-08-03 10:43:32 [ERROR] nl.siegmann.epublib.epub.NCXDocument.read(NCXDocument.java:82) Book does not contain a table of contents file
2015-08-03 10:43:32 [INFO] org.springframework.context.support.AbstractApplicationContext.doClose(AbstractApplicationContext.java:862) Closing org.springframework.boot.context.embedded.AnnotationConfigEmbeddedWebApplicationContext@14c1b4f: startup date [Mon Aug 03 10:43:29 CEST 2015]; root of context hierarchy
2015-08-03 10:43:32 [INFO] org.springframework.jmx.export.MBeanExporter.destroy(MBeanExporter.java:449) Unregistering JMX-exposed beans on shutdown
Tests run: 3, Failures: 1, Errors: 1, Skipped: 0, Time elapsed: 26.918 sec <<< FAILURE!
TestSections(eu.freme.eservices.epublishing.webservice.EPubTest)  Time elapsed: 2.77 sec  <<< FAILURE!
java.lang.AssertionError: All titles are in the EPUB.
    at org.junit.Assert.fail(Assert.java:91)
    at org.junit.Assert.assertTrue(Assert.java:43)
    at eu.freme.eservices.epublishing.webservice.EPubTest.TestSections(EPubTest.java:141)
    at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
    at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
    at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
    at java.lang.reflect.Method.invoke(Method.java:606)
    at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:44)
    at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15)
    at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:41)
    at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:20)
    at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:28)
    at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:31)
    at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:76)
    at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:50)
    at org.junit.runners.ParentRunner$3.run(ParentRunner.java:193)
    at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:52)
    at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:191)
    at org.junit.runners.ParentRunner.access$000(ParentRunner.java:42)
    at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:184)
    at org.junit.runners.ParentRunner.run(ParentRunner.java:236)
    at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:252)
    at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:141)
    at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:112)
    at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
    at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
    at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
    at java.lang.reflect.Method.invoke(Method.java:606)
    at org.apache.maven.surefire.util.ReflectionUtils.invokeMethodWithArray(ReflectionUtils.java:189)
    at org.apache.maven.surefire.booter.ProviderFactory$ProviderProxy.invoke(ProviderFactory.java:165)
    at org.apache.maven.surefire.booter.ProviderFactory.invokeProvider(ProviderFactory.java:85)
    at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:115)
    at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:75)

TestCoverImage(eu.freme.eservices.epublishing.webservice.EPubTest)  Time elapsed: 3.199 sec  <<< ERROR!
java.lang.NullPointerException
    at eu.freme.eservices.epublishing.webservice.EPubTest.TestCoverImage(EPubTest.java:193)
    at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
    at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
    at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
    at java.lang.reflect.Method.invoke(Method.java:606)
    at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:44)
    at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15)
    at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:41)
    at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:20)
    at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:28)
    at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:31)
    at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:76)
    at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:50)
    at org.junit.runners.ParentRunner$3.run(ParentRunner.java:193)
    at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:52)
    at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:191)
    at org.junit.runners.ParentRunner.access$000(ParentRunner.java:42)
    at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:184)
    at org.junit.runners.ParentRunner.run(ParentRunner.java:236)
    at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:252)
    at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:141)
    at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:112)
    at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
    at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
    at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
    at java.lang.reflect.Method.invoke(Method.java:606)
    at org.apache.maven.surefire.util.ReflectionUtils.invokeMethodWithArray(ReflectionUtils.java:189)
    at org.apache.maven.surefire.booter.ProviderFactory$ProviderProxy.invoke(ProviderFactory.java:165)
    at org.apache.maven.surefire.booter.ProviderFactory.invokeProvider(ProviderFactory.java:85)
    at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:115)
    at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:75)


Results :

Failed tests:   TestSections(eu.freme.eservices.epublishing.webservice.EPubTest): All titles are in the EPUB.

Tests in error: 
  testWithoutAuthor(eu.freme.eservices.epublishing.webservice.EPUBCreationTest): Unable to delete file: C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591383103\cover.jpeg
  testCoverImage(eu.freme.eservices.epublishing.webservice.EPUBCreationTest): Unable to delete file: C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591383746\cover.jpeg
  testTitles(eu.freme.eservices.epublishing.webservice.EPUBCreationTest): Unable to delete file: C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591384286\cover.jpeg
  testAuthors(eu.freme.eservices.epublishing.webservice.EPUBCreationTest): Unable to delete file: C:\Users\jnehring\AppData\Local\Temp\freme_epublishing_1438591384708\cover.jpeg
  TestCoverImage(eu.freme.eservices.epublishing.webservice.EPubTest)

Tests run: 10, Failures: 1, Errors: 5, Skipped: 0

[INFO] ------------------------------------------------------------------------
[INFO] BUILD FAILURE
[INFO] ------------------------------------------------------------------------
[INFO] Total time: 50.272 s
[INFO] Finished at: 2015-08-03T10:43:33+01:00
[INFO] Final Memory: 9M/23M
[INFO] ------------------------------------------------------------------------
[ERROR] Failed to execute goal org.apache.maven.plugins:maven-surefire-plugin:2.12.4:test (default-test) on project e-publishing: There are test failures.
[ERROR] 
[ERROR] Please refer to C:\Users\jnehring\git\e-Publishing\target\surefire-reports for the individual test results.
[ERROR] -> [Help 1]
[ERROR] 
[ERROR] To see the full stack trace of the errors, re-run Maven with the -e switch.
[ERROR] Re-run Maven using the -X switch to enable full debug logging.
[ERROR] 
[ERROR] For more information about the errors and possible solutions, please read the following articles:
[ERROR] [Help 1] http://cwiki.apache.org/confluence/display/MAVEN/MojoFailureException

The text was updated successfully, but these errors were encountered:

jnehring · 2015-08-03T09:00:32Z

@ArneBinder @josauder I believe you saw these messages also while creating integration tests, am i right?

pheyvaer · 2015-08-07T12:15:37Z

It has to do with the permission that the JVM has regarding the file management, I think. The JVM has not the correct rights to remove files from the temp folder ...

jnehring · 2015-10-28T15:13:20Z

@pheyvaer do you have a solution for this problem?

pheyvaer · 2015-10-28T15:15:28Z

@jnehring did you try checking the file permissions as I said in the previous comment?

jnehring · 2015-10-29T10:53:16Z

Its odd that the JVM can create the files but not remove them. I dont really know what to try out here. @pheyvaer do you have a windows machine and are able to reproduce the bug?

pheyvaer · 2015-10-30T14:30:41Z

I have a Windows machine, but haven't tried to replicate the bug. How urgent is this issue?

jnehring · 2015-11-02T08:37:13Z

We can leave the bug for FREME 0.5 but we should get it fixed then because it is already open for 3 month.

jnehring · 2015-11-03T11:04:09Z

Nice. Thanks! This is fixed.

jnehring added the bug label Aug 3, 2015

jnehring assigned pheyvaer Aug 3, 2015

jnehring mentioned this issue Aug 4, 2015

Create integration test for POST /e-publishing/html freme-project/Broker#54

Closed

jnehring modified the milestone: FREME 0.2 Oct 28, 2015

ArneBinder closed this as completed in 36c932e Nov 3, 2015

Provide feedback

Saved searches

Use saved searches to filter your results more quickly

Unit tests on windows #15

Unit tests on windows #15

jnehring commented Aug 3, 2015

jnehring commented Aug 3, 2015

pheyvaer commented Aug 7, 2015

jnehring commented Oct 28, 2015

pheyvaer commented Oct 28, 2015

jnehring commented Oct 29, 2015

pheyvaer commented Oct 30, 2015

jnehring commented Nov 2, 2015

jnehring commented Nov 3, 2015

Unit tests on windows #15

Unit tests on windows #15

Comments

jnehring commented Aug 3, 2015

jnehring commented Aug 3, 2015

pheyvaer commented Aug 7, 2015

jnehring commented Oct 28, 2015

pheyvaer commented Oct 28, 2015

jnehring commented Oct 29, 2015

pheyvaer commented Oct 30, 2015

jnehring commented Nov 2, 2015

jnehring commented Nov 3, 2015