Log warning - apt cleanup

Put support questions regarding KEEP here. Examples are hardware related issues and other questions that are specific to KEEP.
Post Reply
haraldc
Posts: 29
Joined: Sun Oct 05, 2014 9:45 am

Log warning - apt cleanup

Post by haraldc » Mon Jan 11, 2021 7:54 pm

Hi,
The last couple of days I have had problems with the backup. The Keep system did not properly mount the local backup USB disk and there was a repeated warning massage about the apt cleanup failing. After a couple of reboots the system seems to be working, but I still get the warning.

The warning says "apt clean up failed, see log in /var/log/apt-cleanup.log".

There is no such file on /var/log. The failure occurred during the backup. The backup seems to have completed correctly though.

Can the apt clean up warning be ignored?

User avatar
tor
Posts: 120
Joined: Thu Aug 14, 2014 3:42 am
Contact:

Re: Log warning - apt cleanup

Post by tor » Tue Jan 12, 2021 1:30 pm

Hi haraldc,

First off, the warning is related to the update, thus i suspect you have two different issues here. (Not saying that they aren't related). The reason that you did not find the log file is due to a bug in the update script. I have updated that package and pushed it to the repositories (opi-updates 1.0.18~bpo7). If the warning comes back please check the file and, if needed, get back to us.

Regarding the backup problem, that's harder to comment without further information. Information on problems with this can be examined in the /var/log/opi-backup/s3ql_mount.log file. If there is anything suspicious in that, contact support@openproducts and we will take a look at it.

/Tor
Last edited by tor on Tue Jan 12, 2021 1:32 pm, edited 1 time in total.
Reason: Added version of opi-updates with the bugfix.

haraldc
Posts: 29
Joined: Sun Oct 05, 2014 9:45 am

Re: Log warning - apt cleanup

Post by haraldc » Wed Jan 13, 2021 6:55 am

Hi Tor,
The backup has been working the last few nights, so no problem there. I got a warning message about "apt cleanup" last night again,. Maybe the new update had not been installed at that time. I check tomorrow.
/Harald

User avatar
tor
Posts: 120
Joined: Thu Aug 14, 2014 3:42 am
Contact:

Re: Log warning - apt cleanup

Post by tor » Wed Jan 13, 2021 2:28 pm

Yeah, you probably got the update last night and the message then originated from the old update package. Lets wait and see tomorrow :)

/Tor

haraldc
Posts: 29
Joined: Sun Oct 05, 2014 9:45 am

Re: Log warning - apt cleanup

Post by haraldc » Fri Jan 15, 2021 10:14 pm

