2023-10-18 Backup woes

I recently did some misguided something resulting in recursive hardlinked directories … with very, very long path names being the end result of that. Today I want to backup my disk.

Borg Backup (I have 1.1.9) complains indirectly about those long filenames. A bunch of stat calls fail with "Too long file name". I run "borg check" on the archive and it fails with a KeyError. I try to create two more backups, but it keeps running into the long file name issue, resulting in an error for the run, even after I deleted the offending directory in `.local/share/Trash/files`. I guess the long file name is part of one of the archives.

I'm not feeling too good anymore.

I'm going to delete the archives for today and see whether that fixes things.

First, the list of archives on the external disk:

$ sudo borg list /mnt/backup/borg-backups/backup.borg/
[sudo] password for alex: 
Enter passphrase for key /mnt/backup/borg-backups/backup.borg: 
2017-12-29T16:33:27-melanobombus     Fri, 2017-12-29 17:33:35 [62db7fa8b9478dfaff74a3af9401b0c2f78c4317c295d38fbcad31f62c2e29c7]
2018-02-08T06:28:42-melanobombus     Thu, 2018-02-08 07:28:43 [693328a0bb9ace8880ee103786702edd46f79e490d1bae2b99a445b69c79e329]
2021-05-08T10:45:37-melanobombus     Sat, 2021-05-08 12:58:30 [f568870c5bc4b559db42c8dfd6f3dccd9c5fdee822d1259ac230fdbe10c9717e]
2021-08-07T20:35:16-melanobombus     Sat, 2021-08-07 22:35:19 [9014266e4fc839937e7c6dc84ae3b91cb429594ba1c08ae01d9b837384f3c3f3]
2021-08-30T12:20:03-melanobombus     Mon, 2021-08-30 14:20:07 [20652f272a91de0dfe79757c585abeb57125ec88e828f8d410667854e23d7755]
2021-09-25T09:03:07-melanobombus     Sat, 2021-09-25 11:03:11 [7996614981faaa262d8230bc229dc43e9867185aa9071c443259f4969ccc2b2c]
2021-12-11T10:59:43-melanobombus     Sat, 2021-12-11 11:59:47 [0393814733ed1fcab765f4770ba2e39e2a560cc78304b8629c5e5c7a2380512a]
2022-01-08T14:16:07-melanobombus     Sat, 2022-01-08 15:16:10 [4aa308aba2f8b4cabaab31d452d61c432d84dd8cf6152415e9299202a8494e74]
2022-03-18T15:32:12-melanobombus     Fri, 2022-03-18 16:32:16 [c12d0ab8b42ba4409bb3a150b053107cbea9796562378b6273c06a8a71e51bc4]
2022-06-25T08:51:02-melanobombus     Sat, 2022-06-25 10:51:06 [37a4d757de82e02d8c05ad12075a9daf365543475ba1f0770834c4fd48258cb6]
2022-08-12T13:31:51-melanobombus     Fri, 2022-08-12 15:31:56 [44db54a38f242cacc0dae2f55a99a6579b8f1230ec067291e00ec1bbe4dab55c]
2022-10-17T06:18:01-melanobombus     Mon, 2022-10-17 08:18:06 [9986b2caf7fa2540631b049bd7ef33b77f532d8d2d6a6414617cccf18db19817]
2023-03-18T12:55:52-melanobombus     Sat, 2023-03-18 13:55:56 [f64ee862eb9b40d054f1e54a05d58c5d94e1a3d5b42945f1750b22425cedb547]
2023-09-27T20:19:34-melanobombus     Wed, 2023-09-27 22:19:39 [4c4f93c362584027ee6e96f0fad1caa2ffcd7181d0be5b34e3ae2574098f1370]
2023-10-18T07:27:48-melanobombus     Wed, 2023-10-18 09:27:52 [936a414d2c1784d4cc03081845b5903872430f9f508605f51bede4b8100a7af6]
2023-10-18T07:47:02-melanobombus     Wed, 2023-10-18 09:47:08 [63638db0ee29e77e4808fcf2e215984d9bdb304cab7c7058d23a5e1c0443aa8d]
2023-10-18T08:20:37-melanobombus     Wed, 2023-10-18 10:20:44 [187e72afa63b197b79249981669fdf42519eca30f12b60b3a13b3dc63b281f01]

Time to delete the archvies created today:

