Since I added a storage volume to my infrastructure, I've been getting this backup error message very regularly

[girish]

@Dont-Worry that error is not volume related. This is an error returned by your backup provider. Multi-part upload is failing for some reason. If it fails consistently, then we have to investigate if it's bug in the backup service or bug in the cloudron backup code. Which provider are you using? Does it fail all the time?

Also, you will see a more detailed error in the backup logs. Can you check the end of the log file?

[Dont-Worry]

Hello, thank you for your quick answer as always. Currently in my logs there is no error message but I will try to trigger a backup that should also trigger the error in the logs and from that moment I will put it in this message. This morning when I opened the logs, what I realized is that every 5 minutes I have this that appears in my logs concerning the backup :

May 24 06:45:00 box:locker Lock unreleased full_backup

Regarding my other questions, it seems to fail very often. Does it seem to fail all the time? I have the impression that yes, because it's every day at the same time and I have the impression that it's really every day. So I have the impression that yes, there is no backup anymore. And I don't know if it's the reality or not, but in any case, what I see is that every time I go to the backup hours, every time I go to the backup page of my server, it's always on the same application that it's bugging, it's always on Emby. So it's also simply because there is a lot of data on Emby and so when there is a backup, you have to save more than 300 GB of data. But it still happens very often at the time of Emby, of backup Emby.

I am using AWS for mu backups and never had problems with it before the transfer of the data of certain applications on the new volume.

Here is the only abnormal message from the logs when the backup failed :

