Bug#855324: pdfsam fails to start

Philip Rinn rinni at inventati.org
Mon Feb 20 17:29:42 UTC 2017 

On 20.02.2017 at 18:20, tony mancill wrote:
> On Sun, Feb 19, 2017 at 11:36:05PM +0100, gregor herrmann wrote:
>> On Sun, 19 Feb 2017 18:09:22 +0100, Philip Rinn wrote:
>>
>>> On 19.02.2017 at 04:56, gregor herrmann wrote:
>>>> After this change, pdfsam starts for me and shows me an empty white
>>>> window (or grey-ish) with nothing in it.
>>> Uh, strange. So this seems not to be a general fix :(
>>> Gregor, could you try '0'?
>>
>> With "0" I still get an empty greyish window and nothing more.
> 
> Ugh!  I'm just guessing, but I suspect you are now seeing a *different*
> issue related to one of the AWT race conditions we seem to encounter
> from time to time.  If you're willing to try with another window
> manager, that might tell us something.  You can also try exporting
> "NOISY_AWT=true" and hitting CTRL-Shift-F1 to dump the AWT component
> hierarchy [1] (and then sending that to me so I can compare it with the
> hierarchy I see on my system).

Here you go:

philip at debian:~$ NOISY_AWT=true pdfsam
[warning] /usr/bin/pdfsam: No java runtime was found
=========================================================================

 pdfsam

  JAVA: java

  JAVA_OPTS: -Xmx256m

  CLASSPATH:
/usr/share/pdfsam/pdfsam-1.1.4.jar:/usr/share/pdfsam/lib/dom4j.jar:/usr/share/pdfsam/lib/emp4j-1.0.1.jar:/usr/share/pdfsam/lib/itext.jar:/usr/share/pdfsam/lib/jaxen.jar:/usr/share/pdfsam/lib/log4j-1.2.jar:/usr/share/pdfsam/lib/looks.jar:/usr/share/pdfsam/lib/pdfsam-console-2.0.6e.jar:/usr/share/pdfsam/lib/pdfsam-jcmdline-1.0.3.jar:/usr/share/pdfsam/lib/pdfsam-langpack.jar

=========================================================================

18:24:39,603 INFO  JMainFrame  Starting PDF Split and Merge basic Ver. 1.1.4
18:24:39,641 INFO  Configuration  Loading configuration..
18:24:39,676 INFO  Configuration  Getting language...
18:24:39,727 INFO  Configuration  Setting look and feel...
18:24:39,747 INFO  Configuration  Setting logging level...
18:24:39,763 INFO  Configuration  Logging level set to DEBUG
18:24:39,894 INFO  PlugInsLoader  Merge/Extract plugin loaded.
18:24:39,931 INFO  PlugInsLoader  Split plugin loaded.
18:24:40,142 INFO  JMainFrame  PDF Split and Merge basic Ver. 1.1.4 started in 536ms
Exception in thread "AWT-EventQueue-1" java.lang.NullPointerException
	at javax.swing.text.FlowView$FlowStrategy.layoutRow(FlowView.java:563)
	at javax.swing.text.FlowView$FlowStrategy.layout(FlowView.java:477)
	at javax.swing.text.FlowView.layout(FlowView.java:201)
	at javax.swing.text.BoxView.setSize(BoxView.java:397)
	at javax.swing.text.BoxView.updateChildSizes(BoxView.java:366)
	at javax.swing.text.BoxView.setSpanOnAxis(BoxView.java:348)
	at javax.swing.text.BoxView.layout(BoxView.java:708)
	at javax.swing.text.BoxView.setSize(BoxView.java:397)
	at javax.swing.plaf.basic.BasicTextUI$RootView.setSize(BasicTextUI.java:1722)
	at javax.swing.plaf.basic.BasicTextUI.modelToView(BasicTextUI.java:1046)
	at javax.swing.text.DefaultCaret.repaintNewCaret(DefaultCaret.java:1308)
	at javax.swing.text.DefaultCaret$1.run(DefaultCaret.java:1287)
	at java.awt.event.InvocationEvent.dispatch(InvocationEvent.java:311)
	at java.awt.EventQueue.dispatchEventImpl(EventQueue.java:756)
	at java.awt.EventQueue.access$500(EventQueue.java:97)
	at java.awt.EventQueue$3.run(EventQueue.java:709)
	at java.awt.EventQueue$3.run(EventQueue.java:703)
	at java.security.AccessController.doPrivileged(Native Method)
	at
java.security.ProtectionDomain$JavaSecurityAccessImpl.doIntersectionPrivilege(ProtectionDomain.java:80)
	at java.awt.EventQueue.dispatchEvent(EventQueue.java:726)
	at org.GNOME.Accessibility.AtkWrapper$5.dispatchEvent(AtkWrapper.java:700)
	at java.awt.EventDispatchThread.pumpOneEventForFilters(EventDispatchThread.java:201)
	at java.awt.EventDispatchThread.pumpEventsForFilter(EventDispatchThread.java:116)
	at java.awt.EventDispatchThread.pumpEventsForHierarchy(EventDispatchThread.java:105)
	at java.awt.EventDispatchThread.pumpEvents(EventDispatchThread.java:101)
	at java.awt.EventDispatchThread.pumpEvents(EventDispatchThread.java:93)
	at java.awt.EventDispatchThread.run(EventDispatchThread.java:82)
^[[1;6P^\2017-02-20 18:25:13
Verlassen
Full thread dump OpenJDK 64-Bit Server VM (25.121-b13 mixed mode):

"DestroyJavaVM" #22 prio=5 os_prio=0 tid=0x00007f16f800a000 nid=0x2ced waiting on
condition [0x0000000000000000]
   java.lang.Thread.State: RUNNABLE

"Thread-2" #19 prio=5 os_prio=0 tid=0x00007f16f85a1800 nid=0x2d05 in Object.wait()
[0x00007f169a223000]
   java.lang.Thread.State: WAITING (on object monitor)
	at java.lang.Object.wait(Native Method)
	- waiting on <0x00000000f0175d20> (a java.lang.Object)
	at java.lang.Object.wait(Object.java:502)
	at
org.pdfsam.guiclient.commons.business.PdfLoader$WorkQueue$SingleWorker.run(PdfLoader.java:471)
	- locked <0x00000000f0175d20> (a java.lang.Object)

"Thread-1" #18 prio=5 os_prio=0 tid=0x00007f16f8582000 nid=0x2d04 in Object.wait()
[0x00007f169a324000]
   java.lang.Thread.State: WAITING (on object monitor)
	at java.lang.Object.wait(Native Method)
	- waiting on <0x00000000f014a1c8> (a java.lang.Object)
	at java.lang.Object.wait(Object.java:502)
	at
org.pdfsam.guiclient.commons.business.PdfLoader$WorkQueue$SingleWorker.run(PdfLoader.java:471)
	- locked <0x00000000f014a1c8> (a java.lang.Object)

"Thread-0" #17 daemon prio=5 os_prio=0 tid=0x00007f1694002800 nid=0x2cff runnable
[0x0000000000000000]
   java.lang.Thread.State: RUNNABLE

"AWT-EventQueue-1" #14 prio=6 os_prio=0 tid=0x00007f16f849b800 nid=0x2d02 waiting
on condition [0x00007f169af51000]
   java.lang.Thread.State: WAITING (parking)
	at sun.misc.Unsafe.park(Native Method)
	- parking to wait for  <0x00000000f023f9a0> (a
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject)
	at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
	at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
	at java.awt.EventQueue.getNextEvent(EventQueue.java:554)
philip at debian:~$ 	at
java.awt.EventDispatchThread.pumpOneEventForFilters(EventDispatchThread.java:170)
	at java.awt.EventDispatchThread.pumpEventsForFilter(EventDispatchThread.java:116)
	at java.awt.EventDispatchThread.pumpEventsForHierarchy(EventDispatchThread.java:105)
	at java.awt.EventDispatchThread.pumpEvents(EventDispatchThread.java:101)
	at java.awt.EventDispatchThread.pumpEvents(EventDispatchThread.java:93)
	at java.awt.EventDispatchThread.run(EventDispatchThread.java:82)

"AWT-Shutdown" #15 prio=5 os_prio=0 tid=0x00007f16f849a000 nid=0x2d01 in
Object.wait() [0x00007f169b052000]
   java.lang.Thread.State: WAITING (on object monitor)
	at java.lang.Object.wait(Native Method)
	- waiting on <0x00000000f02412f8> (a java.lang.Object)
	at java.lang.Object.wait(Object.java:502)
	at sun.awt.AWTAutoShutdown.run(AWTAutoShutdown.java:295)
	- locked <0x00000000f02412f8> (a java.lang.Object)
	at java.lang.Thread.run(Thread.java:745)

"TimerQueue" #13 daemon prio=5 os_prio=0 tid=0x00007f16f848c000 nid=0x2d00 waiting
on condition [0x00007f16dc15c000]
   java.lang.Thread.State: WAITING (parking)
	at sun.misc.Unsafe.park(Native Method)
	- parking to wait for  <0x00000000f02416d8> (a
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject)
	at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
	at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
	at java.util.concurrent.DelayQueue.take(DelayQueue.java:211)
	at javax.swing.TimerQueue.run(TimerQueue.java:174)
	at java.lang.Thread.run(Thread.java:745)

"process reaper" #12 daemon prio=10 os_prio=0 tid=0x00007f16f83d8000 nid=0x2cfe
waiting on condition [0x00007f16de7bd000]
   java.lang.Thread.State: TIMED_WAITING (parking)
	at sun.misc.Unsafe.park(Native Method)
	- parking to wait for  <0x00000000f02419f8> (a
java.util.concurrent.SynchronousQueue$TransferStack)
	at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
	at
java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460)
	at
java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362)
	at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941)
	at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1066)
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
	at java.lang.Thread.run(Thread.java:745)

