[Bug]: [LogManager] Invalid log dne #1432

Closed
opened 2026-04-24 23:45:20 +02:00 by adam · 3 comments
Owner

Originally created by @prolife86 on GitHub (Oct 2, 2023).

Describe the issue

since a few weeks I see the notification:
ERROR
[LogManager] Invalid log dne /metadata/logs/daily/2023-09-12.txt
Schermafbeelding 2023-10-02 164934

But the file itself is non-existent on the server:
Schermafbeelding 2023-10-02 165016

How can I resolve the error?

Steps to reproduce the issue

  1. Unknown as the error is there, without a source for the error, as that seems to be the issue?

Audiobookshelf version

2.4.4, but also present in 2.4.2 and 2.4.3

How are you running audiobookshelf?

Docker

Docker LOG:
Config /config /metadata
[2023-10-02 16:43:00] INFO: === Starting Server ===
[2023-10-02 16:43:00] INFO: [Server] Init v2.4.4
[2023-10-02 16:43:00] INFO: [Database] Initializing db at "/config/absdatabase.sqlite"
[2023-10-02 16:43:00] INFO: [Database] Db connection was successful
[2023-10-02 16:43:00] INFO: [Database] Db initialized with models: user, library, libraryFolder, book, podcast, podcastEpisode, libraryItem, mediaProgress, series, bookSeries, author, bookAuthor, collection, collectionBook, playlist, playlistMediaItem, device, playbackSession, feed, feedEpisode, setting
[2023-10-02 16:43:00] DEBUG: Set Log Level to DEBUG (Logger.js:87)
[2023-10-02 16:43:01] DEBUG: [BackupManager] Backup found "2023-09-25T1900" (BackupManager.js:206)
[2023-10-02 16:43:01] DEBUG: [BackupManager] Backup found "2023-09-26T1900" (BackupManager.js:206)
[2023-10-02 16:43:01] DEBUG: [BackupManager] Backup found "2023-09-27T1900" (BackupManager.js:206)
[2023-10-02 16:43:01] DEBUG: [BackupManager] Backup found "2023-09-28T1900" (BackupManager.js:206)
[2023-10-02 16:43:01] DEBUG: [BackupManager] Backup found "2023-09-29T1900" (BackupManager.js:206)
[2023-10-02 16:43:01] DEBUG: [BackupManager] Backup found "2023-09-30T1900" (BackupManager.js:206)
[2023-10-02 16:43:01] DEBUG: [BackupManager] Backup found "2023-10-01T1900" (BackupManager.js:206)
[2023-10-02 16:43:01] INFO: [BackupManager] 7 Backups Found
[2023-10-02 16:43:01] DEBUG: Daily Log file found 2023-09-26.txt (LogManager.js:76)
[2023-10-02 16:43:01] DEBUG: Daily Log file found 2023-09-27.txt (LogManager.js:76)
[2023-10-02 16:43:01] DEBUG: Daily Log file found 2023-09-28.txt (LogManager.js:76)
[2023-10-02 16:43:01] DEBUG: Daily Log file found 2023-09-29.txt (LogManager.js:76)
[2023-10-02 16:43:01] DEBUG: Daily Log file found 2023-09-30.txt (LogManager.js:76)
[2023-10-02 16:43:01] DEBUG: Daily Log file found 2023-10-01.txt (LogManager.js:76)
[2023-10-02 16:43:01] DEBUG: Daily Log file found 2023-10-02.txt (LogManager.js:76)
[2023-10-02 16:43:01] INFO: [LogManager] Init current daily log filename: 2023-10-02.txt
[2023-10-02 16:43:01] DEBUG: [LogManager] Daily log file already exists - set in Logger (LogManager.js:56)
[2023-10-02 16:43:01] DEBUG: [DailyLog] 2023-10-02: Loaded 1360 Logs (DailyLog.js:126)
[2023-10-02 16:43:01] DEBUG: [CronManager] Init library scan cron for PodCasts on schedule 0 21 * * 2 (CronManager.js:39)
[2023-10-02 16:43:01] DEBUG: [CronManager] Found 3 podcast episode schedules to start (CronManager.js:104)
[2023-10-02 16:43:01] DEBUG: [CronManager] Scheduling podcast episode check cron "0 16 * * 1" for 1 item(s) (CronManager.js:112)
[2023-10-02 16:43:01] DEBUG: [CronManager] Scheduling podcast episode check cron "0 15 * * 1" for 1 item(s) (CronManager.js:112)
[2023-10-02 16:43:01] DEBUG: [CronManager] Scheduling podcast episode check cron "0 14 * * 1" for 1 item(s) (CronManager.js:112)
[2023-10-02 16:43:01] INFO: [Server] Watcher is disabled
[2023-10-02 16:43:01] INFO: Listening on port :80
[2023-10-02 16:43:07] INFO: [SocketAuthority] Socket Connected A2n4uQEsvmO_tzenAB
[2023-10-02 16:43:07] DEBUG: [SocketAuthority] User Online ------- (SocketAuthority.js:172)
[2023-10-02 16:43:48] DEBUG: Loaded filterdata in 0.22s (libraryFilters.js:509)
[2023-10-02 16:43:48] INFO: [SocketAuthority] Socket Connected 90r8qllFPEVVZAL-AD
[2023-10-02 16:43:48] DEBUG: [SocketAuthority] User Online root (SocketAuthority.js:172)
[2023-10-02 16:43:48] DEBUG: Loaded 3 personalized shelves in 0.31s (LibraryItem.js:667)
[2023-10-02 16:44:19] DEBUG: [SocketAuthority] User Offline ------- (SocketAuthority.js:116)
[2023-10-02 16:44:19] INFO: [SocketAuthority] Socket A2n4uQEsvmO_tzenAB disconnected from client "-------" after 72327ms (Reason: transport close)