May 24 08:30:00 box:shell disks execArgs: df ["-B1","--output=source,fstype,size,used,avail,pcent,target"]
May 24 08:30:00 box:system checkDiskSpace: checking disk space
May 24 08:30:00 box:eventlog cleanup: pruning events. creationTime: Sat Feb 24 2024 06:30:00 GMT+0000 (Coordinated Universal Time)
May 24 08:30:00 box:janitor Cleaning up expired tokens
May 24 08:30:00 box:shell file execArgs: df ["-B1","--output=source,fstype,size,used,avail,pcent,target","/home/yellowtent/platformdata"]
May 24 08:30:00 box:janitor Cleaned up 0 expired tokens
May 24 08:30:00 box:shell file execArgs: df ["-B1","--output=source,fstype,size,used,avail,pcent,target","/home/yellowtent/boxdata/box"]
May 24 08:30:00 box:shell file execArgs: df ["-B1","--output=source,fstype,size,used,avail,pcent,target","/home/yellowtent/boxdata/mail"]
May 24 08:30:00 box:shell file execArgs: df ["-B1","--output=source,fstype,size,used,avail,pcent,target","/var/backups"]
May 24 08:30:00 box:shell file execArgs: df ["-B1","--output=source,fstype,size,used,avail,pcent,target","/var/lib/docker"]
May 24 08:30:00 box:shell file execArgs: df ["-B1","--output=source,fstype,size,used,avail,pcent,target","/mnt/volumes/6e6d77d1ac41447da93a95677f20c922"]
May 24 08:30:00 box:shell file execArgs: df ["-B1","--output=source,fstype,size,used,avail,pcent,target","/home/yellowtent/appsdata/01791754-76d6-4905-a91c-0d0c4c9fef52/data"]
May 24 08:30:00 box:shell file execArgs: df ["-B1","--output=source,fstype,size,used,avail,pcent,target","/home/yellowtent/appsdata/099b00c5-4f03-4bc4-ab94-a77e88bd8886/data"]
May 24 08:30:00 box:shell file execArgs: df ["-B1","--output=source,fstype,size,used,avail,pcent,target","/home/yellowtent/appsdata/0a34d9c0-b5da-43f4-a7a7-7716d29aec1b/data"]
May 24 08:30:00 box:shell file execArgs: df ["-B1","--output=source,fstype,size,used,avail,pcent,target","/home/yellowtent/appsdata/0d38ed29-3da5-4274-91e7-ded0697e07cb/data"]
May 24 08:30:00 box:shell file execArgs: df ["-B1","--output=source,fstype,size,used,avail,pcent,target","/home/yellowtent/appsdata/15c17f52-3b4c-4b72-9660-62a693083ac2/data"]
May 24 08:30:00 box:shell file execArgs: df ["-B1","--output=source,fstype,size,used,avail,pcent,target","/home/yellowtent/appsdata/1884b6cf-1589-4132-9974-a69d29659b7d/data"]
May 24 08:30:00 box:shell file execArgs: df ["-B1","--output=source,fstype,size,used,avail,pcent,target","/home/yellowtent/appsdata/20f7321a-d243-4c79-9b6b-4e715effd442/data"]
May 24 08:30:00 box:shell file execArgs: df ["-B1","--output=source,fstype,size,used,avail,pcent,target","/home/yellowtent/appsdata/2252ffc3-333e-460b-bec3-4fbd6b5fb80a/data"]
May 24 08:30:00 box:shell file execArgs: df ["-B1","--output=source,fstype,size,used,avail,pcent,target","/home/yellowtent/appsdata/268432b4-db5f-4b70-aa95-441da4c7a5eb/data"]
May 24 08:30:00 box:shell file execArgs: df ["-B1","--output=source,fstype,size,used,avail,pcent,target","/mnt/volumes/6e6d77d1ac41447da93a95677f20c922/Drive"]
May 24 08:30:00 box:shell file execArgs: df ["-B1","--output=source,fstype,size,used,avail,pcent,target","/home/yellowtent/appsdata/38e4a54f-6083-47fa-ab0f-7f2ddde4dfda/data"]
May 24 08:30:00 box:shell file execArgs: df ["-B1","--output=source,fstype,size,used,avail,pcent,target","/home/yellowtent/appsdata/4301ac69-18c5-4c85-8500-ef78a0e1be1d/data"]
May 24 08:30:00 box:shell file execArgs: df ["-B1","--output=source,fstype,size,used,avail,pcent,target","/home/yellowtent/appsdata/457e262f-ce24-4fd3-bca3-f7d851a208e1/data"]
May 24 08:30:00 box:shell file execArgs: df ["-B1","--output=source,fstype,size,used,avail,pcent,target","/home/yellowtent/appsdata/48e7c8d9-1e42-4dce-be6b-35bcddffbb92/data"]
May 24 08:30:00 box:shell file execArgs: df ["-B1","--output=source,fstype,size,used,avail,pcent,target","/home/yellowtent/appsdata/50d053bd-2337-45bc-bb8c-944e2f1dda70/data"]
May 24 08:30:00 box:shell file execArgs: df ["-B1","--output=source,fstype,size,used,avail,pcent,target","/home/yellowtent/appsdata/63cf7c11-05e2-4353-ab76-42fd8c995a49/data"]
May 24 08:30:00 box:shell file execArgs: df ["-B1","--output=source,fstype,size,used,avail,pcent,target","/home/yellowtent/appsdata/64c46b4e-1079-4145-b34d-6b56a16d5f57/data"]
May 24 08:30:00 box:shell file execArgs: df ["-B1","--output=source,fstype,size,used,avail,pcent,target","/home/yellowtent/appsdata/6926e060-0503-428b-a329-9877f2c2f26d/data"]
May 24 08:30:00 box:shell file execArgs: df ["-B1","--output=source,fstype,size,used,avail,pcent,target","/home/yellowtent/appsdata/6f73c336-d6a8-41e6-8491-d5572a6dc594/data"]
May 24 08:30:00 box:shell file execArgs: df ["-B1","--output=source,fstype,size,used,avail,pcent,target","/mnt/volumes/6e6d77d1ac41447da93a95677f20c922/Emby"]
May 24 08:30:00 box:shell file execArgs: df ["-B1","--output=source,fstype,size,used,avail,pcent,target","/home/yellowtent/appsdata/7b74d39a-9abe-49a4-bc66-a282063891c4/data"]
May 24 08:30:00 box:shell file execArgs: df ["-B1","--output=source,fstype,size,used,avail,pcent,target","/home/yellowtent/appsdata/7d46be1a-7c85-434b-802d-ab9055d061ff/data"]
May 24 08:30:00 box:shell file execArgs: df ["-B1","--output=source,fstype,size,used,avail,pcent,target","/home/yellowtent/appsdata/81f280de-caee-41c9-9e2f-b51af8ecf126/data"]
May 24 08:30:00 box:shell file execArgs: df ["-B1","--output=source,fstype,size,used,avail,pcent,target","/home/yellowtent/appsdata/84de5fd1-227f-4b11-b2d2-7f8b6afa9e76/data"]
May 24 08:30:00 box:shell file execArgs: df ["-B1","--output=source,fstype,size,used,avail,pcent,target","/mnt/volumes/6e6d77d1ac41447da93a95677f20c922/LanguageTool"]
May 24 08:30:00 box:shell file execArgs: df ["-B1","--output=source,fstype,size,used,avail,pcent,target","/mnt/volumes/6e6d77d1ac41447da93a95677f20c922/IA"]
May 24 08:30:00 box:shell file execArgs: df ["-B1","--output=source,fstype,size,used,avail,pcent,target","/home/yellowtent/appsdata/89c768f6-7e77-4f7e-a09e-8f38f4df0552/data"]
May 24 08:30:00 box:shell file execArgs: df ["-B1","--output=source,fstype,size,used,avail,pcent,target","/home/yellowtent/appsdata/a0786ced-9a4a-489a-b8d3-9aeb4332c176/data"]
May 24 08:30:00 box:shell file execArgs: df ["-B1","--output=source,fstype,size,used,avail,pcent,target","/home/yellowtent/appsdata/a3fd5fa1-5d3c-4289-822e-fe9de7ce2631/data"]
May 24 08:30:00 box:shell file execArgs: df ["-B1","--output=source,fstype,size,used,avail,pcent,target","/home/yellowtent/appsdata/ab6251c1-bfdd-4456-9435-fd8c3ecf5bf4/data"]
May 24 08:30:00 box:shell file execArgs: df ["-B1","--output=source,fstype,size,used,avail,pcent,target","/home/yellowtent/appsdata/aba64b2b-70aa-4a65-9f26-88eb231695a5/data"]
May 24 08:30:00 box:shell file execArgs: df ["-B1","--output=source,fstype,size,used,avail,pcent,target","/home/yellowtent/appsdata/ae3fe2ba-00f1-4e29-8c59-4f1b8b9ad197/data"]
May 24 08:30:00 box:shell file execArgs: df ["-B1","--output=source,fstype,size,used,avail,pcent,target","/home/yellowtent/appsdata/cc9b35bb-b16b-41c5-8a5d-e29577d093b3/data"]
May 24 08:30:00 box:shell file execArgs: df ["-B1","--output=source,fstype,size,used,avail,pcent,target","/home/yellowtent/appsdata/d2d6ce57-6782-48fa-ba9b-ff6c12458cb2/data"]
May 24 08:30:00 box:shell file execArgs: df ["-B1","--output=source,fstype,size,used,avail,pcent,target","/mnt/volumes/6e6d77d1ac41447da93a95677f20c922/Joplin"]
May 24 08:30:00 box:shell file execArgs: df ["-B1","--output=source,fstype,size,used,avail,pcent,target","/home/yellowtent/appsdata/e18a336b-b88c-43da-96eb-c68e265b6a97/data"]
May 24 08:30:00 box:shell file execArgs: df ["-B1","--output=source,fstype,size,used,avail,pcent,target","/home/yellowtent/appsdata/e8497d15-8bab-4abe-8265-bd39074f0f93/data"]
May 24 08:30:00 box:shell file execArgs: df ["-B1","--output=source,fstype,size,used,avail,pcent,target","/mnt/volumes/6e6d77d1ac41447da93a95677f20c922/Matrix"]
May 24 08:30:00 box:shell file execArgs: df ["-B1","--output=source,fstype,size,used,avail,pcent,target","/home/yellowtent/appsdata/ec4ab20c-ffe1-4e62-8cac-3ab2a2ae40fd/data"]
May 24 08:30:00 box:shell file execArgs: df ["-B1","--output=source,fstype,size,used,avail,pcent,target","/home/yellowtent/appsdata/ec65758f-9f71-47f3-8c7e-18ec52107ca5/data"]
May 24 08:30:00 box:shell file execArgs: df ["-B1","--output=source,fstype,size,used,avail,pcent,target","/home/yellowtent/appsdata/ee0e59a7-619f-4a4c-a714-a0573a7529b1/data"]
May 24 08:30:00 box:shell file execArgs: df ["-B1","--output=source,fstype,size,used,avail,pcent,target","/home/yellowtent/appsdata/ee7de913-5fe5-4281-a9ef-20d21b94e553/data"]
May 24 08:30:00 box:shell getSwaps execArgs: swapon ["--noheadings","--raw","--bytes","--show=type,size,used,name"]
May 24 08:30:00 box:shell file execArgs: df ["-B1","--output=source,fstype,size,used,avail,pcent,target","/apps.swap"]
May 24 08:30:00 box:system checkDiskSpace: disk space checked. out of space: no
May 24 08:30:00 box:apphealthmonitor app health: 31 running / 11 stopped / 0 unresponsive
May 24 08:30:08 box:shell tail /usr/bin/sudo -S /home/yellowtent/box/src/scripts/logtail.sh --lines=100 --follow /home/yellowtent/platformdata/logs/box.log