"AWT-XAWT" #11 daemon prio=6 os_prio=0 tid=0x00007f16f83cd800 nid=0x2cfc runnable
[0x00007f16de8be000]
   java.lang.Thread.State: RUNNABLE
	at sun.awt.X11.XToolkit.waitForEvents(Native Method)
	at sun.awt.X11.XToolkit.run(XToolkit.java:568)
	at sun.awt.X11.XToolkit.run(XToolkit.java:532)
	at java.lang.Thread.run(Thread.java:745)

"Java2D Disposer" #9 daemon prio=10 os_prio=0 tid=0x00007f16f83a7800 nid=0x2cfb in
Object.wait() [0x00007f16dedd0000]
   java.lang.Thread.State: WAITING (on object monitor)
	at java.lang.Object.wait(Native Method)
	- waiting on <0x00000000f01604d8> (a java.lang.ref.ReferenceQueue$Lock)
	at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:143)
	- locked <0x00000000f01604d8> (a java.lang.ref.ReferenceQueue$Lock)
	at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:164)
	at sun.java2d.Disposer.run(Disposer.java:148)
	at java.lang.Thread.run(Thread.java:745)

"Service Thread" #8 daemon prio=9 os_prio=0 tid=0x00007f16f80c1800 nid=0x2cf9
runnable [0x0000000000000000]
   java.lang.Thread.State: RUNNABLE

