"./sync.test -test.v -test.timeout 1h0m0s -remote TestDropbox: -verbose -test.run '^TestSyncOverlapWithFilter$'" - Starting (try 2/5) 2025/01/22 03:37:36 DEBUG : Creating backend with remote "TestDropbox:rclone-test-duseqaw1lise" 2025/01/22 03:37:36 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/01/22 03:37:36 DEBUG : Creating backend with remote "/tmp/rclone2271336607" === RUN TestSyncOverlapWithFilter run.go:180: Remote "Dropbox root 'rclone-test-duseqaw1lise'", Local "Local file system at /tmp/rclone2271336607", Modify Window "1s" 2025/01/22 03:37:36 DEBUG : Creating backend with remote "TestDropbox:rclone-test-duseqaw1lise/rclone-sync-test" 2025/01/22 03:37:37 DEBUG : Creating backend with remote "TestDropbox:rclone-test-duseqaw1lise/rclone-sync-test-include/layer2" 2025/01/22 03:37:38 DEBUG : pacer: low level retry 1/10 (error too_many_write_operations/...) 2025/01/22 03:37:38 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/01/22 03:37:39 DEBUG : pacer: Reducing sleep to 15ms 2025/01/22 03:37:39 DEBUG : Creating backend with remote "TestDropbox:rclone-test-duseqaw1lise/rclone-sync-test-ignore-file" 2025/01/22 03:37:41 DEBUG : pacer: low level retry 1/10 (error too_many_write_operations/...) 2025/01/22 03:37:41 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/01/22 03:37:42 DEBUG : pacer: low level retry 2/10 (error too_many_write_operations/..) 2025/01/22 03:37:42 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2025/01/22 03:37:42 DEBUG : pacer: Reducing sleep to 30ms 2025/01/22 03:37:43 DEBUG : rclone-sync-test-ignore-file/.ignore: Uploading chunk 1/1 2025/01/22 03:37:43 DEBUG : rclone-sync-test-ignore-file/.ignore: Uploading chunk 2/1 2025/01/22 03:37:44 DEBUG : Dropbox root 'rclone-test-duseqaw1lise': Adding "rclone-sync-test-ignore-file/.ignore" to batch 2025/01/22 03:37:44 DEBUG : Dropbox root 'rclone-test-duseqaw1lise': Batch idle for 500ms so committing 2025/01/22 03:37:44 DEBUG : Dropbox root 'rclone-test-duseqaw1lise': Committing sync batch length 1 starting with: rclone-sync-test-ignore-file/.ignore 2025/01/22 03:37:45 DEBUG : Dropbox root 'rclone-test-duseqaw1lise': Committed sync batch length 1 starting with: rclone-sync-test-ignore-file/.ignore 2025/01/22 03:37:46 DEBUG : rclone-sync-test: Excluded 2025/01/22 03:37:46 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/01/22 03:37:47 DEBUG : rclone-sync-test-include/layer2: Excluded 2025/01/22 03:37:47 DEBUG : Dropbox root 'rclone-test-duseqaw1lise/rclone-sync-test': Waiting for checks to finish 2025/01/22 03:37:47 DEBUG : Dropbox root 'rclone-test-duseqaw1lise/rclone-sync-test': Waiting for transfers to finish 2025/01/22 03:37:47 DEBUG : Waiting for deletions to finish 2025/01/22 03:37:47 INFO : There was nothing to transfer 2025/01/22 03:37:47 DEBUG : rclone-sync-test: Excluded 2025/01/22 03:37:48 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/01/22 03:37:49 DEBUG : rclone-sync-test-include/layer2: Excluded 2025/01/22 03:37:49 DEBUG : Dropbox root 'rclone-test-duseqaw1lise': Waiting for checks to finish 2025/01/22 03:37:49 DEBUG : Dropbox root 'rclone-test-duseqaw1lise': Waiting for transfers to finish 2025/01/22 03:37:49 DEBUG : Waiting for deletions to finish 2025/01/22 03:37:49 INFO : rclone-sync-test-include: Removing directory 2025/01/22 03:37:49 DEBUG : rclone-sync-test-include: Failed to Rmdir: directory not empty 2025/01/22 03:37:49 DEBUG : Dropbox root 'rclone-test-duseqaw1lise': failed to delete 1 directories 2025/01/22 03:37:49 INFO : There was nothing to transfer run.go:180: Remote "Dropbox root 'rclone-test-duseqaw1lise'", Local "Local file system at /tmp/rclone2271336607", Modify Window "1s" run.go:180: Remote "Dropbox root 'rclone-test-duseqaw1lise'", Local "Local file system at /tmp/rclone2271336607", Modify Window "1s" run.go:180: Remote "Dropbox root 'rclone-test-duseqaw1lise'", Local "Local file system at /tmp/rclone2271336607", Modify Window "1s" run.go:180: Remote "Dropbox root 'rclone-test-duseqaw1lise'", Local "Local file system at /tmp/rclone2271336607", Modify Window "1s" run.go:180: Remote "Dropbox root 'rclone-test-duseqaw1lise'", Local "Local file system at /tmp/rclone2271336607", Modify Window "1s" run.go:180: Remote "Dropbox root 'rclone-test-duseqaw1lise'", Local "Local file system at /tmp/rclone2271336607", Modify Window "1s" 2025/01/22 03:37:50 DEBUG : pacer: Reducing sleep to 11.25ms 2025/01/22 03:37:50 DEBUG : rclone-sync-test: Excluded 2025/01/22 03:37:50 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/01/22 03:37:51 DEBUG : rclone-sync-test-include/layer2: Excluded 2025/01/22 03:37:51 DEBUG : Dropbox root 'rclone-test-duseqaw1lise/rclone-sync-test-include/layer2': Waiting for checks to finish 2025/01/22 03:37:51 DEBUG : Dropbox root 'rclone-test-duseqaw1lise/rclone-sync-test-include/layer2': Waiting for transfers to finish 2025/01/22 03:37:51 DEBUG : Waiting for deletions to finish 2025/01/22 03:37:51 INFO : There was nothing to transfer run.go:180: Remote "Dropbox root 'rclone-test-duseqaw1lise'", Local "Local file system at /tmp/rclone2271336607", Modify Window "1s" run.go:180: Remote "Dropbox root 'rclone-test-duseqaw1lise'", Local "Local file system at /tmp/rclone2271336607", Modify Window "1s" 2025/01/22 03:37:51 DEBUG : pacer: Reducing sleep to 10ms 2025/01/22 03:37:52 DEBUG : rclone-sync-test: Excluded 2025/01/22 03:37:52 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/01/22 03:37:53 DEBUG : rclone-sync-test-include/layer2: Excluded 2025/01/22 03:37:53 DEBUG : Dropbox root 'rclone-test-duseqaw1lise': Waiting for checks to finish 2025/01/22 03:37:53 DEBUG : Dropbox root 'rclone-test-duseqaw1lise': Waiting for transfers to finish 2025/01/22 03:37:53 DEBUG : Waiting for deletions to finish 2025/01/22 03:37:53 INFO : rclone-sync-test-include: Removing directory 2025/01/22 03:37:54 DEBUG : rclone-sync-test-include: Failed to Rmdir: directory not empty 2025/01/22 03:37:54 DEBUG : Dropbox root 'rclone-test-duseqaw1lise': failed to delete 1 directories 2025/01/22 03:37:54 INFO : There was nothing to transfer run.go:180: Remote "Dropbox root 'rclone-test-duseqaw1lise'", Local "Local file system at /tmp/rclone2271336607", Modify Window "1s" run.go:180: Remote "Dropbox root 'rclone-test-duseqaw1lise'", Local "Local file system at /tmp/rclone2271336607", Modify Window "1s" run.go:180: Remote "Dropbox root 'rclone-test-duseqaw1lise'", Local "Local file system at /tmp/rclone2271336607", Modify Window "1s" run.go:180: Remote "Dropbox root 'rclone-test-duseqaw1lise'", Local "Local file system at /tmp/rclone2271336607", Modify Window "1s" 2025/01/22 03:37:54 DEBUG : pacer: Reducing sleep to 22.5ms 2025/01/22 03:37:54 DEBUG : : Excluded 2025/01/22 03:37:54 DEBUG : rclone-sync-test: Excluded 2025/01/22 03:37:55 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/01/22 03:37:56 DEBUG : rclone-sync-test-include/layer2: Excluded 2025/01/22 03:37:56 DEBUG : Dropbox root 'rclone-test-duseqaw1lise/rclone-sync-test-ignore-file': Waiting for checks to finish 2025/01/22 03:37:56 DEBUG : Dropbox root 'rclone-test-duseqaw1lise/rclone-sync-test-ignore-file': Waiting for transfers to finish 2025/01/22 03:37:56 DEBUG : Waiting for deletions to finish 2025/01/22 03:37:56 INFO : There was nothing to transfer run.go:180: Remote "Dropbox root 'rclone-test-duseqaw1lise'", Local "Local file system at /tmp/rclone2271336607", Modify Window "1s" run.go:180: Remote "Dropbox root 'rclone-test-duseqaw1lise'", Local "Local file system at /tmp/rclone2271336607", Modify Window "1s" run.go:180: Remote "Dropbox root 'rclone-test-duseqaw1lise'", Local "Local file system at /tmp/rclone2271336607", Modify Window "1s" run.go:180: Remote "Dropbox root 'rclone-test-duseqaw1lise'", Local "Local file system at /tmp/rclone2271336607", Modify Window "1s" run.go:180: Remote "Dropbox root 'rclone-test-duseqaw1lise'", Local "Local file system at /tmp/rclone2271336607", Modify Window "1s" --- PASS: TestSyncOverlapWithFilter (34.30s) PASS 2025/01/22 03:38:11 DEBUG : Dropbox root 'rclone-test-duseqaw1lise': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestDropbox: -verbose -test.run '^TestSyncOverlapWithFilter$'" - Finished OK in 35.290562312s (try 2/5)