Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

ERROR: unable to get maintenance params: error looking for maintenance manifest: unable to load manifest contents: error loading manifest content: getCacheForContentID: BLOB not found #1087

Closed
dbousamra opened this issue May 20, 2021 · 11 comments
Labels

Comments

@dbousamra
Copy link

My repository seems to be in a corrupted state somehow. I've done nothing other than periodically run snapshot create.

If I do a kopia content verify:

Finished verifying 1222 contents, found 272 errors.
ERROR: encountered 272 errors

What do I do next?

@jkowalski
Copy link
Contributor

jkowalski commented May 20, 2021

Can you provide the following:

  1. version of Kopia used to create repository
  2. the provider used
  3. a timeline of things?
  4. are you backing up more than one machine/user?

Ideally, if you can hop on https://slack.kopia.io, we can chat interactively and hopefully figure it out quickly.

@jkowalski
Copy link
Contributor

Current working theory is that this was due to multiple overlapping maintenance jobs that ran and messed up blob deletion. The environment was kubernetes cron jobs where each call was overriding the hostname, so regular file-based lock did not protect against it.

We'll need to do more debugging.

@julio-lopez
Copy link
Collaborator

Without having additional information, that'd be my hypothesis as well.

@xxxliqu1dxxx
Copy link

I too just have run into the same issue.

kopia snapshot verify shows:

listing manifest contents
[STORAGE] GetBlob("qff81a9260101bf45359b9a262a80d5fc-sc8353eb072f12b8c104",0,-1)=([], BLOB not found) took 271.022µs
[STORAGE] GetBlob("qe100920de5694f07e0b1dee85ce76c5a-sb01a1d3889fe035f104",0,-1)=([], BLOB not found) took 411.866µs
[STORAGE] GetBlob("q7f0cbc27e2864c87321aa0130d6f6c5c-s1f2cd4d80292b610104",0,-1)=([], BLOB not found) took 359.665µs
[STORAGE] GetBlob("qff81a9260101bf45359b9a262a80d5fc-sc8353eb072f12b8c104",0,-1)=([], BLOB not found) took 346.205µs
[STORAGE] GetBlob("q7f358cbf4d60026d153aa93f6ad524c1-sf342a987fb610318104",0,-1)=([], BLOB not found) took 711.362µs
[STORAGE] GetBlob("qff81a9260101bf45359b9a262a80d5fc-sc8353eb072f12b8c104",0,-1)=([], BLOB not found) took 1.629611ms
[STORAGE] GetBlob("qff81a9260101bf45359b9a262a80d5fc-sc8353eb072f12b8c104",0,-1)=([], BLOB not found) took 1.373131ms
[STORAGE] GetBlob("qff81a9260101bf45359b9a262a80d5fc-sc8353eb072f12b8c104",0,-1)=([], BLOB not found) took 233.618µs
[STORAGE] GetBlob("qff81a9260101bf45359b9a262a80d5fc-sc8353eb072f12b8c104",0,-1)=([], BLOB not found) took 207.605µs
[STORAGE] GetBlob("qd96e7efbbda73858746c9e7e404a7b1a-se4d0d547ccdfccef104",0,-1)=({272077 bytes}, ) took 7.981658ms
closing shared manager
finished sweeping metadata cache in 184.006µs and retained 57984219/5242880000 bytes (1 %)
[STORAGE] Close()= took 360ns
in use heap 2719744 (delta 2719744 max 0) stack 425984 (delta 425984 max 0)
ERROR: unable to list snapshot manifests: unable to find manifest entries: unable to load manifest contents: error loading manifest content: getCacheForContentID: BLOB not found

and when connecting from various clients, i see this:

[2021-05-23 12:27:12.037] [info] failed to open repository: unable to create shared content manager: error loading indexes: error listing index blobs: error listing indexes: error listing m blobs: error listing blobs: error listing all blobs: error reading directory: unable to complete ReadDir:\192.168.2.3\backups\kopia/m98 despite 10 retries

Really not sure what happened... as we speak, I cannot connect from any Windows CLI, just from a linux CLI that I have...

@jkowalski
Copy link
Contributor

Seems you're connecting to a repository mounted over UNC path, can you ping me on https://slack.kopia.io so we can diagnose further?

@xxxliqu1dxxx
Copy link

xxxliqu1dxxx commented May 23, 2021

I am on slack, and sent you a DM. let me know if you have time.

@jkowalski
Copy link
Contributor

We tried recovering all indexes in @xxxliqu1dxxx 's repository and that brought it to somewhat-recovered state, but several blob files were missing. Figuring out why and this happened wasn't possible without all logs.

I think we need to ensure we have logs to understand this better in the future - I'm going to add a feature that low-level stores content manipulation logs in the repository itself for some amount of time, so we can get more information about what leads to situation like this. I suspect in no particular order a) time sync issues, b) incomplete data migration, c) kopia maintenance bugs, d) problems with the blob storage provider or the underlying filesystem.