"C1 CompilerThread2" #7 daemon prio=9 os_prio=0 tid=0x00007f16f80b2000 nid=0x2cf8
waiting on condition [0x0000000000000000]
   java.lang.Thread.State: RUNNABLE

"C2 CompilerThread1" #6 daemon prio=9 os_prio=0 tid=0x00007f16f80b0000 nid=0x2cf7
waiting on condition [0x0000000000000000]
   java.lang.Thread.State: RUNNABLE

"C2 CompilerThread0" #5 daemon prio=9 os_prio=0 tid=0x00007f16f80ad000 nid=0x2cf6
waiting on condition [0x0000000000000000]
   java.lang.Thread.State: RUNNABLE

"Signal Dispatcher" #4 daemon prio=9 os_prio=0 tid=0x00007f16f80ab000 nid=0x2cf5
waiting on condition [0x0000000000000000]
   java.lang.Thread.State: RUNNABLE

"Finalizer" #3 daemon prio=8 os_prio=0 tid=0x00007f16f8083800 nid=0x2cf4 in
Object.wait() [0x00007f16e55d4000]
   java.lang.Thread.State: WAITING (on object monitor)
	at java.lang.Object.wait(Native Method)
	- waiting on <0x00000000f02441c0> (a java.lang.ref.ReferenceQueue$Lock)
	at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:143)
	- locked <0x00000000f02441c0> (a java.lang.ref.ReferenceQueue$Lock)
	at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:164)
	at java.lang.ref.Finalizer$FinalizerThread.run(Finalizer.java:209)