$ sudo borg delete --stats --prefix '2023-10-18' /mnt/backup/borg-backups/backup.borg/
Enter passphrase for key /mnt/backup/borg-backups/backup.borg: 
------------------------------------------------------------------------------
                       Original size      Compressed size    Deduplicated size
Deleted data:               -1.13 TB           -985.04 GB             -3.33 GB
All archives:                4.75 TB              4.31 TB            626.73 GB

                       Unique chunks         Total chunks
Chunk index:                 3207724             28426283
------------------------------------------------------------------------------

Unmount it all, and plug it in again. Monitor the automatic backup service. Sadly, the problem is still there – this time in the "expunged" subdirectory.

automatic backup service

Oct 18 13:49:35 melanobombus run.sh[12335]: Disk 7c478832-5d7f-43d3-9b79-20cfc67fb0e6 is a backup disk
Oct 18 13:49:35 melanobombus run.sh[12335]: /dev/sdb1 on /mnt/backup type ext4 (rw,nosuid,nodev,relatime,x-gvfs-show)
Oct 18 13:49:35 melanobombus run.sh[12335]: Drive path: /dev/sdb1
Oct 18 13:49:35 melanobombus run.sh[12335]: borgbackup version 1.1.9
Oct 18 13:50:28 melanobombus run.sh[12335]: /home/alex/.local/share/Trash/expunged/1012023798/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/man/oddmu-templates.5.txt: stat: [Errno 36] File name too long: '/home/alex/.local/share/Trash/expunged/1012023798/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/testdata/static/man/oddmu-templates.5.txt'
...

I don't dare interrupt the backup process. What I'll do is wait until it's done (and unmounts), then mount it again on a different mount point, delete today's archive again, and retry.

Unfortunately, at this point I realized that the backup was failing and restarting again and again. I don't know why this happened?

I disabled the service:

$ sudo systemctl disable automatic-backup
Removed /etc/systemd/system/automatic-backup.service.

At this point I had a lot of errors when I tried to umount, mount, run the backup script by hand, and so on. I did not investigate every single one of them. In the end, I reinstated that link, replugged the disk and hoped for the best. As far as I knew, I had deleted all the archives for today using "borg delete" according to "borg list" and I had used "rm -rf" on all the directories containing those very long file names.

I just wanted to see it work again. But it soon ran into the KeyError again. And restarted immediately. It removed the stale lock. It ran some more. I decided to let it run.