@xxxliqu1dxxx
Copy link

Been talking with Jarek pretty much the whole day on this... Additional information for documentation purposes.

The repo sits on a SMB Share, accessed from Windows, but sitting on an Unraid backend.
Not sure if OP is also having an unraid backend, but perhaps that's the similarity.

In addition, though all snapshots have always run "fine" prior to this strange issue and the repo now seems corrupted beyond repairs, when I initiated a kopia restore from the linux cli, running off a Unassigned Device in Unraid, the CLI was using /root as temporary directory to perform its things, and these messages showed up not even 30 seconds after initiating a restore:

got error can't write temporary file: write /root/.cache/kopia/ee4ed598dcadfdc7/contents/e6/233c7f6a2e12fc1ce18754093c4b64.f.tmp.ac76a49f9981f281: no space left on device when PutBlobInPath:/root/.cache/kopia/ee4ed598dcadfdc7/contents/e6/233c7f6a2e12fc1ce18754093c4b64.f (#0), sleeping for 1s before retrying
got error can't write temporary file: write /root/.cache/kopia/ee4ed598dcadfdc7/contents/e6/233c7f6a2e12fc1ce18754093c4b64.f.tmp.aece3bbf679ce0e5: no space left on device when PutBlobInPath:/root/.cache/kopia/ee4ed598dcadfdc7/contents/e6/233c7f6a2e12fc1ce18754093c4b64.f (#1), sleeping for 1.5s before retrying
finished sweeping metadata cache in 1.066947ms and retained 147281108/5242880000 bytes (2 %)
finished sweeping content cache in 25.832162ms and retained 2978151137/5242880000 bytes (56 %)
got error can't write temporary file: write /root/.cache/kopia/ee4ed598dcadfdc7/contents/e6/233c7f6a2e12fc1ce18754093c4b64.f.tmp.723d43dd7b1b221d: no space left on device when PutBlobInPath:/root/.cache/kopia/ee4ed598dcadfdc7/contents/e6/233c7f6a2e12fc1ce18754093c4b64.f (#2), sleeping for 2.25s before retrying
got error can't write temporary file: write /root/.cache/kopia/ee4ed598dcadfdc7/contents/e6/233c7f6a2e12fc1ce18754093c4b64.f.tmp.955abfe9da6acc42: no space left on device when PutBlobInPath:/root/.cache/kopia/ee4ed598dcadfdc7/contents/e6/233c7f6a2e12fc1ce18754093c4b64.f (#3), sleeping for 3.375s before retrying
got error can't write temporary file: write /root/.cache/kopia/ee4ed598dcadfdc7/contents/e6/233c7f6a2e12fc1ce18754093c4b64.f.tmp.8e6c9ff13b04a69d: no space left on device when PutBlobInPath:/root/.cache/kopia/ee4ed598dcadfdc7/contents/e6/233c7f6a2e12fc1ce18754093c4b64.f (#4), sleeping for 5.0625s before retrying
got error can't write temporary file: write /root/.cache/kopia/ee4ed598dcadfdc7/contents/e6/233c7f6a2e12fc1ce18754093c4b64.f.tmp.464c0ff0fc90f545: no space left on device when PutBlobInPath:/root/.cache/kopia/ee4ed598dcadfdc7/contents/e6/233c7f6a2e12fc1ce18754093c4b64.f (#5), sleeping for 7.59375s before retrying
got error can't write temporary file: write /root/.cache/kopia/ee4ed598dcadfdc7/contents/e6/233c7f6a2e12fc1ce18754093c4b64.f.tmp.d93dcba4fd5889b5: no space left on device when PutBlobInPath:/root/.cache/kopia/ee4ed598dcadfdc7/contents/e6/233c7f6a2e12fc1ce18754093c4b64.f (#6), sleeping for 11.390625s before retrying

This effectively was crashing the server and had to reboot it.

Between the reboots, not sure if something in the Unraid Array corrupted the Repo, but that's where I am at right now.

@jens-f
Copy link

jens-f commented May 7, 2022

I'm suddenly facing the same problem, getting this similar error message:

ERROR error running maintenance: error running maintenance: unable to get maintenance params: error looking for maintenance manifest: unable to load manifest contents: error loading manifest content: error getting cached content: BLOB not found

and also

ERROR error upgrading repository: error looking for maintenance parameters: error looking for maintenance manifest: unable to load manifest contents: error loading manifest content: error getting cached content: BLOB not found

Based on what I read in this issue and in some related kopia forums, I tried a couple of things, but without success.

Infos:

  1. version of Kopia used to create repository: 0.10.7
  2. the provider used: rclone provider with pcloud backend
  3. are you backing up more than one machine/user? no, just one user from one machine
  4. OS: OSX 12.3.1
  5. a timeline of things? see below.

