"./operations.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestCheckSumDownload|TestCopyFileBackupDir|TestPurge|TestRemoveExisting|TestRmdirsWithFilter)$|^TestRcat$/^withChecksum=false,ignoreChecksum=false$'" - Starting (try 2/5) 2025/04/04 01:21:39 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-sizereb7xake" 2025/04/04 01:21:39 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/04/04 01:21:40 DEBUG : Starting OpenDrive session with ID: 43d6d4abf44e2d6c500a2197e03282a8dd36e55972877e5eda0ca0c053a092b1 2025/04/04 01:21:40 DEBUG : Creating backend with remote "/tmp/rclone3921672900" === RUN TestCheckSumDownload run.go:180: Remote "OpenDrive root 'rclone-test-sizereb7xake'", Local "Local file system at /tmp/rclone3921672900", Modify Window "1s" 2025/04/04 01:21:40 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-sizereb7xake/data" 2025/04/04 01:21:41 DEBUG : Starting OpenDrive session with ID: 0e432ffa2bc198dc062b9d63b1419e87848dd057d90eee00ae47adcde3c98c87 2025/04/04 01:21:43 DEBUG : data/banana: Uploading chunk 0, size=13, remain=0 2025/04/04 01:21:45 DEBUG : test.sum: Uploading chunk 0, size=41, remain=0 === RUN TestCheckSumDownload/subtest1 2025/04/04 01:21:48 DEBUG : data/potato: Uploading chunk 0, size=15, remain=0 2025/04/04 01:21:50 DEBUG : test.sum: Uploading chunk 0, size=41, remain=0 === RUN TestCheckSumDownload/subtest2 2025/04/04 01:21:52 DEBUG : test.sum: Uploading chunk 0, size=82, remain=0 === RUN TestCheckSumDownload/subtest3 2025/04/04 01:21:55 DEBUG : test.sum: Uploading chunk 0, size=82, remain=0 === RUN TestCheckSumDownload/subtest4 2025/04/04 01:21:58 DEBUG : test.sum: Uploading chunk 0, size=123, remain=0 === RUN TestCheckSumDownload/subtest5 2025/04/04 01:22:00 DEBUG : test.sum: Uploading chunk 0, size=123, remain=0 === RUN TestCheckSumDownload/subtest6 2025/04/04 01:22:03 DEBUG : data/banana: Uploading chunk 0, size=13, remain=0 2025/04/04 01:22:04 DEBUG : data/potato: Uploading chunk 0, size=15, remain=0 2025/04/04 01:22:05 DEBUG : test.sum: Uploading chunk 0, size=82, remain=0 === RUN TestCheckSumDownload/subtest7 --- PASS: TestCheckSumDownload (28.75s) --- PASS: TestCheckSumDownload/subtest1 (1.16s) --- PASS: TestCheckSumDownload/subtest2 (0.72s) --- PASS: TestCheckSumDownload/subtest3 (1.10s) --- PASS: TestCheckSumDownload/subtest4 (0.76s) --- PASS: TestCheckSumDownload/subtest5 (0.72s) --- PASS: TestCheckSumDownload/subtest6 (0.80s) --- PASS: TestCheckSumDownload/subtest7 (0.75s) === RUN TestCopyFileBackupDir run.go:180: Remote "OpenDrive root 'rclone-test-sizereb7xake'", Local "Local file system at /tmp/rclone3921672900", Modify Window "1s" 2025/04/04 01:22:10 DEBUG : dst/file1: Uploading chunk 0, size=18, remain=0 2025/04/04 01:22:12 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-sizereb7xake/backup" 2025/04/04 01:22:12 DEBUG : Config file has changed externally - reloading 2025/04/04 01:22:13 DEBUG : Starting OpenDrive session with ID: 37b3620a0d4d7c4869368927b009c9159660f1a51dec7e8adc37a836d54829ff 2025/04/04 01:22:13 DEBUG : dst/file1: Sizes differ (src 14 vs dst 18) 2025/04/04 01:22:15 INFO : dst/file1: Moved (server-side) 2025/04/04 01:22:16 DEBUG : dst/file1: Uploading chunk 0, size=14, remain=0 2025/04/04 01:22:17 DEBUG : dst/file1: md5 = 0ef726ce9b1a7692357ff70dd321d595 OK 2025/04/04 01:22:17 INFO : dst/file1: Copied (new) --- PASS: TestCopyFileBackupDir (11.09s) === RUN TestPurge 2025/04/04 01:22:20 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-feniceh3zafu" 2025/04/04 01:22:20 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/04/04 01:22:21 DEBUG : Starting OpenDrive session with ID: 1813f162863529202a6ab5bef6501d037df9e7cbae4d02294239c789a42844bf 2025/04/04 01:22:21 DEBUG : Creating backend with remote "/tmp/rclone1834193167" run.go:180: Remote "OpenDrive root 'rclone-test-feniceh3zafu'", Local "Local file system at /tmp/rclone1834193167", Modify Window "1s" 2025/04/04 01:22:24 DEBUG : A1/B1/C1/one: Uploading chunk 0, size=3, remain=0 2025/04/04 01:22:24 INFO : A2: Making directory 2025/04/04 01:22:25 INFO : A1/B2: Making directory 2025/04/04 01:22:25 INFO : A1/B2/C2: Making directory 2025/04/04 01:22:26 INFO : A1/B1/C3: Making directory 2025/04/04 01:22:26 INFO : A3: Making directory 2025/04/04 01:22:26 INFO : A3/B3: Making directory 2025/04/04 01:22:27 INFO : A3/B3/C4: Making directory 2025/04/04 01:22:28 DEBUG : A1/two: Uploading chunk 0, size=3, remain=0 2025/04/04 01:22:32 ERROR : : error listing: directory not found 2025/04/04 01:22:32 DEBUG : OpenDrive root 'rclone-test-feniceh3zafu': Purge remote 2025/04/04 01:22:32 NOTICE: purge failed: directory not found --- PASS: TestPurge (11.69s) === RUN TestRmdirsWithFilter run.go:180: Remote "OpenDrive root 'rclone-test-sizereb7xake'", Local "Local file system at /tmp/rclone3921672900", Modify Window "1s" 2025/04/04 01:22:32 INFO : A1: Making directory 2025/04/04 01:22:32 INFO : A1/B1: Making directory 2025/04/04 01:22:33 INFO : A1/B1/C1: Making directory 2025/04/04 01:22:35 DEBUG : removing 1 level 3 directories 2025/04/04 01:22:35 INFO : A1/B1/C1: Removing directory 2025/04/04 01:22:35 DEBUG : removing 1 level 2 directories 2025/04/04 01:22:35 INFO : A1/B1: Removing directory fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache fstest.go:286: Sleeping for 6s just to make sure --- PASS: TestRmdirsWithFilter (12.44s) === RUN TestRcat === RUN TestRcat/withChecksum=false,ignoreChecksum=false run.go:180: Remote "OpenDrive root 'rclone-test-sizereb7xake'", Local "Local file system at /tmp/rclone3921672900", Modify Window "1s" 2025/04/04 01:22:45 DEBUG : OpenDrive root 'rclone-test-sizereb7xake': File to upload is small (34 bytes), uploading instead of streaming 2025/04/04 01:22:45 DEBUG : no_checksum_small_file_from_pipe: Uploading chunk 0, size=34, remain=0 2025/04/04 01:22:46 DEBUG : no_checksum_small_file_from_pipe: md5 = 4c762bc6ec18f21b23fcae426c7863b5 OK 2025/04/04 01:22:46 DEBUG : no_checksum_small_file_from_pipe: Size and md5 of src and dst objects identical 2025/04/04 01:22:46 DEBUG : OpenDrive root 'rclone-test-sizereb7xake': Target remote doesn't support streaming uploads, creating temporary local FS to spool file 2025/04/04 01:22:47 DEBUG : no_checksum_big_file_from_pipe: Uploading chunk 0, size=102401, remain=0 2025/04/04 01:22:48 DEBUG : no_checksum_big_file_from_pipe: md5 = fffc7956ba9a7b58a63c01b6ce1ddc45 OK 2025/04/04 01:22:48 DEBUG : no_checksum_big_file_from_pipe: Size and md5 of src and dst objects identical --- PASS: TestRcat (5.12s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=false (5.12s) === RUN TestRemoveExisting run.go:180: Remote "OpenDrive root 'rclone-test-sizereb7xake'", Local "Local file system at /tmp/rclone3921672900", Modify Window "1s" 2025/04/04 01:22:51 DEBUG : sub dir/test remove existing: Uploading chunk 0, size=11, remain=0 2025/04/04 01:22:52 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: Uploading chunk 0, size=21, remain=0 2025/04/04 01:22:55 DEBUG : sub dir/test remove existing: TEST: renaming existing object to "sub dir/test remove existing.qulukuc0" before starting 2025/04/04 01:22:55 DEBUG : sub dir/test remove existing: same parent dir (ODFfODIwMjYzMF9KZlZiWA) - using file/rename instead of move_copy for sub dir/test remove existing.qulukuc0 2025/04/04 01:22:56 DEBUG : sub dir/test remove existing.qulukuc0: TEST: removing renamed existing file after operation 2025/04/04 01:22:56 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: TEST: renaming existing object to "sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.forugef6" before starting 2025/04/04 01:22:57 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: same parent dir (ODFfODIwMjYzMF9KZlZiWA) - using file/rename instead of move_copy for sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.forugef6 2025/04/04 01:22:57 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.forugef6: TEST: renaming existing back after failed operation 2025/04/04 01:22:57 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.forugef6: same parent dir (ODFfODIwMjYzMF9KZlZiWA) - using file/rename instead of move_copy for sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789 2025/04/04 01:22:58 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: TEST: renaming existing object to "sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.napuxow4" before starting 2025/04/04 01:22:58 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: same parent dir (ODFfODIwMjYzMF9KZlZiWA) - using file/rename instead of move_copy for sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.napuxow4 2025/04/04 01:22:59 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.napuxow4: TEST: removing renamed existing file after operation --- PASS: TestRemoveExisting (10.85s) PASS 2025/04/04 01:23:00 DEBUG : OpenDrive root 'rclone-test-sizereb7xake': Purge remote "./operations.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestCheckSumDownload|TestCopyFileBackupDir|TestPurge|TestRemoveExisting|TestRmdirsWithFilter)$|^TestRcat$/^withChecksum=false,ignoreChecksum=false$'" - Finished OK in 1m21.384411156s (try 2/5)