"go test -v -timeout 1h0m0s -remote TestFileFabric: -verbose" - Starting (try 1/5) === RUN TestIntegration fstests.go:418: Using remote "TestFileFabric:" 2021/07/25 05:40:02 DEBUG : Creating backend with remote "TestFileFabric:rclone-test-pacavib1tepukaf7niralum4" 2021/07/25 05:40:02 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2021/07/25 05:40:02 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: checkPathExists(map[path:rclone-test-pacavib1tepukaf7niralum4 pid:120673762]) options=[] 2021/07/25 05:40:02 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= === RUN TestIntegration/FsCheckWrap fstests.go:455: Not a wrapping Fs === RUN TestIntegration/FsCommand fstests.go:483: No commands in this remote === RUN TestIntegration/FsRmdirNotFound 2021/07/25 05:40:02 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': 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/07/25 05:40:04 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:40:04 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': 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/07/25 05:40:04 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:40:04 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doCreateNewFolder(map[fi_name:rclone-test-pacavib1tepukaf7niralum4 fi_pid:120673762]) options=[] 2021/07/25 05:40:12 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:40:12 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000082 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:40:13 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= === RUN TestIntegration/FsString === RUN TestIntegration/FsName === RUN TestIntegration/FsRoot === RUN TestIntegration/FsRmdirEmpty 2021/07/25 05:40:13 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000082 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:40:14 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:40:14 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doDeleteFolder(map[fi_id:121000082]) options=[] 2021/07/25 05:40:17 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= === RUN TestIntegration/FsMkdir 2021/07/25 05:40:17 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': 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/07/25 05:40:17 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:40:17 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doCreateNewFolder(map[fi_name:rclone-test-pacavib1tepukaf7niralum4 fi_pid:120673762]) options=[] 2021/07/25 05:40:17 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': Sleeping for 6s to wait for "error_background" error to clear 2021/07/25 05:40:23 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/07/25 05:40:23 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2021/07/25 05:40:24 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': Sleeping for 6s to wait for "error_background" error to clear 2021/07/25 05:40:30 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/07/25 05:40:30 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2021/07/25 05:40:34 DEBUG : pacer: Reducing sleep to 60ms 2021/07/25 05:40:34 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:40:34 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:40:36 DEBUG : pacer: Reducing sleep to 45ms 2021/07/25 05:40:36 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= === RUN TestIntegration/FsMkdir/FsMkdirRmdirSubdir 2021/07/25 05:40:36 DEBUG : dir/subdir: Making directory 2021/07/25 05:40:36 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:40:36 DEBUG : pacer: Reducing sleep to 33.75ms 2021/07/25 05:40:36 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:40:36 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doCreateNewFolder(map[fi_name:dir fi_pid:121000084]) options=[] 2021/07/25 05:40:40 DEBUG : pacer: Reducing sleep to 25.3125ms 2021/07/25 05:40:40 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:40:40 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000085 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:40:42 DEBUG : pacer: Reducing sleep to 20ms 2021/07/25 05:40:42 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:40:42 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doCreateNewFolder(map[fi_name:subdir fi_pid:121000085]) options=[] 2021/07/25 05:40:48 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:40:48 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:40:50 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:40:50 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000085 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:40:52 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:40:52 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000087 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:40:54 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:40:54 INFO : dir/subdir: Removing directory 2021/07/25 05:40:54 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000087 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:40:54 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:40:54 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doDeleteFolder(map[fi_id:121000087]) options=[] 2021/07/25 05:40:59 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:40:59 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:40:59 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:40:59 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000085 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:41:00 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:41:00 INFO : dir: Removing directory 2021/07/25 05:41:00 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000085 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:41:00 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:41:00 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doDeleteFolder(map[fi_id:121000085]) options=[] 2021/07/25 05:41:00 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': Sleeping for 6s to wait for "error_background" error to clear 2021/07/25 05:41:06 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/07/25 05:41:06 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2021/07/25 05:41:06 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': Sleeping for 6s to wait for "error_background" error to clear 2021/07/25 05:41:12 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/07/25 05:41:12 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2021/07/25 05:41:20 DEBUG : pacer: Reducing sleep to 60ms 2021/07/25 05:41:20 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:41:20 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:41:20 DEBUG : pacer: Reducing sleep to 45ms 2021/07/25 05:41:20 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= === RUN TestIntegration/FsMkdir/FsListEmpty 2021/07/25 05:41:20 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:41:20 DEBUG : pacer: Reducing sleep to 33.75ms 2021/07/25 05:41:20 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= === RUN TestIntegration/FsMkdir/FsListDirEmpty 2021/07/25 05:41:20 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:41:21 DEBUG : pacer: Reducing sleep to 25.3125ms 2021/07/25 05:41:21 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= === RUN TestIntegration/FsMkdir/FsListRDirEmpty fstests.go:378: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsListDirNotFound 2021/07/25 05:41:21 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:41:21 DEBUG : pacer: Reducing sleep to 20ms 2021/07/25 05:41:21 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:41:21 ERROR : does not exist: error listing: directory not found === RUN TestIntegration/FsMkdir/FsListRDirNotFound fstests.go:378: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsEncoding 2021/07/25 05:41:21 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:41:22 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= === RUN TestIntegration/FsMkdir/FsEncoding/control_chars fstests.go:669: testing "␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡" 2021/07/25 05:41:22 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:41:22 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:41:22 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doCreateNewFolder(map[fi_name:␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡ fi_pid:121000084]) options=[] 2021/07/25 05:41:27 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:41:27 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doInitUpload(map[directuploadsupport:n fi_contenttype:application/octet-stream fi_filename:␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡ fi_localtime:2021-07-25 05:41:27 fi_modified:2021-07-25 05:41:27 fi_name:␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡ fi_pid:121000091 fi_size:100 responsetype:json]) options=[] 2021/07/25 05:41:28 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:41:29 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doCompleteUpload(map[fi_size:100 remotetime:2021-07-25 05:41:27 uploadcode:23f7ada786a6ff33c35c99343dd38c67]) options=[] 2021/07/25 05:41:35 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:41:35 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: checkPathExists(map[path:␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡/␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡ pid:121000084]) options=[] 2021/07/25 05:41:35 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:41:35 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:41:41 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:41:41 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000091 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:41:46 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:41:46 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doDeleteFile(map[completedeletion:n fi_id:121000092]) options=[] 2021/07/25 05:42:02 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:42:02 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000091 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:42:07 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:42:07 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doDeleteFolder(map[fi_id:121000091]) options=[] 2021/07/25 05:42:12 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:42:12 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:42:12 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= === RUN TestIntegration/FsMkdir/FsEncoding/dot fstests.go:669: testing "．" 2021/07/25 05:42:12 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:42:13 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:42:13 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doCreateNewFolder(map[fi_name:． fi_pid:121000084]) options=[] 2021/07/25 05:42:17 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:42:17 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doInitUpload(map[directuploadsupport:n fi_contenttype:application/octet-stream fi_filename:． fi_localtime:2021-07-25 05:42:17 fi_modified:2021-07-25 05:42:17 fi_name:． fi_pid:121000094 fi_size:100 responsetype:json]) options=[] 2021/07/25 05:42:19 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:42:20 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doCompleteUpload(map[fi_size:100 remotetime:2021-07-25 05:42:17 uploadcode:4c6cd6852bcc9db930b61d78e0407bbf]) options=[] 2021/07/25 05:42:23 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:42:23 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: checkPathExists(map[path:．/． pid:121000084]) options=[] 2021/07/25 05:42:23 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:42:23 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:42:28 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:42:28 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000094 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:42:32 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:42:32 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doDeleteFile(map[completedeletion:n fi_id:121000095]) options=[] 2021/07/25 05:42:44 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:42:44 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000094 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:42:46 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:42:46 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doDeleteFolder(map[fi_id:121000094]) options=[] 2021/07/25 05:42:48 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:42:48 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:42:49 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= === RUN TestIntegration/FsMkdir/FsEncoding/dot_dot fstests.go:669: testing "．．" 2021/07/25 05:42:49 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:42:49 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:42:49 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doCreateNewFolder(map[fi_name:．． fi_pid:121000084]) options=[] 2021/07/25 05:42:54 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:42:54 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doInitUpload(map[directuploadsupport:n fi_contenttype:application/octet-stream fi_filename:．． fi_localtime:2021-07-25 05:42:54 fi_modified:2021-07-25 05:42:54 fi_name:．． fi_pid:121000099 fi_size:100 responsetype:json]) options=[] 2021/07/25 05:42:55 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:42:57 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doCompleteUpload(map[fi_size:100 remotetime:2021-07-25 05:42:54 uploadcode:0c8a2da0f5d2867eb0837840f10b6be2]) options=[] 2021/07/25 05:42:58 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:42:58 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: checkPathExists(map[path:．．/．． pid:121000084]) options=[] 2021/07/25 05:42:59 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:42:59 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:43:00 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:43:00 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000099 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:43:03 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:43:03 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doDeleteFile(map[completedeletion:n fi_id:121000100]) options=[] 2021/07/25 05:43:17 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:43:17 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000099 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:43:19 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:43:19 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doDeleteFolder(map[fi_id:121000099]) options=[] 2021/07/25 05:43:25 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:43:25 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:43:26 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= === RUN TestIntegration/FsMkdir/FsEncoding/punctuation fstests.go:669: testing "!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~" 2021/07/25 05:43:26 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:43:27 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:43:27 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doCreateNewFolder(map[fi_name:!"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~ fi_pid:121000084]) options=[] 2021/07/25 05:43:31 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:43:31 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doInitUpload(map[directuploadsupport:n fi_contenttype:application/octet-stream fi_filename:!"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~ fi_localtime:2021-07-25 05:43:31 fi_modified:2021-07-25 05:43:31 fi_name:!"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~ fi_pid:121000103 fi_size:100 responsetype:json]) options=[] 2021/07/25 05:43:33 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:43:34 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doCompleteUpload(map[fi_size:100 remotetime:2021-07-25 05:43:31 uploadcode:27cbd2489303aee1a6aac6cdfe48fdca]) options=[] 2021/07/25 05:43:36 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:43:36 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: checkPathExists(map[path:!"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~/!"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~ pid:121000084]) options=[] 2021/07/25 05:43:36 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:43:36 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:43:38 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:43:38 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000103 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:43:40 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:43:40 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doDeleteFile(map[completedeletion:n fi_id:121000104]) options=[] 2021/07/25 05:43:59 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:43:59 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000103 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:44:00 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:44:00 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doDeleteFolder(map[fi_id:121000103]) options=[] 2021/07/25 05:44:05 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:44:05 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:44:05 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= === RUN TestIntegration/FsMkdir/FsEncoding/leading_space fstests.go:669: testing " leading space" 2021/07/25 05:44:05 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:44:06 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:44:06 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doCreateNewFolder(map[fi_name: leading space fi_pid:121000084]) options=[] 2021/07/25 05:44:13 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:44:13 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doInitUpload(map[directuploadsupport:n fi_contenttype:application/octet-stream fi_filename: leading space fi_localtime:2021-07-25 05:44:13 fi_modified:2021-07-25 05:44:13 fi_name: leading space fi_pid:121000107 fi_size:100 responsetype:json]) options=[] 2021/07/25 05:44:14 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:44:16 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doCompleteUpload(map[fi_size:100 remotetime:2021-07-25 05:44:13 uploadcode:595cc16c1f1d3f035defcad09ee81630]) options=[] 2021/07/25 05:44:18 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:44:18 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: checkPathExists(map[path: leading space/ leading space pid:121000084]) options=[] 2021/07/25 05:44:18 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:44:18 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:44:20 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:44:20 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000107 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:44:21 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:44:21 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doDeleteFile(map[completedeletion:n fi_id:121000108]) options=[] 2021/07/25 05:44:35 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:44:35 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000107 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:44:36 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:44:36 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doDeleteFolder(map[fi_id:121000107]) options=[] 2021/07/25 05:44:41 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:44:41 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:44:43 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= === RUN TestIntegration/FsMkdir/FsEncoding/leading_tilde fstests.go:669: testing "~leading tilde" 2021/07/25 05:44:43 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:44:43 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:44:43 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doCreateNewFolder(map[fi_name:~leading tilde fi_pid:121000084]) options=[] 2021/07/25 05:44:50 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:44:50 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doInitUpload(map[directuploadsupport:n fi_contenttype:application/octet-stream fi_filename:~leading tilde fi_localtime:2021-07-25 05:44:50 fi_modified:2021-07-25 05:44:50 fi_name:~leading tilde fi_pid:121000110 fi_size:100 responsetype:json]) options=[] 2021/07/25 05:44:51 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:44:52 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doCompleteUpload(map[fi_size:100 remotetime:2021-07-25 05:44:50 uploadcode:949d6c663ad73a26faf59663192e29d1]) options=[] 2021/07/25 05:44:55 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:44:55 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: checkPathExists(map[path:~leading tilde/~leading tilde pid:121000084]) options=[] 2021/07/25 05:44:55 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:44:55 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:45:00 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:45:00 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000110 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:45:04 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:45:04 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doDeleteFile(map[completedeletion:n fi_id:121000112]) options=[] 2021/07/25 05:45:17 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:45:17 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000110 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:45:19 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:45:19 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doDeleteFolder(map[fi_id:121000110]) options=[] 2021/07/25 05:45:23 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:45:23 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:45:23 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= === RUN TestIntegration/FsMkdir/FsEncoding/leading_CR fstests.go:669: testing "␍leading CR" 2021/07/25 05:45:23 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:45:24 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:45:24 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doCreateNewFolder(map[fi_name:␍leading CR fi_pid:121000084]) options=[] 2021/07/25 05:45:30 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:45:30 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doInitUpload(map[directuploadsupport:n fi_contenttype:application/octet-stream fi_filename:␍leading CR fi_localtime:2021-07-25 05:45:30 fi_modified:2021-07-25 05:45:30 fi_name:␍leading CR fi_pid:121000114 fi_size:100 responsetype:json]) options=[] 2021/07/25 05:45:31 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:45:32 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doCompleteUpload(map[fi_size:100 remotetime:2021-07-25 05:45:30 uploadcode:644e3f0bba96762a960d99b1caaa9635]) options=[] 2021/07/25 05:45:34 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:45:34 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: checkPathExists(map[path:␍leading CR/␍leading CR pid:121000084]) options=[] 2021/07/25 05:45:34 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:45:34 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:45:36 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:45:36 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000114 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:45:37 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:45:37 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doDeleteFile(map[completedeletion:n fi_id:121000115]) options=[] 2021/07/25 05:45:51 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:45:51 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000114 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:45:53 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:45:53 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doDeleteFolder(map[fi_id:121000114]) options=[] 2021/07/25 05:45:57 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:45:57 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:45:58 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= === RUN TestIntegration/FsMkdir/FsEncoding/leading_LF fstests.go:669: testing "␊leading LF" 2021/07/25 05:45:58 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:45:59 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:45:59 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doCreateNewFolder(map[fi_name:␊leading LF fi_pid:121000084]) options=[] 2021/07/25 05:46:05 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:46:05 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doInitUpload(map[directuploadsupport:n fi_contenttype:application/octet-stream fi_filename:␊leading LF fi_localtime:2021-07-25 05:46:05 fi_modified:2021-07-25 05:46:05 fi_name:␊leading LF fi_pid:121000118 fi_size:100 responsetype:json]) options=[] 2021/07/25 05:46:06 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:46:07 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doCompleteUpload(map[fi_size:100 remotetime:2021-07-25 05:46:05 uploadcode:84e9374deea4664c46ed5615023caffe]) options=[] 2021/07/25 05:46:09 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:46:09 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: checkPathExists(map[path:␊leading LF/␊leading LF pid:121000084]) options=[] 2021/07/25 05:46:09 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:46:09 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:46:11 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:46:11 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000118 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:46:12 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:46:12 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doDeleteFile(map[completedeletion:n fi_id:121000119]) options=[] 2021/07/25 05:46:25 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:46:25 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000118 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:46:27 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:46:27 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doDeleteFolder(map[fi_id:121000118]) options=[] 2021/07/25 05:46:29 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:46:29 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:46:30 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= === RUN TestIntegration/FsMkdir/FsEncoding/leading_HT fstests.go:669: testing "␉leading HT" 2021/07/25 05:46:30 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:46:30 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:46:30 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doCreateNewFolder(map[fi_name:␉leading HT fi_pid:121000084]) options=[] 2021/07/25 05:46:37 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:46:37 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doInitUpload(map[directuploadsupport:n fi_contenttype:application/octet-stream fi_filename:␉leading HT fi_localtime:2021-07-25 05:46:37 fi_modified:2021-07-25 05:46:37 fi_name:␉leading HT fi_pid:121000121 fi_size:100 responsetype:json]) options=[] 2021/07/25 05:46:38 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:46:39 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doCompleteUpload(map[fi_size:100 remotetime:2021-07-25 05:46:37 uploadcode:3b7f982b669b7dd17d37ae98563fa248]) options=[] 2021/07/25 05:46:42 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:46:42 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: checkPathExists(map[path:␉leading HT/␉leading HT pid:121000084]) options=[] 2021/07/25 05:46:42 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:46:42 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:46:45 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:46:45 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000121 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:46:46 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:46:46 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doDeleteFile(map[completedeletion:n fi_id:121000123]) options=[] 2021/07/25 05:47:00 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:47:00 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000121 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:47:01 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:47:01 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doDeleteFolder(map[fi_id:121000121]) options=[] 2021/07/25 05:47:04 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:47:04 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:47:05 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= === RUN TestIntegration/FsMkdir/FsEncoding/leading_VT fstests.go:669: testing "␋leading VT" 2021/07/25 05:47:05 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:47:05 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:47:05 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doCreateNewFolder(map[fi_name:␋leading VT fi_pid:121000084]) options=[] 2021/07/25 05:47:11 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:47:11 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doInitUpload(map[directuploadsupport:n fi_contenttype:application/octet-stream fi_filename:␋leading VT fi_localtime:2021-07-25 05:47:11 fi_modified:2021-07-25 05:47:11 fi_name:␋leading VT fi_pid:121000125 fi_size:100 responsetype:json]) options=[] 2021/07/25 05:47:12 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:47:13 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doCompleteUpload(map[fi_size:100 remotetime:2021-07-25 05:47:11 uploadcode:1a7095e2f6dec156600008fb23e99205]) options=[] 2021/07/25 05:47:16 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:47:16 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: checkPathExists(map[path:␋leading VT/␋leading VT pid:121000084]) options=[] 2021/07/25 05:47:17 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:47:17 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:47:19 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:47:19 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000125 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:47:20 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:47:20 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doDeleteFile(map[completedeletion:n fi_id:121000126]) options=[] 2021/07/25 05:47:36 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:47:36 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000125 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:47:38 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:47:38 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doDeleteFolder(map[fi_id:121000125]) options=[] 2021/07/25 05:47:42 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:47:42 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:47:42 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= === RUN TestIntegration/FsMkdir/FsEncoding/leading_dot fstests.go:669: testing ".leading dot" 2021/07/25 05:47:42 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:47:43 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:47:43 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doCreateNewFolder(map[fi_name:.leading dot fi_pid:121000084]) options=[] 2021/07/25 05:47:49 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:47:49 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doInitUpload(map[directuploadsupport:n fi_contenttype:application/octet-stream fi_filename:.leading dot fi_localtime:2021-07-25 05:47:49 fi_modified:2021-07-25 05:47:49 fi_name:.leading dot fi_pid:121000130 fi_size:100 responsetype:json]) options=[] 2021/07/25 05:47:51 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:47:52 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doCompleteUpload(map[fi_size:100 remotetime:2021-07-25 05:47:49 uploadcode:fad3098281c52bb38c60969e05df79be]) options=[] 2021/07/25 05:47:54 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:47:54 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: checkPathExists(map[path:.leading dot/.leading dot pid:121000084]) options=[] 2021/07/25 05:47:54 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:47:54 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:47:55 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:47:55 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000130 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:47:57 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:47:57 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doDeleteFile(map[completedeletion:n fi_id:121000131]) options=[] 2021/07/25 05:48:10 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:48:10 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000130 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:48:11 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:48:11 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doDeleteFolder(map[fi_id:121000130]) options=[] 2021/07/25 05:48:15 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:48:15 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:48:16 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= === RUN TestIntegration/FsMkdir/FsEncoding/trailing_space fstests.go:669: testing "trailing space " 2021/07/25 05:48:16 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:48:16 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:48:16 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doCreateNewFolder(map[fi_name:trailing space fi_pid:121000084]) options=[] 2021/07/25 05:48:25 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:48:25 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doInitUpload(map[directuploadsupport:n fi_contenttype:application/octet-stream fi_filename:trailing space fi_localtime:2021-07-25 05:48:25 fi_modified:2021-07-25 05:48:25 fi_name:trailing space fi_pid:121000134 fi_size:100 responsetype:json]) options=[] 2021/07/25 05:48:27 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:48:28 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doCompleteUpload(map[fi_size:100 remotetime:2021-07-25 05:48:25 uploadcode:3185bc607e0c320577700ebe260478d8]) options=[] 2021/07/25 05:48:32 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:48:32 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: checkPathExists(map[path:trailing space /trailing space pid:121000084]) options=[] 2021/07/25 05:48:33 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:48:33 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:48:35 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:48:35 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000134 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:48:38 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:48:38 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doDeleteFile(map[completedeletion:n fi_id:121000135]) options=[] 2021/07/25 05:48:49 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:48:49 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000134 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:48:51 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:48:51 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doDeleteFolder(map[fi_id:121000134]) options=[] 2021/07/25 05:48:53 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:48:53 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:48:54 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= === RUN TestIntegration/FsMkdir/FsEncoding/trailing_CR fstests.go:669: testing "trailing CR␍" 2021/07/25 05:48:54 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:48:54 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:48:54 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doCreateNewFolder(map[fi_name:trailing CR␍ fi_pid:121000084]) options=[] 2021/07/25 05:49:00 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:49:00 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doInitUpload(map[directuploadsupport:n fi_contenttype:application/octet-stream fi_filename:trailing CR␍ fi_localtime:2021-07-25 05:49:00 fi_modified:2021-07-25 05:49:00 fi_name:trailing CR␍ fi_pid:121000137 fi_size:100 responsetype:json]) options=[] 2021/07/25 05:49:01 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:49:02 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doCompleteUpload(map[fi_size:100 remotetime:2021-07-25 05:49:00 uploadcode:a311fcc98a068022c4e0fed9cdb3692c]) options=[] 2021/07/25 05:49:08 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:49:08 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: checkPathExists(map[path:trailing CR␍/trailing CR␍ pid:121000084]) options=[] 2021/07/25 05:49:08 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:49:08 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:49:12 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:49:12 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000137 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:49:14 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:49:14 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doDeleteFile(map[completedeletion:n fi_id:121000138]) options=[] 2021/07/25 05:49:27 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:49:27 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000137 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:49:29 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:49:29 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doDeleteFolder(map[fi_id:121000137]) options=[] 2021/07/25 05:49:32 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:49:32 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:49:32 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= === RUN TestIntegration/FsMkdir/FsEncoding/trailing_LF fstests.go:669: testing "trailing LF␊" 2021/07/25 05:49:32 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:49:33 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:49:33 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doCreateNewFolder(map[fi_name:trailing LF␊ fi_pid:121000084]) options=[] 2021/07/25 05:49:38 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:49:38 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doInitUpload(map[directuploadsupport:n fi_contenttype:application/octet-stream fi_filename:trailing LF␊ fi_localtime:2021-07-25 05:49:38 fi_modified:2021-07-25 05:49:38 fi_name:trailing LF␊ fi_pid:121000141 fi_size:100 responsetype:json]) options=[] 2021/07/25 05:49:39 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:49:41 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doCompleteUpload(map[fi_size:100 remotetime:2021-07-25 05:49:38 uploadcode:f0a39039f9d4fdaa2d01bb463ae6f4b0]) options=[] 2021/07/25 05:49:43 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:49:43 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: checkPathExists(map[path:trailing LF␊/trailing LF␊ pid:121000084]) options=[] 2021/07/25 05:49:43 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:49:43 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:49:46 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:49:46 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000141 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:49:48 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:49:48 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doDeleteFile(map[completedeletion:n fi_id:121000142]) options=[] 2021/07/25 05:50:05 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:50:05 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000141 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:50:07 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:50:07 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doDeleteFolder(map[fi_id:121000141]) options=[] 2021/07/25 05:50:12 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:50:12 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:50:13 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= === RUN TestIntegration/FsMkdir/FsEncoding/trailing_HT fstests.go:669: testing "trailing HT␉" 2021/07/25 05:50:13 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:50:13 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:50:13 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doCreateNewFolder(map[fi_name:trailing HT␉ fi_pid:121000084]) options=[] 2021/07/25 05:50:21 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:50:21 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doInitUpload(map[directuploadsupport:n fi_contenttype:application/octet-stream fi_filename:trailing HT␉ fi_localtime:2021-07-25 05:50:21 fi_modified:2021-07-25 05:50:21 fi_name:trailing HT␉ fi_pid:121000144 fi_size:100 responsetype:json]) options=[] 2021/07/25 05:50:22 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:50:23 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doCompleteUpload(map[fi_size:100 remotetime:2021-07-25 05:50:21 uploadcode:9835c5d34084d6a88f803240bc1974e8]) options=[] 2021/07/25 05:50:26 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:50:26 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: checkPathExists(map[path:trailing HT␉/trailing HT␉ pid:121000084]) options=[] 2021/07/25 05:50:26 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:50:26 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:50:30 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:50:30 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000144 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:50:34 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:50:34 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doDeleteFile(map[completedeletion:n fi_id:121000146]) options=[] 2021/07/25 05:50:46 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:50:46 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000144 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:50:47 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:50:47 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doDeleteFolder(map[fi_id:121000144]) options=[] 2021/07/25 05:50:52 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:50:52 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:50:52 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= === RUN TestIntegration/FsMkdir/FsEncoding/trailing_VT fstests.go:669: testing "trailing VT␋" 2021/07/25 05:50:52 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:50:52 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:50:52 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doCreateNewFolder(map[fi_name:trailing VT␋ fi_pid:121000084]) options=[] 2021/07/25 05:50:56 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:50:56 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doInitUpload(map[directuploadsupport:n fi_contenttype:application/octet-stream fi_filename:trailing VT␋ fi_localtime:2021-07-25 05:50:56 fi_modified:2021-07-25 05:50:56 fi_name:trailing VT␋ fi_pid:121000148 fi_size:100 responsetype:json]) options=[] 2021/07/25 05:50:58 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:50:59 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doCompleteUpload(map[fi_size:100 remotetime:2021-07-25 05:50:56 uploadcode:4d7f66dbebc8a4f58bcab07c90c57989]) options=[] 2021/07/25 05:51:00 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:51:00 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: checkPathExists(map[path:trailing VT␋/trailing VT␋ pid:121000084]) options=[] 2021/07/25 05:51:01 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:51:01 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:51:02 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:51:02 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000148 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:51:04 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:51:04 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doDeleteFile(map[completedeletion:n fi_id:121000149]) options=[] 2021/07/25 05:51:18 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:51:18 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000148 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:51:19 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:51:19 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doDeleteFolder(map[fi_id:121000148]) options=[] 2021/07/25 05:51:22 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:51:22 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:51:23 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= === RUN TestIntegration/FsMkdir/FsEncoding/trailing_dot fstests.go:669: testing "trailing dot." 2021/07/25 05:51:23 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:51:23 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:51:23 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doCreateNewFolder(map[fi_name:trailing dot. fi_pid:121000084]) options=[] 2021/07/25 05:51:27 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:51:27 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doInitUpload(map[directuploadsupport:n fi_contenttype:application/octet-stream fi_filename:trailing dot. fi_localtime:2021-07-25 05:51:27 fi_modified:2021-07-25 05:51:27 fi_name:trailing dot. fi_pid:121000152 fi_size:100 responsetype:json]) options=[] 2021/07/25 05:51:28 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:51:29 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doCompleteUpload(map[fi_size:100 remotetime:2021-07-25 05:51:27 uploadcode:cf40e0638486af342c4204acac5f35bc]) options=[] 2021/07/25 05:51:33 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:51:33 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: checkPathExists(map[path:trailing dot./trailing dot. pid:121000084]) options=[] 2021/07/25 05:51:33 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:51:33 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:51:36 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:51:36 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000152 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:51:38 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:51:38 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doDeleteFile(map[completedeletion:n fi_id:121000153]) options=[] 2021/07/25 05:51:51 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:51:51 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000152 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:51:53 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:51:53 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doDeleteFolder(map[fi_id:121000152]) options=[] 2021/07/25 05:51:56 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:51:56 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:51:57 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= === RUN TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8 fstests.go:669: testing "invalid utf-8\xfe" 2021/07/25 05:51:57 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:51:57 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:51:57 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doCreateNewFolder(map[fi_name:invalid utf-8‛FE fi_pid:121000084]) options=[] 2021/07/25 05:52:01 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:52:01 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doInitUpload(map[directuploadsupport:n fi_contenttype:application/octet-stream fi_filename:invalid utf-8‛FE fi_localtime:2021-07-25 05:52:01 fi_modified:2021-07-25 05:52:01 fi_name:invalid utf-8‛FE fi_pid:121000156 fi_size:100 responsetype:json]) options=[] 2021/07/25 05:52:03 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:52:04 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doCompleteUpload(map[fi_size:100 remotetime:2021-07-25 05:52:01 uploadcode:e75c2d3c155281d59e1675e11311eac2]) options=[] 2021/07/25 05:52:06 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:52:06 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: checkPathExists(map[path:invalid utf-8‛FE/invalid utf-8‛FE pid:121000084]) options=[] 2021/07/25 05:52:06 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:52:06 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:52:07 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:52:07 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000156 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:52:09 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:52:09 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doDeleteFile(map[completedeletion:n fi_id:121000157]) options=[] 2021/07/25 05:52:21 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:52:21 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000156 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:52:23 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:52:23 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doDeleteFolder(map[fi_id:121000156]) options=[] 2021/07/25 05:52:28 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:52:28 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:52:29 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= === RUN TestIntegration/FsMkdir/FsNewObjectNotFound 2021/07/25 05:52:29 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: checkPathExists(map[path:potato pid:121000084]) options=[] 2021/07/25 05:52:30 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:52:30 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: checkPathExists(map[path:directory/not/found/potato pid:121000084]) options=[] 2021/07/25 05:52:30 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= === RUN TestIntegration/FsMkdir/FsPutError 2021/07/25 05:52:30 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:52:30 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:52:30 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doCreateNewFolder(map[fi_name:hello? sausage fi_pid:121000084]) options=[] 2021/07/25 05:52:35 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:52:35 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000161 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:52:36 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:52:36 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doCreateNewFolder(map[fi_name:êé fi_pid:121000161]) options=[] 2021/07/25 05:52:40 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:52:40 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000162 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:52:42 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:52:42 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doCreateNewFolder(map[fi_name:Hello, 世界 fi_pid:121000162]) options=[] 2021/07/25 05:52:46 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:52:46 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000163 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:52:49 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:52:49 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doCreateNewFolder(map[fi_name: " ' @ < > & ? + ≠ fi_pid:121000163]) options=[] 2021/07/25 05:52:53 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:52:53 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doInitUpload(map[directuploadsupport:n fi_contenttype:text/plain; charset=utf-8 fi_filename:z.txt fi_localtime:2001-02-03 04:05:10 fi_modified:2001-02-03 04:05:10 fi_name:z.txt fi_pid:121000164 fi_size:10240 responsetype:json]) options=[] 2021/07/25 05:52:54 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:52:54 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Cancelling upload 9caebdc27f019a473cff3760dc9d1852 2021/07/25 05:52:54 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doAbortUpload(map[uploadcode:9caebdc27f019a473cff3760dc9d1852]) options=[] 2021/07/25 05:52:55 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:52:55 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: checkPathExists(map[path:hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt pid:121000084]) options=[] 2021/07/25 05:52:55 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= === RUN TestIntegration/FsMkdir/FsPutZeroLength 2021/07/25 05:52:55 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doInitUpload(map[directuploadsupport:n fi_contenttype:application/vnd.rclone.empty.file fi_filename:zero-length-file fi_localtime:2001-02-03 04:05:06 fi_modified:2001-02-03 04:05:06 fi_name:zero-length-file fi_pid:121000084 fi_size:0 responsetype:json]) options=[] 2021/07/25 05:52:56 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:52:57 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doCompleteUpload(map[fi_size:1 remotetime:2001-02-03 04:05:06 uploadcode:5891daf5a7b9e0a66342b36b97cba425]) options=[] 2021/07/25 05:52:59 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:52:59 DEBUG : zero-length-file: Correcting mime type from "application/octet-stream" to "application/vnd.rclone.empty.file" 2021/07/25 05:52:59 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doModifyFile(map[data:fi_contenttype=application/vnd.rclone.empty.file fi_id:121000166]) options=[] 2021/07/25 05:52:59 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:52:59 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: checkPathExists(map[path:zero-length-file pid:121000084]) options=[] 2021/07/25 05:52:59 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:52:59 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doDeleteFile(map[completedeletion:n fi_id:121000166]) options=[] 2021/07/25 05:53:11 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= === RUN TestIntegration/FsMkdir/FsOpenWriterAt fstests.go:742: FS has no OpenWriterAt interface === RUN TestIntegration/FsMkdir/FsChangeNotify fstests.go:778: FS has no ChangeNotify interface === RUN TestIntegration/FsMkdir/FsPutFiles 2021/07/25 05:53:11 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doInitUpload(map[directuploadsupport:n fi_contenttype:text/plain; charset=utf-8 fi_filename:file name.txt fi_localtime:2001-02-03 04:05:06 fi_modified:2001-02-03 04:05:06 fi_name:file name.txt fi_pid:121000084 fi_size:100 responsetype:json]) options=[] 2021/07/25 05:53:12 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:53:14 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doCompleteUpload(map[fi_size:100 remotetime:2001-02-03 04:05:06 uploadcode:85251884439ae628c5106252b41f9cbb]) options=[] 2021/07/25 05:53:16 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:53:16 DEBUG : file name.txt: Correcting mime type from "text/plain" to "text/plain; charset=utf-8" 2021/07/25 05:53:16 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doModifyFile(map[data:fi_contenttype=text/plain; charset=utf-8 fi_id:121000167]) options=[] 2021/07/25 05:53:16 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:53:16 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: checkPathExists(map[path:file name.txt pid:121000084]) options=[] 2021/07/25 05:53:17 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:53:17 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doInitUpload(map[directuploadsupport:n fi_contenttype:text/plain; charset=utf-8 fi_filename:z.txt fi_localtime:2001-02-03 04:05:10 fi_modified:2001-02-03 04:05:10 fi_name:z.txt fi_pid:121000164 fi_size:100 responsetype:json]) options=[] 2021/07/25 05:53:19 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:53:20 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doCompleteUpload(map[fi_size:100 remotetime:2001-02-03 04:05:10 uploadcode:0f72d0afbbe647d81e46e70a17598d11]) options=[] 2021/07/25 05:53:23 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:53:23 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Correcting mime type from "text/plain" to "text/plain; charset=utf-8" 2021/07/25 05:53:23 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doModifyFile(map[data:fi_contenttype=text/plain; charset=utf-8 fi_id:121000168]) options=[] 2021/07/25 05:53:23 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:53:23 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: checkPathExists(map[path:hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt pid:121000084]) options=[] 2021/07/25 05:53:23 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:53:23 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doInitUpload(map[directuploadsupport:n fi_contenttype:text/csv fi_filename:file name.txt fi_localtime:2001-02-03 04:05:06 fi_modified:2001-02-03 04:05:06 fi_name:file name.txt fi_pid:121000084 fi_size:100 responsetype:json]) options=[] 2021/07/25 05:53:25 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:53:26 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doCompleteUpload(map[fi_size:100 remotetime:2001-02-03 04:05:06 uploadcode:575d0352439b33e1d2d1371565f848a9]) options=[] 2021/07/25 05:53:54 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:53:54 DEBUG : file name.txt: Correcting mime type from "text/plain" to "text/csv" 2021/07/25 05:53:54 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doModifyFile(map[data:fi_contenttype=text/csv fi_id:121000167]) options=[] 2021/07/25 05:53:55 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:53:55 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: checkPathExists(map[path:file name.txt pid:121000084]) options=[] 2021/07/25 05:53:55 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsListDirFile2 2021/07/25 05:53:55 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000164 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:53:57 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:53:57 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000163 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:53:58 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:53:58 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000162 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:54:01 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:54:01 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000161 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:54:02 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:54:02 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:54:04 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRDirFile2 fstests.go:378: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListR 2021/07/25 05:54:04 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:54:05 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:54:05 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000161 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:54:05 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:54:05 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000162 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:54:05 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:54:05 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000163 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:54:06 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:54:06 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000164 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:54:06 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir 2021/07/25 05:54:06 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000161 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:54:06 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:54:06 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000162 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:54:06 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:54:06 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000163 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:54:07 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:54:07 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000164 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:54:07 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsListDirRoot 2021/07/25 05:54:07 DEBUG : Creating backend with remote "TestFileFabric:" 2021/07/25 05:54:07 DEBUG : filefabric root '': 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/07/25 05:54:08 DEBUG : filefabric root '': >rpc: result=Success (ok), err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRDirRoot fstests.go:378: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListSubdir 2021/07/25 05:54:08 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000164 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:54:09 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:54:09 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000164 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:54:09 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir#01 fstests.go:378: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListLevel2 2021/07/25 05:54:09 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:54:09 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:54:09 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000161 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:54:09 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRLevel2 fstests.go:378: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListFile1 2021/07/25 05:54:09 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:54:10 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:54:10 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000161 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:54:10 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:54:10 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000162 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:54:11 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:54:11 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000163 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:54:11 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:54:11 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000164 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:54:11 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObject 2021/07/25 05:54:11 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: checkPathExists(map[path:file name.txt pid:121000084]) options=[] 2021/07/25 05:54:12 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObjectCaseInsensitive 2021/07/25 05:54:12 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: checkPathExists(map[path:FILE NAME.TXT pid:121000084]) options=[] 2021/07/25 05:54:12 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObjectCaseInsensitive/Dir 2021/07/25 05:54:12 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: checkPathExists(map[path:HELLO? SAUSAGE/êé/HELLO, 世界/ " ' @ < > & ? + ≠/Z.TXT pid:121000084]) options=[] 2021/07/25 05:54:12 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsListFile1and2 2021/07/25 05:54:12 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:54:13 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:54:13 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000161 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:54:13 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:54:13 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000162 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:54:13 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:54:13 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000163 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:54:14 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:54:14 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000164 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:54:14 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObjectDir 2021/07/25 05:54:14 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: checkPathExists(map[path:hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠ pid:121000084]) options=[] 2021/07/25 05:54:14 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsPurge 2021/07/25 05:54:14 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:54:14 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:54:14 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doCreateNewFolder(map[fi_name:dirToPurge fi_pid:121000084]) options=[] 2021/07/25 05:54:19 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:54:19 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doInitUpload(map[directuploadsupport:n fi_contenttype:text/plain; charset=utf-8 fi_filename:fileToPurge.txt fi_localtime:2001-02-03 04:05:06 fi_modified:2001-02-03 04:05:06 fi_name:fileToPurge.txt fi_pid:121000171 fi_size:100 responsetype:json]) options=[] 2021/07/25 05:54:20 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:54:21 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doCompleteUpload(map[fi_size:100 remotetime:2001-02-03 04:05:06 uploadcode:dfea6f7527735eb1770ffd0a3d9ca434]) options=[] 2021/07/25 05:54:25 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:54:25 DEBUG : dirToPurge/fileToPurge.txt: Correcting mime type from "text/plain" to "text/plain; charset=utf-8" 2021/07/25 05:54:25 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doModifyFile(map[data:fi_contenttype=text/plain; charset=utf-8 fi_id:121000172]) options=[] 2021/07/25 05:54:25 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:54:25 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: checkPathExists(map[path:dirToPurge/fileToPurge.txt pid:121000084]) options=[] 2021/07/25 05:54:25 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:54:25 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:54:27 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:54:27 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000161 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:54:27 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000171 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:54:27 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:54:27 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000162 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:54:28 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:54:28 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000163 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:54:28 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:54:28 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000164 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:54:28 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:54:29 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:54:29 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doDeleteFolder(map[fi_id:121000171]) options=[] 2021/07/25 05:54:32 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:54:32 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:54:32 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:54:32 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000161 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:54:32 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:54:32 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000162 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:54:33 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:54:33 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000163 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:54:33 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:54:33 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000164 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:54:33 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsCopy 2021/07/25 05:54:33 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: checkPathExists(map[path:hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt pid:121000084]) options=[] 2021/07/25 05:54:34 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:54:34 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doCopyFile(map[fi_id:121000168 fi_name:z.txt-copy fi_pid:121000164 force:y options:allownoextension]) options=[] 2021/07/25 05:54:41 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:54:41 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:54:41 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:54:41 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000161 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:54:41 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:54:41 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000162 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:54:42 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:54:42 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000163 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:54:42 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:54:42 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000164 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:54:43 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:54:43 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doDeleteFile(map[completedeletion:n fi_id:121000173]) options=[] 2021/07/25 05:55:12 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsMove 2021/07/25 05:55:12 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: checkPathExists(map[path:hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt pid:121000084]) options=[] 2021/07/25 05:55:12 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:55:12 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doRenameFile(map[fi_id:121000168 fi_name:other.txt.gecogav7]) options=[] 2021/07/25 05:55:24 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:55:24 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doMoveFiles(map[dir_id:121000084 fi_ids:121000168]) options=[] 2021/07/25 05:56:00 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Succcess. (ok), err= 2021/07/25 05:56:00 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doRenameFile(map[fi_id:121000168 fi_name:other.txt]) options=[] 2021/07/25 05:56:10 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:56:10 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:56:11 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:56:11 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000161 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:56:13 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:56:13 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000162 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:56:15 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:56:15 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000163 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:56:20 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:56:20 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000164 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:56:21 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:56:21 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: checkPathExists(map[path:file name.txt pid:121000084]) options=[] 2021/07/25 05:56:22 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:56:22 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:56:22 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:56:22 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doCreateNewFolder(map[fi_name:moveTest fi_pid:121000084]) options=[] 2021/07/25 05:56:26 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:56:26 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doRenameFile(map[fi_id:121000167 fi_name:other.txt.wewazov2]) options=[] 2021/07/25 05:56:37 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:56:37 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doMoveFiles(map[dir_id:121000182 fi_ids:121000167]) options=[] 2021/07/25 05:57:10 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Succcess. (ok), err= 2021/07/25 05:57:10 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doRenameFile(map[fi_id:121000167 fi_name:other.txt]) options=[] 2021/07/25 05:57:10 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': Token expired - refreshing 2021/07/25 05:57:10 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getTokenByAuthToken(map[authtoken:5da9521d9eaa946f-a20b55c2f82d2303 token:*]) options=[] 2021/07/25 05:57:10 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:57:10 DEBUG : Saving config "token" in section "TestFileFabric" of the config file 2021/07/25 05:57:10 DEBUG : Config file has changed externaly - reloading 2021/07/25 05:57:10 DEBUG : Saving config "token_expiry" in section "TestFileFabric" of the config file 2021/07/25 05:57:10 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getApplianceInfo(map[token:*]) options=[] 2021/07/25 05:57:11 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:57:11 DEBUG : Saving config "version" in section "TestFileFabric" of the config file 2021/07/25 05:57:23 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:57:23 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:57:25 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:57:25 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000182 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:57:25 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000161 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:57:25 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:57:25 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000162 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:57:26 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:57:26 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000163 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:57:26 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:57:26 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000164 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:57:26 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:57:27 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:57:27 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: checkPathExists(map[path:moveTest/other.txt pid:121000084]) options=[] 2021/07/25 05:57:27 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:57:27 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doRenameFile(map[fi_id:121000167 fi_name:file name.txt.tiyeson0]) options=[] 2021/07/25 05:57:38 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:57:38 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doMoveFiles(map[dir_id:121000084 fi_ids:121000167]) options=[] 2021/07/25 05:58:13 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Succcess. (ok), err= 2021/07/25 05:58:13 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doRenameFile(map[fi_id:121000167 fi_name:file name.txt]) options=[] 2021/07/25 05:58:25 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:58:25 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:58:27 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:58:27 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000182 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:58:27 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000161 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:58:27 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:58:27 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000162 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:58:27 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:58:27 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000163 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:58:28 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:58:28 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000164 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:58:28 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:58:28 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:58:28 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: checkPathExists(map[path:other.txt pid:121000084]) options=[] 2021/07/25 05:58:29 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:58:29 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doRenameFile(map[fi_id:121000168 fi_name:z.txt.kokaciw6]) options=[] 2021/07/25 05:58:42 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:58:42 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doMoveFiles(map[dir_id:121000164 fi_ids:121000168]) options=[] 2021/07/25 05:59:15 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Succcess. (ok), err= 2021/07/25 05:59:15 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doRenameFile(map[fi_id:121000168 fi_name:z.txt]) options=[] 2021/07/25 05:59:27 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:59:27 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:59:29 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:59:29 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000182 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:59:29 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000161 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:59:29 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:59:32 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:59:32 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000162 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:59:34 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:59:34 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000163 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:59:36 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:59:36 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000164 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:59:39 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:59:39 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000182 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:59:39 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 05:59:39 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doDeleteFolder(map[fi_id:121000182]) options=[] 2021/07/25 05:59:43 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsDirMove 2021/07/25 05:59:43 DEBUG : Creating backend with remote "TestFileFabric:rclone-test-lacawev2qateler1vovihar4" 2021/07/25 05:59:43 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': rpc: checkPathExists(map[path:rclone-test-lacawev2qateler1vovihar4 pid:120673762]) options=[] 2021/07/25 05:59:43 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': >rpc: result=Success (ok), err= 2021/07/25 05:59:43 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': 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/07/25 05:59:44 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': >rpc: result=Success (ok), err= 2021/07/25 05:59:44 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': rpc: doCreateNewFolder(map[fi_name:rclone-test-lacawev2qateler1vovihar4 fi_pid:120673762]) options=[] 2021/07/25 05:59:49 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': >rpc: result=Success (ok), err= 2021/07/25 05:59:49 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': rpc: getFolderContents(map[count:1000 fi_pid:121000192 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:59:51 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': >rpc: result=Success (ok), err= 2021/07/25 05:59:51 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': rpc: doCreateNewFolder(map[fi_name:new_name fi_pid:121000192]) options=[] 2021/07/25 05:59:55 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': >rpc: result=Success (ok), err= 2021/07/25 05:59:55 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': rpc: getFolderContents(map[count:1000 fi_pid:121000193 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 05:59:57 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': >rpc: result=Success (ok), err= 2021/07/25 05:59:57 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': rpc: doRenameFolder(map[fi_id:121000084 fi_name:sub_new_name.dadolon5]) options=[] 2021/07/25 06:00:12 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': >rpc: result=Success (ok), err= 2021/07/25 06:00:12 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': rpc: getUserBackgroundTasks(map[taskid:1277076]) options=[] 2021/07/25 06:00:14 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': >rpc: result=Success (ok), err= 2021/07/25 06:00:14 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': Waiting for task ID 1277076: Rename folder 'S3 Storage/smestoragencw/sub_new_name.dadolon5': to completed for 1s - waited 1.518125631s already 2021/07/25 06:00:15 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': rpc: getUserBackgroundTasks(map[taskid:1277076]) options=[] 2021/07/25 06:00:16 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': >rpc: result=Success (ok), err= 2021/07/25 06:00:16 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': Waiting for task ID 1277076: Rename folder 'S3 Storage/smestoragencw/sub_new_name.dadolon5': to completed for 1s - waited 4.151107462s already 2021/07/25 06:00:17 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': rpc: getUserBackgroundTasks(map[taskid:1277076]) options=[] 2021/07/25 06:00:18 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': >rpc: result=Success (ok), err= 2021/07/25 06:00:18 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': Waiting for task ID 1277076: Rename folder 'S3 Storage/smestoragencw/sub_new_name.dadolon5': to completed for 1s - waited 6.465658287s already 2021/07/25 06:00:19 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': rpc: getUserBackgroundTasks(map[taskid:1277076]) options=[] 2021/07/25 06:00:21 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': >rpc: result=Success (ok), err= 2021/07/25 06:00:21 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': Waiting for task ID 1277076: Rename folder 'S3 Storage/smestoragencw/sub_new_name.dadolon5': to completed for 1s - waited 8.772007159s already 2021/07/25 06:00:22 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': rpc: getUserBackgroundTasks(map[taskid:1277076]) options=[] 2021/07/25 06:00:23 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': >rpc: result=Success (ok), err= 2021/07/25 06:00:23 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': Waiting for task ID 1277076: Rename folder 'S3 Storage/smestoragencw/sub_new_name.dadolon5': to completed for 1s - waited 11.335965656s already 2021/07/25 06:00:24 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': rpc: getUserBackgroundTasks(map[taskid:1277076]) options=[] 2021/07/25 06:00:26 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': >rpc: result=Success (ok), err= 2021/07/25 06:00:26 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': Waiting for task ID 1277076: Rename folder 'S3 Storage/smestoragencw/sub_new_name.dadolon5': to completed for 1s - waited 14.04051349s already 2021/07/25 06:00:27 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': rpc: getUserBackgroundTasks(map[taskid:1277076]) options=[] 2021/07/25 06:00:30 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': >rpc: result=Success (ok), err= 2021/07/25 06:00:30 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': Waiting for task ID 1277076: Rename folder 'S3 Storage/smestoragencw/sub_new_name.dadolon5': to completed for 1s - waited 17.823350247s already 2021/07/25 06:00:31 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': rpc: getUserBackgroundTasks(map[taskid:1277076]) options=[] 2021/07/25 06:00:33 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': >rpc: result=Success (ok), err= 2021/07/25 06:00:33 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': Waiting for task ID 1277076: Rename folder 'S3 Storage/smestoragencw/sub_new_name.dadolon5': to completed for 1s - waited 20.540397381s already 2021/07/25 06:00:34 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': rpc: getUserBackgroundTasks(map[taskid:1277076]) options=[] 2021/07/25 06:00:38 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': >rpc: result=Success (ok), err= 2021/07/25 06:00:38 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': Waiting for task ID 1277076: Rename folder 'S3 Storage/smestoragencw/sub_new_name.dadolon5': to completed for 1s - waited 25.712595231s already 2021/07/25 06:00:39 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': rpc: getUserBackgroundTasks(map[taskid:1277076]) options=[] 2021/07/25 06:00:40 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': >rpc: result=Success (ok), err= 2021/07/25 06:00:40 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': Waiting for task ID 1277076: Rename folder 'S3 Storage/smestoragencw/sub_new_name.dadolon5': to completed for 1s - waited 28.102408879s already 2021/07/25 06:00:41 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': rpc: getUserBackgroundTasks(map[taskid:1277076]) options=[] 2021/07/25 06:00:45 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': >rpc: result=Success (ok), err= 2021/07/25 06:00:45 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': Waiting for task ID 1277076: Rename folder 'S3 Storage/smestoragencw/sub_new_name.dadolon5': to completed for 1s - waited 32.643025043s already 2021/07/25 06:00:46 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': rpc: getUserBackgroundTasks(map[taskid:1277076]) options=[] 2021/07/25 06:00:49 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': >rpc: result=Success (ok), err= 2021/07/25 06:00:49 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': Waiting for task ID 1277076: Rename folder 'S3 Storage/smestoragencw/sub_new_name.dadolon5': to completed for 1s - waited 36.672299502s already 2021/07/25 06:00:50 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': rpc: getUserBackgroundTasks(map[taskid:1277076]) options=[] 2021/07/25 06:00:51 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': >rpc: result=Success (ok), err= 2021/07/25 06:00:51 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': Waiting for task ID 1277076: Rename folder 'S3 Storage/smestoragencw/sub_new_name.dadolon5': to completed for 1s - waited 39.257902136s already 2021/07/25 06:00:52 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': rpc: getUserBackgroundTasks(map[taskid:1277076]) options=[] 2021/07/25 06:00:54 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': >rpc: result=Success (ok), err= 2021/07/25 06:00:54 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': Waiting for task ID 1277076: Rename folder 'S3 Storage/smestoragencw/sub_new_name.dadolon5': to completed for 1s - waited 41.545312873s already 2021/07/25 06:00:55 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': rpc: getUserBackgroundTasks(map[taskid:1277076]) options=[] 2021/07/25 06:00:57 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': >rpc: result=Success (ok), err= 2021/07/25 06:00:57 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': Waiting for task ID 1277076: Rename folder 'S3 Storage/smestoragencw/sub_new_name.dadolon5': to completed for 1s - waited 45.469022551s already 2021/07/25 06:00:58 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': rpc: getUserBackgroundTasks(map[taskid:1277076]) options=[] 2021/07/25 06:01:00 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': >rpc: result=Success (ok), err= 2021/07/25 06:01:00 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': Waiting for task ID 1277076: Rename folder 'S3 Storage/smestoragencw/sub_new_name.dadolon5': to completed for 1s - waited 48.138920617s already 2021/07/25 06:01:01 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': rpc: getUserBackgroundTasks(map[taskid:1277076]) options=[] 2021/07/25 06:01:02 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': >rpc: result=Success (ok), err= 2021/07/25 06:01:02 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': Waiting for task ID 1277076: Rename folder 'S3 Storage/smestoragencw/sub_new_name.dadolon5': to completed for 1s - waited 50.449487292s already 2021/07/25 06:01:03 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': rpc: getUserBackgroundTasks(map[taskid:1277076]) options=[] 2021/07/25 06:01:05 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': >rpc: result=Success (ok), err= 2021/07/25 06:01:05 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': Waiting for task ID 1277076: Rename folder 'S3 Storage/smestoragencw/sub_new_name.dadolon5': to completed for 1s - waited 52.70990841s already 2021/07/25 06:01:06 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': rpc: getUserBackgroundTasks(map[taskid:1277076]) options=[] 2021/07/25 06:01:07 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': >rpc: result=Success (ok), err= 2021/07/25 06:01:07 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': Waiting for task ID 1277076: Rename folder 'S3 Storage/smestoragencw/sub_new_name.dadolon5': to completed for 1s - waited 55.155037112s already 2021/07/25 06:01:08 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': rpc: getUserBackgroundTasks(map[taskid:1277076]) options=[] 2021/07/25 06:01:10 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': >rpc: result=Success (ok), err= 2021/07/25 06:01:10 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': Waiting for task ID 1277076: Rename folder 'S3 Storage/smestoragencw/sub_new_name.dadolon5': to completed for 1s - waited 57.505115059s already 2021/07/25 06:01:11 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': rpc: getUserBackgroundTasks(map[taskid:1277076]) options=[] 2021/07/25 06:01:12 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': >rpc: result=Success (ok), err= 2021/07/25 06:01:12 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': Waiting for task ID 1277076: Rename folder 'S3 Storage/smestoragencw/sub_new_name.dadolon5': to completed for 1s - waited 59.88096379s already 2021/07/25 06:01:13 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': rpc: getUserBackgroundTasks(map[taskid:1277076]) options=[] 2021/07/25 06:01:14 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': >rpc: result=Success (ok), err= 2021/07/25 06:01:14 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': Waiting for task ID 1277076: Rename folder 'S3 Storage/smestoragencw/sub_new_name.dadolon5': to completed for 1s - waited 1m2.431985853s already 2021/07/25 06:01:15 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': rpc: getUserBackgroundTasks(map[taskid:1277076]) options=[] 2021/07/25 06:01:17 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': >rpc: result=Success (ok), err= 2021/07/25 06:01:17 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': Waiting for task ID 1277076: Rename folder 'S3 Storage/smestoragencw/sub_new_name.dadolon5': to completed for 1s - waited 1m4.711576071s already 2021/07/25 06:01:18 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': rpc: getUserBackgroundTasks(map[taskid:1277076]) options=[] 2021/07/25 06:01:19 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': >rpc: result=Success (ok), err= 2021/07/25 06:01:19 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': Waiting for task ID 1277076: Rename folder 'S3 Storage/smestoragencw/sub_new_name.dadolon5': to completed for 1s - waited 1m6.979557127s already 2021/07/25 06:01:20 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': rpc: getUserBackgroundTasks(map[taskid:1277076]) options=[] 2021/07/25 06:01:23 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': >rpc: result=Success (ok), err= 2021/07/25 06:01:23 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': Waiting for task ID 1277076: Rename folder 'S3 Storage/smestoragencw/sub_new_name.dadolon5': to completed for 1s - waited 1m10.732362944s already 2021/07/25 06:01:24 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': rpc: getUserBackgroundTasks(map[taskid:1277076]) options=[] 2021/07/25 06:01:25 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': >rpc: result=Success (ok), err= 2021/07/25 06:01:25 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': rpc: doMoveFolders(map[dir_id:121000193 fi_ids:121000084]) options=[] 2021/07/25 06:01:32 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': >rpc: result=Succcess. (ok), err= 2021/07/25 06:01:32 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': rpc: doRenameFolder(map[fi_id:121000084 fi_name:sub_new_name]) options=[] 2021/07/25 06:01:36 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': Sleeping for 6s to wait for "error_background" error to clear 2021/07/25 06:01:42 DEBUG : pacer: low level retry 1/10 (error Can not rename folder now. We are not able to complete the requested operation. We are processing move folder in parent folder. Please try again later. (error_background)) 2021/07/25 06:01:42 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2021/07/25 06:01:46 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': Sleeping for 6s to wait for "error_background" error to clear 2021/07/25 06:01:52 DEBUG : pacer: low level retry 2/10 (error Can not rename folder now. We are not able to complete the requested operation. We are processing move folder in parent folder. Please try again later. (error_background)) 2021/07/25 06:01:52 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2021/07/25 06:01:57 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': Sleeping for 6s to wait for "error_background" error to clear 2021/07/25 06:02:03 DEBUG : pacer: low level retry 3/10 (error Can not rename folder now. We are not able to complete the requested operation. We are processing move folder in parent folder. Please try again later. (error_background)) 2021/07/25 06:02:03 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2021/07/25 06:02:07 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': Sleeping for 6s to wait for "error_background" error to clear 2021/07/25 06:02:13 DEBUG : pacer: low level retry 4/10 (error Can not rename folder now. We are not able to complete the requested operation. We are processing move folder in parent folder. Please try again later. (error_background)) 2021/07/25 06:02:13 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2021/07/25 06:02:17 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': Sleeping for 6s to wait for "error_background" error to clear 2021/07/25 06:02:23 DEBUG : pacer: low level retry 5/10 (error Can not rename folder now. We are not able to complete the requested operation. We are processing move folder in parent folder. Please try again later. (error_background)) 2021/07/25 06:02:23 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2021/07/25 06:02:27 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': Sleeping for 6s to wait for "error_background" error to clear 2021/07/25 06:02:33 DEBUG : pacer: low level retry 6/10 (error Can not rename folder now. We are not able to complete the requested operation. We are processing move folder in parent folder. Please try again later. (error_background)) 2021/07/25 06:02:33 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2021/07/25 06:02:37 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': Sleeping for 6s to wait for "error_background" error to clear 2021/07/25 06:02:43 DEBUG : pacer: low level retry 7/10 (error Can not rename folder now. We are not able to complete the requested operation. We are processing move folder in parent folder. Please try again later. (error_background)) 2021/07/25 06:02:43 DEBUG : pacer: Rate limited, increasing sleep to 2.56s 2021/07/25 06:02:47 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': Sleeping for 6s to wait for "error_background" error to clear 2021/07/25 06:02:53 DEBUG : pacer: low level retry 8/10 (error Can not rename folder now. We are not able to complete the requested operation. We are processing move folder in parent folder. Please try again later. (error_background)) 2021/07/25 06:02:53 DEBUG : pacer: Rate limited, increasing sleep to 5.12s 2021/07/25 06:02:58 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': Sleeping for 6s to wait for "error_background" error to clear 2021/07/25 06:03:04 DEBUG : pacer: low level retry 9/10 (error Can not rename folder now. We are not able to complete the requested operation. We are processing move folder in parent folder. Please try again later. (error_background)) 2021/07/25 06:03:04 DEBUG : pacer: Rate limited, increasing sleep to 10s 2021/07/25 06:03:07 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': Sleeping for 6s to wait for "error_background" error to clear 2021/07/25 06:03:13 DEBUG : pacer: low level retry 10/10 (error Can not rename folder now. We are not able to complete the requested operation. We are processing move folder in parent folder. Please try again later. (error_background)) 2021/07/25 06:03:13 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': >rpc: result=Can not rename folder now. We are not able to complete the requested operation. We are processing move folder in parent folder. Please try again later. (error_background), err=Can not rename folder now. We are not able to complete the requested operation. We are processing move folder in parent folder. Please try again later. (error_background) fstests.go:1220: Error Trace: fstests.go:1220 Error: Received unexpected error: Can not rename folder now. We are not able to complete the requested operation. We are processing move folder in parent folder. Please try again later. (error_background) failed to rename leaf github.com/rclone/rclone/backend/filefabric.(*Fs).renameLeaf /home/rclone/go/src/github.com/rclone/rclone/backend/filefabric/filefabric.go:888 github.com/rclone/rclone/backend/filefabric.(*Fs).move /home/rclone/go/src/github.com/rclone/rclone/backend/filefabric/filefabric.go:943 github.com/rclone/rclone/backend/filefabric.(*Fs).DirMove /home/rclone/go/src/github.com/rclone/rclone/backend/filefabric/filefabric.go:1022 github.com/rclone/rclone/fstest/fstests.Run.func13.13.19 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:1219 testing.tRunner /usr/local/go/src/testing/testing.go:1123 runtime.goexit /usr/local/go/src/runtime/asm_amd64.s:1374 Test: TestIntegration/FsMkdir/FsPutFiles/FsDirMove 2021/07/25 06:03:13 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': Purge remote 2021/07/25 06:03:13 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': rpc: doDeleteFolder(map[fi_id:121000192]) options=[] 2021/07/25 06:03:14 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': Sleeping for 6s to wait for "error_background" error to clear 2021/07/25 06:03:20 DEBUG : pacer: low level retry 1/10 (error We are not able to complete the requested operation. We are processing move folder in parent folder. Please try again later. (error_background)) 2021/07/25 06:03:28 DEBUG : pacer: Reducing sleep to 7.5s 2021/07/25 06:03:28 DEBUG : filefabric root 'rclone-test-lacawev2qateler1vovihar4': >rpc: result=Success (ok), err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsRmdirFull 2021/07/25 06:03:28 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFolderContents(map[count:1000 fi_pid:121000084 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 06:03:28 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 06:03:28 DEBUG : : Rmdir: contains file: "hello? sausage" === RUN TestIntegration/FsMkdir/FsPutFiles/FsPrecision === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectString 2021/07/25 06:03:28 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: checkPathExists(map[path:file name.txt pid:121000084]) options=[] 2021/07/25 06:03:28 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectFs 2021/07/25 06:03:28 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: checkPathExists(map[path:file name.txt pid:121000084]) options=[] 2021/07/25 06:03:28 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectRemote 2021/07/25 06:03:28 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: checkPathExists(map[path:file name.txt pid:121000084]) options=[] 2021/07/25 06:03:29 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectHashes 2021/07/25 06:03:29 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: checkPathExists(map[path:file name.txt pid:121000084]) options=[] 2021/07/25 06:03:29 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectModTime 2021/07/25 06:03:29 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: checkPathExists(map[path:file name.txt pid:121000084]) options=[] 2021/07/25 06:03:29 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectMimeType 2021/07/25 06:03:29 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: checkPathExists(map[path:file name.txt pid:121000084]) options=[] 2021/07/25 06:03:29 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= fstests.go:1342: Error Trace: fstests.go:1342 Error: Not equal: expected: "text/csv" actual : "text/plain" Diff: --- Expected +++ Actual @@ -1 +1 @@ -text/csv +text/plain Test: TestIntegration/FsMkdir/FsPutFiles/ObjectMimeType Messages: can read and write mime types but failed === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectSetModTime 2021/07/25 06:03:29 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: checkPathExists(map[path:file name.txt pid:121000084]) options=[] 2021/07/25 06:03:30 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 06:03:30 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doModifyFile(map[data:fi_localtime=2011-12-13 14:15:16 fi_id:121000167]) options=[] 2021/07/25 06:03:30 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 06:03:30 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: checkPathExists(map[path:file name.txt pid:121000084]) options=[] 2021/07/25 06:03:30 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectSize 2021/07/25 06:03:30 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: checkPathExists(map[path:file name.txt pid:121000084]) options=[] 2021/07/25 06:03:30 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpen 2021/07/25 06:03:30 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: checkPathExists(map[path:file name.txt pid:121000084]) options=[] 2021/07/25 06:03:30 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 06:03:30 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFile(map[fi_id:121000167]) options=[] 2021/07/25 06:03:33 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=, err= === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenSeek 2021/07/25 06:03:33 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: checkPathExists(map[path:file name.txt pid:121000084]) options=[] 2021/07/25 06:03:33 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 06:03:33 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFile(map[fi_id:121000167]) options=[SeekOption(50)] 2021/07/25 06:03:36 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=, err= === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenRange 2021/07/25 06:03:36 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: checkPathExists(map[path:file name.txt pid:121000084]) options=[] 2021/07/25 06:03:36 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 06:03:36 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFile(map[fi_id:121000167]) options=[RangeOption(5,15)] 2021/07/25 06:03:39 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=, err= 2021/07/25 06:03:39 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFile(map[fi_id:121000167]) options=[RangeOption(80,-1)] 2021/07/25 06:03:42 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=, err= 2021/07/25 06:03:42 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFile(map[fi_id:121000167]) options=[RangeOption(81,99)] 2021/07/25 06:03:44 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=, err= 2021/07/25 06:03:44 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFile(map[fi_id:121000167]) options=[RangeOption(80,-1)] 2021/07/25 06:03:47 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=, err= === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectPartialRead 2021/07/25 06:03:47 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: checkPathExists(map[path:file name.txt pid:121000084]) options=[] 2021/07/25 06:03:47 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 06:03:47 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: getFile(map[fi_id:121000167]) options=[] 2021/07/25 06:03:49 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=, err= === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectUpdate 2021/07/25 06:03:49 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: checkPathExists(map[path:file name.txt pid:121000084]) options=[] 2021/07/25 06:03:50 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 06:03:50 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doInitUpload(map[directuploadsupport:n fi_contenttype:text/plain; charset=utf-8 fi_filename:file name.txt fi_localtime:2011-12-13 14:15:16 fi_modified:2011-12-13 14:15:16 fi_name:file name.txt fi_pid:121000084 fi_size:200 responsetype:json]) options=[] 2021/07/25 06:03:50 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Can not upload file now. We are not able to complete the requested operation. We are processing delete in parent folder. Please try again later. (error_locked), err=Can not upload file now. We are not able to complete the requested operation. We are processing delete in parent folder. Please try again later. (error_locked) fstests.go:1432: Error Trace: fstests.go:1432 Error: Received unexpected error: Can not upload file now. We are not able to complete the requested operation. We are processing delete in parent folder. Please try again later. (error_locked) failed to initialize upload github.com/rclone/rclone/backend/filefabric.(*Object).Update /home/rclone/go/src/github.com/rclone/rclone/backend/filefabric/filefabric.go:1245 github.com/rclone/rclone/fstest/fstests.Run.func13.13.34 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:1431 testing.tRunner /usr/local/go/src/testing/testing.go:1123 runtime.goexit /usr/local/go/src/runtime/asm_amd64.s:1374 Test: TestIntegration/FsMkdir/FsPutFiles/ObjectUpdate === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectStorable 2021/07/25 06:03:50 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: checkPathExists(map[path:file name.txt pid:121000084]) options=[] 2021/07/25 06:03:51 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile 2021/07/25 06:03:51 DEBUG : Creating backend with remote "TestFileFabric:rclone-test-pacavib1tepukaf7niralum4/hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠/z.txt" 2021/07/25 06:03:51 DEBUG : Config file has changed externaly - reloading 2021/07/25 06:03:51 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4/hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt': rpc: checkPathExists(map[path:rclone-test-pacavib1tepukaf7niralum4/hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt pid:120673762]) options=[] 2021/07/25 06:03:51 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4/hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt': >rpc: result=Success (ok), err= fstests.go:1463: Error Trace: fstests.go:1463 Error: Not equal: expected: *errors.fundamental(is a file not a directory) actual : () Test: TestIntegration/FsMkdir/FsPutFiles/FsIsFile 2021/07/25 06:03:51 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4/hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt': 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/07/25 06:03:51 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4/hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt': >rpc: result=Success (ok), err= 2021/07/25 06:03:51 ERROR : : error listing: directory not found fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:302: Flushing the directory cache 2021/07/25 06:03:52 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4/hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt': 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/07/25 06:03:53 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4/hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt': >rpc: result=Success (ok), err= 2021/07/25 06:03:53 ERROR : : error listing: directory not found fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:302: Flushing the directory cache 2021/07/25 06:03:55 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4/hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt': 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/07/25 06:03:55 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4/hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt': >rpc: result=Success (ok), err= 2021/07/25 06:03:55 ERROR : : error listing: directory not found fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:302: Flushing the directory cache fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:345 fstests.go:1470 Error: Should be true Test: TestIntegration/FsMkdir/FsPutFiles/FsIsFile Messages: listing wrong, want z.txt (100) got fstest.go:204: Not found "z.txt" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:345 fstests.go:1470 Error: Not equal: expected: 0 actual : 1 Test: TestIntegration/FsMkdir/FsPutFiles/FsIsFile Messages: 1 objects not found === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound 2021/07/25 06:03:59 DEBUG : Creating backend with remote "TestFileFabric:rclone-test-pacavib1tepukaf7niralum4/not found.txt" 2021/07/25 06:03:59 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4/not found.txt': rpc: checkPathExists(map[path:rclone-test-pacavib1tepukaf7niralum4/not found.txt pid:120673762]) options=[] 2021/07/25 06:03:59 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4/not found.txt': >rpc: result=Success (ok), err= 2021/07/25 06:03:59 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4/not found.txt': 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/07/25 06:04:00 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4/not found.txt': >rpc: result=Success (ok), err= 2021/07/25 06:04:00 ERROR : : error listing: directory not found === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot fstests.go:1496: Opening root remote "TestFileFabric:" path "rclone-test-pacavib1tepukaf7niralum4" from "TestFileFabric:rclone-test-pacavib1tepukaf7niralum4" 2021/07/25 06:04:00 DEBUG : Creating backend with remote "TestFileFabric:" === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/List 2021/07/25 06:04:00 DEBUG : filefabric root '': 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/07/25 06:04:00 DEBUG : filefabric root '': >rpc: result=Success (ok), err= 2021/07/25 06:04:00 ERROR : rclone-test-pacavib1tepukaf7niralum4: error listing: directory not found fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:302: Flushing the directory cache 2021/07/25 06:04:01 DEBUG : filefabric root '': 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/07/25 06:04:01 DEBUG : filefabric root '': >rpc: result=Success (ok), err= 2021/07/25 06:04:01 ERROR : rclone-test-pacavib1tepukaf7niralum4: error listing: directory not found fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:302: Flushing the directory cache 2021/07/25 06:04:03 DEBUG : filefabric root '': 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/07/25 06:04:04 DEBUG : filefabric root '': >rpc: result=Success (ok), err= 2021/07/25 06:04:04 ERROR : rclone-test-pacavib1tepukaf7niralum4: error listing: directory not found fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:302: Flushing the directory cache fstest.go:306: Error Trace: fstest.go:306 fstests.go:1516 Error: Should be true Test: TestIntegration/FsMkdir/FsPutFiles/FromRoot/List Messages: listing wrong, want rclone-test-pacavib1tepukaf7niralum4/file name.txt (200), rclone-test-pacavib1tepukaf7niralum4/hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt (100) got fstest.go:204: Not found "rclone-test-pacavib1tepukaf7niralum4/file name.txt" fstest.go:204: Not found "rclone-test-pacavib1tepukaf7niralum4/hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠/z.txt" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstests.go:1516 Error: Not equal: expected: 0 actual : 2 Test: TestIntegration/FsMkdir/FsPutFiles/FromRoot/List Messages: 2 objects not found fstest.go:328: Error Trace: fstest.go:328 fstests.go:1516 Error: Not equal: expected: []string{"rclone-test-pacavib1tepukaf7niralum4/hello? sausage", "rclone-test-pacavib1tepukaf7niralum4/hello? sausage/êé", "rclone-test-pacavib1tepukaf7niralum4/hello? sausage/êé/Hello, 世界", "rclone-test-pacavib1tepukaf7niralum4/hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠"} actual : []string{} Diff: --- Expected +++ Actual @@ -1,6 +1,2 @@ -([]string) (len=4) { - (string) (len=51) "rclone-test-pacavib1tepukaf7niralum4/hello? sausage", - (string) (len=56) "rclone-test-pacavib1tepukaf7niralum4/hello? sausage/êé", - (string) (len=70) "rclone-test-pacavib1tepukaf7niralum4/hello? sausage/êé/Hello, 世界", - (string) (len=91) "rclone-test-pacavib1tepukaf7niralum4/hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠" +([]string) { } Test: TestIntegration/FsMkdir/FsPutFiles/FromRoot/List Messages: directories === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries 2021/07/25 06:04:08 DEBUG : filefabric root '': 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/07/25 06:04:08 DEBUG : filefabric root '': >rpc: result=Success (ok), err= fstests.go:1522: Error Trace: fstests.go:1522 Error: Received unexpected error: directory not found github.com/rclone/rclone/fs.init /home/rclone/go/src/github.com/rclone/rclone/fs/fs.go:34 runtime.doInit /usr/local/go/src/runtime/proc.go:5625 runtime.doInit /usr/local/go/src/runtime/proc.go:5620 runtime.doInit /usr/local/go/src/runtime/proc.go:5620 runtime.doInit /usr/local/go/src/runtime/proc.go:5620 runtime.main /usr/local/go/src/runtime/proc.go:191 runtime.goexit /usr/local/go/src/runtime/asm_amd64.s:1374 Test: TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListR fstests.go:1530: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put 2021/07/25 06:04:08 DEBUG : filefabric root '': 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/07/25 06:04:09 DEBUG : filefabric root '': >rpc: result=Success (ok), err= 2021/07/25 06:04:09 DEBUG : filefabric root '': rpc: doCreateNewFolder(map[fi_name:rclone-test-pacavib1tepukaf7niralum4 fi_pid:120673762]) options=[] 2021/07/25 06:04:13 DEBUG : filefabric root '': >rpc: result=Success (ok), err= 2021/07/25 06:04:13 DEBUG : filefabric root '': rpc: doInitUpload(map[directuploadsupport:n fi_contenttype:text/plain; charset=utf-8 fi_filename:created from root.txt fi_localtime:2021-07-25 06:04:08 fi_modified:2021-07-25 06:04:08 fi_name:created from root.txt fi_pid:121000214 fi_size:100 responsetype:json]) options=[] 2021/07/25 06:04:14 DEBUG : filefabric root '': >rpc: result=Success (ok), err= 2021/07/25 06:04:15 DEBUG : filefabric root '': rpc: doCompleteUpload(map[fi_size:100 remotetime:2021-07-25 06:04:08 uploadcode:36d70b8a4387e308a0df21dbfb5af6d3]) options=[] 2021/07/25 06:04:17 DEBUG : filefabric root '': >rpc: result=Success (ok), err= 2021/07/25 06:04:17 DEBUG : rclone-test-pacavib1tepukaf7niralum4/created from root.txt: Correcting mime type from "text/plain" to "text/plain; charset=utf-8" 2021/07/25 06:04:17 DEBUG : filefabric root '': rpc: doModifyFile(map[data:fi_contenttype=text/plain; charset=utf-8 fi_id:121000216]) options=[] 2021/07/25 06:04:17 DEBUG : filefabric root '': >rpc: result=Success (ok), err= 2021/07/25 06:04:17 DEBUG : filefabric root '': rpc: checkPathExists(map[path:rclone-test-pacavib1tepukaf7niralum4/created from root.txt pid:120673762]) options=[] 2021/07/25 06:04:17 DEBUG : filefabric root '': >rpc: result=Success (ok), err= 2021/07/25 06:04:17 DEBUG : filefabric root '': rpc: getFolderContents(map[count:1000 fi_pid:121000214 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 06:04:19 DEBUG : filefabric root '': >rpc: result=Success (ok), err= fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:302: Flushing the directory cache 2021/07/25 06:04:20 DEBUG : filefabric root '': 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/07/25 06:04:21 DEBUG : filefabric root '': >rpc: result=Success (ok), err= 2021/07/25 06:04:21 DEBUG : filefabric root '': rpc: getFolderContents(map[count:1000 fi_pid:121000214 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 06:04:21 DEBUG : filefabric root '': >rpc: result=Success (ok), err= fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:302: Flushing the directory cache 2021/07/25 06:04:23 DEBUG : filefabric root '': 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/07/25 06:04:23 DEBUG : filefabric root '': >rpc: result=Success (ok), err= 2021/07/25 06:04:23 DEBUG : filefabric root '': rpc: getFolderContents(map[count:1000 fi_pid:121000214 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 06:04:24 DEBUG : filefabric root '': >rpc: result=Success (ok), err= fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:302: Flushing the directory cache fstest.go:306: Error Trace: fstest.go:306 fstests.go:1572 Error: Should be true Test: TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put Messages: listing wrong, want rclone-test-pacavib1tepukaf7niralum4/created from root.txt (100), rclone-test-pacavib1tepukaf7niralum4/file name.txt (200), rclone-test-pacavib1tepukaf7niralum4/hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt (100) got rclone-test-pacavib1tepukaf7niralum4/created from root.txt (100) fstest.go:204: Not found "rclone-test-pacavib1tepukaf7niralum4/file name.txt" fstest.go:204: Not found "rclone-test-pacavib1tepukaf7niralum4/hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠/z.txt" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstests.go:1572 Error: Not equal: expected: 0 actual : 2 Test: TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put Messages: 2 objects not found === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put/Remove 2021/07/25 06:04:28 DEBUG : filefabric root '': rpc: doDeleteFile(map[completedeletion:n fi_id:121000216]) options=[] 2021/07/25 06:04:44 DEBUG : filefabric root '': >rpc: result=Success (ok), err= 2021/07/25 06:04:44 DEBUG : filefabric root '': 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/07/25 06:04:45 DEBUG : filefabric root '': >rpc: result=Success (ok), err= 2021/07/25 06:04:45 DEBUG : filefabric root '': rpc: getFolderContents(map[count:1000 fi_pid:121000214 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 06:04:48 DEBUG : filefabric root '': >rpc: result=Success (ok), err= fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:302: Flushing the directory cache 2021/07/25 06:04:49 DEBUG : filefabric root '': 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/07/25 06:04:49 DEBUG : filefabric root '': >rpc: result=Success (ok), err= 2021/07/25 06:04:49 DEBUG : filefabric root '': rpc: getFolderContents(map[count:1000 fi_pid:121000214 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 06:04:49 DEBUG : filefabric root '': >rpc: result=Success (ok), err= fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:302: Flushing the directory cache 2021/07/25 06:04:51 DEBUG : filefabric root '': 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/07/25 06:04:51 DEBUG : filefabric root '': >rpc: result=Success (ok), err= 2021/07/25 06:04:51 DEBUG : filefabric root '': rpc: getFolderContents(map[count:1000 fi_pid:121000214 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 06:04:52 DEBUG : filefabric root '': >rpc: result=Success (ok), err= fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:302: Flushing the directory cache fstest.go:306: Error Trace: fstest.go:306 fstests.go:1577 Error: Should be true Test: TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put/Remove Messages: listing wrong, want rclone-test-pacavib1tepukaf7niralum4/file name.txt (200), rclone-test-pacavib1tepukaf7niralum4/hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt (100) got fstest.go:204: Not found "rclone-test-pacavib1tepukaf7niralum4/file name.txt" fstest.go:204: Not found "rclone-test-pacavib1tepukaf7niralum4/hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠/z.txt" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstests.go:1577 Error: Not equal: expected: 0 actual : 2 Test: TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put/Remove Messages: 2 objects not found === RUN TestIntegration/FsMkdir/FsPutFiles/PublicLink fstests.go:1589: FS has no PublicLinker interface === RUN TestIntegration/FsMkdir/FsPutFiles/SetTier fstests.go:392: FS has no SetTier & GetTier interfaces === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectCheckWrap fstests.go:1674: Not a wrapping Fs === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectRemove 2021/07/25 06:04:56 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: checkPathExists(map[path:file name.txt pid:121000084]) options=[] 2021/07/25 06:04:56 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 06:04:56 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doDeleteFile(map[completedeletion:n fi_id:121000167]) options=[] 2021/07/25 06:04:57 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': Sleeping for 6s to wait for "error_background" error to clear 2021/07/25 06:05:03 DEBUG : pacer: low level retry 1/10 (error We are not able to complete the requested operation. We are processing delete in parent folder. Please try again later. (error_background)) 2021/07/25 06:05:03 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2021/07/25 06:05:04 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': Sleeping for 6s to wait for "error_background" error to clear 2021/07/25 06:05:10 DEBUG : pacer: low level retry 2/10 (error We are not able to complete the requested operation. We are processing delete in parent folder. Please try again later. (error_background)) 2021/07/25 06:05:10 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2021/07/25 06:05:11 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': Sleeping for 6s to wait for "error_background" error to clear 2021/07/25 06:05:17 DEBUG : pacer: low level retry 3/10 (error We are not able to complete the requested operation. We are processing delete in parent folder. Please try again later. (error_background)) 2021/07/25 06:05:17 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2021/07/25 06:05:17 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': Sleeping for 6s to wait for "error_background" error to clear 2021/07/25 06:05:23 DEBUG : pacer: low level retry 4/10 (error We are not able to complete the requested operation. We are processing delete in parent folder. Please try again later. (error_background)) 2021/07/25 06:05:23 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2021/07/25 06:05:24 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': Sleeping for 6s to wait for "error_background" error to clear 2021/07/25 06:05:30 DEBUG : pacer: low level retry 5/10 (error We are not able to complete the requested operation. We are processing delete in parent folder. Please try again later. (error_background)) 2021/07/25 06:05:30 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2021/07/25 06:05:33 DEBUG : pacer: Reducing sleep to 480ms 2021/07/25 06:05:33 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=File file name.txt is already in trash. To delete it permanently use the DeleteFromTrash function. (file_error), err=File file name.txt is already in trash. To delete it permanently use the DeleteFromTrash function. (file_error) fstests.go:1691: Error Trace: fstests.go:1691 Error: Received unexpected error: File file name.txt is already in trash. To delete it permanently use the DeleteFromTrash function. (file_error) failed to delete file github.com/rclone/rclone/backend/filefabric.(*Fs).deleteObject /home/rclone/go/src/github.com/rclone/rclone/backend/filefabric/filefabric.go:724 github.com/rclone/rclone/backend/filefabric.(*Object).Remove /home/rclone/go/src/github.com/rclone/rclone/backend/filefabric/filefabric.go:1326 github.com/rclone/rclone/fstest/fstests.Run.func13.13.42 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:1690 testing.tRunner /usr/local/go/src/testing/testing.go:1123 runtime.goexit /usr/local/go/src/runtime/asm_amd64.s:1374 Test: TestIntegration/FsMkdir/FsPutFiles/ObjectRemove === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectAbout fstests.go:1703: FS does not support About === RUN TestIntegration/FsMkdir/FsPutFiles/FsPutStream fstests.go:1721: FS has no PutStream interface === RUN TestIntegration/FsMkdir/FsPutFiles/Internal fstests.go:1765: *filefabric.Fs does not implement InternalTester === RUN TestIntegration/FsMkdir/FsPutChunked fstests.go:1782: *filefabric.Fs does not implement SetUploadChunkSizer === RUN TestIntegration/FsMkdir/FsUploadUnknownSize === RUN TestIntegration/FsMkdir/FsUploadUnknownSize/FsPutUnknownSize 2021/07/25 06:05:33 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doInitUpload(map[directuploadsupport:n fi_contenttype:text/plain; charset=utf-8 fi_filename:unknown-size-put.txt fi_localtime:2002-02-03 04:05:06 fi_modified:2002-02-03 04:05:06 fi_name:unknown-size-put.txt fi_pid:121000084 responsetype:json]) options=[] 2021/07/25 06:05:34 DEBUG : pacer: Reducing sleep to 360ms 2021/07/25 06:05:34 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 06:05:35 DEBUG : pacer: Reducing sleep to 270ms 2021/07/25 06:05:35 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doCompleteUpload(map[fi_size:100 remotetime:2002-02-03 04:05:06 uploadcode:eee660c1c8d6f2e8e2350cab8d8d1521]) options=[] 2021/07/25 06:05:39 DEBUG : pacer: Reducing sleep to 202.5ms 2021/07/25 06:05:39 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 06:05:39 DEBUG : unknown-size-put.txt: Correcting mime type from "text/plain" to "text/plain; charset=utf-8" 2021/07/25 06:05:39 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doModifyFile(map[data:fi_contenttype=text/plain; charset=utf-8 fi_id:121000227]) options=[] 2021/07/25 06:05:39 DEBUG : pacer: Reducing sleep to 151.875ms 2021/07/25 06:05:39 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 06:05:39 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doDeleteFile(map[completedeletion:n fi_id:121000227]) options=[] 2021/07/25 06:05:42 DEBUG : pacer: Reducing sleep to 113.90625ms 2021/07/25 06:05:42 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=File unknown-size-put.txt is already in trash. To delete it permanently use the DeleteFromTrash function. (file_error), err=File unknown-size-put.txt is already in trash. To delete it permanently use the DeleteFromTrash function. (file_error) fstests.go:1899: Error Trace: fstests.go:1899 Error: Received unexpected error: File unknown-size-put.txt is already in trash. To delete it permanently use the DeleteFromTrash function. (file_error) failed to delete file github.com/rclone/rclone/backend/filefabric.(*Fs).deleteObject /home/rclone/go/src/github.com/rclone/rclone/backend/filefabric/filefabric.go:724 github.com/rclone/rclone/backend/filefabric.(*Object).Remove /home/rclone/go/src/github.com/rclone/rclone/backend/filefabric/filefabric.go:1326 github.com/rclone/rclone/fstest/fstests.Run.func13.15.1 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:1899 testing.tRunner /usr/local/go/src/testing/testing.go:1123 runtime.goexit /usr/local/go/src/runtime/asm_amd64.s:1374 Test: TestIntegration/FsMkdir/FsUploadUnknownSize/FsPutUnknownSize Messages: successfully uploaded unknown-sized file but failed to remove === RUN TestIntegration/FsMkdir/FsUploadUnknownSize/FsUpdateUnknownSize 2021/07/25 06:05:42 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doInitUpload(map[directuploadsupport:n fi_contenttype:text/plain; charset=utf-8 fi_filename:unknown-size-update.txt fi_localtime:2002-02-03 04:05:06 fi_modified:2002-02-03 04:05:06 fi_name:unknown-size-update.txt fi_pid:121000084 fi_size:100 responsetype:json]) options=[] 2021/07/25 06:05:44 DEBUG : pacer: Reducing sleep to 85.429687ms 2021/07/25 06:05:44 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 06:05:45 DEBUG : pacer: Reducing sleep to 64.072265ms 2021/07/25 06:05:45 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doCompleteUpload(map[fi_size:100 remotetime:2002-02-03 04:05:06 uploadcode:676f6805f1485f93a816c79ba74d4ca5]) options=[] 2021/07/25 06:05:47 DEBUG : pacer: Reducing sleep to 48.054198ms 2021/07/25 06:05:47 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 06:05:47 DEBUG : unknown-size-update.txt: Correcting mime type from "text/plain" to "text/plain; charset=utf-8" 2021/07/25 06:05:47 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doModifyFile(map[data:fi_contenttype=text/plain; charset=utf-8 fi_id:121000228]) options=[] 2021/07/25 06:05:48 DEBUG : pacer: Reducing sleep to 36.040648ms 2021/07/25 06:05:48 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 06:05:48 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: checkPathExists(map[path:unknown-size-update.txt pid:121000084]) options=[] 2021/07/25 06:05:48 DEBUG : pacer: Reducing sleep to 27.030486ms 2021/07/25 06:05:48 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 06:05:48 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: checkPathExists(map[path:unknown-size-update.txt pid:121000084]) options=[] 2021/07/25 06:05:48 DEBUG : pacer: Reducing sleep to 20.272864ms 2021/07/25 06:05:48 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 06:05:48 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doInitUpload(map[directuploadsupport:n fi_contenttype:text/plain; charset=utf-8 fi_filename:unknown-size-update.txt fi_localtime:2002-02-03 04:05:06 fi_modified:2002-02-03 04:05:06 fi_name:unknown-size-update.txt fi_pid:121000084 responsetype:json]) options=[] 2021/07/25 06:05:49 DEBUG : pacer: Reducing sleep to 20ms 2021/07/25 06:05:49 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 06:05:50 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doCompleteUpload(map[fi_size:200 remotetime:2002-02-03 04:05:06 uploadcode:20e1fc574d4d5446151c32cbfaf409c6]) options=[] 2021/07/25 06:06:13 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 06:06:13 DEBUG : unknown-size-update.txt: Correcting mime type from "text/plain" to "text/plain; charset=utf-8" 2021/07/25 06:06:13 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doModifyFile(map[data:fi_contenttype=text/plain; charset=utf-8 fi_id:121000228]) options=[] 2021/07/25 06:06:14 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 06:06:14 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doDeleteFile(map[completedeletion:n fi_id:121000228]) options=[] 2021/07/25 06:06:18 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=File unknown-size-update.txt is already in trash. To delete it permanently use the DeleteFromTrash function. (file_error), err=File unknown-size-update.txt is already in trash. To delete it permanently use the DeleteFromTrash function. (file_error) fstests.go:1923: Error Trace: fstests.go:1923 Error: Received unexpected error: File unknown-size-update.txt is already in trash. To delete it permanently use the DeleteFromTrash function. (file_error) failed to delete file github.com/rclone/rclone/backend/filefabric.(*Fs).deleteObject /home/rclone/go/src/github.com/rclone/rclone/backend/filefabric/filefabric.go:724 github.com/rclone/rclone/backend/filefabric.(*Object).Remove /home/rclone/go/src/github.com/rclone/rclone/backend/filefabric/filefabric.go:1326 github.com/rclone/rclone/fstest/fstests.Run.func13.15.2 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:1923 testing.tRunner /usr/local/go/src/testing/testing.go:1123 runtime.goexit /usr/local/go/src/runtime/asm_amd64.s:1374 Test: TestIntegration/FsMkdir/FsUploadUnknownSize/FsUpdateUnknownSize Messages: successfully updated object with unknown-sized source but failed to remove === RUN TestIntegration/FsMkdir/FsRootCollapse 2021/07/25 06:06:18 DEBUG : Creating backend with remote "TestFileFabric:rclone-test-pacavib1tepukaf7niralum4/deeper/nonexisting/directory" 2021/07/25 06:06:18 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4/deeper/nonexisting/directory': rpc: checkPathExists(map[path:rclone-test-pacavib1tepukaf7niralum4/deeper/nonexisting/directory pid:120673762]) options=[] 2021/07/25 06:06:19 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4/deeper/nonexisting/directory': >rpc: result=Success (ok), err= 2021/07/25 06:06:19 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4/deeper/nonexisting/directory': 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/07/25 06:06:20 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4/deeper/nonexisting/directory': >rpc: result=Success (ok), err= 2021/07/25 06:06:20 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4/deeper/nonexisting/directory': rpc: getFolderContents(map[count:1000 fi_pid:121000214 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2021/07/25 06:06:21 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4/deeper/nonexisting/directory': >rpc: result=Success (ok), err= 2021/07/25 06:06:21 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doDeleteFolder(map[fi_id:121000084]) options=[] 2021/07/25 06:06:24 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Folder sub_new_name.dadolon5 is already in trash. To delete it permanently use the DeleteFromTrash function. (file_error), err=Folder sub_new_name.dadolon5 is already in trash. To delete it permanently use the DeleteFromTrash function. (file_error) === CONT TestIntegration/FsMkdir fstests.go:1954: Error Trace: fstests.go:1954 Error: Received unexpected error: failed to remove directory: Folder sub_new_name.dadolon5 is already in trash. To delete it permanently use the DeleteFromTrash function. (file_error) Test: TestIntegration/FsMkdir 2021/07/25 06:06:24 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': 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/07/25 06:06:24 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= 2021/07/25 06:06:24 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': rpc: doDeleteFolder(map[fi_id:121000214]) options=[] 2021/07/25 06:06:27 DEBUG : filefabric root 'rclone-test-pacavib1tepukaf7niralum4': >rpc: result=Success (ok), err= === RUN TestIntegration/FsShutdown fstests.go:1978: Shutdown method not supported --- FAIL: TestIntegration (1585.24s) --- SKIP: TestIntegration/FsCheckWrap (0.00s) --- SKIP: TestIntegration/FsCommand (0.00s) --- PASS: TestIntegration/FsRmdirNotFound (1.50s) --- PASS: TestIntegration/FsString (0.00s) --- PASS: TestIntegration/FsName (0.00s) --- PASS: TestIntegration/FsRoot (0.00s) --- PASS: TestIntegration/FsRmdirEmpty (3.30s) --- FAIL: TestIntegration/FsMkdir (1567.33s) --- PASS: TestIntegration/FsMkdir/FsMkdirRmdirSubdir (44.26s) --- PASS: TestIntegration/FsMkdir/FsListEmpty (0.39s) --- PASS: TestIntegration/FsMkdir/FsListDirEmpty (0.41s) --- SKIP: TestIntegration/FsMkdir/FsListRDirEmpty (0.00s) --- PASS: TestIntegration/FsMkdir/FsListDirNotFound (0.34s) --- SKIP: TestIntegration/FsMkdir/FsListRDirNotFound (0.00s) --- PASS: TestIntegration/FsMkdir/FsEncoding (668.29s) --- PASS: TestIntegration/FsMkdir/FsEncoding/control_chars (50.69s) --- PASS: TestIntegration/FsMkdir/FsEncoding/dot (36.54s) --- PASS: TestIntegration/FsMkdir/FsEncoding/dot_dot (37.42s) --- PASS: TestIntegration/FsMkdir/FsEncoding/punctuation (38.81s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_space (37.72s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_tilde (40.52s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_CR (35.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_LF (31.53s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_HT (34.79s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_VT (37.49s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_dot (33.68s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_space (38.17s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_CR (38.46s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_LF (40.50s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_HT (39.08s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_VT (30.76s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_dot (34.15s) --- PASS: TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8 (32.48s) --- PASS: TestIntegration/FsMkdir/FsNewObjectNotFound (0.41s) --- PASS: TestIntegration/FsMkdir/FsPutError (25.19s) --- PASS: TestIntegration/FsMkdir/FsPutZeroLength (16.27s) --- SKIP: TestIntegration/FsMkdir/FsOpenWriterAt (0.00s) --- SKIP: TestIntegration/FsMkdir/FsChangeNotify (0.00s) --- FAIL: TestIntegration/FsMkdir/FsPutFiles (741.78s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListDirFile2 (9.53s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsListRDirFile2 (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListR (1.42s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir (1.15s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListDirRoot (1.47s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsListRDirRoot (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListSubdir (0.49s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir#01 (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListLevel2 (0.51s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsListRLevel2 (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListFile1 (1.99s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsNewObject (0.37s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsNewObjectCaseInsensitive (0.71s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsNewObjectCaseInsensitive/Dir (0.31s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListFile1and2 (1.54s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsNewObjectDir (0.22s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPurge (19.08s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsCopy (38.66s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsMove (271.00s) --- FAIL: TestIntegration/FsMkdir/FsPutFiles/FsDirMove (224.57s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsRmdirFull (0.48s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPrecision (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectString (0.21s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectFs (0.20s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectRemote (0.20s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectHashes (0.22s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectModTime (0.21s) --- FAIL: TestIntegration/FsMkdir/FsPutFiles/ObjectMimeType (0.21s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectSetModTime (0.63s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectSize (0.21s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpen (2.75s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpenSeek (3.19s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpenRange (10.66s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectPartialRead (2.66s) --- FAIL: TestIntegration/FsMkdir/FsPutFiles/ObjectUpdate (0.85s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectStorable (0.36s) --- FAIL: TestIntegration/FsMkdir/FsPutFiles/FsIsFile (8.20s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound (0.79s) --- FAIL: TestIntegration/FsMkdir/FsPutFiles/FromRoot (55.99s) --- FAIL: TestIntegration/FsMkdir/FsPutFiles/FromRoot/List (8.02s) --- FAIL: TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries (0.69s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListR (0.00s) --- FAIL: TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put (47.28s) --- FAIL: TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put/Remove (28.05s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/PublicLink (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/SetTier (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/ObjectCheckWrap (0.00s) --- FAIL: TestIntegration/FsMkdir/FsPutFiles/ObjectRemove (37.51s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/ObjectAbout (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsPutStream (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/Internal (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutChunked (0.00s) --- FAIL: TestIntegration/FsMkdir/FsUploadUnknownSize (45.28s) --- FAIL: TestIntegration/FsMkdir/FsUploadUnknownSize/FsPutUnknownSize (9.41s) --- FAIL: TestIntegration/FsMkdir/FsUploadUnknownSize/FsUpdateUnknownSize (35.88s) --- PASS: TestIntegration/FsMkdir/FsRootCollapse (2.35s) --- SKIP: TestIntegration/FsShutdown (0.00s) FAIL exit status 1 FAIL github.com/rclone/rclone/backend/filefabric 1585.254s "go test -v -timeout 1h0m0s -remote TestFileFabric: -verbose" - Finished ERROR in 26m29.295025539s (try 1/5): exit status 1: Failed [TestIntegration/FsMkdir/FsPutFiles/FsDirMove TestIntegration/FsMkdir/FsPutFiles/ObjectMimeType TestIntegration/FsMkdir/FsPutFiles/ObjectUpdate TestIntegration/FsMkdir/FsPutFiles/FsIsFile TestIntegration/FsMkdir/FsPutFiles/FromRoot/List TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put/Remove TestIntegration/FsMkdir/FsPutFiles/ObjectRemove TestIntegration/FsMkdir/FsUploadUnknownSize/FsPutUnknownSize TestIntegration/FsMkdir/FsUploadUnknownSize/FsUpdateUnknownSize]