Questions:

  • Is there anything else I can try?
  • Is there a way to run more verbose logging to get more details around what exact BLOB is not found?
  • If the snapshots are permanently broken, is there a way to create a fresh set of snapshots but keep the blobs? I have backed up about 67 GB of data and if possible would love to avoid a re-upload of all of this.

Timeline:

Ran backup

$  kopia --config-file="rclone.config" snapshot create /Users/myuser
Snapshotting myuser@my-mac:/Users/myuser ...
 * 0 hashing, 9 hashed (1.7 MB), 71603 cached (67.1 GB), uploaded 205 B, estimated 67.1 GB (100.0%) 0s left
Created snapshot with root k3dbc086689bfbba75a8c2c1ee04a2855 and ID 9688d16afc6fe101e2582451af093367 in 6s
Running quick maintenance...
Finished quick maintenance.

A couple of days later, ran another backup

$  kopia --config-file="rclone.config" snapshot create /Users/myuser
Snapshotting myuser@my-mac:/Users/myuser ...
 * 0 hashing, 125 hashed (326.6 MB), 71553 cached (67.1 GB), uploaded 280.9 MB, estimated 67.4 GB (100.0%) 0s left  
Created snapshot with root kf53aecb9496e38cce45caa65224e1baa and ID 7dc6902f3350ab3baae170ca7f9c1058 in 12m40s
Running full maintenance...
Looking for active contents...
Looking for unreferenced contents...
Previous content rewrite has not been finalized yet, waiting until the next blob deletion.
Found safe time to drop indexes: 2022-04-22 21:25:20.860737 -0700 PDT
Dropping contents deleted before 2022-04-22 21:25:20.860737 -0700 PDT
Looking for unreferenced blobs...
Deleted total 17 unreferenced blobs (97.6 MB)
Cleaned up 1 logs.
Cleaning up old index blobs which have already been compacted...
Finished full maintenance.

rebooted machine a couple of days later

  [Restored May 3, 2022 at 21:50:20]
Last login: Tue May  3 21:50:19 on ttys000
Restored session: Tue May  3 09:51:43 PDT 2022

Same day, ran another backup which failed

$  kopia --config-file="rclone.config" snapshot create /Users/myuser
ERROR error running maintenance: error running maintenance: unable to get maintenance params: error looking for maintenance manifest: unable to load manifest contents: error loading manifest content: error getting cached content: BLOB not found
ERROR error upgrading repository: error looking for maintenance parameters: error looking for maintenance manifest: unable to load manifest contents: error loading manifest content: error getting cached content: BLOB not found

Ran verify

$  kopia --config-file="rclone.config" snapshot verify        
Listing blobs...
Listed 3059 blobs.
ERROR unable to list snapshot manifests: unable to find snapshot manifests: unable to load manifest contents: error loading manifest content: error getting cached content: BLOB not found

Ran full maintainance

$  kopia maintenance run --full --safety=none
ERROR unable to get maintenance params: error looking for maintenance manifest: unable to load manifest contents: error loading manifest content: error getting cached content: BLOB not found

Verified content

$ kopia --config-file="rclone.config" content verify
Listing blobs...
	
Listed 3060 blobs.
Verifying all contents...
  Verified 1 contents, 0 errors, estimating...
