"./sync.test -test.v -test.timeout 1h0m0s -remote TestCryptSwift: -verbose -test.run '^(TestSyncAfterRemovingAFileAndAddingAFileSubDir|TestSyncConcurrentTruncate)$'" - Starting (try 3/5)
2025/05/22 03:34:33 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-nifomug2quso"
2025/05/22 03:34:33 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/05/22 03:34:33 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-nifomug2quso.bin"
2025/05/22 03:34:33 DEBUG : Swift container crypt path rclone-test-nifomug2quso.bin: Auto set use_segments_container to true
2025/05/22 03:34:38 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-nifomug2quso"
2025/05/22 03:34:39 DEBUG : Swift container crypt path rclone-test-nifomug2quso: Auto set use_segments_container to true
2025/05/22 03:34:44 DEBUG : Creating backend with remote "/tmp/rclone4096675031"
=== RUN   TestSyncAfterRemovingAFileAndAddingAFileSubDir
    run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-nifomug2quso'", Local "Local file system at /tmp/rclone4096675031", Modify Window "1ns"
2025/05/22 03:34:44 DEBUG : b/potato: md5 = 0d685696d5e15df6077d433f319ac4f9 OK
2025/05/22 03:34:45 DEBUG : c/non empty space: md5 = e2be18c68224792f82edaf24a783b4b0 OK
2025/05/22 03:34:45 INFO  : d: Making directory
2025/05/22 03:34:45 INFO  : d/e: Making directory
    fstest.go:244: Filtering empty directory "d"
    fstest.go:244: Filtering empty directory "d/e"
2025/05/22 03:34:45 DEBUG : a/potato2: Need to transfer - File not found at Destination
2025/05/22 03:34:45 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/22 03:34:45 DEBUG : c/non empty space: Unchanged skipping
2025/05/22 03:34:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nifomug2quso': Waiting for checks to finish
2025/05/22 03:34:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nifomug2quso': Waiting for transfers to finish
2025/05/22 03:34:50 DEBUG : a/potato2: md5 = 27f53801e9340e174c512e8785701b62 OK
2025/05/22 03:34:50 INFO  : a/potato2: Copied (new)
2025/05/22 03:34:50 DEBUG : Waiting for deletions to finish
2025/05/22 03:34:51 INFO  : b/potato: Deleted
    run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-nifomug2quso'", Local "Local file system at /tmp/rclone4096675031", Modify Window "1ns"
--- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDir (12.66s)
=== RUN   TestSyncConcurrentTruncate
    run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-nifomug2quso'", Local "Local file system at /tmp/rclone4096675031", Modify Window "1ns"