[Dont-Worry]

Hello,

I'm coming back to you because this problem is still unresolved. I haven't been able to find a solution to get the backups working again.

And I can now confirm that it is indeed all the backups that are not working. Every day the backup fails.

[Dont-Worry]

[no timestamp]  at ChildProcess.<anonymous> (/home/yellowtent/box/src/shell.js:110:19)
[no timestamp]  at ChildProcess._handle.onexit (node:internal/child_process:291:12) {
[no timestamp]  at ChildProcess.emit (node:domain:489:12)
[no timestamp]  at ChildProcess.emit (node:events:513:28)
[no timestamp]  at async backupAppWithTag (/home/yellowtent/box/src/backuptask.js:382:5)
[no timestamp]  at async fullBackup (/home/yellowtent/box/src/backuptask.js:503:29)
[no timestamp]  at async uploadAppSnapshot (/home/yellowtent/box/src/backuptask.js:360:5)
[no timestamp]  at process.processTicksAndRejections (node:internal/process/task_queues:95:5)
[no timestamp]  at runBackupUpload (/home/yellowtent/box/src/backuptask.js:165:15)
[no timestamp]  code: 50,
[no timestamp]  details: {},
[no timestamp]  reason: 'Shell Error',
[no timestamp]  signal: null
May 25 07:32:09 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 67499M@41MBps (emby.example.com)"}
May 25 07:32:19 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 67910M@41MBps (emby.example.com)"}
May 25 07:32:29 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 68326M@42MBps (emby.example.com)"}
May 25 07:32:39 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 68735M@41MBps (emby..com)"}
May 25 07:32:49 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 69152M@42MBps (emby.example.com)"}
May 25 07:32:59 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 69562M@41MBps (emby.example.com)"}
May 25 07:33:09 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 69970M@41MBps (emby.example.com)"}
May 25 07:33:19 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 70385M@42MBps (emby.example.com)"}
May 25 07:33:29 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 70795M@41MBps (emby.example.com)"}
May 25 07:33:39 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 71207M@41MBps (emby.example.com)"}
May 25 07:33:49 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 71626M@42MBps (emby.example.com)"}
May 25 07:33:59 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 72039M@41MBps (emby.example.com)"}
May 25 07:34:09 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 72453M@41MBps (emby.example.com)"}
May 25 07:34:19 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 72867M@41MBps (emby.example.com)"}
May 25 07:34:29 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 73281M@41MBps (emby.example.com)"}
May 25 07:34:39 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 73696M@42MBps (emby.example.com)"}
May 25 07:34:49 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 74095M@40MBps (emby.example.com)"}
May 25 07:34:59 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 74510M@41MBps (emby.example.com)"}
May 25 07:35:09 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 74917M@41MBps (emby.example.com)"}
May 25 07:35:19 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 75321M@40MBps (emby.example.com)"}
May 25 07:35:29 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 75728M@41MBps (emby.example.com)"}
May 25 07:35:39 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 76141M@41MBps (emby.example.com)"}
May 25 07:35:49 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 76552M@41MBps (emby.example.com)"}
May 25 07:35:59 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 76950M@40MBps (emby.example.com)"}
May 25 07:36:09 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 77361M@41MBps (emby.example.com)"}
May 25 07:36:19 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 77777M@42MBps (emby.example.com)"}
May 25 07:36:29 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 78189M@41MBps (emby.example.com)"}
May 25 07:36:39 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 78611M@42MBps (emby.example.com)"}
May 25 07:36:49 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 79020M@41MBps (emby.example.com)"}
May 25 07:36:59 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 79430M@41MBps (emby.example.com)"}
May 25 07:37:09 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 79840M@41MBps (emby.example.com)"}
May 25 07:37:19 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 80250M@41MBps (emby.example.com)"}
May 25 07:37:29 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 80660M@41MBps (emby.example.com)"}
May 25 07:37:39 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 81070M@41MBps (emby.example.com)"}
May 25 07:37:49 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 81471M@40MBps (emby.example.com)"}
May 25 07:37:59 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 81879M@41MBps (emby.example.com)"}
May 25 07:38:09 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 82293M@41MBps (emby.example.com)"}
May 25 07:38:19 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 82698M@40MBps (emby.example.com)"}
May 25 07:38:29 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 83112M@41MBps (emby.example.com)"}
May 25 07:38:39 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 83527M@41MBps (emby.example.com)"}
May 25 07:38:49 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 83940M@41MBps (emby.example.com)"}
May 25 07:38:59 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 84353M@41MBps (emby.example.com)"}
May 25 07:39:09 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 84763M@41MBps (emby.example.com)"}
May 25 07:39:19 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 85180M@42MBps (emby.example.com)"}
May 25 07:39:29 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 85585M@41MBps (emby.example.com)"}
May 25 07:39:39 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 86005M@42MBps (emby.example.com)"}
May 25 07:39:49 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 86416M@41MBps (emby.example.com)"}
May 25 07:39:59 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 86833M@42MBps (emby.example.com)"}
May 25 07:40:09 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 87242M@41MBps (emby.example.com)"}
May 25 07:40:19 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 87662M@42MBps (emby.example.com)"}
May 25 07:40:29 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 88056M@39MBps (emby.example.com)"}
May 25 07:40:39 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 88466M@41MBps (emby.example.com)"}
May 25 07:40:49 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 88876M@41MBps (emby.example.com)"}
May 25 07:40:59 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 89289M@41MBps (emby.example.com)"}
May 25 07:41:09 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 89692M@40MBps (emby.example.com)"}
May 25 07:41:19 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 90101M@41MBps (emby.example.com)"}
May 25 07:41:29 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 90518M@42MBps (emby.example.com)"}
May 25 07:41:39 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 90931M@41MBps (emby.example.com)"}
May 25 07:41:49 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 91341M@41MBps (emby.example.com)"}
May 25 07:41:59 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 91749M@41MBps (emby.example.com)"}
May 25 07:42:09 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 92143M@39MBps (emby.example.com)"}
May 25 07:42:19 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 92556M@41MBps (emby.example.com)"}
May 25 07:42:29 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 92968M@41MBps (emby.example.com)"}
May 25 07:42:39 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 93382M@41MBps (emby.example.com)"}
May 25 07:42:49 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 93792M@41MBps (emby.example.com)"}
May 25 07:42:59 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 94198M@41MBps (emby.example.com)"}
May 25 07:43:09 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 94617M@42MBps (emby.example.com)"}
May 25 07:43:19 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 95029M@41MBps (emby.example.com)"}
May 25 07:43:29 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 95434M@41MBps (emby.example.com)"}
May 25 07:43:39 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 95846M@41MBps (emby.example.com)"}
May 25 07:43:49 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 96256M@41MBps (emby.example.com)"}
May 25 07:43:59 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 96658M@40MBps (emby.example.com)"}
May 25 07:44:09 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 97074M@42MBps (emby.example.com)"}
May 25 07:44:19 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 97483M@41MBps (emby.example.com)"}
May 25 07:44:29 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 97892M@41MBps (emby.example.com)"}
May 25 07:44:39 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 98308M@42MBps (emby.example.com)"}
May 25 07:44:49 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 98711M@40MBps (emby.example.com)"}
May 25 07:44:59 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 99131M@42MBps (emby.example.com)"}
May 25 07:45:09 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 99530M@40MBps (emby.example.com)"}
May 25 07:45:19 box:tasks update 2168: {"percent":45.444444444444436,"message":"Uploading backup 99927M@40MBps (emby.example.com)"}
May 25 07:45:21 box:backuptask runBackupUpload: result - {"result":"Error uploading snapshot/app_78974c13-af83-4056-b7ff-218dd7fcb471.tar.gz.enc. Message: Part number must be an integer between 1 and 10000, inclusive HTTP Code: InvalidArgument"}
May 25 07:45:21 box:shell backup-snapshot/app_78974c13-af83-4056-b7ff-218dd7fcb471: /usr/bin/sudo -S -E --close-from=4 /home/yellowtent/box/src/scripts/backupupload.js snapshot/app_78974c13-af83-4056-b7ff-218dd7fcb471 tgz {"localRoot":"/home/yellowtent/appsdata/78974c13-af83-4056-b7ff-218dd7fcb471","layout":[{"localDir":"/mnt/volumes/6e6d77d1ac41447da93a95677f20c922/Emby","remoteDir":"data"}]} errored BoxError: backup-snapshot/app_78974c13-af83-4056-b7ff-218dd7fcb471 exited with code 50 signal null
May 25 07:45:21 box:tasks setCompleted - 2168: {"result":null,"error":{"stack":"BoxError: Error uploading snapshot/app_78974c13-af83-4056-b7ff-218dd7fcb471.tar.gz.enc. Message: Part number must be an integer between 1 and 10000, inclusive HTTP Code: InvalidArgument\n at runBackupUpload (/home/yellowtent/box/src/backuptask.js:165:15)\n at process.processTicksAndRejections (node:internal/process/task_queues:95:5)\n at async uploadAppSnapshot (/home/yellowtent/box/src/backuptask.js:360:5)\n at async backupAppWithTag (/home/yellowtent/box/src/backuptask.js:382:5)\n at async fullBackup (/home/yellowtent/box/src/backuptask.js:503:29)","name":"BoxError","reason":"External Error","details":{},"message":"Error uploading snapshot/app_78974c13-af83-4056-b7ff-218dd7fcb471.tar.gz.enc. Message: Part number must be an integer between 1 and 10000, inclusive HTTP Code: InvalidArgument"}}
May 25 07:45:21 box:tasks update 2168: {"percent":100,"result":null,"error":{"stack":"BoxError: Error uploading snapshot/app_78974c13-af83-4056-b7ff-218dd7fcb471.tar.gz.enc. Message: Part number must be an integer between 1 and 10000, inclusive HTTP Code: InvalidArgument\n at runBackupUpload (/home/yellowtent/box/src/backuptask.js:165:15)\n at process.processTicksAndRejections (node:internal/process/task_queues:95:5)\n at async uploadAppSnapshot (/home/yellowtent/box/src/backuptask.js:360:5)\n at async backupAppWithTag (/home/yellowtent/box/src/backuptask.js:382:5)\n at async fullBackup (/home/yellowtent/box/src/backuptask.js:503:29)","name":"BoxError","reason":"External Error","details":{},"message":"Error uploading snapshot/app_78974c13-af83-4056-b7ff-218dd7fcb471.tar.gz.enc. Message: Part number must be an integer between 1 and 10000, inclusive HTTP Code: InvalidArgument"}}
May 25 07:45:21 box:taskworker Task took 13520.94 seconds
[no timestamp]  Error uploading snapshot/app_78974c13-af83-4056-b7ff-218dd7fcb471.tar.gz.enc. Message: Part number must be an integer between 1 and 10000, inclusive HTTP Code: InvalidArgument
[no timestamp]  }