ERROR error content 17f68289e0fe30009c186d3b12c35c15 depends on missing blob pa742b5d1e6103c032d4f8ff9f7068b09-s056736796ffd148110c
ERROR error content 281dae6e05da807858071baab779a137 depends on missing blob pa742b5d1e6103c032d4f8ff9f7068b09-s056736796ffd148110c
ERROR error content 806da0bed2bb61aaa3be0136219a8b7f depends on missing blob pa742b5d1e6103c032d4f8ff9f7068b09-s056736796ffd148110c
ERROR error content 89e6aa6815deaa2c424e8b047b5ab2b5 depends on missing blob pa742b5d1e6103c032d4f8ff9f7068b09-s056736796ffd148110c
ERROR error content b26c6f388cdf3f40eb08ab4e7a068533 depends on missing blob pa742b5d1e6103c032d4f8ff9f7068b09-s056736796ffd148110c
ERROR error content ccc29bc883dbcdae0c4eede6764240a7 depends on missing blob pa742b5d1e6103c032d4f8ff9f7068b09-s056736796ffd148110c
ERROR error content cf0a98e5b822c227578937db1ea12942 depends on missing blob pa742b5d1e6103c032d4f8ff9f7068b09-s056736796ffd148110c
ERROR error content cf9040d9489f2e4ea20031ed2d5b1085 depends on missing blob pa742b5d1e6103c032d4f8ff9f7068b09-s056736796ffd148110c
ERROR error content f210bf6a2cebbadd36a240944d5ce332 depends on missing blob pa742b5d1e6103c032d4f8ff9f7068b09-s056736796ffd148110c
ERROR error content k01a0d01676fca38ebf88edacd0ce8a5d depends on missing blob q16ccf282732d9fbba8be613e6bd4e027-sc286df115f7ef1f410d
ERROR error content k0ac1274ee0ffe0a7ec09beccd16b43ea depends on missing blob q16ccf282732d9fbba8be613e6bd4e027-sc286df115f7ef1f410d
ERROR error content k148a35efeb5ea07619b94b23175a23ca depends on missing blob q16ccf282732d9fbba8be613e6bd4e027-sc286df115f7ef1f410d
ERROR error content k1883e202b6a70778ce5eeff3d45675a4 depends on missing blob q16ccf282732d9fbba8be613e6bd4e027-sc286df115f7ef1f410d
ERROR error content k1a64a8dd8aadda96b6a195e70bd26a63 depends on missing blob q16ccf282732d9fbba8be613e6bd4e027-sc286df115f7ef1f410d
ERROR error content k21b3a8af5bd02d9a030fe04513ca22b4 depends on missing blob q16ccf282732d9fbba8be613e6bd4e027-sc286df115f7ef1f410d
ERROR error content k277507ff8a3a134d02b59b2296b8f84b depends on missing blob q16ccf282732d9fbba8be613e6bd4e027-sc286df115f7ef1f410d
ERROR error content k2f485c2749b52948d993e03c01e88389 depends on missing blob q16ccf282732d9fbba8be613e6bd4e027-sc286df115f7ef1f410d
ERROR error content k399ecba191b96fe0bed292db6f48b2a0 depends on missing blob q16ccf282732d9fbba8be613e6bd4e027-sc286df115f7ef1f410d
ERROR error content k3add272fbe8bb197da2b110946929fee depends on missing blob q16ccf282732d9fbba8be613e6bd4e027-sc286df115f7ef1f410d
ERROR error content k4442e94f2899557541f6a07605f32950 depends on missing blob q16ccf282732d9fbba8be613e6bd4e027-sc286df115f7ef1f410d
ERROR error content k4b3dd04747848bb694331fae619a80fe depends on missing blob q16ccf282732d9fbba8be613e6bd4e027-sc286df115f7ef1f410d
ERROR error content k525051898caafe39f7bad41c225c0996 depends on missing blob q16ccf282732d9fbba8be613e6bd4e027-sc286df115f7ef1f410d
ERROR error content k56d8ca1fb23e5a264e5bcc88f294d535 depends on missing blob q16ccf282732d9fbba8be613e6bd4e027-sc286df115f7ef1f410d
ERROR error content k57353ca678ada1f36d52657e0ee4bb5f depends on missing blob q16ccf282732d9fbba8be613e6bd4e027-sc286df115f7ef1f410d
ERROR error content k637bcd312d709fe93db258f4df664102 depends on missing blob q16ccf282732d9fbba8be613e6bd4e027-sc286df115f7ef1f410d
ERROR error content k643a6d3af5dd5dcb7a7825adfc3698dd depends on missing blob q16ccf282732d9fbba8be613e6bd4e027-sc286df115f7ef1f410d
ERROR error content k6c1909fefafd6d57bc1d7818bae7b6f0 depends on missing blob q16ccf282732d9fbba8be613e6bd4e027-sc286df115f7ef1f410d
ERROR error content k6d22a19e320a227f369821eb59267d71 depends on missing blob q16ccf282732d9fbba8be613e6bd4e027-sc286df115f7ef1f410d
ERROR error content k6e0140d25350d7016af676200ce37164 depends on missing blob q16ccf282732d9fbba8be613e6bd4e027-sc286df115f7ef1f410d
ERROR error content k6fcbb17e7326909c113edc85b5e6af76 depends on missing blob q16ccf282732d9fbba8be613e6bd4e027-sc286df115f7ef1f410d
ERROR error content k71f72c7221c98805bfcfec4a4d711bf0 depends on missing blob q16ccf282732d9fbba8be613e6bd4e027-sc286df115f7ef1f410d
ERROR error content k83c684de4ccb8b5a291da1a23a670fc3 depends on missing blob q16ccf282732d9fbba8be613e6bd4e027-sc286df115f7ef1f410d
ERROR error content k8429519c7a15064f8549cea3a6dc4c43 depends on missing blob q16ccf282732d9fbba8be613e6bd4e027-sc286df115f7ef1f410d
ERROR error content k8e8079be12bf29f74397d79f13a95d0f depends on missing blob q16ccf282732d9fbba8be613e6bd4e027-sc286df115f7ef1f410d
ERROR error content k8ec4bb6e44cd86cd95e738da92cddcec depends on missing blob q16ccf282732d9fbba8be613e6bd4e027-sc286df115f7ef1f410d
ERROR error content k9783ea7ff12dc002983319dfe92346dd depends on missing blob q16ccf282732d9fbba8be613e6bd4e027-sc286df115f7ef1f410d
ERROR error content ka6b1af6fbd8b0d9839e0bccaac7b74fd depends on missing blob q16ccf282732d9fbba8be613e6bd4e027-sc286df115f7ef1f410d
ERROR error content ka7be80bd3e011fd4cb1f089e61d1012f depends on missing blob q16ccf282732d9fbba8be613e6bd4e027-sc286df115f7ef1f410d
ERROR error content kaf33ae646ae346a482f21ddfa7ae68ff depends on missing blob q16ccf282732d9fbba8be613e6bd4e027-sc286df115f7ef1f410d
ERROR error content kb45351439f9b3d32bd60d516c948243c depends on missing blob q16ccf282732d9fbba8be613e6bd4e027-sc286df115f7ef1f410d
ERROR error content kba257892bd903608cd9091394f00f649 depends on missing blob q16ccf282732d9fbba8be613e6bd4e027-sc286df115f7ef1f410d
ERROR error content kc12ce4478451a4e8271de1040c928c01 depends on missing blob q16ccf282732d9fbba8be613e6bd4e027-sc286df115f7ef1f410d
ERROR error content kcfca75bc2693f1bb0185ce22fcf308e5 depends on missing blob q16ccf282732d9fbba8be613e6bd4e027-sc286df115f7ef1f410d
ERROR error content kd521a9cf117802ac9aed00fd7f2724f7 depends on missing blob q16ccf282732d9fbba8be613e6bd4e027-sc286df115f7ef1f410d
ERROR error content kecbcbd5f44d31491156865ce4e6667db depends on missing blob q16ccf282732d9fbba8be613e6bd4e027-sc286df115f7ef1f410d
ERROR error content kf8c0c01658134995a5803495ebe8c8c7 depends on missing blob q16ccf282732d9fbba8be613e6bd4e027-sc286df115f7ef1f410d
ERROR error content kffd6b7ffb3b34eb184d663fe0ed1f927 depends on missing blob q16ccf282732d9fbba8be613e6bd4e027-sc286df115f7ef1f410d
ERROR error content m1434cacb784f4e66bf1409e89e107a7d depends on missing blob q16ccf282732d9fbba8be613e6bd4e027-sc286df115f7ef1f410d
ERROR error content m1c769e21c88d715017e914bc0a93632f depends on missing blob q16ccf282732d9fbba8be613e6bd4e027-sc286df115f7ef1f410d
ERROR error content m2ea6bf5ae9d88e0b51ecb628e98d59fa depends on missing blob q16ccf282732d9fbba8be613e6bd4e027-sc286df115f7ef1f410d
ERROR error content m38b8a2b4709271fa47ad457d5db3c3d1 depends on missing blob q16ccf282732d9fbba8be613e6bd4e027-sc286df115f7ef1f410d
ERROR error content m490d9a9e7e57b861e0214cf27e2560d8 depends on missing blob q16ccf282732d9fbba8be613e6bd4e027-sc286df115f7ef1f410d
ERROR error content m4bfa306d54e5049f43b3f96a57601763 depends on missing blob q16ccf282732d9fbba8be613e6bd4e027-sc286df115f7ef1f410d
ERROR error content m6fd7cc47c5768b3672074bfee4419c41 depends on missing blob q16ccf282732d9fbba8be613e6bd4e027-sc286df115f7ef1f410d
ERROR error content m7afc86df365eabe46bfd6a5e6bfefd5d depends on missing blob q16ccf282732d9fbba8be613e6bd4e027-sc286df115f7ef1f410d
ERROR error content m85a2ee47e46557367f9cf3ba4915a8eb depends on missing blob q16ccf282732d9fbba8be613e6bd4e027-sc286df115f7ef1f410d
ERROR error content m97ddebbda7913c8e113fd888303c0b2e depends on missing blob q16ccf282732d9fbba8be613e6bd4e027-sc286df115f7ef1f410d
ERROR error content ma6efb0804e39b88491d469283bf759bb depends on missing blob q16ccf282732d9fbba8be613e6bd4e027-sc286df115f7ef1f410d
ERROR error content x340361c695b85f37bee5b61250e152d5 depends on missing blob q16ccf282732d9fbba8be613e6bd4e027-sc286df115f7ef1f410d
Finished verifying 104765 contents, found 59 errors.
ERROR encountered 59 errors