"Reference Handler" #2 daemon prio=10 os_prio=0 tid=0x00007f16f807f000 nid=0x2cf3
in Object.wait() [0x00007f16e56d5000]
   java.lang.Thread.State: WAITING (on object monitor)
	at java.lang.Object.wait(Native Method)
	- waiting on <0x00000000f02443f0> (a java.lang.ref.Reference$Lock)
	at java.lang.Object.wait(Object.java:502)
	at java.lang.ref.Reference.tryHandlePending(Reference.java:191)
	- locked <0x00000000f02443f0> (a java.lang.ref.Reference$Lock)
	at java.lang.ref.Reference$ReferenceHandler.run(Reference.java:153)

"VM Thread" os_prio=0 tid=0x00007f16f8077800 nid=0x2cf2 runnable

"GC task thread#0 (ParallelGC)" os_prio=0 tid=0x00007f16f801f000 nid=0x2cee runnable

"GC task thread#1 (ParallelGC)" os_prio=0 tid=0x00007f16f8020800 nid=0x2cef runnable

"GC task thread#2 (ParallelGC)" os_prio=0 tid=0x00007f16f8022800 nid=0x2cf0 runnable

"GC task thread#3 (ParallelGC)" os_prio=0 tid=0x00007f16f8024000 nid=0x2cf1 runnable

"VM Periodic Task Thread" os_prio=0 tid=0x00007f16f80c4000 nid=0x2cfa waiting on
condition

JNI global references: 1385

Heap
 PSYoungGen      total 74752K, used 7097K [0x00000000fab00000, 0x0000000100000000,
0x0000000100000000)
  eden space 64512K, 11% used
[0x00000000fab00000,0x00000000fb1ee668,0x00000000fea00000)
  from space 10240K, 0% used
[0x00000000fea00000,0x00000000fea00000,0x00000000ff400000)
  to   space 10240K, 0% used
[0x00000000ff600000,0x00000000ff600000,0x0000000100000000)
 ParOldGen       total 115200K, used 6096K [0x00000000f0000000,
0x00000000f7080000, 0x00000000fab00000)
  object space 115200K, 5% used
[0x00000000f0000000,0x00000000f05f42c0,0x00000000f7080000)
 Metaspace       used 21732K, capacity 22176K, committed 22528K, reserved 1069056K
  class space    used 2799K, capacity 2959K, committed 3072K, reserved 1048576K


NB everything is working for me even after dumping all this stuff...

> But the larger question of whether pdfsam is fit for stretch is probably
> the more pressing.  The Debian packaging is quite dated at this point
> [2], and while looking into this issue, I noticed that the Debian
> version phones home for an update check during its startup...  :(

True, I'd say "no", but it's up to you.

Best,
Philip


-------------- next part --------------
A non-text attachment was scrubbed...
Name: signature.asc
Type: application/pgp-signature
Size: 833 bytes
Desc: OpenPGP digital signature
URL: <http://lists.alioth.debian.org/pipermail/pkg-java-maintainers/attachments/20170220/aed806c6/attachment-0001.sig>

More information about the pkg-java-maintainers mailing list