"./operations.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestCheckSum|TestMoveFile|TestPurge|TestRemoveExisting)$'" - Starting (try 2/5) 2025/01/11 05:25:13 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-xolefor2soqe" 2025/01/11 05:25:13 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/01/11 05:25:14 DEBUG : Starting OpenDrive session with ID: e3bfddd313d8e9a73c6408d8b3cccf32110dfd303f47051465343df495bf1107 2025/01/11 05:25:15 DEBUG : Creating backend with remote "/tmp/rclone1125017222" === RUN TestCheckSum run.go:180: Remote "OpenDrive root 'rclone-test-xolefor2soqe'", Local "Local file system at /tmp/rclone1125017222", Modify Window "1s" 2025/01/11 05:25:15 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-xolefor2soqe/data" 2025/01/11 05:25:15 DEBUG : Starting OpenDrive session with ID: 9531c1667b123e70d738410dfbdcdd6127e7dbf1195496db473765f1ea22fe00 2025/01/11 05:25:17 DEBUG : data/banana: Uploading chunk 0, size=13, remain=0 2025/01/11 05:25:19 DEBUG : test.sum: Uploading chunk 0, size=41, remain=0 === RUN TestCheckSum/subtest1 2025/01/11 05:25:22 DEBUG : data/potato: Uploading chunk 0, size=15, remain=0 2025/01/11 05:25:23 DEBUG : test.sum: Uploading chunk 0, size=41, remain=0 === RUN TestCheckSum/subtest2 2025/01/11 05:25:25 DEBUG : test.sum: Uploading chunk 0, size=82, remain=0 === RUN TestCheckSum/subtest3 2025/01/11 05:25:28 DEBUG : test.sum: Uploading chunk 0, size=82, remain=0 === RUN TestCheckSum/subtest4 2025/01/11 05:25:31 DEBUG : test.sum: Uploading chunk 0, size=123, remain=0 === RUN TestCheckSum/subtest5 2025/01/11 05:25:33 DEBUG : test.sum: Uploading chunk 0, size=123, remain=0 === RUN TestCheckSum/subtest6 2025/01/11 05:25:35 DEBUG : data/banana: Uploading chunk 0, size=13, remain=0 2025/01/11 05:25:37 DEBUG : data/potato: Uploading chunk 0, size=15, remain=0 2025/01/11 05:25:38 DEBUG : test.sum: Uploading chunk 0, size=82, remain=0 === RUN TestCheckSum/subtest7 --- PASS: TestCheckSum (27.07s) --- PASS: TestCheckSum/subtest1 (0.94s) --- PASS: TestCheckSum/subtest2 (0.52s) --- PASS: TestCheckSum/subtest3 (0.66s) --- PASS: TestCheckSum/subtest4 (0.54s) --- PASS: TestCheckSum/subtest5 (0.55s) --- PASS: TestCheckSum/subtest6 (0.55s) --- PASS: TestCheckSum/subtest7 (0.57s) === RUN TestPurge 2025/01/11 05:25:42 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-miwinam8luso" 2025/01/11 05:25:42 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/01/11 05:25:42 DEBUG : Starting OpenDrive session with ID: 2506e9228c3a01ad6fe005afdd41ad9331f8987ff2c9ced1f6ef168eea0f7564 2025/01/11 05:25:42 DEBUG : Creating backend with remote "/tmp/rclone2429549540" run.go:180: Remote "OpenDrive root 'rclone-test-miwinam8luso'", Local "Local file system at /tmp/rclone2429549540", Modify Window "1s" 2025/01/11 05:25:45 DEBUG : A1/B1/C1/one: Uploading chunk 0, size=3, remain=0 2025/01/11 05:25:46 INFO : A2: Making directory 2025/01/11 05:25:46 INFO : A1/B2: Making directory 2025/01/11 05:25:46 INFO : A1/B2/C2: Making directory 2025/01/11 05:25:47 INFO : A1/B1/C3: Making directory 2025/01/11 05:25:47 INFO : A3: Making directory 2025/01/11 05:25:47 INFO : A3/B3: Making directory 2025/01/11 05:25:48 INFO : A3/B3/C4: Making directory 2025/01/11 05:25:49 DEBUG : A1/two: Uploading chunk 0, size=3, remain=0 2025/01/11 05:25:53 ERROR : : error listing: directory not found 2025/01/11 05:25:53 DEBUG : OpenDrive root 'rclone-test-miwinam8luso': Purge remote 2025/01/11 05:25:53 NOTICE: purge failed: directory not found --- PASS: TestPurge (11.65s) === RUN TestMoveFile run.go:180: Remote "OpenDrive root 'rclone-test-xolefor2soqe'", Local "Local file system at /tmp/rclone1125017222", Modify Window "1s" 2025/01/11 05:25:53 DEBUG : file1: Need to transfer - File not found at Destination 2025/01/11 05:25:55 DEBUG : sub/file2: Uploading chunk 0, size=14, remain=0 2025/01/11 05:25:56 DEBUG : file1: md5 = 0ef726ce9b1a7692357ff70dd321d595 OK 2025/01/11 05:25:56 INFO : file1: Copied (new) to: sub/file2 2025/01/11 05:25:56 INFO : file1: Deleted 2025/01/11 05:25:56 DEBUG : file1: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/01/11 05:25:56 DEBUG : file1: Unchanged skipping 2025/01/11 05:25:56 INFO : file1: Deleted 2025/01/11 05:25:57 DEBUG : OpenDrive root 'rclone-test-xolefor2soqe': don't need to copy/move sub/file2, it is already at target location --- PASS: TestMoveFile (4.66s) === RUN TestRemoveExisting run.go:180: Remote "OpenDrive root 'rclone-test-xolefor2soqe'", Local "Local file system at /tmp/rclone1125017222", Modify Window "1s" 2025/01/11 05:25:59 DEBUG : sub dir/test remove existing: Uploading chunk 0, size=11, remain=0 2025/01/11 05:26:01 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: Uploading chunk 0, size=21, remain=0 2025/01/11 05:26:03 DEBUG : sub dir/test remove existing: TEST: renaming existing object to "sub dir/test remove existing.mewalal2" before starting 2025/01/11 05:26:03 DEBUG : sub dir/test remove existing: same parent dir (ODFfNzkxNDIxMV90b3pjbg) - using file/rename instead of move_copy for sub dir/test remove existing.mewalal2 2025/01/11 05:26:04 DEBUG : sub dir/test remove existing.mewalal2: TEST: removing renamed existing file after operation 2025/01/11 05:26:04 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: TEST: renaming existing object to "sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.qetohiw1" before starting 2025/01/11 05:26:05 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: same parent dir (ODFfNzkxNDIxMV90b3pjbg) - using file/rename instead of move_copy for sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.qetohiw1 2025/01/11 05:26:05 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.qetohiw1: TEST: renaming existing back after failed operation 2025/01/11 05:26:05 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.qetohiw1: same parent dir (ODFfNzkxNDIxMV90b3pjbg) - using file/rename instead of move_copy for sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789 2025/01/11 05:26:06 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: TEST: renaming existing object to "sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.genacir2" before starting 2025/01/11 05:26:06 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: same parent dir (ODFfNzkxNDIxMV90b3pjbg) - using file/rename instead of move_copy for sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.genacir2 2025/01/11 05:26:07 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.genacir2: TEST: removing renamed existing file after operation --- PASS: TestRemoveExisting (10.25s) PASS 2025/01/11 05:26:08 DEBUG : OpenDrive root 'rclone-test-xolefor2soqe': Purge remote "./operations.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestCheckSum|TestMoveFile|TestPurge|TestRemoveExisting)$'" - Finished OK in 55.954787628s (try 2/5)