[necrevistonnezr]

Please use the code tag for the log messages - they’re unreadable without.

[Dont-Worry]

@necrevistonnezr oh, my bad, i'll correct it rn. Thanks

[necrevistonnezr]

I understand correctly: You’re trying a 100+ GB backup via an encrypted tar.gz archive of an Emby instance (which already contains highly compressed movie files, probably, that cannot be compressed further)?

[Dont-Worry]

I have something like 200gb of Emby data (and it's growing every day) you're right about that. On top we have a lot of music and a lot of youtube videos. Some files are in MP3 but the majority are in .WAV and for videos we're on .MP4.

Do you have any idea what I should do to be able to restore the backups system?

[humptydumpty]

I’m also getting backup failed errors much more often lately. No volumes attached though. Backing up to backblaze. Latest fails happened two nights in a row on 5/23 and 5/24, worked fine on 5/25.

2024-05-23T05:06:29.143Z box:shell backup-snapshot/app_56523b4e-70f6-4323-98b9-559b87e74173: /usr/bin/sudo -S -E --close-from=4 /home/yellowtent/box/src/scripts/backupupload.js snapshot/app_56523b4e-70f6-4323-98b9-559b87e74173 tgz {"localRoot":"/home/yellowtent/appsdata/56523b4e-70f6-4323-98b9-559b87e74173","layout":[]} errored BoxError: backup-snapshot/app_56523b4e-70f6-4323-98b9-559b87e74173 exited with code 1 signal null
2024-05-23T05:06:29.148Z box:tasks setCompleted - 12664: {"result":null,"error":{"stack":"BoxError: Backuptask crashed\n    at runBackupUpload (/home/yellowtent/box/src/backuptask.js:163:15)\n    at process.processTicksAndRejections (node:internal/process/task_queues:95:5)\n    at async uploadAppSnapshot (/home/yellowtent/box/src/backuptask.js:360:5)\n    at async backupAppWithTag (/home/yellowtent/box/src/backuptask.js:382:5)\n    at async fullBackup (/home/yellowtent/box/src/backuptask.js:503:29)","name":"BoxError","reason":"Internal Error","details":{},"message":"Backuptask crashed"}}
2024-05-23T05:06:29.148Z box:taskworker Task took 381.54 seconds
2024-05-23T05:06:29.149Z box:tasks update 12664: {"percent":100,"result":null,"error":{"stack":"BoxError: Backuptask crashed\n    at runBackupUpload (/home/yellowtent/box/src/backuptask.js:163:15)\n    at process.processTicksAndRejections (node:internal/process/task_queues:95:5)\n    at async uploadAppSnapshot (/home/yellowtent/box/src/backuptask.js:360:5)\n    at async backupAppWithTag (/home/yellowtent/box/src/backuptask.js:382:5)\n    at async fullBackup (/home/yellowtent/box/src/backuptask.js:503:29)","name":"BoxError","reason":"Internal Error","details":{},"message":"Backuptask crashed"}}
BoxError: Backuptask crashed

@girish please see last section of the logs above in case there is indeed a bug in the code. However, I believe my issue is network related and differs from yours @Dont-Worry

[girish]

@Dont-Worry It's failing pretty much at 100GB. It seems that the provider may not support > 100GB files. Which backup provider are you using (Is it OVH?) ?

[necrevistonnezr]

@Dont-Worry said in Since I added a storage volume to my infrastructure, I've been getting this backup error message very regularly:

I have something like 200gb of Emby data (and it's growing every day) you're right about that. On top we have a lot of music and a lot of youtube videos. Some files are in MP3 but the majority are in .WAV and for videos we're on .MP4.

Using the encrypted tar backend for this mass of - already compressed - data is asking for trouble, IMHO. As girish pointed out, you end up with a single file of huge proportions, from the docs:

"The tgz format stores all the backup information in a single tarball whereas the rsync format stores all backup information as files inside a directory."

I could be wrong but many B2C providers will probably have difficulties supporting a 200 GB + file?!

[Dont-Worry]

Thank you for your answer @necrevistonnezr
@girish To answer your question, I am using AWS for backups. I tried to use OVH when the problem started but idk why i cant connect OVH Object Storage with my cloudron.

@necrevistonnezr When you say "Using the encrypted tar backend for this mass of - already compressed - data is asking for trouble, IMHO.". I don't think 200gb is a huuuge mass of data (especially for AWS that is not a B2C focused provider). For our Company this is a little bit concerning if we cannot backup anything above 100gb.... Because we use cloudron for Entertainment (Emby), but also for business purposes (Files storages, apps ...) and we planned to use cloudron for long term purposes and forcasted more than 5 to 10 TB of data in a year. There is any way to Backup large amount of data w/cloudron in an automated way, as the system usually worked ?

[necrevistonnezr]

Use the rsync backend?

[Dont-Worry]

The problem has been solved. I have increased the size of the packages which are sent to the S3 and the whole was less than 10,000 packages so it was getting in. I read the documentation and really been over all my Cloudron panel many times, but I really didn't think about reviewing the advanced configurations of my backup, that is the thing I do not often change.

This solution works in the short term, but according to my calculations, once it reaches 10Tb, the maximum cut proposed by Cloudron, namely the cut by 1gb per package, will not be enough. Would it be possible to increase the maximum in the Cloudron application for people who use and consider Cloudron as a long-term mid-scale solution ?

This would not change anything if it was adjustable, but for people like us, who will have to deploy Cloudron on a powerful infrastructure, the double could be supported without any problem (2gb/Package).

[girish]

@Dont-Worry Thanks for the follow-up. Can you tell me which region of OVH Object Storage you are using?

[Dont-Worry]

@girish I am using Graveline for OVH Object Storage. First I choosed Roubaix, but it wasn't in the Cloudron list so I created a new bucket in Graveline. But it never worked. Now it is still working on the old AWS S3 bucket.
Didn't found any way to make the backup to an OVH bucket work.

[humptydumpty]

@girish Can you please expand the doc page to cover what the "advanced settings" in the backups section offers and how they can be best used/optimized for a few use-cases like (general-use, heavy media use, etc.). Thank you!

[girish]

@Dont-Worry thanks, I have added RBX to the region list.

[girish]

@humptydumpty I think the issue is that there are too many providers, each with their own region. Back in the day, we hardcoded this and the advanced setting was not even exposed to the user because we thought it's impossible for the user to guess correct values. We ended up exposing the values because we couldn't figure a way to guess values . Even now, I have no concrete values to suggest for each provider, they keep changing (since upstream providers are also deploying updates) and each network/app/cpu is different. It's a bit of trial and error . But I will discuss internally and add something to the docs to help users.

[Dont-Worry]

@girish Thank you very much !