@jens-f
Copy link

jens-f commented May 14, 2022

I was able to fix the issue. Below is a sequence of steps I attempted to fix it:

Created missing blob files (see errors above) in remote repository

touch <repo>/pa7/42b/5d1e6103c032d4f8ff9f7068b09-s056736796ffd148110c.f
touch <repo>/q16/ccf/282732d9fbba8be613e6bd4e027-sc286df115f7ef1f410d.f

Ran

$  kopia manifest list

This resulted in the new error message:

ERROR unable to find manifests: unable to load manifest contents: error loading manifest content: error getting cached content: invalid (offset=3531425,length=641) for blob "q16ccf282732d9fbba8be613e6bd4e027-sc286df115f7ef1f410d" of size 0

While this didn't fix the issue, it provided a confirmation in the error message which blobs are being accessed.

Then inspected the logfile for the command (see https://kopia.io/docs/advanced/logging/).
Opened:

~/Library/Logs/kopia/content-logs/kopia-20220514-063650-85951-manifest-list.0.log

The content looks like this:

2022-05-14T05:38:14.766544Z refreshing committed state because it's no longer valid (now 2022-05-13 22:38:14.766528 -0700 PDT, valid until 0001-01-01T00:00:00Z)
2022-05-14T05:38:14.767331Z refreshAttemptLocked
2022-05-14T05:38:16.182017Z ranges: [{xr0_7_7659d223598e7ba4b1b4d9e71df9817d-s2d0a0a8927491a27-c1 3624814 2022-04-29 00:30:01 +0000 GMT}]
2022-05-14T05:38:38.595392Z current epoch 9, uncompacted epoch sets 20 0 0, valid until 2022-05-13T22:58:14.76733-07:00
2022-05-14T05:38:38.595438Z not generating range checkpoint
2022-05-14T05:38:38.595512Z adding incremental state for epochs 8..10 on top of [{xr0_7_7659d223598e7ba4b1b4d9e71df9817d-s2d0a0a8927491a27-c1 3624814 2022-04-29 00:30:01 +0000 GMT}]
2022-05-14T05:38:38.595639Z Complete Index Set for [0..10]: [xr0_7_7659d223598e7ba4b1b4d9e71df9817d-s2d0a0a8927491a27-c1 xn8_ff34e0c6b3ef03ef42ca025a7a67c840-s123b74bdcc1121b810e-c1 xn8_eb52fd878fd69e2dcd18ece32cddd4c3-se8f932bb77f3dcb210f-c1 xn8_deefa4b3a506326ba3a1fd98e64d753e-sd55ff5a9f02bb6cb10f-c1 xn8_b81b515329f490c1ef34f02730463838-sc0dbc547d656fd3b10f-c1 xn8_afba5dfafed37cf46c13a4147d813e02-s54ab105c9532ccca10f-c1 xn8_ae47ba68d49a934aa64b332773f3cac1-sfbeaa52db2c42a2b10f-c1 xn8_ad21006e1934dfb48b09c540e35e422e-s136c67d3e27487b310f-c1 xn8_a86983a9e86470494a6aaa4c68735438-s0da5db7967d3f58310f-c1 xn8_a21c22cca52f5eaa2e4c29b9eebda2d1-sb95ad4cf637eaa1110f-c1 xn8_a11c2efe4ee915d0d479134f5c427a25-sdb1c7f0401e6961810f-c1 xn8_914d6eb76f17a1320a4ab4f51db95245-s382108ce8136e1a110f-c1 xn8_7e7facb5b4ec08751a064a00f2308e18-s24673ee70ce7272710f-c1 xn8_67354b048b229127dde1a97c16dc0dbd-s07b016d89c269eb710f-c1 xn8_5ac341f46576622fc2edcbd58be15b28-sedc386780da4ef5710f-c1 xn8_4b66cd6cac33d510574318cebd32037a-s57c50e1272c6bdb710f-c1 xn8_3ca4e2b665cd8a6af0bd09088a48712b-s14a022fdcad70a1610f-c1 xn8_206a7d056f01af1b4622111f20dc6e4d-s1e51c08fdb80284710f-c1 xn8_1fb6a11ff254035abb56ea78ffbcbe34-sedcbff48d2b032f110f-c1 xn8_028e8429529307494c2d2ababbca19b9-s3c2a69a5b54f2a6e10f-c1 xn8_00139e5e560beb464ede55be74a7b20a-sf7c54f7729a5972610f-c1], deletion watermark 2022-04-22 21:25:20 -0700 PDT
2022-05-14T05:38:38.595755Z active indexes [xr0_7_7659d223598e7ba4b1b4d9e71df9817d-s2d0a0a8927491a27-c1 xn8_ff34e0c6b3ef03ef42ca025a7a67c840-s123b74bdcc1121b810e-c1 xn8_eb52fd878fd69e2dcd18ece32cddd4c3-se8f932bb77f3dcb210f-c1 xn8_deefa4b3a506326ba3a1fd98e64d753e-sd55ff5a9f02bb6cb10f-c1 xn8_b81b515329f490c1ef34f02730463838-sc0dbc547d656fd3b10f-c1 xn8_afba5dfafed37cf46c13a4147d813e02-s54ab105c9532ccca10f-c1 xn8_ae47ba68d49a934aa64b332773f3cac1-sfbeaa52db2c42a2b10f-c1 xn8_ad21006e1934dfb48b09c540e35e422e-s136c67d3e27487b310f-c1 xn8_a86983a9e86470494a6aaa4c68735438-s0da5db7967d3f58310f-c1 xn8_a21c22cca52f5eaa2e4c29b9eebda2d1-sb95ad4cf637eaa1110f-c1 xn8_a11c2efe4ee915d0d479134f5c427a25-sdb1c7f0401e6961810f-c1 xn8_914d6eb76f17a1320a4ab4f51db95245-s382108ce8136e1a110f-c1 xn8_7e7facb5b4ec08751a064a00f2308e18-s24673ee70ce7272710f-c1 xn8_67354b048b229127dde1a97c16dc0dbd-s07b016d89c269eb710f-c1 xn8_5ac341f46576622fc2edcbd58be15b28-sedc386780da4ef5710f-c1 xn8_4b66cd6cac33d510574318cebd32037a-s57c50e1272c6bdb710f-c1 xn8_3ca4e2b665cd8a6af0bd09088a48712b-s14a022fdcad70a1610f-c1 xn8_206a7d056f01af1b4622111f20dc6e4d-s1e51c08fdb80284710f-c1 xn8_1fb6a11ff254035abb56ea78ffbcbe34-sedcbff48d2b032f110f-c1 xn8_028e8429529307494c2d2ababbca19b9-s3c2a69a5b54f2a6e10f-c1 xn8_00139e5e560beb464ede55be74a7b20a-sf7c54f7729a5972610f-c1] deletion watermark 2022-04-22 21:25:20 -0700 PDT
2022-05-14T05:38:38.596148Z use-indexes [xr0_7_7659d223598e7ba4b1b4d9e71df9817d-s2d0a0a8927491a27-c1 xn8_ff34e0c6b3ef03ef42ca025a7a67c840-s123b74bdcc1121b810e-c1 xn8_eb52fd878fd69e2dcd18ece32cddd4c3-se8f932bb77f3dcb210f-c1 xn8_deefa4b3a506326ba3a1fd98e64d753e-sd55ff5a9f02bb6cb10f-c1 xn8_b81b515329f490c1ef34f02730463838-sc0dbc547d656fd3b10f-c1 xn8_afba5dfafed37cf46c13a4147d813e02-s54ab105c9532ccca10f-c1 xn8_ae47ba68d49a934aa64b332773f3cac1-sfbeaa52db2c42a2b10f-c1 xn8_ad21006e1934dfb48b09c540e35e422e-s136c67d3e27487b310f-c1 xn8_a86983a9e86470494a6aaa4c68735438-s0da5db7967d3f58310f-c1 xn8_a21c22cca52f5eaa2e4c29b9eebda2d1-sb95ad4cf637eaa1110f-c1 xn8_a11c2efe4ee915d0d479134f5c427a25-sdb1c7f0401e6961810f-c1 xn8_914d6eb76f17a1320a4ab4f51db95245-s382108ce8136e1a110f-c1 xn8_7e7facb5b4ec08751a064a00f2308e18-s24673ee70ce7272710f-c1 xn8_67354b048b229127dde1a97c16dc0dbd-s07b016d89c269eb710f-c1 xn8_5ac341f46576622fc2edcbd58be15b28-sedc386780da4ef5710f-c1 xn8_4b66cd6cac33d510574318cebd32037a-s57c50e1272c6bdb710f-c1 xn8_3ca4e2b665cd8a6af0bd09088a48712b-s14a022fdcad70a1610f-c1 xn8_206a7d056f01af1b4622111f20dc6e4d-s1e51c08fdb80284710f-c1 xn8_1fb6a11ff254035abb56ea78ffbcbe34-sedcbff48d2b032f110f-c1 xn8_028e8429529307494c2d2ababbca19b9-s3c2a69a5b54f2a6e10f-c1 xn8_00139e5e560beb464ede55be74a7b20a-sf7c54f7729a5972610f-c1]
2022-05-14T05:38:38.600561Z combined 21 into 8 index segments
2022-05-14T05:38:38.600636Z expireUnused {"except": ["xr0_7_7659d223598e7ba4b1b4d9e71df9817d-s2d0a0a8927491a27-c1","xn8_ff34e0c6b3ef03ef42ca025a7a67c840-s123b74bdcc1121b810e-c1","xn8_eb52fd878fd69e2dcd18ece32cddd4c3-se8f932bb77f3dcb210f-c1","xn8_deefa4b3a506326ba3a1fd98e64d753e-sd55ff5a9f02bb6cb10f-c1","xn8_b81b515329f490c1ef34f02730463838-sc0dbc547d656fd3b10f-c1","xn8_afba5dfafed37cf46c13a4147d813e02-s54ab105c9532ccca10f-c1","xn8_ae47ba68d49a934aa64b332773f3cac1-sfbeaa52db2c42a2b10f-c1","xn8_ad21006e1934dfb48b09c540e35e422e-s136c67d3e27487b310f-c1","xn8_a86983a9e86470494a6aaa4c68735438-s0da5db7967d3f58310f-c1","xn8_a21c22cca52f5eaa2e4c29b9eebda2d1-sb95ad4cf637eaa1110f-c1","xn8_a11c2efe4ee915d0d479134f5c427a25-sdb1c7f0401e6961810f-c1","xn8_914d6eb76f17a1320a4ab4f51db95245-s382108ce8136e1a110f-c1","xn8_7e7facb5b4ec08751a064a00f2308e18-s24673ee70ce7272710f-c1","xn8_67354b048b229127dde1a97c16dc0dbd-s07b016d89c269eb710f-c1","xn8_5ac341f46576622fc2edcbd58be15b28-sedc386780da4ef5710f-c1","xn8_4b66cd6cac33d510574318cebd32037a-s57c50e1272c6bdb710f-c1","xn8_3ca4e2b665cd8a6af0bd09088a48712b-s14a022fdcad70a1610f-c1","xn8_206a7d056f01af1b4622111f20dc6e4d-s1e51c08fdb80284710f-c1","xn8_1fb6a11ff254035abb56ea78ffbcbe34-sedcbff48d2b032f110f-c1","xn8_028e8429529307494c2d2ababbca19b9-s3c2a69a5b54f2a6e10f-c1","xn8_00139e5e560beb464ede55be74a7b20a-sf7c54f7729a5972610f-c1"], "minSweepAge": "1h0m0s"}
2022-05-14T05:38:39.963031Z getContentInfoReadLocked(m1434cacb784f4e66bf1409e89e107a7d) error error getting cached content: BLOB not found
2022-05-14T05:38:39.964114Z getContentInfoReadLocked(m38b8a2b4709271fa47ad457d5db3c3d1) error error getting cached content: BLOB not found
2022-05-14T05:38:39.964970Z getContentInfoReadLocked(m1c769e21c88d715017e914bc0a93632f) error error getting cached content: BLOB not found
2022-05-14T05:38:39.965933Z getContentInfoReadLocked(m97ddebbda7913c8e113fd888303c0b2e) error error getting cached content: BLOB not found
2022-05-14T05:38:39.967688Z getContentInfoReadLocked(m2ea6bf5ae9d88e0b51ecb628e98d59fa) error error getting cached content: BLOB not found
2022-05-14T05:38:39.968636Z getContentInfoReadLocked(m490d9a9e7e57b861e0214cf27e2560d8) error error getting cached content: BLOB not found
2022-05-14T05:38:39.969430Z getContentInfoReadLocked(m4bfa306d54e5049f43b3f96a57601763) error error getting cached content: BLOB not found
2022-05-14T05:38:39.970362Z getContentInfoReadLocked(m6fd7cc47c5768b3672074bfee4419c41) error error getting cached content: BLOB not found
2022-05-14T05:38:39.971316Z getContentInfoReadLocked(m7afc86df365eabe46bfd6a5e6bfefd5d) error error getting cached content: BLOB not found
2022-05-14T05:38:39.972135Z getContentInfoReadLocked(m85a2ee47e46557367f9cf3ba4915a8eb) error error getting cached content: BLOB not found
2022-05-14T05:38:39.975018Z getContentInfoReadLocked(ma6efb0804e39b88491d469283bf759bb) error error getting cached content: BLOB not found
2022-05-14T05:38:39.976449Z closing shared manager

Since xr0_7_7659d223598e7ba4b1b4d9e71df9817d-s2d0a0a8927491a27-c1 seems to be the most recent index, inspected it with

kopia index inspect xr0_7_7659d223598e7ba4b1b4d9e71df9817d-s2d0a0a8927491a27-c1   

and it contained references to the missing files.

Following ideas from https://kopia.io/docs/advanced/consistency/

If a repository can’t be opened but was working recently and maintenance has not run yet, it may be helpful to try to remove (or stash away) the most recently-written index files whose names start with x in the reverse timestamp order one by one until the issue is fixed. This will effectively roll back the repository writes to a prior state. Exercise caution when removing the files.

I then decided to remove that index file from the remote repository

rm <repo>/xr0/_7_/7659d223598e7ba4b1b4d9e71df9817d-s2d0a0a8927491a27-c1.f

then re-ran

kopia snapshot create /Users/myuser

and it ran successfully.

@github-actions github-actions bot added the stale label Apr 24, 2023
@github-actions github-actions bot closed this as not planned Won't fix, can't repro, duplicate, stale May 7, 2023
@faevourite
Copy link

Thanks, @jens-f ! Those steps worked for me. In my case I'm using Google Drive via rclone. A possible contribution to the corruption in my case was that for one of the index files there were duplicate directories in GDrive. Apparently it allows two different directories called "0b1" with different contents as siblings. 🙃

image

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
Projects
None yet
Development

No branches or pull requests

6 participants