"./operations.test -test.v -test.timeout 1h0m0s -remote TestDropbox: -verbose -test.run '^TestRmdirsNoLeaveRoot$'" - Starting (try 2/5)
2024/11/28 01:52:23 DEBUG : Creating backend with remote "TestDropbox:rclone-test-vovebiy1naba"
2024/11/28 01:52:23 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2024/11/28 01:52:23 DEBUG : Creating backend with remote "/tmp/rclone1140229319"
=== RUN   TestRmdirsNoLeaveRoot
    run.go:180: Remote "Dropbox root 'rclone-test-vovebiy1naba'", Local "Local file system at /tmp/rclone1140229319", Modify Window "1s"
2024/11/28 01:52:25 DEBUG : A1/B1/C1/one: Uploading chunk 1/1
2024/11/28 01:52:25 DEBUG : A1/B1/C1/one: Uploading chunk 2/1
2024/11/28 01:52:26 DEBUG : Dropbox root 'rclone-test-vovebiy1naba': Adding "A1/B1/C1/one" to batch
2024/11/28 01:52:26 DEBUG : Dropbox root 'rclone-test-vovebiy1naba': Batch idle for 500ms so committing
2024/11/28 01:52:26 DEBUG : Dropbox root 'rclone-test-vovebiy1naba': Committing sync batch length 1 starting with: A1/B1/C1/one
2024/11/28 01:52:27 DEBUG : Dropbox root 'rclone-test-vovebiy1naba': Committed sync batch length 1 starting with: A1/B1/C1/one
2024/11/28 01:52:27 DEBUG : A2: Making directory
2024/11/28 01:52:28 DEBUG : pacer: low level retry 1/10 (error too_many_write_operations/.)
2024/11/28 01:52:28 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2024/11/28 01:52:28 DEBUG : pacer: Reducing sleep to 15ms
2024/11/28 01:52:28 DEBUG : A1/B2: Making directory
2024/11/28 01:52:28 DEBUG : pacer: Reducing sleep to 11.25ms
2024/11/28 01:52:29 DEBUG : pacer: Reducing sleep to 10ms
2024/11/28 01:52:29 DEBUG : A1/B2/C2: Making directory
2024/11/28 01:52:30 DEBUG : A1/B1/C3: Making directory
2024/11/28 01:52:31 DEBUG : A3: Making directory
2024/11/28 01:52:32 DEBUG : pacer: low level retry 1/10 (error too_many_write_operations/.)
2024/11/28 01:52:32 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2024/11/28 01:52:34 DEBUG : pacer: low level retry 2/10 (error too_many_write_operations/..)
2024/11/28 01:52:34 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2024/11/28 01:52:35 DEBUG : pacer: low level retry 3/10 (error too_many_write_operations/.)
2024/11/28 01:52:35 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2024/11/28 01:52:36 DEBUG : pacer: low level retry 4/10 (error too_many_write_operations/)
2024/11/28 01:52:36 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2024/11/28 01:52:38 DEBUG : pacer: low level retry 5/10 (error too_many_write_operations/.)
2024/11/28 01:52:38 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2024/11/28 01:52:38 DEBUG : pacer: Reducing sleep to 240ms
2024/11/28 01:52:38 DEBUG : A3/B3: Making directory
2024/11/28 01:52:38 DEBUG : pacer: Reducing sleep to 180ms
2024/11/28 01:52:39 DEBUG : pacer: Reducing sleep to 135ms
2024/11/28 01:52:39 DEBUG : A3/B3/C4: Making directory
2024/11/28 01:52:39 DEBUG : pacer: Reducing sleep to 101.25ms
2024/11/28 01:52:39 DEBUG : pacer: Reducing sleep to 75.9375ms
2024/11/28 01:52:40 DEBUG : pacer: Reducing sleep to 56.953125ms
2024/11/28 01:52:40 DEBUG : A1/two: Uploading chunk 1/1
2024/11/28 01:52:40 DEBUG : pacer: Reducing sleep to 42.714843ms
2024/11/28 01:52:40 DEBUG : A1/two: Uploading chunk 2/1
2024/11/28 01:52:41 DEBUG : pacer: Reducing sleep to 32.036132ms
2024/11/28 01:52:41 DEBUG : Dropbox root 'rclone-test-vovebiy1naba': Adding "A1/two" to batch
2024/11/28 01:52:41 DEBUG : Dropbox root 'rclone-test-vovebiy1naba': Batch idle for 500ms so committing
2024/11/28 01:52:41 DEBUG : Dropbox root 'rclone-test-vovebiy1naba': Committing sync batch length 1 starting with: A1/two
2024/11/28 01:52:42 DEBUG : pacer: Reducing sleep to 24.027099ms
2024/11/28 01:52:42 DEBUG : Dropbox root 'rclone-test-vovebiy1naba': Committed sync batch length 1 starting with: A1/two
2024/11/28 01:52:42 DEBUG : pacer: Reducing sleep to 18.020324ms
2024/11/28 01:52:43 DEBUG : pacer: Reducing sleep to 13.515243ms
2024/11/28 01:52:43 DEBUG : pacer: Reducing sleep to 10.136432ms
2024/11/28 01:52:43 DEBUG : pacer: Reducing sleep to 10ms
2024/11/28 01:52:44 DEBUG : removing 1 level 3 directories
2024/11/28 01:52:44 INFO  : A3/B3/C4: Removing directory
2024/11/28 01:52:47 DEBUG : removing 2 level 3 directories
2024/11/28 01:52:47 INFO  : A1/B2/C2: Removing directory
2024/11/28 01:52:47 INFO  : A1/B1/C3: Removing directory
2024/11/28 01:52:49 DEBUG : removing 2 level 2 directories
2024/11/28 01:52:49 INFO  : A3/B3: Removing directory
2024/11/28 01:52:49 INFO  : A1/B2: Removing directory
2024/11/28 01:52:51 DEBUG : removing 2 level 1 directories
2024/11/28 01:52:51 INFO  : A3: Removing directory
2024/11/28 01:52:51 INFO  : A2: Removing directory
2024/11/28 01:52:57 DEBUG : removing 1 level 3 directories
2024/11/28 01:52:57 INFO  : A1/B1/C1: Removing directory
2024/11/28 01:52:58 DEBUG : removing 1 level 2 directories
2024/11/28 01:52:58 INFO  : A1/B1: Removing directory
2024/11/28 01:52:59 DEBUG : removing 1 level 1 directories
2024/11/28 01:52:59 INFO  : A1: Removing directory
2024/11/28 01:53:00 DEBUG : removing 1 level 0 directories
2024/11/28 01:53:00 INFO  : Dropbox root 'rclone-test-vovebiy1naba': Removing directory
2024/11/28 01:53:02 ERROR : : error listing: directory not found
2024/11/28 01:53:02 ERROR : : error listing: directory not found
--- PASS: TestRmdirsNoLeaveRoot (39.00s)
PASS
2024/11/28 01:53:02 DEBUG : Dropbox root 'rclone-test-vovebiy1naba': Purge remote
2024/11/28 01:53:03 NOTICE: purge failed: path_lookup/not_found/...
"./operations.test -test.v -test.timeout 1h0m0s -remote TestDropbox: -verbose -test.run '^TestRmdirsNoLeaveRoot$'" - Finished OK in 39.669431133s (try 2/5)