"./sync.test -test.v -test.timeout 1h0m0s -remote TestYandex: -verbose -test.run '^(TestServerSideCopyOverSelf|TestSyncBackupDirWithSuffixKeepExtension|TestSyncReplaceDirModTime)$'" - Starting (try 2/5) 2025/05/22 01:43:28 DEBUG : Creating backend with remote "TestYandex:rclone-test-sidamiy0remi" 2025/05/22 01:43:28 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/05/22 01:43:28 DEBUG : Creating backend with remote "/tmp/rclone1572527481" === RUN TestServerSideCopyOverSelf run.go:180: Remote "Yandex rclone-test-sidamiy0remi", Local "Local file system at /tmp/rclone1572527481", Modify Window "1ns" 2025/05/22 01:43:32 DEBUG : Creating backend with remote "TestYandex:rclone-test-wuqaqir9wiku" 2025/05/22 01:43:32 DEBUG : Config file has changed externally - reloading sync_test.go:626: Server side copy (if possible) Yandex rclone-test-sidamiy0remi -> Yandex rclone-test-wuqaqir9wiku 2025/05/22 01:43:33 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/05/22 01:43:33 DEBUG : Yandex rclone-test-wuqaqir9wiku: Waiting for checks to finish 2025/05/22 01:43:33 DEBUG : Yandex rclone-test-wuqaqir9wiku: Waiting for transfers to finish 2025/05/22 01:43:35 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/05/22 01:43:35 INFO : sub dir/hello world: Copied (server-side copy) run.go:180: Remote "Yandex rclone-test-sidamiy0remi", Local "Local file system at /tmp/rclone1572527481", Modify Window "1ns" 2025/05/22 01:43:39 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/05/22 01:43:39 DEBUG : Yandex rclone-test-wuqaqir9wiku: Waiting for checks to finish 2025/05/22 01:43:39 DEBUG : Yandex rclone-test-wuqaqir9wiku: Waiting for transfers to finish 2025/05/22 01:43:40 DEBUG : sub dir/hello world: server side copy: renaming existing object to "sub dir/hello world.denuwad6" before starting 2025/05/22 01:43:43 DEBUG : sub dir/hello world.denuwad6: server side copy: removing renamed existing file after operation 2025/05/22 01:43:47 DEBUG : sub dir/hello world: md5 = 00321a507bdb420dbbd4f53c733b1505 OK 2025/05/22 01:43:47 INFO : sub dir/hello world: Copied (server-side copy) run.go:180: Remote "Yandex rclone-test-sidamiy0remi", Local "Local file system at /tmp/rclone1572527481", Modify Window "1ns" 2025/05/22 01:43:48 DEBUG : Yandex rclone-test-wuqaqir9wiku: Purge remote --- PASS: TestServerSideCopyOverSelf (39.96s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:180: Remote "Yandex rclone-test-sidamiy0remi", Local "Local file system at /tmp/rclone1572527481", Modify Window "1ns" 2025/05/22 01:44:15 DEBUG : Creating backend with remote "TestYandex:rclone-test-sidamiy0remi/dst" 2025/05/22 01:44:15 DEBUG : Creating backend with remote "TestYandex:rclone-test-sidamiy0remi/backup" 2025/05/22 01:44:16 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 01:44:16 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/05/22 01:44:16 DEBUG : two: Unchanged skipping 2025/05/22 01:44:16 DEBUG : Yandex rclone-test-sidamiy0remi/dst: Waiting for checks to finish 2025/05/22 01:44:18 INFO : one: Moved (server-side) to: one-2019-01-01 2025/05/22 01:44:18 DEBUG : Yandex rclone-test-sidamiy0remi/dst: Waiting for transfers to finish 2025/05/22 01:44:20 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2025/05/22 01:44:20 INFO : one: Copied (new) 2025/05/22 01:44:20 DEBUG : Waiting for deletions to finish 2025/05/22 01:44:22 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2025/05/22 01:44:22 INFO : three.txt: Moved into backup dir 2025/05/22 01:44:26 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/05/22 01:44:26 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 01:44:26 DEBUG : two: Unchanged skipping 2025/05/22 01:44:26 DEBUG : Yandex rclone-test-sidamiy0remi/dst: Waiting for checks to finish 2025/05/22 01:44:31 INFO : one-2019-01-01: Deleted 2025/05/22 01:44:32 INFO : one: Moved (server-side) to: one-2019-01-01 2025/05/22 01:44:32 DEBUG : Yandex rclone-test-sidamiy0remi/dst: Waiting for transfers to finish 2025/05/22 01:44:35 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2025/05/22 01:44:35 INFO : one: Copied (new) 2025/05/22 01:44:35 DEBUG : Waiting for deletions to finish 2025/05/22 01:44:39 INFO : three-2019-01-01.txt: Deleted 2025/05/22 01:44:40 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2025/05/22 01:44:40 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffixKeepExtension (61.68s) === RUN TestSyncReplaceDirModTime run.go:180: Remote "Yandex rclone-test-sidamiy0remi", Local "Local file system at /tmp/rclone1572527481", Modify Window "1ns" 2025/05/22 01:45:10 DEBUG : empty_dir: Making directory with metadata 2025/05/22 01:45:10 INFO : empty_dir: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/05/22 01:45:10 DEBUG : empty_on_remote: Making directory with metadata 2025/05/22 01:45:10 INFO : empty_on_remote: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/05/22 01:45:10 INFO : empty_on_remote: Making directory 2025/05/22 01:45:10 INFO : empty_on_remote: Made directory with modification time 2011-12-25 12:59:59.123456789 +0000 UTC run.go:180: Remote "Yandex rclone-test-sidamiy0remi", Local "Local file system at /tmp/rclone1572527481", Modify Window "1ns" Diff of "logger" and "lsf" 3d2 < 2011-12-25 12:59:59.123456789;2548729e9c3c60cc3789dfb2408e475d;5;test_dir2/sub_dir/file3 4a4 > 2025-05-22 01:45:13.000000000;2548729e9c3c60cc3789dfb2408e475d;5;test_dir2/sub_dir/file3 sync_test.go:3072: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:3072 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2800 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2833 Error: Received unexpected error: logger does not match lsf! logger: 2011-12-25 12:59:59.123456789;1c1c96fd2cf8330db0bfa936ce82f3b9;5;test_dir1/file2 2011-12-25 12:59:59.123456789;2548729e9c3c60cc3789dfb2408e475d;5;test_dir2/sub_dir/file3 2011-12-25 12:59:59.123456789;826e8142e6baabe8af779f5f490cf5f5;5;file1 lsf: 2011-12-25 12:59:59.123456789;1c1c96fd2cf8330db0bfa936ce82f3b9;5;test_dir1/file2 2011-12-25 12:59:59.123456789;826e8142e6baabe8af779f5f490cf5f5;5;file1 2025-05-22 01:45:13.000000000;2548729e9c3c60cc3789dfb2408e475d;5;test_dir2/sub_dir/file3 Test: TestSyncReplaceDirModTime --- FAIL: TestSyncReplaceDirModTime (43.98s) FAIL 2025/05/22 01:45:54 DEBUG : Yandex rclone-test-sidamiy0remi: Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestYandex: -verbose -test.run '^(TestServerSideCopyOverSelf|TestSyncBackupDirWithSuffixKeepExtension|TestSyncReplaceDirModTime)$'" - Finished ERROR in 2m30.761053693s (try 2/5): exit status 1: Failed [TestSyncReplaceDirModTime]