"./sync.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bYandex: -verbose -fast-list -size-limit 1024 -test.run '^(TestServerSideMove|TestServerSideMoveWithFilter|TestSyncReplaceDirModTime)$'" - Starting (try 2/5) 2025/05/14 03:18:36 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-fowekoy3kizo" 2025/05/14 03:18:36 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/05/14 03:18:36 DEBUG : Creating backend with remote "TestYandex:rclone-test-fowekoy3kizo" 2025/05/14 03:18:36 DEBUG : Creating backend with remote "/tmp/rclone749170831" === RUN TestServerSideMove run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-fowekoy3kizo'", Local "Local file system at /tmp/rclone749170831", Modify Window "1ns" 2025/05/14 03:18:36 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-bisimoj2cuha" 2025/05/14 03:18:36 DEBUG : Creating backend with remote "TestYandex:rclone-test-bisimoj2cuha" 2025/05/14 03:18:45 INFO : potato2.rclone_chunk.001_8k59yi: Moved (server-side) to: potato2.rclone_chunk.001 2025/05/14 03:18:47 INFO : potato2.rclone_chunk.002_8k59yi: Moved (server-side) to: potato2.rclone_chunk.002 2025/05/14 03:18:56 INFO : empty space.rclone_chunk.001_8k5lna: Moved (server-side) to: empty space 2025/05/14 03:19:03 INFO : potato3.rclone_chunk.001_8k5s65: Moved (server-side) to: potato3.rclone_chunk.001 2025/05/14 03:19:04 INFO : potato3.rclone_chunk.002_8k5s65: Moved (server-side) to: potato3.rclone_chunk.002 sync_test.go:1705: Server side move (if possible) Chunked 'TestChunkerChunk50bYandex:rclone-test-fowekoy3kizo' -> Chunked 'TestChunkerChunk50bYandex:rclone-test-bisimoj2cuha' 2025/05/14 03:19:13 INFO : empty space.rclone_chunk.001_8k64lk: Moved (server-side) to: empty space 2025/05/14 03:19:19 INFO : potato3.rclone_chunk.001_8k6980: Moved (server-side) to: potato3.rclone_chunk.001 2025/05/14 03:19:21 INFO : potato3.rclone_chunk.002_8k6980: Moved (server-side) to: potato3.rclone_chunk.002 2025/05/14 03:19:24 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-bisimoj2cuha': Using server-side directory move 2025/05/14 03:19:24 INFO : Chunked 'TestChunkerChunk50bYandex:rclone-test-bisimoj2cuha': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/05/14 03:19:24 DEBUG : potato2: Need to transfer - File not found at Destination 2025/05/14 03:19:24 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/14 03:19:24 DEBUG : empty space: Unchanged skipping 2025/05/14 03:19:24 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/05/14 03:19:24 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-bisimoj2cuha': Waiting for checks to finish 2025/05/14 03:19:25 DEBUG : potato2: move 2 data chunks... 2025/05/14 03:19:26 INFO : potato2.rclone_chunk.001: Moved (server-side) 2025/05/14 03:19:28 INFO : potato2.rclone_chunk.002: Moved (server-side) 2025/05/14 03:19:29 INFO : empty space: Deleted 2025/05/14 03:19:29 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-bisimoj2cuha': Waiting for transfers to finish 2025/05/14 03:19:30 INFO : potato2: Moved (server-side) 2025/05/14 03:19:32 INFO : potato2: Moved (server-side) 2025/05/14 03:19:38 INFO : potato3: Deleted 2025/05/14 03:19:39 DEBUG : potato3: move 2 data chunks... 2025/05/14 03:19:41 INFO : potato3.rclone_chunk.001: Moved (server-side) 2025/05/14 03:19:42 INFO : potato3.rclone_chunk.002: Moved (server-side) 2025/05/14 03:19:43 INFO : potato3: Moved (server-side) 2025/05/14 03:19:46 INFO : potato3: Moved (server-side) 2025/05/14 03:19:48 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-wazuyop8suqe" 2025/05/14 03:19:48 DEBUG : Config file has changed externally - reloading 2025/05/14 03:19:48 DEBUG : Creating backend with remote "TestYandex:rclone-test-wazuyop8suqe" 2025/05/14 03:19:48 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-wazuyop8suqe': Using server-side directory move 2025/05/14 03:19:51 INFO : Chunked 'TestChunkerChunk50bYandex:rclone-test-wazuyop8suqe': Server side directory move succeeded 2025/05/14 03:19:53 ERROR : : error listing: directory not found 2025/05/14 03:19:53 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-wazuyop8suqe': Purge remote 2025/05/14 03:20:06 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-bisimoj2cuha': Purge remote 2025/05/14 03:20:07 NOTICE: purge failed: [404 - DiskNotFoundError] Resource not found. (Не удалось найти запрошенный ресурс.) --- PASS: TestServerSideMove (91.21s) === RUN TestServerSideMoveWithFilter run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-fowekoy3kizo'", Local "Local file system at /tmp/rclone749170831", Modify Window "1ns" 2025/05/14 03:20:07 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-qekayez1jihu" 2025/05/14 03:20:07 DEBUG : Creating backend with remote "TestYandex:rclone-test-qekayez1jihu" 2025/05/14 03:20:16 INFO : potato2.rclone_chunk.001_8k7sp7: Moved (server-side) to: potato2.rclone_chunk.001 2025/05/14 03:20:18 INFO : potato2.rclone_chunk.002_8k7sp7: Moved (server-side) to: potato2.rclone_chunk.002 2025/05/14 03:20:25 INFO : empty space.rclone_chunk.001_8k851z: Moved (server-side) to: empty space 2025/05/14 03:20:33 INFO : potato3.rclone_chunk.001_8k8aa6: Moved (server-side) to: potato3.rclone_chunk.001 2025/05/14 03:20:34 INFO : potato3.rclone_chunk.002_8k8aa6: Moved (server-side) to: potato3.rclone_chunk.002 sync_test.go:1705: Server side move (if possible) Chunked 'TestChunkerChunk50bYandex:rclone-test-fowekoy3kizo' -> Chunked 'TestChunkerChunk50bYandex:rclone-test-qekayez1jihu' 2025/05/14 03:20:42 INFO : empty space.rclone_chunk.001_8k8l2s: Moved (server-side) to: empty space 2025/05/14 03:20:50 INFO : potato3.rclone_chunk.001_8k8q7l: Moved (server-side) to: potato3.rclone_chunk.001 2025/05/14 03:20:52 INFO : potato3.rclone_chunk.002_8k8q7l: Moved (server-side) to: potato3.rclone_chunk.002 2025/05/14 03:20:55 DEBUG : empty space: Excluded (Size Filter) 2025/05/14 03:20:55 DEBUG : empty space: Excluded 2025/05/14 03:20:55 DEBUG : empty space: Excluded (Size Filter) 2025/05/14 03:20:55 DEBUG : empty space: Excluded 2025/05/14 03:20:55 DEBUG : potato2: Need to transfer - File not found at Destination 2025/05/14 03:20:55 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/05/14 03:20:55 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-qekayez1jihu': Waiting for checks to finish 2025/05/14 03:20:55 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-qekayez1jihu': Waiting for transfers to finish 2025/05/14 03:20:56 DEBUG : potato2: move 2 data chunks... 2025/05/14 03:20:57 INFO : potato2.rclone_chunk.001: Moved (server-side) 2025/05/14 03:20:59 INFO : potato2.rclone_chunk.002: Moved (server-side) 2025/05/14 03:21:01 INFO : potato2: Moved (server-side) 2025/05/14 03:21:03 INFO : potato2: Moved (server-side) 2025/05/14 03:21:09 INFO : potato3: Deleted 2025/05/14 03:21:10 DEBUG : potato3: move 2 data chunks... 2025/05/14 03:21:11 INFO : potato3.rclone_chunk.001: Moved (server-side) 2025/05/14 03:21:13 INFO : potato3.rclone_chunk.002: Moved (server-side) 2025/05/14 03:21:15 INFO : potato3: Moved (server-side) 2025/05/14 03:21:17 INFO : potato3: Moved (server-side) 2025/05/14 03:21:19 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-hotepaw1jera" 2025/05/14 03:21:19 DEBUG : Creating backend with remote "TestYandex:rclone-test-hotepaw1jera" 2025/05/14 03:21:20 DEBUG : empty space: Excluded (Size Filter) 2025/05/14 03:21:20 DEBUG : empty space: Excluded 2025/05/14 03:21:20 DEBUG : potato2: Need to transfer - File not found at Destination 2025/05/14 03:21:20 DEBUG : potato3: Need to transfer - File not found at Destination 2025/05/14 03:21:20 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-hotepaw1jera': Waiting for checks to finish 2025/05/14 03:21:20 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-hotepaw1jera': Waiting for transfers to finish 2025/05/14 03:21:20 DEBUG : potato3: move 2 data chunks... 2025/05/14 03:21:21 DEBUG : potato2: move 2 data chunks... 2025/05/14 03:21:22 INFO : potato3.rclone_chunk.001: Moved (server-side) 2025/05/14 03:21:22 INFO : potato2.rclone_chunk.001: Moved (server-side) 2025/05/14 03:21:23 INFO : potato2.rclone_chunk.002: Moved (server-side) 2025/05/14 03:21:23 INFO : potato3.rclone_chunk.002: Moved (server-side) 2025/05/14 03:21:25 INFO : potato2: Moved (server-side) 2025/05/14 03:21:25 INFO : potato3: Moved (server-side) 2025/05/14 03:21:27 INFO : potato2: Moved (server-side) 2025/05/14 03:21:27 INFO : potato3: Moved (server-side) 2025/05/14 03:21:29 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-hotepaw1jera': Purge remote 2025/05/14 03:21:41 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-qekayez1jihu': Purge remote --- PASS: TestServerSideMoveWithFilter (105.77s) === RUN TestSyncReplaceDirModTime run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-fowekoy3kizo'", Local "Local file system at /tmp/rclone749170831", Modify Window "1ns" 2025/05/14 03:21:53 DEBUG : empty_dir: Making directory with metadata 2025/05/14 03:21:53 INFO : empty_dir: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/05/14 03:21:53 DEBUG : empty_on_remote: Making directory with metadata 2025/05/14 03:21:53 INFO : empty_on_remote: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/05/14 03:21:53 INFO : empty_on_remote: Making directory 2025/05/14 03:21:53 INFO : empty_on_remote: Made directory with modification time 2011-12-25 12:59:59.123456789 +0000 UTC run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-fowekoy3kizo'", Local "Local file system at /tmp/rclone749170831", Modify Window "1ns" Diff of "logger" and "lsf" 2d1 < 2011-12-25 12:59:59.123456789;1c1c96fd2cf8330db0bfa936ce82f3b9;5;test_dir1/file2 4a4 > 2025-05-14 03:21:57.000000000;1c1c96fd2cf8330db0bfa936ce82f3b9;5;test_dir1/file2 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;2548729e9c3c60cc3789dfb2408e475d;5;test_dir2/sub_dir/file3 2011-12-25 12:59:59.123456789;826e8142e6baabe8af779f5f490cf5f5;5;file1 2025-05-14 03:21:57.000000000;1c1c96fd2cf8330db0bfa936ce82f3b9;5;test_dir1/file2 Test: TestSyncReplaceDirModTime --- FAIL: TestSyncReplaceDirModTime (49.51s) FAIL 2025/05/14 03:22:42 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-fowekoy3kizo': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bYandex: -verbose -fast-list -size-limit 1024 -test.run '^(TestServerSideMove|TestServerSideMoveWithFilter|TestSyncReplaceDirModTime)$'" - Finished ERROR in 4m11.283071413s (try 2/5): exit status 1: Failed [TestSyncReplaceDirModTime]