Originally created by @prolife86 on GitHub (Oct 2, 2023). ### Describe the issue since a few weeks I see the notification: ERROR [LogManager] Invalid log dne /metadata/logs/daily/2023-09-12.txt ![Schermafbeelding 2023-10-02 164934](https://github.com/advplyr/audiobookshelf/assets/49482721/db0cee4c-7bd3-41ed-a06b-184385eb22c9) But the file itself is non-existent on the server: ![Schermafbeelding 2023-10-02 165016](https://github.com/advplyr/audiobookshelf/assets/49482721/2ad6eced-57eb-4b4c-a964-b37ed680ce0c) How can I resolve the error? ### Steps to reproduce the issue 1. Unknown as the error is there, without a source for the error, as that seems to be the issue? ### Audiobookshelf version 2.4.4, but also present in 2.4.2 and 2.4.3 ### How are you running audiobookshelf? Docker Docker LOG: Config /config /metadata [2023-10-02 16:43:00] INFO: === Starting Server === [2023-10-02 16:43:00] INFO: [Server] Init v2.4.4 [2023-10-02 16:43:00] INFO: [Database] Initializing db at "/config/absdatabase.sqlite" [2023-10-02 16:43:00] INFO: [Database] Db connection was successful [2023-10-02 16:43:00] INFO: [Database] Db initialized with models: user, library, libraryFolder, book, podcast, podcastEpisode, libraryItem, mediaProgress, series, bookSeries, author, bookAuthor, collection, collectionBook, playlist, playlistMediaItem, device, playbackSession, feed, feedEpisode, setting [2023-10-02 16:43:00] DEBUG: Set Log Level to DEBUG (Logger.js:87) [2023-10-02 16:43:01] DEBUG: [BackupManager] Backup found "2023-09-25T1900" (BackupManager.js:206) [2023-10-02 16:43:01] DEBUG: [BackupManager] Backup found "2023-09-26T1900" (BackupManager.js:206) [2023-10-02 16:43:01] DEBUG: [BackupManager] Backup found "2023-09-27T1900" (BackupManager.js:206) [2023-10-02 16:43:01] DEBUG: [BackupManager] Backup found "2023-09-28T1900" (BackupManager.js:206) [2023-10-02 16:43:01] DEBUG: [BackupManager] Backup found "2023-09-29T1900" (BackupManager.js:206) [2023-10-02 16:43:01] DEBUG: [BackupManager] Backup found "2023-09-30T1900" (BackupManager.js:206) [2023-10-02 16:43:01] DEBUG: [BackupManager] Backup found "2023-10-01T1900" (BackupManager.js:206) [2023-10-02 16:43:01] INFO: [BackupManager] 7 Backups Found [2023-10-02 16:43:01] DEBUG: Daily Log file found 2023-09-26.txt (LogManager.js:76) [2023-10-02 16:43:01] DEBUG: Daily Log file found 2023-09-27.txt (LogManager.js:76) [2023-10-02 16:43:01] DEBUG: Daily Log file found 2023-09-28.txt (LogManager.js:76) [2023-10-02 16:43:01] DEBUG: Daily Log file found 2023-09-29.txt (LogManager.js:76) [2023-10-02 16:43:01] DEBUG: Daily Log file found 2023-09-30.txt (LogManager.js:76) [2023-10-02 16:43:01] DEBUG: Daily Log file found 2023-10-01.txt (LogManager.js:76) [2023-10-02 16:43:01] DEBUG: Daily Log file found 2023-10-02.txt (LogManager.js:76) [2023-10-02 16:43:01] INFO: [LogManager] Init current daily log filename: 2023-10-02.txt [2023-10-02 16:43:01] DEBUG: [LogManager] Daily log file already exists - set in Logger (LogManager.js:56) [2023-10-02 16:43:01] DEBUG: [DailyLog] 2023-10-02: Loaded 1360 Logs (DailyLog.js:126) [2023-10-02 16:43:01] DEBUG: [CronManager] Init library scan cron for PodCasts on schedule 0 21 * * 2 (CronManager.js:39) [2023-10-02 16:43:01] DEBUG: [CronManager] Found 3 podcast episode schedules to start (CronManager.js:104) [2023-10-02 16:43:01] DEBUG: [CronManager] Scheduling podcast episode check cron "0 16 * * 1" for 1 item(s) (CronManager.js:112) [2023-10-02 16:43:01] DEBUG: [CronManager] Scheduling podcast episode check cron "0 15 * * 1" for 1 item(s) (CronManager.js:112) [2023-10-02 16:43:01] DEBUG: [CronManager] Scheduling podcast episode check cron "0 14 * * 1" for 1 item(s) (CronManager.js:112) [2023-10-02 16:43:01] INFO: [Server] Watcher is disabled [2023-10-02 16:43:01] INFO: Listening on port :80 [2023-10-02 16:43:07] INFO: [SocketAuthority] Socket Connected A2n4uQEsvmO_tzenAB [2023-10-02 16:43:07] DEBUG: [SocketAuthority] User Online ------- (SocketAuthority.js:172) [2023-10-02 16:43:48] DEBUG: Loaded filterdata in 0.22s (libraryFilters.js:509) [2023-10-02 16:43:48] INFO: [SocketAuthority] Socket Connected 90r8qllFPEVVZAL-AD [2023-10-02 16:43:48] DEBUG: [SocketAuthority] User Online root (SocketAuthority.js:172) [2023-10-02 16:43:48] DEBUG: Loaded 3 personalized shelves in 0.31s (LibraryItem.js:667) [2023-10-02 16:44:19] DEBUG: [SocketAuthority] User Offline ------- (SocketAuthority.js:116) [2023-10-02 16:44:19] INFO: [SocketAuthority] Socket A2n4uQEsvmO_tzenAB disconnected from client "-------" after 72327ms (Reason: transport close)
adam added the bug label 2026-04-24 23:45:20 +02:00
adam closed this issue 2026-04-24 23:45:20 +02:00
Author
Owner

