0
Answered

New attempt with 2.1.1 successful, but empty logfile

Claus44 2 years ago updated by Tom 2 years ago 11

Six or seven month since my first try to change from 1.10 to 2.1 is this now successful. I think, the problems in the past were the old firmware of my QNAP und too little memory for Java/Ubooquity. Now my QNAP NAS runs with firmware 4.3.3 and I have 1024 m memory reserved for Ubooquity. I have installed Java 8 64-bit and Ubooquity 2.1.1 64-bit.

But I have a very little problem:

When I click "View Logs" from Admin site, I see a blank site with following text:

Displaying the last 1000 lines of logs.

While scanning the database I saw the lines of activity. Now I have this blank site and when I look with ssh in the folder "logs" I see one empty textfile ubooquity.

I hope, someone can help me with this?

Under review

A folder writing rights issue perhaps ?

Would be nice easy. The rights setting for the complete folder UbooQuity is oktal 0777.

Just tried a few old log lines manually inserted into the file ubooquity.log.

But clicking "View Logs" continues to show the blank site with text "Displaying the last 1000 lines of logs".

It's more tricky than expected. With "View Logs" I see 8 blank pages, then comes log text. Here is the last log of starting Ubooquity. Maybe you can recognize which error ist behind it.


20180402 18:41:27 [main] INFO
com.ubooquity.Ubooquity - Running with the following parameters -
headless: true, libraryport: -1, adminport: -1, remoteadmin: true, host:
null, debug: false, execdir: /share/MD0_DATA/.qpkg/UbooQuity
20180402 18:41:27 [main] INFO com.ubooquity.Ubooquity - #############################
20180402 18:41:27 [main] INFO com.ubooquity.Ubooquity - # Starting Ubooquity #
20180402 18:41:27 [main] INFO com.ubooquity.Ubooquity - #############################
20180402 18:41:27 [main] INFO com.ubooquity.Ubooquity - Java version: 1.8.0_111
20180402 18:41:27 [main] INFO com.ubooquity.Ubooquity - Java vendor: Oracle Corporation
20180402 18:41:27 [main] INFO com.ubooquity.Ubooquity - Max heap size available: 910 MB
20180402 18:41:27 [main] INFO com.ubooquity.Ubooquity - OS name: Linux
20180402 18:41:27 [main] INFO com.ubooquity.Ubooquity - OS version: 3.4.6
20180402 18:41:27 [main] INFO com.ubooquity.Ubooquity - OS architecture: amd64
20180402 18:41:27 [main] INFO com.ubooquity.Ubooquity - Loading local version info
20180402 18:41:27 [main] INFO com.ubooquity.Ubooquity - Ubooquity 2.1.1 built on 2017-09-22 at 21:10
20180402 18:41:27 [main] INFO com.ubooquity.Ubooquity - Creating application folders
20180402 18:41:27 [main] INFO com.ubooquity.Ubooquity - Loading preferences
20180402 18:41:29 [main] INFO org.eclipse.jetty.util.log - Logging initialized @6694ms to org.eclipse.jetty.util.log.Slf4jLog
20180402 18:41:29 [main] INFO com.ubooquity.b - Starting internal server...
20180402 18:41:29 [main] WARN
com.ubooquity.data.database.a - Could not find id of root path
/share/MD0_DATA/Ebooks/ePub (ignore this warning if you just added a new
root directory)
20180402 18:41:29 [main] WARN
com.ubooquity.data.database.a - Could not find id of root path
/share/MD0_DATA/Ebooks/Zeitschriften (ignore this warning if you just
added a new root directory)
20180402 18:41:29 [main] WARN
com.ubooquity.data.database.a - Could not find id of root path
/share/MD0_DATA/Ebooks/Sachbuch (ignore this warning if you just added a
new root directory)
20180402 18:41:29 [main] WARN
com.ubooquity.data.database.a - Could not find id of root path
/share/MD0_DATA/Ebooks/Kochen (ignore this warning if you just added a
new root directory)
20180402 18:41:29 [main] WARN
com.ubooquity.data.database.a - Could not find id of root path
/share/MD0_DATA/Ebooks/Fitness-Gesundheit (ignore this warning if you
just added a new root directory)
20180402 18:41:29 [main] WARN
com.ubooquity.data.database.c - Could not find id of root path
/share/MD0_DATA/Ebooks/Comics (ignore this warning if you just added a
new root directory)
20180402 18:41:29 [main] INFO com.ubooquity.b - Autoscan is disabled, manually launch or schedule a scan to scan your files
20180402 18:41:29 [main] INFO com.ubooquity.b - Reverse proxy prefix is not activated
20180402 18:41:30 [main] INFO org.eclipse.jetty.server.Server - jetty-9.4.0.v20161208
20180402 18:41:30 [main] INFO o.e.j.server.handler.ContextHandler - Started o.e.j.s.h.ContextHandler@59402b8f{/,null,AVAILABLE,@admin}
20180402 18:41:30 [main] INFO o.e.j.server.handler.ContextHandler - Started o.e.j.s.h.ContextHandler@7188af83{/,null,AVAILABLE,@library}
20180402 18:41:30 [main] ERROR com.ubooquity.Ubooquity - Exiting application because of exception
org.eclipse.jetty.util.MultiException: Multiple exceptions

