spine hangs on startup.

nimbling · 2016年3月22日

Hi! I'm back from a week long holiday, and now Spine refuses to start. (Mentioning this because it worked fine before).
I installed the latest OSX update after this happened, but that did not help.

The log thinks it started just fine, but OSX reports the app as "(not responding)" nor is it showing any ui.

Log, trying to start after a full reinstall:

Spine Launcher 3.0.13
Mac OS X x86_64 10.11.4
Java 1.7.0_51 Esoteric Software
64-bit Server VM
Downloading: Spine 3.1.02
Update complete.
Spine 3.1.02 Professional, JGLFW
Licensed to: Me! Yay
ATI Technologies Inc.
AMD Radeon R9 M290X OpenGL Engine
2.1 ATI-1.42.6
Resetting hotkeys (new version).
Started.

Platform: Last year's 5K iMac model.

Pharan · 2016年3月22日

I got this from the FAQ:

Why does nothing happen when I run Spine?
Are you running antivirus or other software that may interfere with Spine? For example, Bit Defender and 360 Browser have been known to falsly identify Spine as a virus and delete portions of Spine's files. Exclude Spine's installation directory from being tampered with by such software and reinstall Spine.

Why does Spine hang at start up on Mac OS X?
Are you running antivirus or other software that may interfere with Spine? For example, Rescue Time needs to be closed when Spine is started.

Apart from that, I'm not sure what would cause this (especially that it worked before but now it doesn't) but I'll let Nate know.

nimbling · 2016年3月22日

I also got that from the FAQ, and disabled anything I could conceive interfering with Spine (Finder modifications, etc.) but that did not help.
I did a jstack PID thing, and got this in the terminal:

vectorMac-2:~ herman$ jstack 5683
2016-03-22 12:33:17
Full thread dump Java HotSpot(TM) 64-Bit Server VM (24.51-b03 mixed mode):

"Attach Listener" daemon prio=5 tid=0x00007ff3fdb00000 nid=0x290f waiting on condition [0x0000000000000000]
java.lang.Thread.State: RUNNABLE

"AsynchExecutor-Thread" daemon prio=5 tid=0x00007ff3fc247000 nid=0x12a03 waiting on condition [0x0000700002d11000]
java.lang.Thread.State: WAITING (parking)
at sun.misc.Unsafe.park(Native Method)
- parking to wait for <0x00000007c0dee288> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject)
at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
at java.lang.Thread.run(Thread.java:744)

"Timer" daemon prio=5 tid=0x00007ff3fb34e000 nid=0x12007 in Object.wait() [0x0000700002802000]
java.lang.Thread.State: TIMED_WAITING (on object monitor)
at java.lang.Object.wait(Native Method)
at iR.run(SourceFile:257)
- locked <0x00000007c10f4f48> (a hb)
at java.lang.Thread.run(Thread.java:744)

"Thread-8" daemon prio=5 tid=0x00007ff3fc1ae000 nid=0x12803 waiting on condition [0x0000700002c0e000]
java.lang.Thread.State: TIMED_WAITING (parking)
at sun.misc.Unsafe.park(Native Method)
- parking to wait for <0x00000007c0c99730> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject)
at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:226)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2082)
at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1090)
at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:807)
at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
at java.lang.Thread.run(Thread.java:744)

"Images" daemon prio=5 tid=0x00007ff3fb346000 nid=0x12603 waiting on condition [0x0000700002b0b000]
java.lang.Thread.State: WAITING (parking)
at sun.misc.Unsafe.park(Native Method)
- parking to wait for <0x00000007c10f50d0> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject)
at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
at java.lang.Thread.run(Thread.java:744)

"Open" daemon prio=5 tid=0x00007ff3fdb8d000 nid=0x12403 waiting on condition [0x0000700002a08000]
java.lang.Thread.State: TIMED_WAITING (sleeping)
at java.lang.Thread.sleep(Native Method)
at java.lang.invoke.LambdaForm$DMH/1364030911.invokeStatic_J_V(LambdaForm$DMH)
at java.lang.invoke.LambdaForm$MH/2126308798.linkToCallSite(LambdaForm$MH)
at Dn.e(SourceFile:702)
at java.lang.invoke.LambdaForm$DMH/136892060.invokeStatic_I_V(LambdaForm$DMH)
at java.lang.invoke.LambdaForm$MH/789866483.linkToCallSite(LambdaForm$MH)
at mn.run(SourceFile:447)