Oct 18 14:20:42 melanobombus systemd[1]: Starting automatic-backup.service...
Oct 18 14:20:47 melanobombus run.sh[15203]: Disk 7c478832-5d7f-43d3-9b79-20cfc67fb0e6 is a backup disk
Oct 18 14:20:47 melanobombus run.sh[15203]: /dev/sdb1 on /mnt/backup type ext4 (rw,nosuid,nodev,relatime,x-gvfs-show)
Oct 18 14:20:47 melanobombus run.sh[15203]: Drive path: /dev/sdb1
Oct 18 14:20:47 melanobombus run.sh[15203]: borgbackup version 1.1.9
Oct 18 14:26:47 melanobombus run.sh[15203]: Exception ignored in: <function Repository.__del__ at 0x7fca6146ed90>
Oct 18 14:26:47 melanobombus run.sh[15203]: Traceback (most recent call last):
Oct 18 14:26:47 melanobombus run.sh[15203]:   File "/usr/lib/python3/dist-packages/borg/repository.py", line 178, in __del__
Oct 18 14:26:47 melanobombus run.sh[15203]:     self.close()
Oct 18 14:26:47 melanobombus run.sh[15203]:   File "/usr/lib/python3/dist-packages/borg/repository.py", line 427, in close
Oct 18 14:26:47 melanobombus run.sh[15203]:     self.lock.release()
Oct 18 14:26:47 melanobombus run.sh[15203]:   File "/usr/lib/python3/dist-packages/borg/locking.py", line 383, in release
Oct 18 14:26:47 melanobombus run.sh[15203]:     self._roster.modify(EXCLUSIVE, REMOVE)
Oct 18 14:26:47 melanobombus run.sh[15203]:   File "/usr/lib/python3/dist-packages/borg/locking.py", line 282, in modify
Oct 18 14:26:47 melanobombus run.sh[15203]:     elements.remove(self.id)
Oct 18 14:26:47 melanobombus run.sh[15203]: KeyError: (('melanobombus.fritz.box@27278930346555', 15254, 0),)
Oct 18 14:26:47 melanobombus run.sh[15203]: Local Exception
Oct 18 14:26:47 melanobombus run.sh[15203]: Traceback (most recent call last):
Oct 18 14:26:47 melanobombus run.sh[15203]:   File "/usr/lib/python3/dist-packages/borg/archiver.py", line 154, in wrapper
Oct 18 14:26:47 melanobombus run.sh[15203]:     return method(self, args, repository=repository, **kwargs)
Oct 18 14:26:47 melanobombus run.sh[15203]:   File "/usr/lib/python3/dist-packages/borg/archiver.py", line 557, in do_create
Oct 18 14:26:47 melanobombus run.sh[15203]:     create_inner(archive, cache)
Oct 18 14:26:47 melanobombus run.sh[15203]:   File "/usr/lib/python3/dist-packages/borg/archiver.py", line 520, in create_inner
Oct 18 14:26:47 melanobombus run.sh[15203]:     read_special=args.read_special, dry_run=dry_run, st=st)
Oct 18 14:26:47 melanobombus run.sh[15203]:   File "/usr/lib/python3/dist-packages/borg/archiver.py", line 634, in _process
Oct 18 14:26:47 melanobombus run.sh[15203]:     read_special=read_special, dry_run=dry_run)
Oct 18 14:26:47 melanobombus run.sh[15203]:   File "/usr/lib/python3/dist-packages/borg/archiver.py", line 634, in _process
Oct 18 14:26:47 melanobombus run.sh[15203]:     read_special=read_special, dry_run=dry_run)
Oct 18 14:26:47 melanobombus run.sh[15203]:   File "/usr/lib/python3/dist-packages/borg/archiver.py", line 634, in _process
Oct 18 14:26:47 melanobombus run.sh[15203]:     read_special=read_special, dry_run=dry_run)
Oct 18 14:26:47 melanobombus run.sh[15203]:   [Previous line repeated 4 more times]
Oct 18 14:26:47 melanobombus run.sh[15203]:   File "/usr/lib/python3/dist-packages/borg/archiver.py", line 608, in _process
Oct 18 14:26:47 melanobombus run.sh[15203]:     status = archive.process_file(path, st, cache)
Oct 18 14:26:47 melanobombus run.sh[15203]:   File "/usr/lib/python3/dist-packages/borg/archive.py", line 1034, in process_file
Oct 18 14:26:47 melanobombus run.sh[15203]:     self.chunk_file(item, cache, self.stats, backup_io_iter(self.chunker.chunkify(fd, fh)))
Oct 18 14:26:47 melanobombus run.sh[15203]:   File "/usr/lib/python3/dist-packages/borg/archive.py", line 962, in chunk_file
Oct 18 14:26:47 melanobombus run.sh[15203]:     item.chunks.append(chunk_processor(data))
Oct 18 14:26:47 melanobombus run.sh[15203]:   File "/usr/lib/python3/dist-packages/borg/archive.py", line 950, in chunk_processor
Oct 18 14:26:47 melanobombus run.sh[15203]:     chunk_entry = cache.add_chunk(self.key.id_hash(data), data, stats, wait=False)
Oct 18 14:26:47 melanobombus run.sh[15203]:   File "/usr/lib/python3/dist-packages/borg/cache.py", line 899, in add_chunk
Oct 18 14:26:47 melanobombus run.sh[15203]:     self.repository.put(id, data, wait=wait)
Oct 18 14:26:47 melanobombus run.sh[15203]:   File "/usr/lib/python3/dist-packages/borg/repository.py", line 1101, in put
Oct 18 14:26:47 melanobombus run.sh[15203]:     segment, offset = self.io.write_put(id, data)
Oct 18 14:26:47 melanobombus run.sh[15203]:   File "/usr/lib/python3/dist-packages/borg/repository.py", line 1482, in write_put
Oct 18 14:26:47 melanobombus run.sh[15203]:     fd.write(b''.join((crc, header, id, data)))
Oct 18 14:26:47 melanobombus run.sh[15203]:   File "src/borg/platform/linux.pyx", line 279, in borg.platform.linux.SyncFile.write
Oct 18 14:26:47 melanobombus run.sh[15203]: OSError: [Errno 5] Input/output error
Oct 18 14:26:47 melanobombus run.sh[15203]: During handling of the above exception, another exception occurred:
Oct 18 14:26:47 melanobombus run.sh[15203]: Traceback (most recent call last):
Oct 18 14:26:47 melanobombus run.sh[15203]:   File "/usr/lib/python3/dist-packages/borg/platform/base.py", line 136, in close
Oct 18 14:26:47 melanobombus run.sh[15203]:     self.sync()
Oct 18 14:26:47 melanobombus run.sh[15203]:   File "src/borg/platform/linux.pyx", line 292, in borg.platform.linux.SyncFile.sync
Oct 18 14:26:47 melanobombus run.sh[15203]: OSError: [Errno 30] Read-only file system
Oct 18 14:26:47 melanobombus run.sh[15203]: During handling of the above exception, another exception occurred:
Oct 18 14:26:47 melanobombus run.sh[15203]: Traceback (most recent call last):
Oct 18 14:26:47 melanobombus run.sh[15203]:   File "/usr/lib/python3/dist-packages/borg/archiver.py", line 4455, in main
Oct 18 14:26:47 melanobombus run.sh[15203]:     exit_code = archiver.run(args)
Oct 18 14:26:47 melanobombus run.sh[15203]:   File "/usr/lib/python3/dist-packages/borg/archiver.py", line 4387, in run
Oct 18 14:26:47 melanobombus run.sh[15203]:     return set_ec(func(args))
Oct 18 14:26:47 melanobombus run.sh[15203]:   File "/usr/lib/python3/dist-packages/borg/archiver.py", line 154, in wrapper
Oct 18 14:26:47 melanobombus run.sh[15203]:     return method(self, args, repository=repository, **kwargs)
Oct 18 14:26:47 melanobombus run.sh[15203]:   File "/usr/lib/python3/dist-packages/borg/repository.py", line 203, in __exit__
Oct 18 14:26:47 melanobombus run.sh[15203]:     self.close()
Oct 18 14:26:47 melanobombus run.sh[15203]:   File "/usr/lib/python3/dist-packages/borg/repository.py", line 425, in close
Oct 18 14:26:47 melanobombus run.sh[15203]:     self.io.close()
Oct 18 14:26:47 melanobombus run.sh[15203]:   File "/usr/lib/python3/dist-packages/borg/repository.py", line 1176, in close
Oct 18 14:26:47 melanobombus run.sh[15203]:     self.close_segment()
Oct 18 14:26:47 melanobombus run.sh[15203]:   File "/usr/lib/python3/dist-packages/borg/repository.py", line 1307, in close_segment
Oct 18 14:26:47 melanobombus run.sh[15203]:     fd.close()
Oct 18 14:26:47 melanobombus run.sh[15203]:   File "/usr/lib/python3/dist-packages/borg/platform/base.py", line 140, in close
Oct 18 14:26:47 melanobombus run.sh[15203]:     platform.sync_dir(dirname)
Oct 18 14:26:47 melanobombus run.sh[15203]:   File "/usr/lib/python3/dist-packages/borg/platform/base.py", line 60, in sync_dir
Oct 18 14:26:47 melanobombus run.sh[15203]:     os.fsync(fd)
Oct 18 14:26:47 melanobombus run.sh[15203]: OSError: [Errno 30] Read-only file system
Oct 18 14:26:47 melanobombus run.sh[15203]: Platform: Linux melanobombus 4.19.0-24-amd64 #1 SMP Debian 4.19.282-1 (2023-04-29) x86_64
Oct 18 14:26:47 melanobombus run.sh[15203]: Linux: PureOS 9 amber
Oct 18 14:26:47 melanobombus run.sh[15203]: Borg: 1.1.9  Python: CPython 3.7.3
Oct 18 14:26:47 melanobombus run.sh[15203]: PID: 15254  CWD: /
Oct 18 14:26:47 melanobombus run.sh[15203]: sys.argv: ['/usr/bin/borg', 'create', '--stats', '--one-file-system', '--compression', 'lz4', '--show-version', '--exclude', '/root/.cache', '--exclude', '/var/cache', '/mnt/backup/borg-backups/backup.borg::{utcnow}-{hostname}', '/']
Oct 18 14:26:47 melanobombus run.sh[15203]: SSH_ORIGINAL_COMMAND: None
Oct 18 14:26:47 melanobombus systemd[1]: automatic-backup.service: Main process exited, code=exited, status=2/INVALIDARGUMENT
Oct 18 14:26:47 melanobombus systemd[1]: automatic-backup.service: Failed with result 'exit-code'.
Oct 18 14:26:47 melanobombus systemd[1]: Failed to start automatic-backup.service.
Oct 18 14:26:48 melanobombus systemd[1]: Starting automatic-backup.service...
Oct 18 14:26:53 melanobombus run.sh[15584]: Disk 7c478832-5d7f-43d3-9b79-20cfc67fb0e6 is a backup disk
Oct 18 14:26:53 melanobombus run.sh[15584]: /dev/sdb1 on /mnt/backup type ext4 (rw,nosuid,nodev,relatime,x-gvfs-show)
Oct 18 14:26:53 melanobombus run.sh[15584]: Drive path: /dev/sdb1
Oct 18 14:26:54 melanobombus run.sh[15584]: borgbackup version 1.1.9
Oct 18 14:26:54 melanobombus run.sh[15584]: Killed stale lock melanobombus.fritz.box@27278930346555.15254-0.
Oct 18 14:26:54 melanobombus run.sh[15584]: Removed stale exclusive roster lock for host melanobombus.fritz.box@27278930346555 pid 15254 thread 0.
Oct 18 14:26:54 melanobombus run.sh[15584]: Removed stale exclusive roster lock for host melanobombus.fritz.box@27278930346555 pid 15254 thread 0.
Oct 18 14:35:29 melanobombus run.sh[15584]: ------------------------------------------------------------------------------
Oct 18 14:35:29 melanobombus run.sh[15584]: Archive name: 2023-10-18T12:26:54-melanobombus
Oct 18 14:35:29 melanobombus run.sh[15584]: Archive fingerprint: 7726baff7a99b1c457ffba60d3a76222bde44a350352b789678c296d4fdcf534
Oct 18 14:35:29 melanobombus run.sh[15584]: Time (start): Wed, 2023-10-18 14:27:00
Oct 18 14:35:29 melanobombus run.sh[15584]: Time (end):   Wed, 2023-10-18 14:35:03
Oct 18 14:35:29 melanobombus run.sh[15584]: Duration: 8 minutes 2.40 seconds
Oct 18 14:35:29 melanobombus run.sh[15584]: Number of files: 2562556
Oct 18 14:35:29 melanobombus run.sh[15584]: Utilization of max. archive size: 1%
Oct 18 14:35:29 melanobombus run.sh[15584]: ------------------------------------------------------------------------------
Oct 18 14:35:29 melanobombus run.sh[15584]:                        Original size      Compressed size    Deduplicated size
Oct 18 14:35:29 melanobombus run.sh[15584]: This archive:              371.43 GB            324.59 GB              2.97 GB
Oct 18 14:35:29 melanobombus run.sh[15584]: All archives:                5.12 TB              4.63 TB            629.70 GB
Oct 18 14:35:29 melanobombus run.sh[15584]:                        Unique chunks         Total chunks
Oct 18 14:35:29 melanobombus run.sh[15584]: Chunk index:                 3236063             31098748
Oct 18 14:35:29 melanobombus run.sh[15584]: ------------------------------------------------------------------------------
Oct 18 14:35:29 melanobombus run.sh[15584]: Keeping archive: 2023-10-18T12:26:54-melanobombus     Wed, 2023-10-18 14:27:00 [7726baff7a99b1c457ffba60d3a76222bde44a350352b789678c296d4fdcf534]
Oct 18 14:35:29 melanobombus run.sh[15584]: Keeping archive: 2023-09-27T20:19:34-melanobombus     Wed, 2023-09-27 22:19:39 [4c4f93c362584027ee6e96f0fad1caa2ffcd7181d0be5b34e3ae2574098f1370]
Oct 18 14:35:29 melanobombus run.sh[15584]: Keeping archive: 2023-03-18T12:55:52-melanobombus     Sat, 2023-03-18 13:55:56 [f64ee862eb9b40d054f1e54a05d58c5d94e1a3d5b42945f1750b22425cedb547]
Oct 18 14:35:29 melanobombus run.sh[15584]: Keeping archive: 2022-10-17T06:18:01-melanobombus     Mon, 2022-10-17 08:18:06 [9986b2caf7fa2540631b049bd7ef33b77f532d8d2d6a6414617cccf18db19817]
Oct 18 14:35:29 melanobombus run.sh[15584]: Keeping archive: 2022-08-12T13:31:51-melanobombus     Fri, 2022-08-12 15:31:56 [44db54a38f242cacc0dae2f55a99a6579b8f1230ec067291e00ec1bbe4dab55c]
Oct 18 14:35:29 melanobombus run.sh[15584]: Keeping archive: 2022-06-25T08:51:02-melanobombus     Sat, 2022-06-25 10:51:06 [37a4d757de82e02d8c05ad12075a9daf365543475ba1f0770834c4fd48258cb6]
Oct 18 14:35:29 melanobombus run.sh[15584]: Keeping archive: 2022-03-18T15:32:12-melanobombus     Fri, 2022-03-18 16:32:16 [c12d0ab8b42ba4409bb3a150b053107cbea9796562378b6273c06a8a71e51bc4]
Oct 18 14:35:29 melanobombus run.sh[15584]: Keeping archive: 2022-01-08T14:16:07-melanobombus     Sat, 2022-01-08 15:16:10 [4aa308aba2f8b4cabaab31d452d61c432d84dd8cf6152415e9299202a8494e74]
Oct 18 14:35:29 melanobombus run.sh[15584]: Keeping archive: 2021-12-11T10:59:43-melanobombus     Sat, 2021-12-11 11:59:47 [0393814733ed1fcab765f4770ba2e39e2a560cc78304b8629c5e5c7a2380512a]
Oct 18 14:35:29 melanobombus run.sh[15584]: Keeping archive: 2021-09-25T09:03:07-melanobombus     Sat, 2021-09-25 11:03:11 [7996614981faaa262d8230bc229dc43e9867185aa9071c443259f4969ccc2b2c]
Oct 18 14:35:29 melanobombus run.sh[15584]: Keeping archive: 2021-08-30T12:20:03-melanobombus     Mon, 2021-08-30 14:20:07 [20652f272a91de0dfe79757c585abeb57125ec88e828f8d410667854e23d7755]
Oct 18 14:35:29 melanobombus run.sh[15584]: Pruning archive: 2021-08-07T20:35:16-melanobombus     Sat, 2021-08-07 22:35:19 [9014266e4fc839937e7c6dc84ae3b91cb429594ba1c08ae01d9b837384f3c3f3] (1/1)
Oct 18 14:35:58 melanobombus run.sh[15584]: Keeping archive: 2021-05-08T10:45:37-melanobombus     Sat, 2021-05-08 12:58:30 [f568870c5bc4b559db42c8dfd6f3dccd9c5fdee822d1259ac230fdbe10c9717e]
Oct 18 14:35:58 melanobombus run.sh[15584]: Keeping archive: 2018-02-08T06:28:42-melanobombus     Thu, 2018-02-08 07:28:43 [693328a0bb9ace8880ee103786702edd46f79e490d1bae2b99a445b69c79e329]
Oct 18 14:35:58 melanobombus run.sh[15584]: Keeping archive: 2017-12-29T16:33:27-melanobombus     Fri, 2017-12-29 17:33:35 [62db7fa8b9478dfaff74a3af9401b0c2f78c4317c295d38fbcad31f62c2e29c7]
Oct 18 14:37:00 melanobombus run.sh[15584]: ------------------------------------------------------------------------------
Oct 18 14:37:00 melanobombus run.sh[15584]:                        Original size      Compressed size    Deduplicated size
Oct 18 14:37:00 melanobombus run.sh[15584]: Deleted data:             -405.05 GB           -374.23 GB           -480.57 MB
Oct 18 14:37:00 melanobombus run.sh[15584]: All archives:                4.72 TB              4.26 TB            629.22 GB
Oct 18 14:37:00 melanobombus run.sh[15584]:                        Unique chunks         Total chunks
Oct 18 14:37:00 melanobombus run.sh[15584]: Chunk index:                 3227590             28893326
Oct 18 14:37:00 melanobombus run.sh[15584]: ------------------------------------------------------------------------------
Oct 18 14:37:00 melanobombus run.sh[15584]: terminating with success status, rc 0
Oct 18 14:37:01 melanobombus run.sh[15584]: /dev/sdb1:
Oct 18 14:37:01 melanobombus run.sh[15584]:  issuing sleep command
Oct 18 14:37:01 melanobombus systemd[1]: automatic-backup.service: Succeeded.
Oct 18 14:37:01 melanobombus systemd[1]: Started automatic-backup.service.

Success!

I am so relieved.

​#Backup ​#Borg