"./sync.test -test.v -test.timeout 1h0m0s -remote TestFileFabric: -verbose -test.run '^(TestCopyEmptyDirectories|TestSyncEmptyDirectories)$'" - Starting (try 3/5)
2021/03/23 06:40:37 DEBUG : Creating backend with remote "TestFileFabric:rclone-test-tijomuk1dipocis5sotuciw4"
2021/03/23 06:40:37 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': rpc: checkPathExists(map[path:rclone-test-tijomuk1dipocis5sotuciw4 pid:120673762]) options=[]
2021/03/23 06:40:37 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': >rpc: result=Success (ok), err=<nil>
2021/03/23 06:40:37 DEBUG : Creating backend with remote "/tmp/rclone125894071"
=== RUN   TestCopyEmptyDirectories
    run.go:176: Remote "filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4'", Local "Local file system at /tmp/rclone125894071", Modify Window "1s"
2021/03/23 06:40:37 DEBUG : sub dir2: Making directory
2021/03/23 06:40:37 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': rpc: getFolderContents(map[count:1000 fi_pid:120673762 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[]
2021/03/23 06:40:38 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': >rpc: result=Success (ok), err=<nil>
2021/03/23 06:40:38 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': rpc: doCreateNewFolder(map[fi_name:rclone-test-tijomuk1dipocis5sotuciw4 fi_pid:120673762]) options=[]
2021/03/23 06:40:42 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': >rpc: result=Success (ok), err=<nil>
2021/03/23 06:40:42 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': rpc: getFolderContents(map[count:1000 fi_pid:120880821 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[]
2021/03/23 06:40:43 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': >rpc: result=Success (ok), err=<nil>
2021/03/23 06:40:43 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': Waiting for checks to finish
2021/03/23 06:40:43 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': Waiting for transfers to finish
2021/03/23 06:40:43 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': rpc: getFolderContents(map[count:1000 fi_pid:120880821 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[]
2021/03/23 06:40:43 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': >rpc: result=Success (ok), err=<nil>
2021/03/23 06:40:43 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': rpc: doCreateNewFolder(map[fi_name:sub dir fi_pid:120880821]) options=[]
2021/03/23 06:40:46 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': >rpc: result=Success (ok), err=<nil>
2021/03/23 06:40:46 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': rpc: doInitUpload(map[directuploadsupport:n fi_contenttype:application/octet-stream fi_filename:hello world fi_localtime:2001-02-03 04:05:06 fi_modified:2001-02-03 04:05:06 fi_name:hello world fi_pid:120880822 fi_size:11 responsetype:json]) options=[]
2021/03/23 06:40:47 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': >rpc: result=Success (ok), err=<nil>
2021/03/23 06:40:49 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': rpc: doCompleteUpload(map[fi_size:11 remotetime:2001-02-03 04:05:06 uploadcode:221530c38d3e40070ca291090b5b835f]) options=[]
2021/03/23 06:40:50 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': >rpc: result=Success (ok), err=<nil>
2021/03/23 06:40:50 INFO  : sub dir/hello world: Copied (new)
2021/03/23 06:40:50 DEBUG : sub dir2: Making directory
2021/03/23 06:40:50 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': rpc: getFolderContents(map[count:1000 fi_pid:120880821 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[]
2021/03/23 06:40:51 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': >rpc: result=Success (ok), err=<nil>
2021/03/23 06:40:51 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': rpc: doCreateNewFolder(map[fi_name:sub dir2 fi_pid:120880821]) options=[]
2021/03/23 06:40:55 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': >rpc: result=Success (ok), err=<nil>
2021/03/23 06:40:55 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': copied 1 directories
2021/03/23 06:40:55 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': rpc: getFolderContents(map[count:1000 fi_pid:120880821 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[]
2021/03/23 06:40:56 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': >rpc: result=Success (ok), err=<nil>
2021/03/23 06:40:56 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': rpc: getFolderContents(map[count:1000 fi_pid:120880824 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[]
2021/03/23 06:40:56 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': rpc: getFolderContents(map[count:1000 fi_pid:120880822 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[]
2021/03/23 06:40:57 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': >rpc: result=Success (ok), err=<nil>
2021/03/23 06:40:57 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': >rpc: result=Success (ok), err=<nil>
2021/03/23 06:40:57 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': rpc: getFolderContents(map[count:1000 fi_pid:120880821 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[]
2021/03/23 06:40:57 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': >rpc: result=Success (ok), err=<nil>
2021/03/23 06:40:57 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': rpc: getFolderContents(map[count:1000 fi_pid:120880824 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[]
2021/03/23 06:40:57 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': rpc: getFolderContents(map[count:1000 fi_pid:120880822 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[]
2021/03/23 06:40:58 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': >rpc: result=Success (ok), err=<nil>
2021/03/23 06:40:58 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': >rpc: result=Success (ok), err=<nil>
2021/03/23 06:40:58 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': rpc: doDeleteFile(map[completedeletion:n fi_id:120880823]) options=[]
2021/03/23 06:41:08 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': >rpc: result=Success (ok), err=<nil>
2021/03/23 06:41:08 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': rpc: getFolderContents(map[count:1000 fi_pid:120880824 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[]
2021/03/23 06:41:08 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': >rpc: result=Success (ok), err=<nil>
2021/03/23 06:41:08 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': rpc: doDeleteFolder(map[fi_id:120880824]) options=[]
2021/03/23 06:41:10 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': >rpc: result=Success (ok), err=<nil>
2021/03/23 06:41:10 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': rpc: getFolderContents(map[count:1000 fi_pid:120880822 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[]
2021/03/23 06:41:11 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': >rpc: result=Success (ok), err=<nil>
2021/03/23 06:41:11 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': rpc: doDeleteFolder(map[fi_id:120880822]) options=[]
2021/03/23 06:41:13 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': >rpc: result=Success (ok), err=<nil>
2021/03/23 06:41:13 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': rpc: getFolderContents(map[count:1000 fi_pid:120880821 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[]
2021/03/23 06:41:14 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': >rpc: result=Success (ok), err=<nil>
--- PASS: TestCopyEmptyDirectories (37.20s)
=== RUN   TestSyncEmptyDirectories
    run.go:176: Remote "filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4'", Local "Local file system at /tmp/rclone125894071", Modify Window "1s"