"FileWatch" prio=5 tid=0x00007ff3fc62f800 nid=0x11d07 waiting on condition [0x00007000026ff000]
java.lang.Thread.State: TIMED_WAITING (parking)
at sun.misc.Unsafe.park(Native Method)
- parking to wait for <0x00000007c0c9db90> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject)
at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:226)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2082)
at java.util.concurrent.LinkedBlockingDeque.pollFirst(LinkedBlockingDeque.java:519)
at java.util.concurrent.LinkedBlockingDeque.poll(LinkedBlockingDeque.java:682)
at sun.nio.fs.AbstractWatchService.poll(AbstractWatchService.java:108)
at CN.run(SourceFile:54)
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)
at java.util.concurrent.FutureTask.run(FutureTask.java:262)
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:178)
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:292)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
at java.lang.Thread.run(Thread.java:744)

"TimerQueue" daemon prio=5 tid=0x00007ff3fd946000 nid=0xf307 waiting on condition [0x0000700002579000]
java.lang.Thread.State: WAITING (parking)
at sun.misc.Unsafe.park(Native Method)
- parking to wait for <0x00000007c081f9e0> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject)
at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
at java.util.concurrent.DelayQueue.take(DelayQueue.java:209)
at javax.swing.TimerQueue.run(TimerQueue.java:171)
at java.lang.Thread.run(Thread.java:744)

"Timer-0" daemon prio=5 tid=0x00007ff3fc107000 nid=0xec0b in Object.wait() [0x0000700002373000]
java.lang.Thread.State: WAITING (on object monitor)
at java.lang.Object.wait(Native Method)
at java.lang.Object.wait(Object.java:503)
at java.util.TimerThread.mainLoop(Timer.java:526)
- locked <0x00000007c08248f8> (a java.util.TaskQueue)
at java.util.TimerThread.run(Timer.java:505)

"Java2D Disposer" daemon prio=5 tid=0x00007ff3fb8e9000 nid=0xd703 in Object.wait() [0x000070000216d000]
java.lang.Thread.State: WAITING (on object monitor)
at java.lang.Object.wait(Native Method)
at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:135)
- locked <0x00000007c07d2808> (a java.lang.ref.ReferenceQueue$Lock)
at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:151)
at sun.java2d.Disposer.run(Disposer.java:145)
at java.lang.Thread.run(Thread.java:744)

"Java2D Queue Flusher" daemon prio=5 tid=0x00007ff3fb12e800 nid=0xcc07 in Object.wait() [0x000070000206a000]
java.lang.Thread.State: TIMED_WAITING (on object monitor)
at java.lang.Object.wait(Native Method)
at sun.java2d.opengl.OGLRenderQueue$QueueFlusher.run(OGLRenderQueue.java:208)
- locked <0x00000007c081f740> (a sun.java2d.opengl.OGLRenderQueue$QueueFlusher)

"Spine" prio=5 tid=0x00007ff3fb057800 nid=0xc503 waiting on condition [0x0000700001f66000]
java.lang.Thread.State: TIMED_WAITING (sleeping)
at java.lang.Thread.sleep(Native Method)
at at.a(SourceFile:63)
at ac.e(SourceFile:236)
at mJ.e(SourceFile:92)
at ah.run(SourceFile:189)
at java.awt.event.InvocationEvent.dispatch(InvocationEvent.java:251)
at java.awt.EventQueue.dispatchEventImpl(EventQueue.java:733)
at java.awt.EventQueue.access$200(EventQueue.java:103)
at java.awt.EventQueue$3.run(EventQueue.java:694)
at java.awt.EventQueue$3.run(EventQueue.java:692)
at java.security.AccessController.doPrivileged(Native Method)
at java.security.ProtectionDomain$1.doIntersectionPrivilege(ProtectionDomain.java:76)
at java.awt.EventQueue.dispatchEvent(EventQueue.java:703)
at java.awt.EventDispatchThread.pumpOneEventForFilters(EventDispatchThread.java:242)
at java.awt.EventDispatchThread.pumpEventsForFilter(EventDispatchThread.java:161)
at java.awt.EventDispatchThread.pumpEventsForHierarchy(EventDispatchThread.java:150)
at java.awt.EventDispatchThread.pumpEvents(EventDispatchThread.java:146)
at java.awt.EventDispatchThread.pumpEvents(EventDispatchThread.java:138)
at java.awt.EventDispatchThread.run(EventDispatchThread.java:91)