@advplyr commented on GitHub (Oct 3, 2023):

I'm not sure how this is happening. In the full log you shared I'm not seeing that error either. How often are you seeing this? Can you replicate it?

@advplyr commented on GitHub (Oct 3, 2023): I'm not sure how this is happening. In the full log you shared I'm not seeing that error either. How often are you seeing this? Can you replicate it?
Author
Owner

@prolife86 commented on GitHub (Oct 3, 2023):

I'm not sure how this is happening. In the full log you shared I'm not seeing that error either. How often are you seeing this? Can you replicate it?

It is always there after a reboot of the container.

@prolife86 commented on GitHub (Oct 3, 2023): > I'm not sure how this is happening. In the full log you shared I'm not seeing that error either. How often are you seeing this? Can you replicate it? It is always there after a reboot of the container.
Author
Owner

@prolife86 commented on GitHub (Oct 3, 2023):

Today I removed the container and installed it again in Unraid, now the error is gone.
I will report if it turns up again.

@prolife86 commented on GitHub (Oct 3, 2023): Today I removed the container and installed it again in Unraid, now the error is gone. I will report if it turns up again.
Sign in to join this conversation.
1 Participants
Notifications
Due Date
No due date set.
Dependencies

No dependencies set.

Reference: starred/audiobookshelf#1432