"./operations.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestApplyTransforms|TestCheck|TestCheckSum|TestCopyFileBackupDir|TestCopyFileCompareDest|TestCopyFileMaxTransfer|TestCopyURL|TestDirMove|TestLsLong|TestMaxDeleteSizeLargeFile|TestMoveFileBackupDir|TestParseSumFile|TestPurge|TestRmdirsLeaveRoot|TestRmdirsNoLeaveRoot|TestRmdirsWithFilter|TestTouchDir)$|^TestCheckSizeOnly$/^(3|4)$'" - Starting (try 2/5) 2025/09/30 01:15:37 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-wepigip1sada" 2025/09/30 01:15:37 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/09/30 01:15:38 DEBUG : Starting OpenDrive session with ID: 333d39765fc65ae77a2db2fb31be5e138543abf9cfa9acf4261c9c550f9dd80b 2025/09/30 01:15:38 DEBUG : Creating backend with remote "/tmp/rclone565059359" === RUN TestCheck run.go:180: Remote "OpenDrive root 'rclone-test-wepigip1sada'", Local "Local file system at /tmp/rclone565059359", Modify Window "1s" 2025/09/30 01:15:39 DEBUG : rutabaga: Uploading chunk 0, size=8, remain=0 === RUN TestCheck/1 === RUN TestCheck/2 2025/09/30 01:15:42 DEBUG : empty space: Uploading chunk 0, size=1, remain=0 === NAME TestCheck run.go:285: Failed to put "empty space" to "OpenDrive root 'rclone-test-wepigip1sada'": failed to get fileinfo: File was not found (Error 404) fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:173 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:388 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 /usr/local/go/src/runtime/panic.go:636 /usr/local/go/src/testing/testing.go:1041 /usr/local/go/src/testing/testing.go:1125 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:285 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:292 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:121 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:181 Error: Should be true Test: TestCheck Messages: listing wrong, want got empty space (1) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:173 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:388 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 /usr/local/go/src/runtime/panic.go:636 /usr/local/go/src/testing/testing.go:1041 /usr/local/go/src/testing/testing.go:1125 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:285 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:292 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:121 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:181 Error: Should be true Test: TestCheck Messages: Unexpected file "empty space" --- FAIL: TestCheck (12.98s) --- PASS: TestCheck/1 (0.18s) --- PASS: TestCheck/2 (0.18s) === RUN TestCheckSizeOnly run.go:180: Remote "OpenDrive root 'rclone-test-wepigip1sada'", Local "Local file system at /tmp/rclone565059359", Modify Window "1s" 2025/09/30 01:15:52 DEBUG : rutabaga: Uploading chunk 0, size=8, remain=0 fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:99 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:181 Error: Should be true Test: TestCheckSizeOnly Messages: listing wrong, want rutabaga (8) got empty space (1), rutabaga (8) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:99 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:181 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:212 Error: Should be true Test: TestCheckSizeOnly Messages: Unexpected file "empty space" 2025/09/30 01:16:01 DEBUG : empty space: Uploading chunk 0, size=1, remain=0 === RUN TestCheckSizeOnly/3 2025/09/30 01:16:03 DEBUG : potato2: Uploading chunk 0, size=60, remain=0 === NAME TestCheckSizeOnly run.go:285: Failed to put "potato2" to "OpenDrive root 'rclone-test-wepigip1sada'": failed to get fileinfo: File was not found (Error 404) fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:173 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:388 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 /usr/local/go/src/runtime/panic.go:636 /usr/local/go/src/testing/testing.go:1041 /usr/local/go/src/testing/testing.go:1125 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:285 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:292 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:134 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:181 Error: Should be true Test: TestCheckSizeOnly Messages: listing wrong, want got potato2 (60) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:173 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:388 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 /usr/local/go/src/runtime/panic.go:636 /usr/local/go/src/testing/testing.go:1041 /usr/local/go/src/testing/testing.go:1125 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:285 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:292 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:134 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:181 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Should be true Test: TestCheckSizeOnly Messages: Unexpected file "potato2" --- FAIL: TestCheckSizeOnly (21.80s) --- PASS: TestCheckSizeOnly/3 (0.18s) === RUN TestParseSumFile run.go:180: Remote "OpenDrive root 'rclone-test-wepigip1sada'", Local "Local file system at /tmp/rclone565059359", Modify Window "1s" 2025/09/30 01:16:14 DEBUG : test.sum: Uploading chunk 0, size=81, remain=0 run.go:285: Failed to put "test.sum" to "OpenDrive root 'rclone-test-wepigip1sada'": failed to get fileinfo: File was not found (Error 404) fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:173 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:388 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 /usr/local/go/src/runtime/panic.go:636 /usr/local/go/src/testing/testing.go:1041 /usr/local/go/src/testing/testing.go:1125 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:285 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:292 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:308 Error: Should be true Test: TestParseSumFile Messages: listing wrong, want got test.sum (81) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:173 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:388 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 /usr/local/go/src/runtime/panic.go:636 /usr/local/go/src/testing/testing.go:1041 /usr/local/go/src/testing/testing.go:1125 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:285 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:292 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:308 Error: Should be true Test: TestParseSumFile Messages: Unexpected file "test.sum" --- FAIL: TestParseSumFile (10.16s) === RUN TestCheckSum run.go:180: Remote "OpenDrive root 'rclone-test-wepigip1sada'", Local "Local file system at /tmp/rclone565059359", Modify Window "1s" 2025/09/30 01:16:23 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-wepigip1sada/data" 2025/09/30 01:16:24 DEBUG : Starting OpenDrive session with ID: 3652f0eb9cfec4c8689d02402ced911fcf69fc93fdb774c15e7b8f4e150a22ab 2025/09/30 01:16:26 DEBUG : data/banana: Uploading chunk 0, size=13, remain=0 2025/09/30 01:16:27 DEBUG : test.sum: Uploading chunk 0, size=41, remain=0 === RUN TestCheckSum/subtest1 2025/09/30 01:16:30 DEBUG : data/potato: Uploading chunk 0, size=15, remain=0 2025/09/30 01:16:31 DEBUG : test.sum: Uploading chunk 0, size=41, remain=0 === RUN TestCheckSum/subtest2 2025/09/30 01:16:34 DEBUG : test.sum: Uploading chunk 0, size=82, remain=0 === RUN TestCheckSum/subtest3 2025/09/30 01:16:36 DEBUG : test.sum: Uploading chunk 0, size=82, remain=0 === RUN TestCheckSum/subtest4 2025/09/30 01:16:38 DEBUG : test.sum: Uploading chunk 0, size=123, remain=0 === RUN TestCheckSum/subtest5 2025/09/30 01:16:40 DEBUG : test.sum: Uploading chunk 0, size=123, remain=0 === RUN TestCheckSum/subtest6 2025/09/30 01:16:42 DEBUG : data/banana: Uploading chunk 0, size=13, remain=0 2025/09/30 01:16:44 DEBUG : data/potato: Uploading chunk 0, size=15, remain=0 2025/09/30 01:16:45 DEBUG : test.sum: Uploading chunk 0, size=82, remain=0 === RUN TestCheckSum/subtest7 --- PASS: TestCheckSum (25.27s) --- PASS: TestCheckSum/subtest1 (0.73s) --- PASS: TestCheckSum/subtest2 (0.55s) --- PASS: TestCheckSum/subtest3 (0.60s) --- PASS: TestCheckSum/subtest4 (0.55s) --- PASS: TestCheckSum/subtest5 (0.55s) --- PASS: TestCheckSum/subtest6 (0.56s) --- PASS: TestCheckSum/subtest7 (0.56s) === RUN TestApplyTransforms 2025/09/30 01:16:48 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-yenupej6wewa" 2025/09/30 01:16:48 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/09/30 01:16:49 DEBUG : Starting OpenDrive session with ID: 150302089845d70da2ff5ca9134687df4e4128a7aa6837ef0f4441a773fa1f33 2025/09/30 01:16:49 DEBUG : Creating backend with remote "/tmp/rclone1662226287" run.go:180: Remote "OpenDrive root 'rclone-test-yenupej6wewa'", Local "Local file system at /tmp/rclone1662226287", Modify Window "1s" 2025/09/30 01:16:51 DEBUG : hello, world!: Uploading chunk 0, size=13, remain=0 upper checkfile vs. lower remote (without normalization) 2025/09/30 01:16:52 ERROR : hello, world!: sum not found 2025/09/30 01:16:52 ERROR : HELLO, WORLD!: file not in OpenDrive root 'rclone-test-yenupej6wewa' 2025/09/30 01:16:52 NOTICE: OpenDrive root 'rclone-test-yenupej6wewa': 1 files missing 2025/09/30 01:16:52 NOTICE: 1 hashes missing 2025/09/30 01:16:52 NOTICE: OpenDrive root 'rclone-test-yenupej6wewa': 1 differences found 2025/09/30 01:16:52 NOTICE: OpenDrive root 'rclone-test-yenupej6wewa': 2 errors while checking upper checkfile vs. lower remote (with normalization) 2025/09/30 01:16:52 DEBUG : hello, world!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/09/30 01:16:52 NOTICE: OpenDrive root 'rclone-test-yenupej6wewa': 0 differences found 2025/09/30 01:16:52 NOTICE: OpenDrive root 'rclone-test-yenupej6wewa': 1 matching files 2025/09/30 01:16:52 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-vehujat4raru" 2025/09/30 01:16:52 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/09/30 01:16:53 DEBUG : Starting OpenDrive session with ID: 10a3cdfeb69c360fd18425fe775e879fbd72363c8d033cc75bed08228042b289 2025/09/30 01:16:53 DEBUG : Creating backend with remote "/tmp/rclone3235775987" run.go:180: Remote "OpenDrive root 'rclone-test-vehujat4raru'", Local "Local file system at /tmp/rclone3235775987", Modify Window "1s" 2025/09/30 01:16:54 DEBUG : HELLO, WORLD!: Uploading chunk 0, size=13, remain=0 lower checkfile vs. upper remote (without normalization) 2025/09/30 01:16:56 ERROR : HELLO, WORLD!: sum not found 2025/09/30 01:16:56 ERROR : hello, world!: file not in OpenDrive root 'rclone-test-vehujat4raru' 2025/09/30 01:16:56 NOTICE: OpenDrive root 'rclone-test-vehujat4raru': 1 files missing 2025/09/30 01:16:56 NOTICE: 1 hashes missing 2025/09/30 01:16:56 NOTICE: OpenDrive root 'rclone-test-vehujat4raru': 1 differences found 2025/09/30 01:16:56 NOTICE: OpenDrive root 'rclone-test-vehujat4raru': 2 errors while checking lower checkfile vs. upper remote (with normalization) 2025/09/30 01:16:56 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/09/30 01:16:56 NOTICE: OpenDrive root 'rclone-test-vehujat4raru': 0 differences found 2025/09/30 01:16:56 NOTICE: OpenDrive root 'rclone-test-vehujat4raru': 1 matching files 2025/09/30 01:16:56 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-fuweyur4gibu" 2025/09/30 01:16:56 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/09/30 01:16:57 DEBUG : Starting OpenDrive session with ID: 91e61bd8c16862fe3b3a815f3b0b9edb50186bc26c4d43569ddc2742c30de54b 2025/09/30 01:16:57 DEBUG : Creating backend with remote "/tmp/rclone4123796444" run.go:180: Remote "OpenDrive root 'rclone-test-fuweyur4gibu'", Local "Local file system at /tmp/rclone4123796444", Modify Window "1s" 2025/09/30 01:16:59 DEBUG : HeLlO, wOrLd!: Uploading chunk 0, size=13, remain=0 lower checkfile vs. upperlowermixed remote (without normalization) 2025/09/30 01:17:00 ERROR : HeLlO, wOrLd!: sum not found 2025/09/30 01:17:00 ERROR : hello, world!: file not in OpenDrive root 'rclone-test-fuweyur4gibu' 2025/09/30 01:17:00 NOTICE: OpenDrive root 'rclone-test-fuweyur4gibu': 1 files missing 2025/09/30 01:17:00 NOTICE: 1 hashes missing 2025/09/30 01:17:00 NOTICE: OpenDrive root 'rclone-test-fuweyur4gibu': 1 differences found 2025/09/30 01:17:00 NOTICE: OpenDrive root 'rclone-test-fuweyur4gibu': 2 errors while checking lower checkfile vs. upperlowermixed remote (with normalization) 2025/09/30 01:17:01 DEBUG : HeLlO, wOrLd!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/09/30 01:17:01 NOTICE: OpenDrive root 'rclone-test-fuweyur4gibu': 0 differences found 2025/09/30 01:17:01 NOTICE: OpenDrive root 'rclone-test-fuweyur4gibu': 1 matching files 2025/09/30 01:17:01 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-decekix8bifi" 2025/09/30 01:17:01 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/09/30 01:17:01 DEBUG : Starting OpenDrive session with ID: ebd2741f6609e3ad7f707390ea645c1828a7eedc658de736b8df87e9cba7d60d 2025/09/30 01:17:01 DEBUG : pacer: low level retry 1/10 (error Session does not exist, please re-login. (Error 401)) 2025/09/30 01:17:01 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/09/30 01:17:01 DEBUG : pacer: Reducing sleep to 10ms 2025/09/30 01:17:02 DEBUG : Creating backend with remote "/tmp/rclone3790857318" run.go:180: Remote "OpenDrive root 'rclone-test-decekix8bifi'", Local "Local file system at /tmp/rclone3790857318", Modify Window "1s" 2025/09/30 01:17:03 DEBUG : HELLO, WORLD!: Uploading chunk 0, size=13, remain=0 upperlowermixed checkfile vs. upper remote (without normalization) 2025/09/30 01:17:04 ERROR : HELLO, WORLD!: sum not found 2025/09/30 01:17:04 ERROR : HeLlO, wOrLd!: file not in OpenDrive root 'rclone-test-decekix8bifi' 2025/09/30 01:17:04 NOTICE: OpenDrive root 'rclone-test-decekix8bifi': 1 files missing 2025/09/30 01:17:04 NOTICE: 1 hashes missing 2025/09/30 01:17:04 NOTICE: OpenDrive root 'rclone-test-decekix8bifi': 1 differences found 2025/09/30 01:17:04 NOTICE: OpenDrive root 'rclone-test-decekix8bifi': 2 errors while checking upperlowermixed checkfile vs. upper remote (with normalization) 2025/09/30 01:17:04 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/09/30 01:17:04 NOTICE: OpenDrive root 'rclone-test-decekix8bifi': 0 differences found 2025/09/30 01:17:04 NOTICE: OpenDrive root 'rclone-test-decekix8bifi': 1 matching files 2025/09/30 01:17:04 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-hikedol5paru" 2025/09/30 01:17:04 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/09/30 01:17:05 DEBUG : Starting OpenDrive session with ID: e8b6e23aa021c5acd8e905775226f2ff384c7be583b964ed80640c2c727c9403 2025/09/30 01:17:05 DEBUG : pacer: low level retry 1/10 (error Session does not exist, please re-login. (Error 401)) 2025/09/30 01:17:05 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/09/30 01:17:05 DEBUG : pacer: Reducing sleep to 10ms 2025/09/30 01:17:06 DEBUG : pacer: low level retry 1/10 (error Session does not exist, please re-login. (Error 401)) 2025/09/30 01:17:06 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/09/30 01:17:06 DEBUG : pacer: low level retry 2/10 (error Session does not exist, please re-login. (Error 401)) 2025/09/30 01:17:06 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2025/09/30 01:17:06 DEBUG : pacer: low level retry 3/10 (error Session does not exist, please re-login. (Error 401)) 2025/09/30 01:17:06 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2025/09/30 01:17:06 DEBUG : pacer: low level retry 4/10 (error Session does not exist, please re-login. (Error 401)) 2025/09/30 01:17:06 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2025/09/30 01:17:06 DEBUG : pacer: Reducing sleep to 80ms 2025/09/30 01:17:06 DEBUG : Creating backend with remote "/tmp/rclone3936931577" run.go:180: Remote "OpenDrive root 'rclone-test-hikedol5paru'", Local "Local file system at /tmp/rclone3936931577", Modify Window "1s" 2025/09/30 01:17:06 DEBUG : pacer: Reducing sleep to 40ms 2025/09/30 01:17:07 DEBUG : pacer: Reducing sleep to 20ms 2025/09/30 01:17:07 DEBUG : pacer: Reducing sleep to 10ms 2025/09/30 01:17:08 DEBUG : 測試_Русский___ě_áñ: Uploading chunk 0, size=13, remain=0 NFD checkfile vs. NFC remote (without normalization) 2025/09/30 01:17:09 ERROR : 測試_Русский___ě_áñ: sum not found 2025/09/30 01:17:09 ERROR : 測試_Русский___ě_áñ: file not in OpenDrive root 'rclone-test-hikedol5paru' 2025/09/30 01:17:09 NOTICE: OpenDrive root 'rclone-test-hikedol5paru': 1 files missing 2025/09/30 01:17:09 NOTICE: 1 hashes missing 2025/09/30 01:17:09 NOTICE: OpenDrive root 'rclone-test-hikedol5paru': 1 differences found 2025/09/30 01:17:09 NOTICE: OpenDrive root 'rclone-test-hikedol5paru': 2 errors while checking NFD checkfile vs. NFC remote (with normalization) 2025/09/30 01:17:09 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/09/30 01:17:09 NOTICE: OpenDrive root 'rclone-test-hikedol5paru': 0 differences found 2025/09/30 01:17:09 NOTICE: OpenDrive root 'rclone-test-hikedol5paru': 1 matching files 2025/09/30 01:17:09 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-gibecaw4xune" 2025/09/30 01:17:09 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/09/30 01:17:10 DEBUG : Starting OpenDrive session with ID: cdb662e1925d889a8670dfc1f3331e101642f8b3437313670931a04444cb5385 2025/09/30 01:17:10 DEBUG : Creating backend with remote "/tmp/rclone1707442157" run.go:180: Remote "OpenDrive root 'rclone-test-gibecaw4xune'", Local "Local file system at /tmp/rclone1707442157", Modify Window "1s" 2025/09/30 01:17:12 DEBUG : 測試_Русский___ě_áñ: Uploading chunk 0, size=13, remain=0 NFC checkfile vs. NFD remote (without normalization) 2025/09/30 01:17:13 ERROR : 測試_Русский___ě_áñ: sum not found 2025/09/30 01:17:13 ERROR : 測試_Русский___ě_áñ: file not in OpenDrive root 'rclone-test-gibecaw4xune' 2025/09/30 01:17:13 NOTICE: OpenDrive root 'rclone-test-gibecaw4xune': 1 files missing 2025/09/30 01:17:13 NOTICE: 1 hashes missing 2025/09/30 01:17:13 NOTICE: OpenDrive root 'rclone-test-gibecaw4xune': 1 differences found 2025/09/30 01:17:13 NOTICE: OpenDrive root 'rclone-test-gibecaw4xune': 2 errors while checking NFC checkfile vs. NFD remote (with normalization) 2025/09/30 01:17:13 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/09/30 01:17:13 NOTICE: OpenDrive root 'rclone-test-gibecaw4xune': 0 differences found 2025/09/30 01:17:13 NOTICE: OpenDrive root 'rclone-test-gibecaw4xune': 1 matching files 2025/09/30 01:17:13 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-comerar7javi" 2025/09/30 01:17:13 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/09/30 01:17:14 DEBUG : Starting OpenDrive session with ID: 86a0567ec20070c7b24beafab2846f2b625234a8ec108ee585fd7c070482ccb1 2025/09/30 01:17:14 DEBUG : pacer: low level retry 1/10 (error Session does not exist, please re-login. (Error 401)) 2025/09/30 01:17:14 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/09/30 01:17:14 DEBUG : pacer: Reducing sleep to 10ms 2025/09/30 01:17:14 DEBUG : pacer: low level retry 1/10 (error Session does not exist, please re-login. (Error 401)) 2025/09/30 01:17:14 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/09/30 01:17:15 DEBUG : pacer: low level retry 2/10 (error Session does not exist, please re-login. (Error 401)) 2025/09/30 01:17:15 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2025/09/30 01:17:15 DEBUG : pacer: Reducing sleep to 20ms 2025/09/30 01:17:15 DEBUG : Creating backend with remote "/tmp/rclone1866304686" run.go:180: Remote "OpenDrive root 'rclone-test-comerar7javi'", Local "Local file system at /tmp/rclone1866304686", Modify Window "1s" 2025/09/30 01:17:15 DEBUG : pacer: Reducing sleep to 10ms 2025/09/30 01:17:16 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Uploading chunk 0, size=13, remain=0 NFDx2 checkfile vs. both remote (without normalization) 2025/09/30 01:17:17 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2025/09/30 01:17:17 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in OpenDrive root 'rclone-test-comerar7javi' 2025/09/30 01:17:17 NOTICE: OpenDrive root 'rclone-test-comerar7javi': 1 files missing 2025/09/30 01:17:17 NOTICE: 1 hashes missing 2025/09/30 01:17:17 NOTICE: OpenDrive root 'rclone-test-comerar7javi': 1 differences found 2025/09/30 01:17:17 NOTICE: OpenDrive root 'rclone-test-comerar7javi': 2 errors while checking NFDx2 checkfile vs. both remote (with normalization) 2025/09/30 01:17:18 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/09/30 01:17:18 NOTICE: OpenDrive root 'rclone-test-comerar7javi': 0 differences found 2025/09/30 01:17:18 NOTICE: OpenDrive root 'rclone-test-comerar7javi': 1 matching files 2025/09/30 01:17:18 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-nizokec1cufu" 2025/09/30 01:17:18 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/09/30 01:17:18 DEBUG : Starting OpenDrive session with ID: 57368a20def14b3441bc2745c941e79fe40b7950c6598aaa601b8ab8fb833ff0 2025/09/30 01:17:18 DEBUG : pacer: low level retry 1/10 (error Session does not exist, please re-login. (Error 401)) 2025/09/30 01:17:18 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/09/30 01:17:19 DEBUG : pacer: Reducing sleep to 10ms 2025/09/30 01:17:19 DEBUG : Creating backend with remote "/tmp/rclone2037239688" run.go:180: Remote "OpenDrive root 'rclone-test-nizokec1cufu'", Local "Local file system at /tmp/rclone2037239688", Modify Window "1s" 2025/09/30 01:17:20 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Uploading chunk 0, size=13, remain=0 NFCx2 checkfile vs. both remote (without normalization) 2025/09/30 01:17:21 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2025/09/30 01:17:21 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in OpenDrive root 'rclone-test-nizokec1cufu' 2025/09/30 01:17:21 NOTICE: OpenDrive root 'rclone-test-nizokec1cufu': 1 files missing 2025/09/30 01:17:21 NOTICE: 1 hashes missing 2025/09/30 01:17:21 NOTICE: OpenDrive root 'rclone-test-nizokec1cufu': 1 differences found 2025/09/30 01:17:21 NOTICE: OpenDrive root 'rclone-test-nizokec1cufu': 2 errors while checking NFCx2 checkfile vs. both remote (with normalization) 2025/09/30 01:17:22 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/09/30 01:17:22 NOTICE: OpenDrive root 'rclone-test-nizokec1cufu': 0 differences found 2025/09/30 01:17:22 NOTICE: OpenDrive root 'rclone-test-nizokec1cufu': 1 matching files 2025/09/30 01:17:22 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-tinixij4yipe" 2025/09/30 01:17:22 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/09/30 01:17:22 DEBUG : Starting OpenDrive session with ID: ca180cf98a591a50233198f39bb2ac31db0af66050a38e9fb4adb9cd04cc65ff 2025/09/30 01:17:23 DEBUG : Creating backend with remote "/tmp/rclone1439919652" run.go:180: Remote "OpenDrive root 'rclone-test-tinixij4yipe'", Local "Local file system at /tmp/rclone1439919652", Modify Window "1s" 2025/09/30 01:17:24 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Uploading chunk 0, size=13, remain=0 both checkfile vs. NFDx2 remote (without normalization) 2025/09/30 01:17:25 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2025/09/30 01:17:25 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in OpenDrive root 'rclone-test-tinixij4yipe' 2025/09/30 01:17:25 NOTICE: OpenDrive root 'rclone-test-tinixij4yipe': 1 files missing 2025/09/30 01:17:25 NOTICE: 1 hashes missing 2025/09/30 01:17:25 NOTICE: OpenDrive root 'rclone-test-tinixij4yipe': 1 differences found 2025/09/30 01:17:25 NOTICE: OpenDrive root 'rclone-test-tinixij4yipe': 2 errors while checking both checkfile vs. NFDx2 remote (with normalization) 2025/09/30 01:17:26 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/09/30 01:17:26 NOTICE: OpenDrive root 'rclone-test-tinixij4yipe': 0 differences found 2025/09/30 01:17:26 NOTICE: OpenDrive root 'rclone-test-tinixij4yipe': 1 matching files 2025/09/30 01:17:26 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-lukofiy4koyu" 2025/09/30 01:17:26 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/09/30 01:17:26 DEBUG : Starting OpenDrive session with ID: a0d3a06a1748beee7df5e6c8780c1d349b5f5df05e38ba5962f3c5392bbd7c0c 2025/09/30 01:17:27 DEBUG : pacer: low level retry 1/10 (error Session does not exist, please re-login. (Error 401)) 2025/09/30 01:17:27 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/09/30 01:17:27 DEBUG : pacer: low level retry 2/10 (error Session does not exist, please re-login. (Error 401)) 2025/09/30 01:17:27 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2025/09/30 01:17:27 DEBUG : pacer: low level retry 3/10 (error Session does not exist, please re-login. (Error 401)) 2025/09/30 01:17:27 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2025/09/30 01:17:27 DEBUG : pacer: low level retry 4/10 (error Session does not exist, please re-login. (Error 401)) 2025/09/30 01:17:27 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2025/09/30 01:17:27 DEBUG : pacer: low level retry 5/10 (error Session does not exist, please re-login. (Error 401)) 2025/09/30 01:17:27 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2025/09/30 01:17:27 DEBUG : pacer: Reducing sleep to 160ms 2025/09/30 01:17:28 DEBUG : pacer: Reducing sleep to 80ms 2025/09/30 01:17:28 DEBUG : Creating backend with remote "/tmp/rclone578570774" run.go:180: Remote "OpenDrive root 'rclone-test-lukofiy4koyu'", Local "Local file system at /tmp/rclone578570774", Modify Window "1s" 2025/09/30 01:17:28 DEBUG : pacer: Reducing sleep to 40ms 2025/09/30 01:17:28 DEBUG : pacer: Reducing sleep to 20ms 2025/09/30 01:17:28 DEBUG : pacer: Reducing sleep to 10ms 2025/09/30 01:17:28 DEBUG : pacer: low level retry 1/10 (error Session does not exist, please re-login. (Error 401)) 2025/09/30 01:17:28 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/09/30 01:17:29 DEBUG : pacer: low level retry 2/10 (error Session does not exist, please re-login. (Error 401)) 2025/09/30 01:17:29 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2025/09/30 01:17:29 DEBUG : pacer: low level retry 3/10 (error Session does not exist, please re-login. (Error 401)) 2025/09/30 01:17:29 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2025/09/30 01:17:29 DEBUG : pacer: Reducing sleep to 40ms 2025/09/30 01:17:29 DEBUG : pacer: Reducing sleep to 20ms 2025/09/30 01:17:29 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Uploading chunk 0, size=13, remain=0 2025/09/30 01:17:30 DEBUG : pacer: Reducing sleep to 10ms 2025/09/30 01:17:30 DEBUG : pacer: low level retry 1/10 (error Session does not exist, please re-login. (Error 401)) 2025/09/30 01:17:30 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/09/30 01:17:30 DEBUG : pacer: Reducing sleep to 10ms both checkfile vs. NFCx2 remote (without normalization) 2025/09/30 01:17:31 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in OpenDrive root 'rclone-test-lukofiy4koyu' 2025/09/30 01:17:31 NOTICE: OpenDrive root 'rclone-test-lukofiy4koyu': 1 files missing 2025/09/30 01:17:31 NOTICE: OpenDrive root 'rclone-test-lukofiy4koyu': 0 differences found 2025/09/30 01:17:31 NOTICE: OpenDrive root 'rclone-test-lukofiy4koyu': 1 errors while checking both checkfile vs. NFCx2 remote (with normalization) 2025/09/30 01:17:31 ERROR : 測試_русский___ě_áñ測試_русский___ě_áñ: file not in OpenDrive root 'rclone-test-lukofiy4koyu' 2025/09/30 01:17:31 NOTICE: OpenDrive root 'rclone-test-lukofiy4koyu': 1 files missing 2025/09/30 01:17:31 NOTICE: OpenDrive root 'rclone-test-lukofiy4koyu': 0 differences found 2025/09/30 01:17:31 NOTICE: OpenDrive root 'rclone-test-lukofiy4koyu': 1 errors while checking check_test.go:591: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:591 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:603 Error: Received unexpected error: file not in OpenDrive root 'rclone-test-lukofiy4koyu' Test: TestApplyTransforms Messages: unexpected error for both checkfile vs. NFCx2 remote (with normalization) 測試_Русский___ě_áñ測試_Русский___ě_áñ 測試_Русский___ě_áñ測試_Русский___ě_áñ 2025/09/30 01:17:31 DEBUG : OpenDrive root 'rclone-test-lukofiy4koyu': Purge remote 2025/09/30 01:17:31 DEBUG : OpenDrive root 'rclone-test-tinixij4yipe': Purge remote 2025/09/30 01:17:32 DEBUG : OpenDrive root 'rclone-test-nizokec1cufu': Purge remote 2025/09/30 01:17:32 DEBUG : OpenDrive root 'rclone-test-comerar7javi': Purge remote 2025/09/30 01:17:32 DEBUG : OpenDrive root 'rclone-test-gibecaw4xune': Purge remote 2025/09/30 01:17:33 DEBUG : OpenDrive root 'rclone-test-hikedol5paru': Purge remote 2025/09/30 01:17:33 DEBUG : OpenDrive root 'rclone-test-decekix8bifi': Purge remote 2025/09/30 01:17:34 DEBUG : OpenDrive root 'rclone-test-fuweyur4gibu': Purge remote 2025/09/30 01:17:34 DEBUG : OpenDrive root 'rclone-test-vehujat4raru': Purge remote 2025/09/30 01:17:34 DEBUG : OpenDrive root 'rclone-test-yenupej6wewa': Purge remote --- FAIL: TestApplyTransforms (46.41s) === RUN TestCopyFileBackupDir run.go:180: Remote "OpenDrive root 'rclone-test-wepigip1sada'", Local "Local file system at /tmp/rclone565059359", Modify Window "1s" 2025/09/30 01:17:36 DEBUG : dst/file1: Uploading chunk 0, size=18, remain=0 run.go:285: Failed to put "dst/file1" to "OpenDrive root 'rclone-test-wepigip1sada'": failed to get fileinfo: File was not found (Error 404) --- FAIL: TestCopyFileBackupDir (3.27s) === RUN TestCopyFileCompareDest run.go:180: Remote "OpenDrive root 'rclone-test-wepigip1sada'", Local "Local file system at /tmp/rclone565059359", Modify Window "1s" 2025/09/30 01:17:38 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-wepigip1sada/dst" 2025/09/30 01:17:38 DEBUG : Starting OpenDrive session with ID: c5696cf74bbf74286b14b8af489600ac49ebf2e5bab4d7c625cff416f277aa3c 2025/09/30 01:17:39 DEBUG : pacer: low level retry 1/10 (error Session does not exist, please re-login. (Error 401)) 2025/09/30 01:17:39 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/09/30 01:17:39 DEBUG : pacer: low level retry 2/10 (error Session does not exist, please re-login. (Error 401)) 2025/09/30 01:17:39 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2025/09/30 01:17:39 DEBUG : pacer: Reducing sleep to 20ms 2025/09/30 01:17:39 DEBUG : pacer: Reducing sleep to 10ms 2025/09/30 01:17:40 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-wepigip1sada/CompareDest" 2025/09/30 01:17:40 DEBUG : Starting OpenDrive session with ID: ee782855d5261c24dd7d160bcace22d470874645a873a64d98aa34f45a4fa557 2025/09/30 01:17:41 DEBUG : one: Need to transfer - File not found at Destination 2025/09/30 01:17:42 DEBUG : one: Uploading chunk 0, size=3, remain=0 2025/09/30 01:17:44 DEBUG : one: md5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2025/09/30 01:17:44 INFO : one: Copied (new) 2025/09/30 01:17:44 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/09/30 01:17:45 DEBUG : one: Uploading chunk 0, size=5, remain=0 2025/09/30 01:17:46 DEBUG : one: md5 = 07912d142f5d63ee918b34796b5a2432 OK 2025/09/30 01:17:46 INFO : one: Copied (replaced existing) 2025/09/30 01:17:46 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0 2025/09/30 01:17:49 DEBUG : CompareDest/one: Uploading chunk 0, size=5, remain=0 2025/09/30 01:17:50 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/09/30 01:17:51 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2025/09/30 01:17:51 DEBUG : one: Destination found in --compare-dest, skipping 2025/09/30 01:17:52 DEBUG : CompareDest/two: Uploading chunk 0, size=3, remain=0 2025/09/30 01:17:53 DEBUG : two: Need to transfer - File not found at Destination 2025/09/30 01:17:53 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2025/09/30 01:17:53 DEBUG : two: Destination found in --compare-dest, skipping 2025/09/30 01:17:54 DEBUG : two: Need to transfer - File not found at Destination 2025/09/30 01:17:54 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2025/09/30 01:17:54 DEBUG : two: Destination found in --compare-dest, skipping 2025/09/30 01:17:55 DEBUG : two: Need to transfer - File not found at Destination 2025/09/30 01:17:55 DEBUG : two: Sizes differ (src 5 vs dst 3) 2025/09/30 01:17:56 DEBUG : two: Uploading chunk 0, size=5, remain=0 2025/09/30 01:17:57 DEBUG : two: md5 = 2379e4ce8c3380e996ab0509f17069ad OK 2025/09/30 01:17:57 INFO : two: Copied (new) --- PASS: TestCopyFileCompareDest (21.38s) === RUN TestCopyFileMaxTransfer run.go:180: Remote "OpenDrive root 'rclone-test-wepigip1sada'", Local "Local file system at /tmp/rclone565059359", Modify Window "1s" 2025/09/30 01:17:59 DEBUG : TestCopyFileMaxTransfer/file1: Need to transfer - File not found at Destination 2025/09/30 01:18:01 DEBUG : TestCopyFileMaxTransfer/file1: Uploading chunk 0, size=14, remain=0 2025/09/30 01:18:02 DEBUG : TestCopyFileMaxTransfer/file1: md5 = 0ef726ce9b1a7692357ff70dd321d595 OK 2025/09/30 01:18:02 INFO : TestCopyFileMaxTransfer/file1: Copied (new) 2025/09/30 01:18:02 DEBUG : TestCopyFileMaxTransfer/file2: Need to transfer - File not found at Destination 2025/09/30 01:18:03 DEBUG : TestCopyFileMaxTransfer/file2: Uploading chunk 0, size=2062, remain=0 2025/09/30 01:18:03 ERROR : TestCopyFileMaxTransfer/file2: Failed to copy: failed to create file: Post "https://dev.opendrive.com/api/v1/upload/upload_file_chunk.json": failed to copy data: max transfer limit reached as set by --max-transfer 2025/09/30 01:18:04 DEBUG : TestCopyFileMaxTransfer/file3: Need to transfer - File not found at Destination 2025/09/30 01:18:04 DEBUG : TestCopyFileMaxTransfer/file4: Need to transfer - File not found at Destination 2025/09/30 01:18:05 DEBUG : TestCopyFileMaxTransfer/file4: Uploading chunk 0, size=2062, remain=0 2025/09/30 01:18:06 DEBUG : TestCopyFileMaxTransfer/file4: md5 = ac9ae5aa9212494bf35d05a34ab2b994 OK 2025/09/30 01:18:06 INFO : TestCopyFileMaxTransfer/file4: Copied (new) --- PASS: TestCopyFileMaxTransfer (8.48s) === RUN TestLsLong run.go:180: Remote "OpenDrive root 'rclone-test-wepigip1sada'", Local "Local file system at /tmp/rclone565059359", Modify Window "1s" 2025/09/30 01:18:09 DEBUG : potato2: Uploading chunk 0, size=60, remain=0 2025/09/30 01:18:10 DEBUG : empty space: Uploading chunk 0, size=1, remain=0 fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 6s just to make sure --- PASS: TestLsLong (11.96s) === RUN TestMaxDeleteSizeLargeFile run.go:180: Remote "OpenDrive root 'rclone-test-wepigip1sada'", Local "Local file system at /tmp/rclone565059359", Modify Window "1s" 2025/09/30 01:18:21 DEBUG : small: Uploading chunk 0, size=10, remain=0 2025/09/30 01:18:23 DEBUG : medium: Uploading chunk 0, size=60, remain=0 2025/09/30 01:18:24 DEBUG : large: Uploading chunk 0, size=100, remain=0 2025/09/30 01:18:26 DEBUG : Waiting for deletions to finish 2025/09/30 01:18:26 INFO : medium: Deleted 2025/09/30 01:18:26 INFO : small: Deleted operations_test.go:471: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:471 Error: An error is expected but got nil. Test: TestMaxDeleteSizeLargeFile fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 6s just to make sure --- FAIL: TestMaxDeleteSizeLargeFile (15.29s) === RUN TestPurge 2025/09/30 01:18:35 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-yohuqim3qoku" 2025/09/30 01:18:35 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/09/30 01:18:36 DEBUG : Starting OpenDrive session with ID: 19cee1ef2c4bd2bfb12735b1d547918d1ec5de164937d5954b6ab7f96ae3d7cc 2025/09/30 01:18:36 DEBUG : pacer: low level retry 1/10 (error Session does not exist, please re-login. (Error 401)) 2025/09/30 01:18:36 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/09/30 01:18:36 DEBUG : pacer: Reducing sleep to 10ms 2025/09/30 01:18:36 DEBUG : pacer: low level retry 1/10 (error Session does not exist, please re-login. (Error 401)) 2025/09/30 01:18:36 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/09/30 01:18:36 DEBUG : pacer: low level retry 2/10 (error Session does not exist, please re-login. (Error 401)) 2025/09/30 01:18:36 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2025/09/30 01:18:36 DEBUG : pacer: Reducing sleep to 20ms 2025/09/30 01:18:36 DEBUG : Creating backend with remote "/tmp/rclone825716524" run.go:180: Remote "OpenDrive root 'rclone-test-yohuqim3qoku'", Local "Local file system at /tmp/rclone825716524", Modify Window "1s" 2025/09/30 01:18:37 DEBUG : pacer: Reducing sleep to 10ms run.go:285: Failed to put "A1/B1/C1/one" to "OpenDrive root 'rclone-test-yohuqim3qoku'": failed to get folder list: Directory does not exist (Error 404) 2025/09/30 01:18:37 DEBUG : OpenDrive root 'rclone-test-yohuqim3qoku': Purge remote --- FAIL: TestPurge (2.64s) === RUN TestRmdirsNoLeaveRoot run.go:180: Remote "OpenDrive root 'rclone-test-wepigip1sada'", Local "Local file system at /tmp/rclone565059359", Modify Window "1s" 2025/09/30 01:18:40 DEBUG : A1/B1/C1/one: Uploading chunk 0, size=3, remain=0 2025/09/30 01:18:41 INFO : A2: Making directory 2025/09/30 01:18:41 INFO : A1/B2: Making directory 2025/09/30 01:18:41 INFO : A1/B2/C2: Making directory 2025/09/30 01:18:42 INFO : A1/B1/C3: Making directory 2025/09/30 01:18:42 INFO : A3: Making directory 2025/09/30 01:18:42 INFO : A3/B3: Making directory 2025/09/30 01:18:43 INFO : A3/B3/C4: Making directory 2025/09/30 01:18:44 DEBUG : A1/two: Uploading chunk 0, size=3, remain=0 2025/09/30 01:18:46 DEBUG : removing 1 level 3 directories 2025/09/30 01:18:46 INFO : A3/B3/C4: Removing directory 2025/09/30 01:18:48 DEBUG : removing 2 level 3 directories 2025/09/30 01:18:48 INFO : A1/B2/C2: Removing directory 2025/09/30 01:18:48 INFO : A1/B1/C3: Removing directory 2025/09/30 01:18:48 DEBUG : removing 2 level 2 directories 2025/09/30 01:18:48 INFO : A3/B3: Removing directory 2025/09/30 01:18:48 INFO : A1/B2: Removing directory 2025/09/30 01:18:49 DEBUG : removing 2 level 1 directories 2025/09/30 01:18:49 INFO : A3: Removing directory 2025/09/30 01:18:49 INFO : A2: Removing directory fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 6s just to make sure 2025/09/30 01:18:59 DEBUG : removing 1 level 3 directories 2025/09/30 01:18:59 INFO : A1/B1/C1: Removing directory 2025/09/30 01:19:00 DEBUG : removing 1 level 2 directories 2025/09/30 01:19:00 INFO : A1/B1: Removing directory 2025/09/30 01:19:00 DEBUG : removing 1 level 1 directories 2025/09/30 01:19:00 INFO : A1: Removing directory 2025/09/30 01:19:00 DEBUG : removing 1 level 0 directories 2025/09/30 01:19:00 INFO : OpenDrive root 'rclone-test-wepigip1sada': Removing directory --- PASS: TestRmdirsNoLeaveRoot (23.99s) === RUN TestRmdirsLeaveRoot run.go:180: Remote "OpenDrive root 'rclone-test-wepigip1sada'", Local "Local file system at /tmp/rclone565059359", Modify Window "1s" 2025/09/30 01:19:02 INFO : A1: Making directory operations_test.go:746: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:746 Error: Received unexpected error: failed to get folder list: Folder is already deleted (Error 403) Test: TestRmdirsLeaveRoot --- FAIL: TestRmdirsLeaveRoot (0.51s) === RUN TestRmdirsWithFilter run.go:180: Remote "OpenDrive root 'rclone-test-wepigip1sada'", Local "Local file system at /tmp/rclone565059359", Modify Window "1s" 2025/09/30 01:19:02 INFO : A1: Making directory operations_test.go:785: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:785 Error: Received unexpected error: failed to get folder list: Folder is already deleted (Error 403) Test: TestRmdirsWithFilter --- FAIL: TestRmdirsWithFilter (0.53s) === RUN TestCopyURL run.go:180: Remote "OpenDrive root 'rclone-test-wepigip1sada'", Local "Local file system at /tmp/rclone565059359", Modify Window "1s" 2025/09/30 01:19:03 ERROR : file1: Post request put error: failed to create file: Parent folder is trashed (Error 403) operations_test.go:843: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:843 Error: Received unexpected error: failed to create file: Parent folder is trashed (Error 403) Test: TestCopyURL --- FAIL: TestCopyURL (0.88s) === RUN TestMoveFileBackupDir run.go:180: Remote "OpenDrive root 'rclone-test-wepigip1sada'", Local "Local file system at /tmp/rclone565059359", Modify Window "1s" run.go:285: Failed to put "dst/file1" to "OpenDrive root 'rclone-test-wepigip1sada'": failed to get folder list: Folder is already deleted (Error 403) --- FAIL: TestMoveFileBackupDir (0.53s) === RUN TestDirMove run.go:180: Remote "OpenDrive root 'rclone-test-wepigip1sada'", Local "Local file system at /tmp/rclone565059359", Modify Window "1s" run.go:285: Failed to put "A1/one" to "OpenDrive root 'rclone-test-wepigip1sada'": failed to get folder list: Folder is already deleted (Error 403) --- FAIL: TestDirMove (0.52s) === RUN TestTouchDir run.go:180: Remote "OpenDrive root 'rclone-test-wepigip1sada'", Local "Local file system at /tmp/rclone565059359", Modify Window "1s" run.go:285: Failed to put "potato2" to "OpenDrive root 'rclone-test-wepigip1sada'": failed to create file: Parent folder is trashed (Error 403) --- FAIL: TestTouchDir (0.71s) FAIL 2025/09/30 01:19:05 DEBUG : OpenDrive root 'rclone-test-wepigip1sada': Purge remote 2025/09/30 01:19:06 NOTICE: purge failed: Folder is already deleted (Error 403) "./operations.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestApplyTransforms|TestCheck|TestCheckSum|TestCopyFileBackupDir|TestCopyFileCompareDest|TestCopyFileMaxTransfer|TestCopyURL|TestDirMove|TestLsLong|TestMaxDeleteSizeLargeFile|TestMoveFileBackupDir|TestParseSumFile|TestPurge|TestRmdirsLeaveRoot|TestRmdirsNoLeaveRoot|TestRmdirsWithFilter|TestTouchDir)$|^TestCheckSizeOnly$/^(3|4)$'" - Finished ERROR in 3m28.436268213s (try 2/5): exit status 1: Failed [TestCheck TestCheckSizeOnly TestParseSumFile TestApplyTransforms TestCopyFileBackupDir TestMaxDeleteSizeLargeFile TestPurge TestRmdirsLeaveRoot TestRmdirsWithFilter TestCopyURL TestMoveFileBackupDir TestDirMove TestTouchDir]