2025/05/22 03:34:57 DEBUG : both0: md5 = 00c129ca523e8dfb51f27d5684f0f19e OK
2025/05/22 03:34:57 DEBUG : only0: md5 = 6f8d4637aae4824427dbd4ac44b79c79 OK
2025/05/22 03:35:03 DEBUG : both1: md5 = 3a4003ebb93422d254bc9899a045eefc OK
2025/05/22 03:35:03 DEBUG : only1: md5 = 819ccf45ea5692a58ebea1066278ca1a OK
2025/05/22 03:35:04 DEBUG : both2: md5 = c50a49e5acaf813c4dee68d7ad3c3b58 OK
2025/05/22 03:35:14 DEBUG : only2: md5 = 4bdbf9b387430dabd3fcc2ccfb1cba52 OK
2025/05/22 03:35:19 DEBUG : both3: md5 = acde9e557cb9d5ef5d067f1f8ce6f3fe OK
2025/05/22 03:35:24 DEBUG : only3: md5 = d14ad855f68923c3515676e7841dd277 OK
2025/05/22 03:35:25 DEBUG : both4: md5 = 20431fa29e07c37cd6e5432c73ed5d80 OK
2025/05/22 03:35:25 DEBUG : only4: md5 = 7826d82e067b5a6b57cc88f4114d8fba OK
2025/05/22 03:35:31 DEBUG : both5: md5 = 0a0a98d940f24d5e4b1f136d9a31e7e7 OK
2025/05/22 03:35:36 DEBUG : only5: md5 = 82f5c0d16566fc197961d34e13a71f1c OK
2025/05/22 03:35:42 DEBUG : both6: md5 = a2942bb50920a547c334d51f01f34a5d OK
2025/05/22 03:35:42 DEBUG : only6: md5 = e3bbdc22f642bdf32b80b87a28bcf552 OK
2025/05/22 03:35:48 DEBUG : both7: md5 = f678de7b1bb9aff665285216c94fd704 OK
2025/05/22 03:35:48 DEBUG : only7: md5 = c54c834e4e4a44f192e21b14e49f12c9 OK
2025/05/22 03:35:48 DEBUG : both8: md5 = ae332b439854d7380348ab39cb1bfcaf OK
2025/05/22 03:35:48 DEBUG : only8: md5 = e74a1ee60b2bd00b3dea69ace973b49c OK
2025/05/22 03:35:54 DEBUG : both9: md5 = 4f7b13e5b2a671f73cbf075c904be99e OK
2025/05/22 03:35:55 DEBUG : only9: md5 = e3259fbe498e79be195c6af521d238f4 OK
2025/05/22 03:35:55 DEBUG : both10: md5 = 96bfeaa961757626c7200f17022e1afb OK
2025/05/22 03:36:00 DEBUG : only10: md5 = f7acf672e1b355c5460858cbbc00c9fd OK
2025/05/22 03:36:01 DEBUG : both11: md5 = ba5d5e2728f2de032b1d7cfdd91db178 OK
2025/05/22 03:36:06 DEBUG : only11: md5 = c15594472f709fc9f63290370e22ebd2 OK
2025/05/22 03:36:19 DEBUG : both12: md5 = a9357d4295b015de68672cf9a486e3cd OK
2025/05/22 03:36:28 DEBUG : only12: md5 = c346e8af8e1a7952477c08b4f9f30ff7 OK
2025/05/22 03:36:36 DEBUG : both13: md5 = e22f4d8ac7e55a41b21585a91caaf7d6 OK
2025/05/22 03:36:44 DEBUG : only13: md5 = 31d7dcbd81102fbf4a3c6f2a1d685e9b OK
2025/05/22 03:36:57 DEBUG : both14: md5 = 7f2f532fa38745bdb281cbc3d5d89859 OK
2025/05/22 03:37:11 DEBUG : only14: md5 = cb4aa4dd36a2b066c3e21bef34bc8f34 OK
2025/05/22 03:37:14 DEBUG : both15: md5 = 956b400f71497f8dc2d6a31524c16f86 OK
2025/05/22 03:37:22 DEBUG : only15: md5 = 2d991bdc91aec6149e413d2461136d34 OK
2025/05/22 03:37:33 DEBUG : both16: md5 = e4c996313f253db42160e7edd036b334 OK
2025/05/22 03:37:38 DEBUG : only16: md5 = 307364929dc03ebd32b0cba020c5a79a OK
2025/05/22 03:37:38 DEBUG : both17: md5 = e75e4abb407561ee3e87ab748477ba16 OK
2025/05/22 03:37:43 DEBUG : only17: md5 = cff08b2db348c2ee10286423f23f6bc3 OK
2025/05/22 03:37:44 DEBUG : both18: md5 = a46521ed73d2c894fc2c34cb32ec1a31 OK
2025/05/22 03:37:54 DEBUG : only18: md5 = 9f9e99b8b9a94053f098b0a65bf7ba6d OK
2025/05/22 03:37:54 DEBUG : both19: md5 = 14f8d2f29bbd96029c946d3c3fdba519 OK
2025/05/22 03:38:00 DEBUG : only19: md5 = 3a997c37dae992659913ad8092810c61 OK
    fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3
    fstest.go:286: Sleeping for 6s just to make sure