"AWT-Shutdown" prio=5 tid=0x00007ff3fb0d4000 nid=0xc303 in Object.wait() [0x0000700001e64000]
java.lang.Thread.State: WAITING (on object monitor)
at java.lang.Object.wait(Native Method)
at java.lang.Object.wait(Object.java:503)
at sun.awt.AWTAutoShutdown.run(AWTAutoShutdown.java:287)
- locked <0x00000007c08aab38> (a java.lang.Object)
at java.lang.Thread.run(Thread.java:744)

"AppKit Thread" daemon prio=5 tid=0x00007ff3fb0b1000 nid=0x160b runnable [0x00007fff5d9a2000]
java.lang.Thread.State: RUNNABLE
at sun.lwawt.macosx.LWCToolkit.doAWTRunLoop(Native Method)
at sun.lwawt.macosx.LWCToolkit.invokeAndWait(LWCToolkit.java:549)
at sun.lwawt.macosx.LWCToolkit.invokeAndWait(LWCToolkit.java:489)
at sun.lwawt.macosx.CAccessibility.invokeAndWait(CAccessibility.java:75)
at sun.lwawt.macosx.CAccessibility.getAccessibleRole(CAccessibility.java:232)

"Service Thread" daemon prio=5 tid=0x00007ff3fc038800 nid=0xbc03 runnable [0x0000000000000000]
java.lang.Thread.State: RUNNABLE

"C2 CompilerThread1" daemon prio=5 tid=0x00007ff3fd83b000 nid=0xba03 waiting on condition [0x0000000000000000]
java.lang.Thread.State: RUNNABLE

"C2 CompilerThread0" daemon prio=5 tid=0x00007ff3fd818000 nid=0xb803 waiting on condition [0x0000000000000000]
java.lang.Thread.State: RUNNABLE

"Signal Dispatcher" daemon prio=5 tid=0x00007ff3fd836800 nid=0xb50b runnable [0x0000000000000000]
java.lang.Thread.State: RUNNABLE

"Finalizer" daemon prio=5 tid=0x00007ff3fb85e000 nid=0xb203 in Object.wait() [0x0000700001852000]
java.lang.Thread.State: WAITING (on object monitor)
at java.lang.Object.wait(Native Method)
at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:135)
- locked <0x00000007c07d7bd8> (a java.lang.ref.ReferenceQueue$Lock)
at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:151)
at java.lang.ref.Finalizer$FinalizerThread.run(Finalizer.java:189)

"Reference Handler" daemon prio=5 tid=0x00007ff3fc038000 nid=0xb003 in Object.wait() [0x000070000174f000]
java.lang.Thread.State: WAITING (on object monitor)
at java.lang.Object.wait(Native Method)
at java.lang.Object.wait(Object.java:503)
at java.lang.ref.Reference$ReferenceHandler.run(Reference.java:133)
- locked <0x00000007c08a3160> (a java.lang.ref.Reference$Lock)

"main" prio=5 tid=0x00007ff3fb07f000 nid=0x870f waiting on condition [0x0000000000000000]
java.lang.Thread.State: RUNNABLE

"VM Thread" prio=5 tid=0x00007ff3fb85b800 nid=0xae03 runnable

"GC task thread#0 (ParallelGC)" prio=5 tid=0x00007ff3fb074000 nid=0x9e03 runnable

