Re: [Audacity-devel] Audacity 3.0.1 RC01 (for testing only)

[Paul L. <pau...@gm...>]

See my other thread!!  I’m preparing a PR with a more plausible fix.

PRL

On Thursday, April 8, 2021, Leland <ll...@ho...> wrote:

> Honestly Paul, before going to be this morning I really didn’t have any
> ideas on what to look at.  The solution (setting a busy timeout) that James
> came up with seems to be a good stopgap measure and should get into 3.0.1
> (IMO).
>
>
>
> *From:* Paul Licameli <pau...@gm...>
> *Sent:* Thursday, April 8, 2021 6:58 AM
> *To:* aud...@li...
> *Subject:* Re: [Audacity-devel] Audacity 3.0.1 RC01 (for testing only)
>
>
>
> Jack, can you describe a precise sequence of reproducible steps?  When you
> say “open” a file, tell us exactly what that means — dragging it onto an
> Audacity window or using a file dialog or something else,
>
>
>
> Leland, are you thinking as I am that this involves a short lived Audacity
> process that results only when you click an icon or use its context menu?
>
>
>
> I have got anecdotal word that the import problem can be on Mac too.
>
>
>
> PRL
>
> On Thursday, April 8, 2021, Jack L. <xxj...@gm...> wrote:
>
> right click filename, open with audacity, then combo of ctrl+o inside
> audacity and import audio. When the error message came up, it was a ctrl+o
> operation.
>
>
>
> On Thu, Apr 8, 2021 at 12:23 AM Leland <ll...@ho...> wrote:
>
> Thanks Jack.  It’s pretty perplexing though.  I don’t understand why the
> log entries are out of order.  Could be that these two messages were
> buffered until the logger was initialized:
>
>
>
> 13:28:05: sqlite3 message: (261) database is locked in "PRAGMA
> main.synchronous = NORMAL;PRAGMA main.journal_mode = WAL;PRAGMA
> main.wal_autocheckpoint = 0;"
>
> 13:28:05: Failed to set mode on C:\Users\jlow\AppData\Local\Audacity\SessionData\New
> Project 2021-04-07 13-28-05 N-1.aup3unsaved
>
>
>
> How did you start Audacity?  From the start menu or by double clicking an
> project file?
>
>
>
> *From:* Jack L. <xxj...@gm...>
> *Sent:* Thursday, April 8, 2021 1:55 AM
> *To:* Audacity-Devel list <aud...@li...>
> *Subject:* Re: [Audacity-devel] Audacity 3.0.1 RC01 (for testing only)
>
>
>
> The install, here's the lastlog
>
>
>
> 13:28:06: Audacity 3.0.1
> 13:28:06: Trying to load FFmpeg libraries...
> 13:28:06: Trying to load FFmpeg libraries from default path, 'C:\Program
> Files (x86)\FFmpeg for Audacity\avformat-55.dll'.
> 13:28:06: Looking up PATH environment variable...
> 13:28:06: PATH = 'C:\Program Files (x86)\Common
> Files\Oracle\Java\javapath;C:\windows\system32;C:\windows;C:
> \windows\System32\Wbem;C:\windows\System32\WindowsPowerShell\v1.0\;C:\
> ProgramData\chocolatey\bin;C:\Program Files (x86)\Intel\Intel(R)
> Management Engine Components\DAL;C:\Program Files\Intel\Intel(R) Management
> Engine Components\DAL;C:\Program Files\PuTTY\;C:\Users\jlow\
> AppData\Local\Microsoft\WindowsApps;C:\Program Files\smartmontools\bin'
> 13:28:06: Temporarily prepending ';C:\Program Files (x86)\FFmpeg for
> Audacity' to PATH...
> 13:28:06: Checking for monolithic avformat from 'C:\Program Files
> (x86)\FFmpeg for Audacity\avformat-55.dll'.
> 13:28:06: Error: Couldn't find symbol 'avutil_version' in a dynamic
> library (error 127: The specified procedure could not be found.)
> 13:28:06: Error: Couldn't find symbol 'avcodec_version' in a dynamic
> library (error 127: The specified procedure could not be found.)
> 13:28:06: avformat not monolithic
> 13:28:06: Loading avutil from 'avutil-52.dll'.
> 13:28:06: Loading avcodec from 'avcodec-55.dll'.
> 13:28:06: Loading avformat from 'C:\Program Files (x86)\FFmpeg for
> Audacity\avformat-55.dll'.
> 13:28:06: Actual avutil path C:\Program Files (x86)\FFmpeg for
> Audacity\avutil-52.dll
> 13:28:06: Actual avcodec path C:\Program Files (x86)\FFmpeg for
> Audacity\avcodec-55.dll
> 13:28:06: Actual avformat path C:\Program Files (x86)\FFmpeg for
> Audacity\avformat-55.dll
> 13:28:06: Importing symbols...
> 13:28:06: All symbols loaded successfully. Initializing the library.
> 13:28:06: Retrieving FFmpeg library version numbers:
> 13:28:06:    AVCodec version 0x373466 - 55.52.102 (built against 0x373466
> - 55.52.102)
> 13:28:06:    AVFormat version 0x372164 - 55.33.100 (built against 0x372164
> - 55.33.100)
> 13:28:06:    AVUtil version 0x344264 - 52.66.100 (built against 0x344264 -
> 52.66.100)
> 13:28:06: Returning PATH to previous setting: C:\Program Files
> (x86)\Common Files\Oracle\Java\javapath;C:\windows\system32;C:\windows;C:
> \windows\System32\Wbem;C:\windows\System32\WindowsPowerShell\v1.0\;C:\
> ProgramData\chocolatey\bin;C:\Program Files (x86)\Intel\Intel(R)
> Management Engine Components\DAL;C:\Program Files\Intel\Intel(R) Management
> Engine Components\DAL;C:\Program Files\PuTTY\;C:\Users\jlow\
> AppData\Local\Microsoft\WindowsApps;C:\Program Files\smartmontools\bin
> 13:28:06: FFmpeg libraries loaded successfully.
> 13:28:06: File name is C:\Users\jlow\Desktop\Bodoni - Lipstick.mp3
> 13:28:06: Mime type is *
> 13:28:06: Opening with libmad
> 13:28:06: Open(C:\Users\jlow\Desktop\Bodoni - Lipstick.mp3) succeeded
> 13:28:07: File name is C:\Users\jlow\Desktop\Bodoni - Influencer
> Influenza.mp3
> 13:28:07: Mime type is *
> 13:28:07: Opening with libmad
> 13:28:07: Open(C:\Users\jlow\Desktop\Bodoni - Influencer Influenza.mp3)
> succeeded
> 13:28:05: sqlite3 message: (261) database is locked in "PRAGMA
> main.synchronous = NORMAL;PRAGMA main.journal_mode = WAL;PRAGMA
> main.wal_autocheckpoint = 0;"
> 13:28:05: Failed to set mode on C:\Users\jlow\AppData\Local\Audacity\SessionData\New
> Project 2021-04-07 13-28-05 N-1.aup3unsaved
> Error: query aborted
> SQL: PRAGMA main.locking_mode = SHARED;PRAGMA main.synchronous =
> NORMAL;PRAGMA main.journal_mode = WAL;PRAGMA main.wal_autocheckpoint = 0;
> 13:28:18: File name is C:\Users\jlow\Desktop\21stpunk1endcredits.mp4
> 13:28:18: Mime type is *
> 13:28:18: Opening with libav
> 13:28:18: Open(C:\Users\jlow\Desktop\21stpunk1endcredits.mp4) succeeded
> 13:28:17: sqlite3 message: (261) database is locked in "PRAGMA
> main.synchronous = NORMAL;PRAGMA main.journal_mode = WAL;PRAGMA
> main.wal_autocheckpoint = 0;"
> 13:28:17: Failed to set mode on C:\Users\jlow\AppData\Local\Audacity\SessionData\New
> Project 2021-04-07 13-28-17 N-2.aup3unsaved
> Error: query aborted
> SQL: PRAGMA main.locking_mode = SHARED;PRAGMA main.synchronous =
> NORMAL;PRAGMA main.journal_mode = WAL;PRAGMA main.wal_autocheckpoint = 0;
> 13:28:29: File name is C:\Users\jlow\Desktop\
> 0ca2af87641e21c6ff89ffadcf4725402078e43e.mp4
> 13:28:29: Mime type is *
> 13:28:29: Opening with libav
> 13:28:29: Open(C:\Users\jlow\Desktop\0ca2af87641e21c6ff89ffadcf4725402078e43e.mp4)
> succeeded
> 13:28:29: sqlite3 message: (261) database is locked in "PRAGMA
> main.synchronous = NORMAL;PRAGMA main.journal_mode = WAL;PRAGMA
> main.wal_autocheckpoint = 0;"
> 13:28:29: Failed to set mode on C:\Users\jlow\AppData\Local\Audacity\SessionData\New
> Project 2021-04-07 13-28-29 N-3.aup3unsaved
> Error: query aborted
> SQL: PRAGMA main.locking_mode = SHARED;PRAGMA main.synchronous =
> NORMAL;PRAGMA main.journal_mode = WAL;PRAGMA main.wal_autocheckpoint = 0;
> 13:28:39: File name is C:\Users\jlow\Desktop\21stpunk1endcredits.mp4
> 13:28:39: Mime type is *
> 13:28:39: Opening with libav
> 13:28:39: Open(C:\Users\jlow\Desktop\21stpunk1endcredits.mp4) succeeded
> 13:28:44: DBConnection SetDBError
> ErrorCode: 5
> LastError: Failed to execute a project file command:
>
> SELECT Count(*) FROM sqlite_master WHERE type='table';
> LibraryError: database is locked
> 13:29:56: File name is C:\Users\jlow\Desktop\21stpunk2credits.mp4
> 13:29:56: Mime type is *
> 13:29:56: Opening with libav
> 13:29:56: Open(C:\Users\jlow\Desktop\21stpunk2credits.mp4) succeeded
> 13:30:30: File name is C:\Users\jlow\Desktop\21stpunk1traileraws.avi
> 13:30:30: Mime type is *
> 13:30:30: Opening with libav
> 13:30:30: Open(C:\Users\jlow\Desktop\21stpunk1traileraws.avi) succeeded
> 13:30:30: sqlite3 message: (261) database is locked in "PRAGMA
> main.synchronous = NORMAL;PRAGMA main.journal_mode = WAL;PRAGMA
> main.wal_autocheckpoint = 0;"
> 13:30:30: Failed to set mode on C:\Users\jlow\AppData\Local\Audacity\SessionData\New
> Project 2021-04-07 13-30-30 N-5.aup3unsaved
> Error: query aborted
> SQL: PRAGMA main.locking_mode = SHARED;PRAGMA main.synchronous =
> NORMAL;PRAGMA main.journal_mode = WAL;PRAGMA main.wal_autocheckpoint = 0;
>
>
>
> On Wed, Apr 7, 2021 at 6:04 PM Leland <ll...@ho...> wrote:
>
> Are you using a “portable” version of Audacity or do you install?  If
> portable, then you’ll find the lastlog.txt file inside your portable
> directory.  If you install Audacity, then you should find it here:
>
>
>
> C:\Users\jlow\AppData\Roaming\Audacity
>
>
>
>
>
> *From:* Jack L. <xxj...@gm...>
> *Sent:* Wednesday, April 7, 2021 7:31 PM
> *To:* Audacity-Devel list <aud...@li...>
> *Subject:* Re: [Audacity-devel] Audacity 3.0.1 RC01 (for testing only)
>
>
>
> I might be looking in the wrong place
>
>
>
> C:\Users\jlow\AppData\Local\Audacity\SessionData\New Project 2021-04-07
> 13-28-44 N-4.aup3unsaved
>
>
>
> is the only thing i found in AppData
>
>
>
> On Wed, Apr 7, 2021 at 5:19 PM Leland <ll...@ho...> wrote:
>
> Not finding the lastlog file is rather interesting by itself.  It should
> be there.  One of the last things that Audacity does when it exits is dump
> the log to that file.  The only reason I can think of that it might not be
> doing that is that Audacity is not ending cleanly.
>
>
>
> James has come up with an idea on how to resolve the open error, but I’m
> still going to look into why it’s happening.  It really shouldn’t be.  But,
> whether I find the real cause or not, we’ll put in James’s solution.
>
>
>
> *From:* Jack L. <xxj...@gm...>
> *Sent:* Wednesday, April 7, 2021 5:34 PM
> *To:* Audacity-Devel list <aud...@li...>
> *Subject:* Re: [Audacity-devel] Audacity 3.0.1 RC01 (for testing only)
>
>
>
> Not able to find the lastlog but I seem to run into the issue pretty
> frequently just by opening/importing files in multiple windows. Maybe that
> is the key.
>
>
>
> Seems to be fairly easy to reproduce on FreeBSD and Windows but it doesn't
> seem to happen in any particular sequence but it always happens during an
> open file via ctrl+o type open from what I can tell.
>
>
>
> On Wed, Apr 7, 2021 at 3:10 PM Leland <ll...@ho...> wrote:
>
> I was able to reproduce your error through some trickery:
>
>
>
>
>
> But, what’s interesting about this is that it’s not as simple as I first
> thought.  If it had been a simple file locking issue, then we could have
> just increased the number of retries that sqlite performs until the
> operation succeeds.
>
>
>
> However, this is not a “plain” file locking issue.  It’s a “database”
> locking issue where two processes/threads are trying to use the database
> concurrently.  Almost as if two Audacity’s were running and fiddling with
> the same aup3.  (I know that’s not the case.)
>
>
>
> I was able to reproduce the same message by holding an active “write”
> transaction using the “sqlite3” command line utility.  If I use the same
> utility to hold a “read” transaction open, then the problem doesn’t
> happen.  That suggests that something is writing to the database and, since
> we can probably assume it’s not some other application, it must be
> Audacity, maybe something in a different thread.
>
>
>
> Are you able to reproduce it Jack?  Do you remember the exact steps?  No
> biggie if you can’t since this is already a great help, but if you can,
> then I’d be interesting to see your “%APPDATA\audacity\lastlog.txt” file
> after you get the error and close Audacity. (the full log is saved at
> Audacity exit.)
>
>
>
> Again, thanks for letting us know.
>
>
>
> > -----Original Message-----
>
> > From: Jack L. <xxj...@gm...>
>
> > Sent: Wednesday, April 7, 2021 3:31 PM
>
> > To: Audacity-Devel list <aud...@li...>
>
> > Subject: Re: [Audacity-devel] Audacity 3.0.1 RC01 (for testing only)
>
> >
>
> > On windows 10, I just opened a mp3, then imported an mp3, then opened an
>
> > mp4 and "Error opening a new empty project". Show Log shows:
>
> >
>
> > 13:28:39: File name is C:\Users\jlow\Desktop\21stpunk1endcredits.mp4
>
> > 13:28:39: Mime type is *
>
> > 13:28:39: Opening with libav
>
> > 13:28:39: Open(C:\Users\jlow\Desktop\21stpunk1endcredits.mp4) succeeded
>
> > 13:28:44: DBConnection SetDBError
>
> >     ErrorCode: 5
>
> >     LastError: Failed to execute a project file command:
>
> >
>
> > SELECT Count(*) FROM sqlite_master WHERE type='table';
>
> >     LibraryError: database is locked
>
> >
>
> >
>
> > Clicked ok and now it says "Connection to project file is null".
>
> >
>
> >
>
> > Seems to work again after closing audacity and reopening.
>
> >
>
> >
>
> > On Wed, Apr 7, 2021 at 11:00 AM James Crook <jam...@gm...>
>
> > wrote:
>
> > >
>
> > >
>
> > > I've just completed making Audacity 3.0.1 Release Candidate 01.
>
> > > It's available for testing from
>
> > > https://www.fosshub.com/Audacity-devel.html
>
> > >
>
> > > This page says what's new; it's mostly fixes to 3.0.0.
>
> > > https://alphamanual.audacityteam.org/man/New_features_in_this_release
>
> > >
>
> > > The other important change is better error reporting, especially the
> 'Show
>
> > Log' button.
>
> > > Please give this build a good test, and if you do get the new extended
> error
>
> > messages we want to know the details of the "Show Log..."
>
> > >
>
> > > --James
>
> > >
>
> > >
>
> > >
>
> > >
>
> > > Checksums:
>
> > > ----------------
>
> > >
>
> > > When you've downloaded an RC you can check the checksums before
>
> > running here:
>
> > > https://md5file.com/calculator
>
> > >
>
> > >
>
> > > audacity-win-3.0.1rc01.exe (application/x-msdownload) - 29491024 bytes
>
> > > SHA-256
>
> > >
>
> > b41b945c919479ad57321da835b31cb63b172b3d4318962eb926ab69a21896c
>
> > 5
>
> > >
>
> > > audacity-macos-3.0.1rc01.dmg (n/a) - 41505451 bytes
>
> > > SHA-256
>
> > >
>
> > ce01b48d7c846503da22b01b01a93eb1ec8d6811c16023b196a555fae514669f
>
> > >
>
> > > audacity-minsrc-3.0.1rc01.tar.xz (n/a) - 13329344 bytes
>
> > > SHA-256
>
> > >
>
> > ea541d874abad6e78a0d721cb5bdc87cc6677bdaa59fbb99b9a2317a90e76e2c
>
> > >
>
> > > audacity-3.0.1rc01.zip (application/x-zip-compressed) - 12587511 bytes
>
> > > SHA-256
>
> > >
>
> > 1955c3e6b029192e7a2c7feec8845777dd783c86da7d018f581034eeaec562a9
>
> > >
>
> > > audacity-manual-3.0.1rc01.zip (application/x-zip-compressed) -
>
> > > 23198502 bytes
>
> > > SHA-256
>
> > > 126b1edf0730f722ad4e58a402ee6d4af741dea38426def289d94af45aaf70ec
>
> > >
>
> > > _______________________________________________
>
> > > audacity-devel mailing list
>
> > > aud...@li...
>
> > > https://lists.sourceforge.net/lists/listinfo/audacity-devel
>
> >
>
> >
>
> > _______________________________________________
>
> > audacity-devel mailing list
>
> > aud...@li...
>
> > https://lists.sourceforge.net/lists/listinfo/audacity-devel
>
> _______________________________________________
> audacity-devel mailing list
> aud...@li...
> https://lists.sourceforge.net/lists/listinfo/audacity-devel
>
> _______________________________________________
> audacity-devel mailing list
> aud...@li...
> https://lists.sourceforge.net/lists/listinfo/audacity-devel
>
> _______________________________________________
> audacity-devel mailing list
> aud...@li...
> https://lists.sourceforge.net/lists/listinfo/audacity-devel
>
> _______________________________________________
> audacity-devel mailing list
> aud...@li...
> https://lists.sourceforge.net/lists/listinfo/audacity-devel
>
>