at org.eclipse.jetty.server.Server.doStart(Server.java:416)
~[jetty-server-9.4.0.v20161208.jar.6503228993343643464.tmp:9.4.0.v20161208]

at
org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:68)

~[jetty-util-9.4.0.v20161208.jar.5973826191773812682.tmp:9.4.0.v20161208]
at com.ubooquity.d.e.a(SourceFile:90) ~[Ubooquity.jar:2.1.1]
at com.ubooquity.b.a(SourceFile:95) ~[Ubooquity.jar:2.1.1]
at com.ubooquity.Ubooquity.main(SourceFile:222) ~[Ubooquity.jar:2.1.1]
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[na:1.8.0_111]
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[na:1.8.0_111]
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[na:1.8.0_111]
at java.lang.reflect.Method.invoke(Method.java:498) ~[na:1.8.0_111]
at com.ubooquity.e.a.a(SourceFile:823) [Ubooquity.jar:2.1.1]
at com.ubooquity.Launcher.main(SourceFile:10) [Ubooquity.jar:2.1.1]
Suppressed: java.net.BindException: Address already in use
at sun.nio.ch.Net.bind0(Native Method) ~[na:1.8.0_111]
at sun.nio.ch.Net.bind(Net.java:433) ~[na:1.8.0_111]
at sun.nio.ch.Net.bind(Net.java:425) ~[na:1.8.0_111]
at sun.nio.ch.ServerSocketChannelImpl.bind(ServerSocketChannelImpl.java:223) ~[na:1.8.0_111]
at sun.nio.ch.ServerSocketAdaptor.bind(ServerSocketAdaptor.java:74) ~[na:1.8.0_111]

at
org.eclipse.jetty.server.ServerConnector.open(ServerConnector.java:298)
~[jetty-server-9.4.0.v20161208.jar.6503228993343643464.tmp:9.4.0.v20161208]

at
org.eclipse.jetty.server.AbstractNetworkConnector.doStart(AbstractNetworkConnector.java:80)

~[jetty-server-9.4.0.v20161208.jar.6503228993343643464.tmp:9.4.0.v20161208]

at
org.eclipse.jetty.server.ServerConnector.doStart(ServerConnector.java:236)

~[jetty-server-9.4.0.v20161208.jar.6503228993343643464.tmp:9.4.0.v20161208]

at
org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:68)

~[jetty-util-9.4.0.v20161208.jar.5973826191773812682.tmp:9.4.0.v20161208]

at org.eclipse.jetty.server.Server.doStart(Server.java:431)
~[jetty-server-9.4.0.v20161208.jar.6503228993343643464.tmp:9.4.0.v20161208]
... 10 common frames omitted
Caused by: java.net.BindException: Address already in use
at sun.nio.ch.Net.bind0(Native Method) ~[na:1.8.0_111]
at sun.nio.ch.Net.bind(Net.java:433) ~[na:1.8.0_111]
at sun.nio.ch.Net.bind(Net.java:425) ~[na:1.8.0_111]
at sun.nio.ch.ServerSocketChannelImpl.bind(ServerSocketChannelImpl.java:223) ~[na:1.8.0_111]
at sun.nio.ch.ServerSocketAdaptor.bind(ServerSocketAdaptor.java:74) ~[na:1.8.0_111]