"GC task thread#1 (ParallelGC)" prio=5 tid=0x00007ff3fb082000 nid=0xa003 runnable

"GC task thread#2 (ParallelGC)" prio=5 tid=0x00007ff3fb006800 nid=0xa203 runnable

"GC task thread#3 (ParallelGC)" prio=5 tid=0x00007ff3fb007800 nid=0xa403 runnable

"GC task thread#4 (ParallelGC)" prio=5 tid=0x00007ff3fb008000 nid=0xa603 runnable

"GC task thread#5 (ParallelGC)" prio=5 tid=0x00007ff3fb821800 nid=0xa803 runnable

"GC task thread#6 (ParallelGC)" prio=5 tid=0x00007ff3fb822000 nid=0xaa03 runnable

"GC task thread#7 (ParallelGC)" prio=5 tid=0x00007ff3fb823000 nid=0xac03 runnable

"VM Periodic Task Thread" prio=5 tid=0x00007ff3fc046000 nid=0xbe03 waiting on condition

JNI global references: 923

Pharan · 2016年3月22日

Thanks for the detailed report. Hopefully, it helps.

nimbling · 2016年3月29日

No, thank you!
I'm hoping it's detailed enough, if there's more I can sample, please let me know.

Ok, so by some magic, Today, Spine asked for my license again and worked straight after that.
YAY!

I'm still available for helping tracking this issue down by the way.

