Bug 8.14 Beta 1 Crash & Sluggish Collection Cache on macOS 10.15

Logos 8.14 Beta 1 quickly leaves the building on macOS 10.15.4 by clicking "+" to open a new tab: attempt to insert nil object from objects[0]

Collection Cache refresh in Logos 8.14 Beta 1 takes over an hour on macOS 10.15.4 (while takes 30 seconds on Windows 10 Pro laptop).

Steps to replicate:

  • Restarted 2019 iMac
  • Opened Logos 8.14 Beta 1 to blank layout
  • Watched Logos.log file for app idle
  • Collection Cache refresh took over an hour (attached zip includes two Samples)
  • Opened search in a tab
  • Clicked "+" to create a new tab: attempt to insert nil object from objects[0]

During Collection Cache refreshing, 3.6 GHz 8-Core Intel Core i9 CPU nearly idle (sometimes 1 %) along with lots of free memory (64 GB Ram) and fast NVMe SSD. Sample files had many Sync Wait's.

4265.814B1_CollectionCache_Crash.zip

Older Logos 8.12 Beta 3 took 11.18 seconds to update collection cache on this 27" iMac:

2020-02-21 14:08:59.2986 INFO 10 ResourceCollectionManager | (Timed) Updating collection cache.
2020-02-21 14:08:59.3495 INFO 10 ResourceCollectionManager | Updating collection cache for 1597 collections.
2020-02-21 14:09:10.4741 INFO 42 ResourceCollectionManager | (11.18s) Updating collection cache.

Keep Smiling [:)]

Comments

  • Bradley Grainger (Logos)
    Bradley Grainger (Logos) Administrator, Logos Employee Posts: 12,113

    Your log file shows that the collection cache was rebuilt twice. The first time was fast (11.96s). For the second time, it's odd that absolutely no activity was logged between these two lines:

    2020-05-06 16:24:27.5191 WARN 127 LibraryCatalog | (296ms, exceeded 250ms) Searching for all records matching: Edition:Logos -Publisher:Perseus
    2020-05-06 17:19:44.6511 INFO 493 ResourceCollectionManager | (1h 10m 49s) Updating collection cache.

    Is it possible your computer was suspended/sleeping for some of that time?

  • Keep Smiling 4 Jesus :)
    Keep Smiling 4 Jesus :) MVP Posts: 23,159

    Is it possible your computer was suspended/sleeping for some of that time?

    No - had Console open for tail monitoring of Logos.log file (checking update every few minutes to keep screen saver from starting after 10 minutes) since was not using Logos 8.14 Beta 1 during long collection cache refresh (wanted to minimize Logos activity so could repeat "+" click for new tab causing a crash for diagnostic file upload). Activity Monitor on macOS was also open so could look at CPU, Memory, &c. (thought about code intentionally sleeping so took two samples).

    FYI: clicking "+" for a new tab has consistently caused Logos 8.14 Beta 1 to crash on macOS 10.15.4 (been awhile since had a beta repeatable crash, using work around of clicking search icon to open in a new tab OR click a resource in Library to open it). Attached zip has Logos.log file having several Collection Cache refreshes with substantial timing differences (less than a second to over an hour) and repeated crash (did have several macOS sleeps during this log period):

    3377.814B1_CollectionCache_Crash2.zip

    Keep Smiling [:)]

  • Bradley Grainger (Logos)
    Bradley Grainger (Logos) Administrator, Logos Employee Posts: 12,113

    " rel="nofollow">Keep Smiling 4 Jesus :) said:

    FYI: clicking "+" for a new tab has consistently caused Logos 8.14 Beta 1 to crash on macOS 10.15.4

    I thought this was already addressed on the forums, so I didn't mention it (but I don't think it actually was). This has already been fixed for Beta 2.

  • Bradley Grainger (Logos)
    Bradley Grainger (Logos) Administrator, Logos Employee Posts: 12,113

    " rel="nofollow">Keep Smiling 4 Jesus :) said:

    Attached zip has Logos.log file having several Collection Cache refreshes with substantial timing differences (less than a second to over an hour)

    I'm not sure what could be causing this. We haven't been able to reproduce it yet.

  • Keep Smiling 4 Jesus :)
    Keep Smiling 4 Jesus :) MVP Posts: 23,159

    " rel="nofollow">Keep Smiling 4 Jesus :) said:

    Attached zip has Logos.log file having several Collection Cache refreshes with substantial timing differences (less than a second to over an hour)

    I'm not sure what could be causing this. We haven't been able to reproduce it yet.

    Launching Logos 8.14 Beta 1 this morning so far has collection cache refresh times of 12.30 and 11.62 seconds (so also not repeating long refresh time consistently).

    Keep Smiling [:)]