2021/03/23 06:41:14 DEBUG : sub dir2: Making directory
2021/03/23 06:41:14 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': rpc: getFolderContents(map[count:1000 fi_pid:120880821 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[]
2021/03/23 06:41:15 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': >rpc: result=Success (ok), err=<nil>
2021/03/23 06:41:15 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': Waiting for checks to finish
2021/03/23 06:41:15 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': Waiting for transfers to finish
2021/03/23 06:41:15 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': rpc: getFolderContents(map[count:1000 fi_pid:120880821 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[]
2021/03/23 06:41:15 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': >rpc: result=Success (ok), err=<nil>
2021/03/23 06:41:15 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': rpc: doCreateNewFolder(map[fi_name:sub dir fi_pid:120880821]) options=[]
2021/03/23 06:41:15 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': Sleeping for 6s to wait for "error_background" error to clear
2021/03/23 06:41:21 DEBUG : pacer: low level retry 1/10 (error Can not create folder now. We are not able to complete the requested operation with such name. We are processing delete in that folder. Please try again later or use another name. (error_background))
2021/03/23 06:41:21 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2021/03/23 06:41:22 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': Sleeping for 6s to wait for "error_background" error to clear
2021/03/23 06:41:28 DEBUG : pacer: low level retry 2/10 (error Can not create folder now. We are not able to complete the requested operation with such name. We are processing delete in that folder. Please try again later or use another name. (error_background))
2021/03/23 06:41:28 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2021/03/23 06:41:31 DEBUG : pacer: Reducing sleep to 60ms
2021/03/23 06:41:31 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': >rpc: result=Success (ok), err=<nil>
2021/03/23 06:41:31 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': rpc: doInitUpload(map[directuploadsupport:n fi_contenttype:application/octet-stream fi_filename:hello world fi_localtime:2001-02-03 04:05:06 fi_modified:2001-02-03 04:05:06 fi_name:hello world fi_pid:120880828 fi_size:11 responsetype:json]) options=[]
2021/03/23 06:41:32 DEBUG : pacer: Reducing sleep to 45ms
2021/03/23 06:41:32 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': >rpc: result=Success (ok), err=<nil>
2021/03/23 06:41:33 DEBUG : pacer: Reducing sleep to 33.75ms
2021/03/23 06:41:33 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': rpc: doCompleteUpload(map[fi_size:11 remotetime:2001-02-03 04:05:06 uploadcode:45c006705c31188bcf8f45632909304f]) options=[]
2021/03/23 06:41:35 DEBUG : pacer: Reducing sleep to 25.3125ms
2021/03/23 06:41:35 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': >rpc: result=Success (ok), err=<nil>
2021/03/23 06:41:35 INFO  : sub dir/hello world: Copied (new)
2021/03/23 06:41:35 DEBUG : sub dir2: Making directory
2021/03/23 06:41:35 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': rpc: getFolderContents(map[count:1000 fi_pid:120880821 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[]
2021/03/23 06:41:36 DEBUG : pacer: Reducing sleep to 20ms
2021/03/23 06:41:36 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': >rpc: result=Success (ok), err=<nil>
2021/03/23 06:41:36 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': rpc: doCreateNewFolder(map[fi_name:sub dir2 fi_pid:120880821]) options=[]
2021/03/23 06:41:40 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': >rpc: result=Success (ok), err=<nil>
2021/03/23 06:41:40 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': copied 1 directories
2021/03/23 06:41:40 DEBUG : Waiting for deletions to finish
2021/03/23 06:41:40 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': rpc: getFolderContents(map[count:1000 fi_pid:120880821 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[]
2021/03/23 06:41:41 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': >rpc: result=Success (ok), err=<nil>
2021/03/23 06:41:41 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': rpc: getFolderContents(map[count:1000 fi_pid:120880830 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[]
2021/03/23 06:41:41 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': rpc: getFolderContents(map[count:1000 fi_pid:120880828 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[]
2021/03/23 06:41:42 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': >rpc: result=Success (ok), err=<nil>
2021/03/23 06:41:42 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': >rpc: result=Success (ok), err=<nil>
2021/03/23 06:41:42 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': rpc: getFolderContents(map[count:1000 fi_pid:120880821 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[]
2021/03/23 06:41:43 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': >rpc: result=Success (ok), err=<nil>
2021/03/23 06:41:43 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': rpc: getFolderContents(map[count:1000 fi_pid:120880830 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[]
2021/03/23 06:41:43 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': rpc: getFolderContents(map[count:1000 fi_pid:120880828 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[]
2021/03/23 06:41:43 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': >rpc: result=Success (ok), err=<nil>
2021/03/23 06:41:43 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': >rpc: result=Success (ok), err=<nil>
2021/03/23 06:41:43 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': rpc: doDeleteFile(map[completedeletion:n fi_id:120880829]) options=[]
2021/03/23 06:41:56 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': >rpc: result=Success (ok), err=<nil>
2021/03/23 06:41:56 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': rpc: getFolderContents(map[count:1000 fi_pid:120880830 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[]
2021/03/23 06:41:56 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': >rpc: result=Success (ok), err=<nil>
2021/03/23 06:41:56 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': rpc: doDeleteFolder(map[fi_id:120880830]) options=[]
2021/03/23 06:41:58 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': >rpc: result=Success (ok), err=<nil>
2021/03/23 06:41:58 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': rpc: getFolderContents(map[count:1000 fi_pid:120880828 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[]
2021/03/23 06:41:59 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': >rpc: result=Success (ok), err=<nil>
2021/03/23 06:41:59 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': rpc: doDeleteFolder(map[fi_id:120880828]) options=[]
2021/03/23 06:42:01 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': >rpc: result=Success (ok), err=<nil>
2021/03/23 06:42:01 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': rpc: getFolderContents(map[count:1000 fi_pid:120880821 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[]
2021/03/23 06:42:02 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': >rpc: result=Success (ok), err=<nil>
--- PASS: TestSyncEmptyDirectories (47.96s)
PASS
2021/03/23 06:42:02 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': Purge remote
2021/03/23 06:42:02 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': rpc: doDeleteFolder(map[fi_id:120880821]) options=[]
2021/03/23 06:42:03 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': Sleeping for 6s to wait for "error_background" error to clear
2021/03/23 06:42:09 DEBUG : pacer: low level retry 1/10 (error The requested operation cannot be completed. A delete operation is already being processed in this folder. Please try again later. (error_background))
2021/03/23 06:42:09 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2021/03/23 06:42:09 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': Sleeping for 6s to wait for "error_background" error to clear
2021/03/23 06:42:15 DEBUG : pacer: low level retry 2/10 (error The requested operation cannot be completed. A delete operation is already being processed in this folder. Please try again later. (error_background))
2021/03/23 06:42:15 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2021/03/23 06:42:15 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': Sleeping for 6s to wait for "error_background" error to clear
2021/03/23 06:42:21 DEBUG : pacer: low level retry 3/10 (error The requested operation cannot be completed. A delete operation is already being processed in this folder. Please try again later. (error_background))
2021/03/23 06:42:21 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2021/03/23 06:42:23 DEBUG : pacer: Reducing sleep to 120ms
2021/03/23 06:42:23 DEBUG : filefabric root 'rclone-test-tijomuk1dipocis5sotuciw4': >rpc: result=Success (ok), err=<nil>
"./sync.test -test.v -test.timeout 1h0m0s -remote TestFileFabric: -verbose -test.run '^(TestCopyEmptyDirectories|TestSyncEmptyDirectories)$'" - Finished OK in 1m46.357699926s (try 3/5)