2025/05/22 03:38:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nifomug2quso': Waiting for checks to finish
2025/05/22 03:38:44 DEBUG : both11: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/22 03:38:44 DEBUG : both11: Unchanged skipping
2025/05/22 03:38:44 DEBUG : both10: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/22 03:38:44 DEBUG : both10: Unchanged skipping
2025/05/22 03:38:44 DEBUG : both0: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/22 03:38:44 DEBUG : both0: Unchanged skipping
2025/05/22 03:38:44 DEBUG : both12: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/22 03:38:44 DEBUG : both12: Unchanged skipping
2025/05/22 03:38:44 DEBUG : both14: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/22 03:38:44 DEBUG : both14: Unchanged skipping
2025/05/22 03:38:44 DEBUG : both15: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/22 03:38:44 DEBUG : both15: Unchanged skipping
2025/05/22 03:38:44 DEBUG : both16: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/22 03:38:44 DEBUG : both16: Unchanged skipping
2025/05/22 03:38:44 DEBUG : both17: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/22 03:38:44 DEBUG : both17: Unchanged skipping
2025/05/22 03:38:44 DEBUG : both18: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/22 03:38:44 DEBUG : both18: Unchanged skipping
2025/05/22 03:38:44 DEBUG : both19: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/22 03:38:44 DEBUG : both19: Unchanged skipping
2025/05/22 03:38:44 DEBUG : both2: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/22 03:38:44 DEBUG : both2: Unchanged skipping
2025/05/22 03:38:44 DEBUG : both3: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/22 03:38:44 DEBUG : both3: Unchanged skipping
2025/05/22 03:38:44 DEBUG : both4: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/22 03:38:44 DEBUG : both4: Unchanged skipping
2025/05/22 03:38:44 DEBUG : both5: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/22 03:38:44 DEBUG : both5: Unchanged skipping
2025/05/22 03:38:44 DEBUG : both6: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/22 03:38:44 DEBUG : both6: Unchanged skipping
2025/05/22 03:38:44 DEBUG : both7: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/22 03:38:44 DEBUG : both7: Unchanged skipping
2025/05/22 03:38:44 DEBUG : both8: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/22 03:38:44 DEBUG : both8: Unchanged skipping
2025/05/22 03:38:44 DEBUG : only0: Sizes differ (src 0 vs dst 6)
2025/05/22 03:38:44 DEBUG : only1: Sizes differ (src 0 vs dst 6)
2025/05/22 03:38:44 DEBUG : only10: Sizes differ (src 0 vs dst 6)
2025/05/22 03:38:44 DEBUG : only11: Sizes differ (src 0 vs dst 6)
2025/05/22 03:38:44 DEBUG : only12: Sizes differ (src 0 vs dst 6)
2025/05/22 03:38:44 DEBUG : only13: Sizes differ (src 0 vs dst 6)
2025/05/22 03:38:44 DEBUG : only14: Sizes differ (src 0 vs dst 6)
2025/05/22 03:38:44 DEBUG : only15: Sizes differ (src 0 vs dst 6)
2025/05/22 03:38:44 DEBUG : only16: Sizes differ (src 0 vs dst 6)
2025/05/22 03:38:44 DEBUG : only17: Sizes differ (src 0 vs dst 6)
2025/05/22 03:38:44 DEBUG : only18: Sizes differ (src 0 vs dst 6)
2025/05/22 03:38:44 DEBUG : only19: Sizes differ (src 0 vs dst 6)
2025/05/22 03:38:44 DEBUG : only2: Sizes differ (src 0 vs dst 6)
2025/05/22 03:38:44 DEBUG : only3: Sizes differ (src 0 vs dst 6)
2025/05/22 03:38:44 DEBUG : only4: Sizes differ (src 0 vs dst 6)
2025/05/22 03:38:44 DEBUG : only5: Sizes differ (src 0 vs dst 6)
2025/05/22 03:38:44 DEBUG : only6: Sizes differ (src 0 vs dst 6)
2025/05/22 03:38:44 DEBUG : only7: Sizes differ (src 0 vs dst 6)
2025/05/22 03:38:44 DEBUG : only8: Sizes differ (src 0 vs dst 6)
2025/05/22 03:38:44 DEBUG : only9: Sizes differ (src 0 vs dst 6)
2025/05/22 03:38:44 DEBUG : both9: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/22 03:38:44 DEBUG : both9: Unchanged skipping
2025/05/22 03:38:45 DEBUG : only1: md5 = 884725048bd61b021165a5780bf750d7 OK
2025/05/22 03:38:45 INFO  : only1: Copied (replaced existing)
2025/05/22 03:38:45 DEBUG : only0: md5 = d639501a79f36395eb1b5ec210cdafb1 OK
2025/05/22 03:38:45 INFO  : only0: Copied (replaced existing)
2025/05/22 03:38:49 DEBUG : both1: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/22 03:38:49 DEBUG : both1: Unchanged skipping
2025/05/22 03:38:49 DEBUG : both13: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/22 03:38:49 DEBUG : both13: Unchanged skipping
2025/05/22 03:38:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nifomug2quso': Waiting for transfers to finish
2025/05/22 03:38:50 DEBUG : only10: md5 = f4d64ceb2d6efe14ae0fcad4e6842c29 OK
2025/05/22 03:38:50 INFO  : only10: Copied (replaced existing)
2025/05/22 03:38:50 DEBUG : only11: md5 = f2e8589235e9a6abcedfc8783ee9a566 OK
2025/05/22 03:38:50 INFO  : only11: Copied (replaced existing)
2025/05/22 03:38:50 DEBUG : only12: md5 = 200d9d45c502893870a82294add9c78e OK
2025/05/22 03:38:50 INFO  : only12: Copied (replaced existing)
2025/05/22 03:38:55 DEBUG : only15: md5 = af3a41425f386eca62d48d1b037282d6 OK
2025/05/22 03:38:55 INFO  : only15: Copied (replaced existing)
2025/05/22 03:38:55 DEBUG : only13: md5 = 7b7cab3f5af8c3a614c4e5c0c5d19b22 OK
2025/05/22 03:38:55 INFO  : only13: Copied (replaced existing)
2025/05/22 03:38:55 DEBUG : only16: md5 = 3c80844a524953832cdd1c61593c5550 OK
2025/05/22 03:38:55 INFO  : only16: Copied (replaced existing)
2025/05/22 03:39:00 DEBUG : only14: md5 = 809a1488d5df34c75ad0140947b55925 OK
2025/05/22 03:39:00 INFO  : only14: Copied (replaced existing)
2025/05/22 03:39:00 DEBUG : only17: md5 = 56f33e91a3fec8cbb4e19c7a28f634e9 OK
2025/05/22 03:39:00 INFO  : only17: Copied (replaced existing)
2025/05/22 03:39:00 DEBUG : only18: md5 = 52031a269f7c43b6351e0ab4587c6c9a OK
2025/05/22 03:39:00 INFO  : only18: Copied (replaced existing)
2025/05/22 03:39:00 DEBUG : only4: md5 = 29522739de7fc2bf74e0c857b89c41be OK
2025/05/22 03:39:00 INFO  : only4: Copied (replaced existing)
2025/05/22 03:39:05 DEBUG : only3: md5 = 305770a51186cf5dde486b91331e525a OK
2025/05/22 03:39:05 INFO  : only3: Copied (replaced existing)
2025/05/22 03:39:05 DEBUG : only19: md5 = 29fba18afd00ef047df96397ca12b983 OK
2025/05/22 03:39:05 INFO  : only19: Copied (replaced existing)
2025/05/22 03:39:05 DEBUG : only6: md5 = 2e5ce34b1a3238462bea45f2b058a079 OK
2025/05/22 03:39:05 INFO  : only6: Copied (replaced existing)
2025/05/22 03:39:05 DEBUG : only7: md5 = 0042e25933c443d7fe661c01ac63783d OK
2025/05/22 03:39:05 INFO  : only7: Copied (replaced existing)
2025/05/22 03:39:05 DEBUG : only5: md5 = f033f547c028e9fa430aac5f96d239ca OK
2025/05/22 03:39:05 INFO  : only5: Copied (replaced existing)
2025/05/22 03:39:06 DEBUG : only8: md5 = c4fd210c8426c69028a1545487614fc7 OK
2025/05/22 03:39:06 INFO  : only8: Copied (replaced existing)
2025/05/22 03:39:06 DEBUG : only9: md5 = e60a3eeba9a6f50c457a361ec20e91e4 OK
2025/05/22 03:39:06 INFO  : only9: Copied (replaced existing)
2025/05/22 03:39:10 DEBUG : only2: md5 = 696c3c9b8a52c927831b88e81a3207bc OK
2025/05/22 03:39:10 INFO  : only2: Copied (replaced existing)
2025/05/22 03:39:10 DEBUG : Waiting for deletions to finish
    run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-nifomug2quso'", Local "Local file system at /tmp/rclone4096675031", Modify Window "1ns"
--- PASS: TestSyncConcurrentTruncate (442.27s)
PASS
2025/05/22 03:42:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nifomug2quso': Purge remote
2025/05/22 03:42:19 DEBUG : Waiting for deletions to finish
"./sync.test -test.v -test.timeout 1h0m0s -remote TestCryptSwift: -verbose -test.run '^(TestSyncAfterRemovingAFileAndAddingAFileSubDir|TestSyncConcurrentTruncate)$'" - Finished OK in 7m46.337906535s (try 3/5)