Hi,
I still get the warning message from apt-cleanup, but now there is a log file. :|
The backup was taken last night according to the log file. :D
However, the backups are not available now since the local backup disk is not mounted. :(

I paste the logs for apt-cleanup and s3ql_mount below

root@keep:/var/log# cat apt-cleanup.log
APT-GET Update was run: Fri Jan 15 00:18:06 2021

Reading package lists...
Building dependency tree...
Reading state information...
Calculating upgrade...
The following packages were automatically installed and are no longer required:
linux-image-4.9.0-11-armmp linux-image-4.9.0-6-armmp
linux-image-4.9.0-9-armmp
Use 'apt autoremove' to remove them.
0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
Cleaning apt cache
Reading package lists...
Building dependency tree...
Reading state information...
The following packages will be REMOVED:
linux-image-4.9.0-11-armmp linux-image-4.9.0-6-armmp
linux-image-4.9.0-9-armmp
0 upgraded, 0 newly installed, 3 to remove and 0 not upgraded.
After this operation, 414 MB disk space will be freed.
Do you want to continue? [Y/n] Abort.
root@keep:/var/log#

root@keep:/var/log/opi-backup# cat s3ql_mount.log | grep 2021-01-15
2021-01-15 00:05:55.537 13125:MainThread s3ql.fsck.main: Starting fsck of local:///mnt/usb/backup_v2.21/
2021-01-15 00:05:55.646 13125:MainThread s3ql.fsck.main: Using cached metadata.
2021-01-15 00:05:55.663 13125:MainThread s3ql.fsck.main: Remote metadata is outdated.
2021-01-15 00:05:55.663 13125:MainThread s3ql.fsck.main: Checking DB integrity...
2021-01-15 00:06:08.033 13125:MainThread s3ql.fsck.check: Creating temporary extra indices...
2021-01-15 00:06:24.365 13125:MainThread s3ql.fsck.check_lof: Checking lost+found...
2021-01-15 00:06:24.367 13125:MainThread s3ql.fsck.check_cache: Checking cached objects...
2021-01-15 00:06:24.367 13125:MainThread s3ql.fsck.check_names_refcount: Checking names (refcounts)...
2021-01-15 00:06:25.669 13125:MainThread s3ql.fsck.check_contents_name: Checking contents (names)...
2021-01-15 00:06:28.104 13125:MainThread s3ql.fsck.check_contents_inode: Checking contents (inodes)...
2021-01-15 00:06:31.474 13125:MainThread s3ql.fsck.check_contents_parent_inode: Checking contents (parent inodes)...
2021-01-15 00:06:32.596 13125:MainThread s3ql.fsck.check_objects_temp: Checking for temporary objects (backend)...
2021-01-15 00:06:34.048 13125:MainThread s3ql.fsck.check_objects_refcount: Checking objects (reference counts)...
2021-01-15 00:06:34.356 13125:MainThread s3ql.fsck.check_objects_id: Checking objects (backend)...
2021-01-15 00:06:43.517 13125:MainThread s3ql.fsck.check_objects_size: Checking objects (sizes)...
2021-01-15 00:06:43.537 13125:MainThread s3ql.fsck.check_blocks_obj_id: Checking blocks (referenced objects)...
2021-01-15 00:06:43.611 13125:MainThread s3ql.fsck.check_blocks_refcount: Checking blocks (refcounts)...
2021-01-15 00:06:49.146 13125:MainThread s3ql.fsck.check_blocks_checksum: Checking blocks (checksums)...
2021-01-15 00:06:49.147 13125:MainThread s3ql.fsck.check_inode_blocks_block_id: Checking inode-block mapping (blocks)...
2021-01-15 00:06:52.214 13125:MainThread s3ql.fsck.check_inode_blocks_inode: Checking inode-block mapping (inodes)...
2021-01-15 00:06:55.545 13125:MainThread s3ql.fsck.check_inodes_refcount: Checking inodes (refcounts)...
2021-01-15 00:07:09.241 13125:MainThread s3ql.fsck.check_inodes_size: Checking inodes (sizes)...
2021-01-15 00:07:29.884 13125:MainThread s3ql.fsck.check_ext_attributes_name: Checking extended attributes (names)...
2021-01-15 00:07:29.885 13125:MainThread s3ql.fsck.check_ext_attributes_inode: Checking extended attributes (inodes)...
2021-01-15 00:07:29.886 13125:MainThread s3ql.fsck.check_symlinks_inode: Checking symlinks (inodes)...
2021-01-15 00:07:29.888 13125:MainThread s3ql.fsck.check_loops: Checking directory reachability...
2021-01-15 00:08:05.069 13125:MainThread s3ql.fsck.check_unix: Checking unix conventions...
2021-01-15 00:10:08.672 13125:MainThread s3ql.fsck.check_foreign_keys: Checking referential integrity...
2021-01-15 00:10:18.438 13125:MainThread s3ql.fsck.check: Dropping temporary indices...
2021-01-15 00:10:20.717 13125:MainThread s3ql.metadata.dump_and_upload_metadata: Dumping metadata...
2021-01-15 00:10:20.771 13125:MainThread s3ql.metadata.dump_metadata: ..objects..
2021-01-15 00:10:21.169 13125:MainThread s3ql.metadata.dump_metadata: ..blocks..
2021-01-15 00:10:21.519 13125:MainThread s3ql.metadata.dump_metadata: ..inodes..
2021-01-15 00:10:36.605 13125:MainThread s3ql.metadata.dump_metadata: ..inode_blocks..
2021-01-15 00:10:44.459 13125:MainThread s3ql.metadata.dump_metadata: ..symlink_targets..
2021-01-15 00:10:44.462 13125:MainThread s3ql.metadata.dump_metadata: ..names..
2021-01-15 00:10:44.592 13125:MainThread s3ql.metadata.dump_metadata: ..contents..
2021-01-15 00:10:52.157 13125:MainThread s3ql.metadata.dump_metadata: ..ext_attributes..
2021-01-15 00:10:52.160 13125:MainThread s3ql.metadata.upload_metadata: Compressing and uploading metadata...
2021-01-15 00:11:52.665 13125:MainThread s3ql.metadata.upload_metadata: Wrote 27.5 MiB of compressed metadata.
2021-01-15 00:11:52.666 13125:MainThread s3ql.metadata.upload_metadata: Cycling metadata backups...
2021-01-15 00:11:52.666 13125:MainThread s3ql.metadata.cycle_metadata: Backing up old metadata...
2021-01-15 00:11:59.177 13125:MainThread s3ql.fsck.main: Cleaning up local metadata...
2021-01-15 00:12:26.029 13125:MainThread s3ql.fsck.main: Completed fsck of local:///mnt/usb/backup_v2.21/
2021-01-15 00:12:27.942 14010:MainThread s3ql.mount.determine_threads: Using 4 upload threads.
2021-01-15 00:12:27.944 14010:MainThread s3ql.mount.main: Autodetected 4052 file descriptors available for cache entries
2021-01-15 00:12:27.963 14010:MainThread s3ql.mount.get_metadata: Using cached metadata.
2021-01-15 00:12:28.100 14010:MainThread s3ql.mount.main: Mounting local:///mnt/usb/backup_v2.21/ at /mnt/backup/v2.21...
2021-01-15 00:12:28.119 14019:MainThread s3ql.daemonize.detach_process_context: Daemonizing, new PID is 14020
2021-01-15 00:18:23.753 14020:MainThread s3ql.mount.main: FUSE main loop terminated.
2021-01-15 00:18:23.971 14020:MainThread s3ql.mount.unmount: Unmounting file system...
2021-01-15 00:18:24.087 14020:MainThread s3ql.metadata.dump_and_upload_metadata: Dumping metadata...
2021-01-15 00:18:24.147 14020:MainThread s3ql.metadata.dump_metadata: ..objects..
2021-01-15 00:18:24.485 14020:MainThread s3ql.metadata.dump_metadata: ..blocks..
2021-01-15 00:18:25.464 14020:MainThread s3ql.metadata.dump_metadata: ..inodes..
2021-01-15 00:18:41.750 14020:MainThread s3ql.metadata.dump_metadata: ..inode_blocks..
2021-01-15 00:18:51.303 14020:MainThread s3ql.metadata.dump_metadata: ..symlink_targets..
2021-01-15 00:18:51.306 14020:MainThread s3ql.metadata.dump_metadata: ..names..
2021-01-15 00:18:51.913 14020:MainThread s3ql.metadata.dump_metadata: ..contents..
2021-01-15 00:19:04.030 14020:MainThread s3ql.metadata.dump_metadata: ..ext_attributes..
2021-01-15 00:19:04.033 14020:MainThread s3ql.metadata.upload_metadata: Compressing and uploading metadata...
root@keep:/var/log/opi-backup#

User avatar
tor
Posts: 120
Joined: Thu Aug 14, 2014 3:42 am
Contact:

Re: Log warning - apt cleanup

Post by tor » Mon Jan 18, 2021 6:05 pm

Hi haraldc,

The backup log seems fine. The error message in the update log however is due to a bug :| I have updated the package once again, (opi-updates 1.0.18~bpo8), and the update should hopefully succeed once updated. If not get back to us!

/Tor

haraldc
Posts: 29
Joined: Sun Oct 05, 2014 9:45 am

Re: Log warning - apt cleanup

Post by haraldc » Mon Jan 18, 2021 7:37 pm

It works fine again.
/Harald

Post Reply