at
org.eclipse.jetty.server.ServerConnector.open(ServerConnector.java:298)
~[jetty-server-9.4.0.v20161208.jar.6503228993343643464.tmp:9.4.0.v20161208]

at
org.eclipse.jetty.server.AbstractNetworkConnector.doStart(AbstractNetworkConnector.java:80)

~[jetty-server-9.4.0.v20161208.jar.6503228993343643464.tmp:9.4.0.v20161208]

at
org.eclipse.jetty.server.ServerConnector.doStart(ServerConnector.java:236)

~[jetty-server-9.4.0.v20161208.jar.6503228993343643464.tmp:9.4.0.v20161208]

at
org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:68)

~[jetty-util-9.4.0.v20161208.jar.5973826191773812682.tmp:9.4.0.v20161208]

at org.eclipse.jetty.server.Server.doStart(Server.java:431)
~[jetty-server-9.4.0.v20161208.jar.6503228993343643464.tmp:9.4.0.v20161208]
... 10 common frames omitted
20180402 18:41:30 [main] ERROR com.ubooquity.Ubooquity - Failed to display error dialog
java.lang.IllegalStateException: Toolkit not initialized
at com.sun.javafx.application.PlatformImpl.runLater(PlatformImpl.java:273) ~[jfxrt.jar:na]
at com.sun.javafx.application.PlatformImpl.runLater(PlatformImpl.java:268) ~[jfxrt.jar:na]
at javafx.application.Platform.runLater(Platform.java:83) ~[jfxrt.jar:na]
at com.ubooquity.Ubooquity.main(SourceFile:264) ~[Ubooquity.jar:2.1.1]
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[na:1.8.0_111]
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[na:1.8.0_111]
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[na:1.8.0_111]
at java.lang.reflect.Method.invoke(Method.java:498) ~[na:1.8.0_111]
at com.ubooquity.e.a.a(SourceFile:823) [Ubooquity.jar:2.1.1]
at com.ubooquity.Launcher.main(SourceFile:10) [Ubooquity.jar:2.1.1]
20180402 18:41:30 [Thread-2] INFO com.ubooquity.b - Stopping internal server...
20180402 18:41:30 [Thread-2] INFO com.ubooquity.data.feeder.a - Aborting content scan...
20180402 18:41:30 [Thread-2] INFO o.e.jetty.server.AbstractConnector - Stopped library@11652246{HTTP/1.1,[http/1.1]}{0.0.0.0:2202}
20180402 18:41:30 [Thread-2] INFO o.e.jetty.server.AbstractConnector - Stopped admin@70ac2103{HTTP/1.1,[http/1.1]}{0.0.0.0:2203}
20180402 18:41:30 [Thread-2] INFO o.e.j.server.handler.ContextHandler - Stopped o.e.j.s.h.ContextHandler@7188af83{/,null,UNAVAILABLE,@library}
20180402 18:41:30 [Thread-2] INFO o.e.j.server.handler.ContextHandler - Stopped o.e.j.s.h.ContextHandler@59402b8f{/,null,UNAVAILABLE,@admin}
20180402 18:41:30 [Thread-2] INFO com.ubooquity.b - Internal server stopped
20180402 18:41:30 [Thread-2] INFO com.ubooquity.Ubooquity - Ubooquity has closed.

 

You have two issues, right ?

  • No logs written to file
  • No logs displayed in the admin screen even when you manually simulate logs

If so I guess it has something to do with the working directory path (perhaps a bug).


Do you use the "workdir" option when launching Ubooquity ?

Not sure it's related but you need to use the "headless" option when running on a NAS.

What I do not understand: in the last both lines ubooquity is stopped and closed. But I can continue to open Ubooquity as admin and as a user.

That should not be possible. Are you sure you have only one instance running ?

I#am searching for it.

Hi Tom!

Your last note has solved the problem. There was a link "UbooQuity" in the directory of UbooQuity. Before that I suspected the latest Java (64-bit, 1.8.162) and downgraded to version 1.8.111. After deleting the link and downgrading Java everything works.

Thank you for your help!

Claus44

Answered

I'm glad your problem is solved !

Ubooquity should have no issue running with Java 1.8.162 though.