Keep up the good work!
(Any plans on making the spine mesh and this guy's mesh work together?
http://svgimporter.com/ )

EDIT added two more samples. The first (2) of spine starting, the second (3) of when it hangs.
I'm a bit scared now, Spine is not starting again, with the same symptoms: not showing anything and just a "not responding" in the activity monitor.

Could we perhaps get in touch to fix this?

29/03/16 17:13:08,011 Spine[6501]: ApplePersistence=YES
29/03/16 17:13:19,107 WindowServer[186]: disable_update_timeout: UI updates were forcibly disabled by application "Spine" for over 1.00 seconds. Server has re-enabled them.
29/03/16 17:13:32,000 kernel[0]: process Spine[6501] caught causing excessive wakeups. EXC_RESOURCE supressed due to audio playback
29/03/16 17:13:33,107 WindowServer[186]: disable_update_likely_unbalanced: UI updates still disabled by application "Spine" after 15.00 seconds (server forcibly re-enabled them after 1.00 seconds). Likely an unbalanced disableUpdate call.
29/03/16 17:14:14,791 loginwindow[96]: Failed to write crash history to file:///Users/herman/Library/Application%20Support/CrashReporter/Spine_8EFC853B-4C45-5ED0-91E1-6A0DFD5886A9.plist.
29/03/16 17:14:17,663 Spine[6557]: ApplePersistence=YES
29/03/16 17:14:22,975 WindowServer[186]: disable_update_timeout: UI updates were forcibly disabled by application "Spine" for over 1.00 seconds. Server has re-enabled them.
29/03/16 17:14:36,974 WindowServer[186]: disable_update_likely_unbalanced: UI updates still disabled by application "Spine" after 15.00 seconds (server forcibly re-enabled them after 1.00 seconds). Likely an unbalanced disableUpdate call.
29/03/16 17:15:02,000 kernel[0]: process Spine[6557] caught causing excessive wakeups. EXC_RESOURCE supressed due to audio playback
29/03/16 17:15:28,319 loginwindow[96]: Failed to write crash history to file:///Users/herman/Library/Application%20Support/CrashReporter/Spine_8EFC853B-4C45-5ED0-91E1-6A0DFD5886A9.plist.

Nate · 2016年3月29日

Sorry for the delay, was away visiting family.

The first jstack shows the "Spine" thread waiting for a vsync, which is a normal place to see Spine's execution. You might try running jstack multiple times and look at the "Spine" thread to try to catch it doing something else, but it seems to be running normally. The log says "Started." which means it got past all the initialization, that is when it should show the window and be running.

The logs in your last post unfortunately don't show anything helpful.

Are you running any display link software, for sharing your cell phone display? No Rescue Time?

It's bizarre that it was working, then suddenly stopped working, then worked once before not working again. Are you sure your system configuration didn't change? Sounds like some kind of race condition, but I'm not sure what it could be.

Have you tried updating your video drivers? Maybe go back a version?

You could try deleting (or otherwise moving) your Spine user files which are stored here:
Mac: <user home folder>/Library/Application Support/Spine/

nimbling · 2016年3月30日

Thanks!

I haven't knowingly updated my video drivers, and once back ar work tomorrow I'll double check Duet is not starting on startup (though it has worked with that on). I'll do the prefs thing again, as that worked last time (other work popped up before I could try that again).

I'll keep you posted, and thanks again.

Hey! this is super weird, when I run it using the system's java like this:

java -jar /Applications/Spine/Spine.app/Contents/MacOS/launcher/launcher-full.jar

It shows me the interface!

I do get this output in the terminal though:
Can I help in resolving this further?

Last login: Tue Mar 29 14:42:35 on console
vectorMac-2:~ herman$ java -jar /Applications/Spine/Spine.app/Contents/MacOS/launcher/launche 2016-03-30 10:00:18.240 java[19805:1922300] ApplePersistence=YES
Spine Launcher 3.0.13
Mac OS X x86_64 10.11.4
Java 1.8.0_74 Oracle Corporation
Java HotSpot(TM) 64-Bit Server VM
WARNING: Not using the Java bundled with Spine.
Downloading: Spine 3.1.03
Update complete.
Spine 3.1.03 Professional, JGLFW
Licensed to: *******************
ATI Technologies Inc.
AMD Radeon R9 M290X OpenGL Engine
2.1 ATI-1.42.6
Resetting hotkeys (new version).
Started.
Mar 30 10:00:42 java[19805] <Warning>: void CGSUpdateManager::log() const: conn Mar 30 10:00:42 java[19805] <Warning>: Backtrace (at 29761.2):
Mar 30 10:00:42 java[19805] <Warning>: void CGSUpdateManager::log() const: 0 CoreGraphics Mar 30 10:00:42 java[19805] <Warning>: void CGSUpdateManager::log() const: 1 AppKit Mar 30 10:00:42 java[19805] <Warning>: void CGSUpdateManager::log() const: 2 Mar 30 10:00:42 java[19805] <Warning>: void CGSUpdateManager::log() const: 3 Mar 30 10:00:42 java[19805] <Warning>: void CGSUpdateManager::log() const: 4 AppKit Mar 30 10:00:42 java[19805] <Warning>: void CGSUpdateManager::log() const: 5 AppKit Mar 30 10:00:42 java[19805] <Warning>: void CGSUpdateManager::log() const: 6 AppKit Mar 30 10:00:42 java[19805] <Warning>: void CGSUpdateManager::log() const: 7 AppKit Mar 30 10:00:42 java[19805] <Warning>: void CGSUpdateManager::log() const: 8 AppKit Mar 30 10:00:42 java[19805] <Warning>: void CGSUpdateManager::log() const: 9 AppKit Mar 30 10:00:42 java[19805] <Warning>: void CGSUpdateManager::log() const: 10 AppKit Mar 30 10:00:42 java[19805] <Warning>: void CGSUpdateManager::log() const: 11 AppKit Mar 30 10:00:42 java[19805] <Warning>: void CGSUpdateManager::log() const: 12 AppKit Mar 30 10:00:42 java[19805] <Warning>: void CGSUpdateManager::log() const: 13 AppKit Mar 30 10:00:42 java[19805] <Warning>: void CGSUpdateManager::log() const: 14 AppKit Mar 30 10:00:42 java[19805] <Warning>: void CGSUpdateManager::log() const: 15 AppKit Mar 30 10:00:42 java[19805] <Warning>: void CGSUpdateManager::log() const: 16 AppKit Mar 30 10:00:42 java[19805] <Warning>: void CGSUpdateManager::log() const: 17 AppKit Mar 30 10:00:42 java[19805] <Warning>: void CGSUpdateManager::log() const: 18 AppKit Mar 30 10:00:42 java[19805] <Warning>: void CGSUpdateManager::log() const: 19 AppKit Mar 30 10:00:42 java[19805] <Warning>: void CGSUpdateManager::log() const: 20 AppKit Mar 30 10:00:42 java[19805] <Warning>: void CGSUpdateManager::log() const: 21 AppKit Mar 30 10:00:42 java[19805] <Warning>: void CGSUpdateManager::log() const: 22 libjglfw64.dylib Mar 30 10:00:42 java[19805] <Warning>: void CGSUpdateManager::log() const: 23 libjglfw64.dylib Mar 30 10:00:42 java[19805] <Warning>: void CGSUpdateManager::log() const: 24 libjglfw64.dylib Mar 30 10:00:42 java[19805] <Warning>: void CGSUpdateManager::log() const: 25 ??? r-full.jar
0x2e697 token 0x1dfffffffffe9cb
0x00007fff90e2ea99 CGSDisableUpdateToken + 210
0x00007fff9c9db524 ___disable_updates_sync_block_invoke_2 + 17
libdispatch.dylib 0x00007fff90d7d40b _dispatch_client_callout + 8
libdispatch.dylib 0x00007fff90d7e9f2 dispatch_barrier_sync_f_invoke + 74
0x00007fff9c9db3f2 NSCGSDisableUpdates + 213
0x00007fff9c9db228 NSCGSTransactionRunPreCommitActionsForOrder + 156
0x00007fff9c9db16f NSCGSTransactionRunPreCommitActions_ + 21
0x00007fff9d189d84 -[_NSCGSTransaction synchronize] + 33
0x00007fff9d189ff9 NSCGSTransactionSynchronize + 76
0x00007fff9cf75065 -[NSSurface syncSurfaceWantsExtendedDynamicRange] + 150
0x00007fff9caad3d1 -[NSSurface createSurface] + 677
0x00007fff9caacd2c -[NSSurface setFrame:] + 785
0x00007fff9cf7516b __38-[NSSurface syncToViewUnconditionally]block_invoke + 154
0x00007fff9c9ca429 NSPerformVisuallyAtomicChange + 147
0x00007fff9caac650 -[NSSurface syncToViewUnconditionally] + 100
0x00007fff9cf75204 __37-[NSSurface orderSurface:relativeTo:]block_invoke + 44
0x00007fff9c9ca429 NSPerformVisuallyAtomicChange + 147
0x00007fff9caac523 -[NSSurface orderSurface:relativeTo:] + 160
0x00007fff9cb9ff22 NSOpenGLContextAttachOnScreenViewSurface + 183
0x00007fff9cecc767 __27-[NSOpenGLContext setView:]block_invoke + 192
0x00007fff9c9ca429 NSPerformVisuallyAtomicChange + 147
0x00007fff9cb8ccd9 -[NSOpenGLContext setView:] + 119
0x000000016b5c5ee4 _glfwPlatformCreateWindow + 2020
0x000000016b5c8c99 glfwCreateWindow + 633
0x000000016b588e73 Java_com_badlogic_jglfw_Glfw_glfwCreateWindowJni + 67
0x0000000102fa4954 0x0 + 4344924500

Oh, and when I resize the window, it flickers like mad. I think my java is borked somehow

I uninstalled my java upgrade, to no avail. I'm going to reinstall the latest Java again, to see if this helps. I do have some preferences set in Java to not install crapware... I hope that's not it!

Nate · 2016年3月31日

I don't know what the cause of the CGSUpdateManager logging could be. Brief Googling mentions balancing viewDidLoad/Appear/Disappear, CGContextSave/RestoreGState, and save/restoreCurrentContext but none of those are a problem in our source.

Some flickering on window resize isn't too alarming. I've seen many other apps do this, such as Photoshop.

Changing your system Java will only have an effect when running from the command line as you have done. When running Spine.app it uses a version of Java bundled with Spine which allows us to control which Java version is used and ensure things are going to work as expected. It's very strange you can run with your system Java but not the bundled version!

Since Spine is working for us and other Spine users, there has to be something different about your hardware and/or software. Unfortunately I'm at a loss as to what that could be. I know it's not ideal and I'd like to fix this as much as anyone, but at least you can run from the command line so you are no longer stuck.

nimbling · 2016年3月31日

Thanks! Yes me and my job are happy it works again...

Photoshop does not flicker like this for me.

I'll see if a "repair permissions" helps, or find some other security settings. Some chown'ing should assure it's executable, no?

I'll post back my findings.

I noticed I have "custom access" - and I'm not listed in spine.app's priveleges list in the info pane in finder. Would changing that help?

Nate · 2016年3月31日

Not sure, can't hurt. You could chmod -r 777 *.

nimbling · 2016年4月6日

hey!
without sudo, I got "operation not permitted" on everything.

So I did

sudo chmod -R 777 *

which allowed me to change the permissions, except on the runtimes folder, which had an ACL set.

I cleared the ACL's using

sudo chmod -R -N *

My latest console output:

05/04/16 13:08:30,886 Spine[9747]: ApplePersistence=NO
05/04/16 13:08:36,232 WindowServer[180]: disable_update_timeout: UI updates were forcibly disabled by application "Spine" for over 1.00 seconds. Server has re-enabled them.
05/04/16 13:08:50,232 WindowServer[180]: disable_update_likely_unbalanced: UI updates still disabled by application "Spine" after 15.00 seconds (server forcibly re-enabled them after 1.00 seconds). Likely an unbalanced disableUpdate call.
05/04/16 13:09:12,000 kernel[0]: process Spine[9747] caught causing excessive wakeups. EXC_RESOURCE supressed due to audio playback

I'm trying to install the trial, but that does not work, either. OSX is kind enough to save a spindump, though.
Please advise.

06/04/16 14:00:10,000 kernel[0]: hfs: mounted Spine Trial on device disk3s1
06/04/16 14:00:10,314 mds[62]: (Volume.Normal:2464) volume:0x7f93dc022000 ********** Bootstrapped Creating a default store:1 SpotLocnull) SpotVerLocnull) occlude:0 /Volumes/Spine Trial
06/04/16 14:00:34,606 SpineTrial[1630]: ApplePersistence=NO
06/04/16 14:00:45,235 WindowServer[180]: disable_update_timeout: UI updates were forcibly disabled by application "SpineTrial" for over 1.00 seconds. Server has re-enabled them.
06/04/16 14:00:59,235 WindowServer[180]: disable_update_likely_unbalanced: UI updates still disabled by application "SpineTrial" after 15.00 seconds (server forcibly re-enabled them after 1.00 seconds). Likely an unbalanced disableUpdate call.
06/04/16 14:01:03,000 kernel[0]: process SpineTrial[1630] caught causing excessive wakeups. Observed wakeups rate (per sec): 1548; Maximum permitted wakeups rate (per sec): 150; Observation period: 300 seconds; Task lifetime number of wakeups: 45111
06/04/16 14:01:09,121 spindump[570]: Saved wakeups_resource.diag report for SpineTrial version 1.0 (1) to /Library/Logs/DiagnosticReports/SpineTrial_2016-04-06-140109_vectorMac-2.wakeups_resource.diag

Nate · 2016年4月6日

It's not clear, did running via the .app file still fail to work after your chmod? You could also try sudo open -n Spine.app.

Lots of reports on Google about "excessive wakeups". It seems apps can spontaneously start doing this, Photoshop and others, but no answers as to why.

nimbling · 2016年4月6日

That did not do the trick.

What puzzles me is how I ended up with "custom access" in the first place.

I have cleared the ACL's and tried some thorough chown'ing, but that did not help.

FOUND THE CULPRIT!
YAY!
It was Default Folder X!
http://www.stclairsoft.com/DefaultFolderX/

I added Spine.app to the exclude list and it booted straight away!

I'll contact them and ask them to look into it.

PHEW

Nate · 2016年4月6日

Wow, kudos for figuring that out! I've added it to the FAQ.