"./operations.test -test.v -test.timeout 2h0m0s -remote TestFileFabric: -verbose -test.run '^(TestCat|TestCount|TestDelete|TestHashSums|TestLsLong)$'" - Starting (try 2/5) 2022/02/10 08:07:40 DEBUG : Creating backend with remote "TestFileFabric:rclone-test-bajilab1nuriqeg7fipacup2" 2022/02/10 08:07:40 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2022/02/10 08:07:40 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': rpc: checkPathExists(map[path:rclone-test-bajilab1nuriqeg7fipacup2 pid:120673762]) options=[] 2022/02/10 08:07:40 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': >rpc: result=Success (ok), err= 2022/02/10 08:07:40 DEBUG : Creating backend with remote "/tmp/rclone1580985328" === RUN TestLsLong run.go:181: Remote "filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2'", Local "Local file system at /tmp/rclone1580985328", Modify Window "1s" 2022/02/10 08:07:40 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': 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=[] 2022/02/10 08:07:47 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': >rpc: result=Success (ok), err= 2022/02/10 08:07:47 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': rpc: doCreateNewFolder(map[fi_name:rclone-test-bajilab1nuriqeg7fipacup2 fi_pid:120673762]) options=[] 2022/02/10 08:07:59 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': >rpc: result=Success (ok), err= 2022/02/10 08:07:59 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': rpc: doInitUpload(map[directuploadsupport:n fi_contenttype:application/octet-stream fi_filename:potato2 fi_localtime:2001-02-03 04:05:06 fi_modified:2001-02-03 04:05:06 fi_name:potato2 fi_pid:121376936 fi_size:60 responsetype:json]) options=[] 2022/02/10 08:08:00 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': >rpc: result=Success (ok), err= 2022/02/10 08:08:01 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': rpc: doCompleteUpload(map[fi_size:60 remotetime:2001-02-03 04:05:06 uploadcode:aae8cfd270d4e97b68dc852ab2e903e0]) options=[] 2022/02/10 08:08:06 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': >rpc: result=Success (ok), err= 2022/02/10 08:08:06 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': rpc: doInitUpload(map[directuploadsupport:n fi_contenttype:application/octet-stream fi_filename:empty space fi_localtime:2011-12-25 12:59:59 fi_modified:2011-12-25 12:59:59 fi_name:empty space fi_pid:121376936 fi_size:1 responsetype:json]) options=[] 2022/02/10 08:08:07 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': >rpc: result=Success (ok), err= 2022/02/10 08:08:08 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': rpc: doCompleteUpload(map[fi_size:1 remotetime:2011-12-25 12:59:59 uploadcode:669d5f5dd6af9382bde6becbe6c6da0b]) options=[] 2022/02/10 08:08:13 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': >rpc: result=Success (ok), err= 2022/02/10 08:08:13 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': rpc: getFolderContents(map[count:1000 fi_pid:121376936 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/02/10 08:08:22 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': >rpc: result=Success (ok), err= 2022/02/10 08:08:22 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': rpc: getFolderContents(map[count:1000 fi_pid:121376936 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/02/10 08:08:23 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': >rpc: result=Success (ok), err= 2022/02/10 08:08:23 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': rpc: getFolderContents(map[count:1000 fi_pid:121376936 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/02/10 08:08:23 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': >rpc: result=Success (ok), err= 2022/02/10 08:08:23 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': rpc: doDeleteFile(map[completedeletion:n fi_id:121376939]) options=[] 2022/02/10 08:08:51 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': >rpc: result=Success (ok), err= 2022/02/10 08:08:51 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': rpc: doDeleteFile(map[completedeletion:n fi_id:121376938]) options=[] 2022/02/10 08:09:14 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': >rpc: result=Success (ok), err= 2022/02/10 08:09:14 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': rpc: getFolderContents(map[count:1000 fi_pid:121376936 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/02/10 08:09:19 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': >rpc: result=Success (ok), err= --- PASS: TestLsLong (98.66s) === RUN TestHashSums run.go:181: Remote "filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2'", Local "Local file system at /tmp/rclone1580985328", Modify Window "1s" 2022/02/10 08:09:19 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': rpc: doInitUpload(map[directuploadsupport:n fi_contenttype:application/octet-stream fi_filename:potato2 fi_localtime:2001-02-03 04:05:06 fi_modified:2001-02-03 04:05:06 fi_name:potato2 fi_pid:121376936 fi_size:60 responsetype:json]) options=[] 2022/02/10 08:09:22 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': >rpc: result=Success (ok), err= 2022/02/10 08:09:23 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': rpc: doCompleteUpload(map[fi_size:60 remotetime:2001-02-03 04:05:06 uploadcode:5c26008f09f89c3c10c8a296d07c11b8]) options=[] 2022/02/10 08:09:30 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': >rpc: result=Success (ok), err= 2022/02/10 08:09:30 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': rpc: doInitUpload(map[directuploadsupport:n fi_contenttype:application/octet-stream fi_filename:empty space fi_localtime:2011-12-25 12:59:59 fi_modified:2011-12-25 12:59:59 fi_name:empty space fi_pid:121376936 fi_size:1 responsetype:json]) options=[] 2022/02/10 08:09:31 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': >rpc: result=Success (ok), err= 2022/02/10 08:09:32 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': rpc: doCompleteUpload(map[fi_size:1 remotetime:2011-12-25 12:59:59 uploadcode:3ebe9c2160102e3dfda1c0154171c27f]) options=[] 2022/02/10 08:09:38 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': >rpc: result=Success (ok), err= 2022/02/10 08:09:38 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': rpc: getFolderContents(map[count:1000 fi_pid:121376936 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/02/10 08:09:45 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': >rpc: result=Success (ok), err= 2022/02/10 08:09:45 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': rpc: getFolderContents(map[count:1000 fi_pid:121376936 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/02/10 08:09:45 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': >rpc: result=Success (ok), err= 2022/02/10 08:09:45 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': rpc: doDeleteFile(map[completedeletion:n fi_id:121376947]) options=[] 2022/02/10 08:10:14 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': >rpc: result=Success (ok), err= 2022/02/10 08:10:14 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': rpc: doDeleteFile(map[completedeletion:n fi_id:121376945]) options=[] 2022/02/10 08:10:48 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': >rpc: result=Success (ok), err= 2022/02/10 08:10:48 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': rpc: getFolderContents(map[count:1000 fi_pid:121376936 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/02/10 08:10:55 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': >rpc: result=Success (ok), err= --- PASS: TestHashSums (96.82s) === RUN TestCount run.go:181: Remote "filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2'", Local "Local file system at /tmp/rclone1580985328", Modify Window "1s" 2022/02/10 08:10:55 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': rpc: doInitUpload(map[directuploadsupport:n fi_contenttype:application/octet-stream fi_filename:potato2 fi_localtime:2001-02-03 04:05:06 fi_modified:2001-02-03 04:05:06 fi_name:potato2 fi_pid:121376936 fi_size:60 responsetype:json]) options=[] 2022/02/10 08:10:58 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': >rpc: result=Success (ok), err= 2022/02/10 08:11:00 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': rpc: doCompleteUpload(map[fi_size:60 remotetime:2001-02-03 04:05:06 uploadcode:f3f643a7679708ef9d3651f9ff62e29f]) options=[] 2022/02/10 08:11:03 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': >rpc: result=Success (ok), err= 2022/02/10 08:11:03 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': rpc: doInitUpload(map[directuploadsupport:n fi_contenttype:application/octet-stream fi_filename:empty space fi_localtime:2011-12-25 12:59:59 fi_modified:2011-12-25 12:59:59 fi_name:empty space fi_pid:121376936 fi_size:1 responsetype:json]) options=[] 2022/02/10 08:11:05 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': >rpc: result=Success (ok), err= 2022/02/10 08:11:07 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': rpc: doCompleteUpload(map[fi_size:1 remotetime:2011-12-25 12:59:59 uploadcode:3f59fd5a5f2c80c762438234653d44fb]) options=[] 2022/02/10 08:11:12 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': >rpc: result=Success (ok), err= 2022/02/10 08:11:12 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': rpc: getFolderContents(map[count:1000 fi_pid:121376936 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/02/10 08:11:16 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': >rpc: result=Success (ok), err= 2022/02/10 08:11:16 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': rpc: doCreateNewFolder(map[fi_name:sub dir fi_pid:121376936]) options=[] 2022/02/10 08:11:26 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': >rpc: result=Success (ok), err= 2022/02/10 08:11:26 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': rpc: doInitUpload(map[directuploadsupport:n fi_contenttype:application/octet-stream fi_filename:potato3 fi_localtime:2011-12-25 12:59:59 fi_modified:2011-12-25 12:59:59 fi_name:potato3 fi_pid:121376951 fi_size:5 responsetype:json]) options=[] 2022/02/10 08:11:29 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': >rpc: result=Success (ok), err= 2022/02/10 08:11:31 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': rpc: doCompleteUpload(map[fi_size:5 remotetime:2011-12-25 12:59:59 uploadcode:2ee5d7126b75310b17689d2c3160d99e]) options=[] 2022/02/10 08:11:36 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': >rpc: result=Success (ok), err= 2022/02/10 08:11:36 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': rpc: getFolderContents(map[count:1000 fi_pid:121376936 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/02/10 08:11:41 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': >rpc: result=Success (ok), err= 2022/02/10 08:11:41 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': rpc: getFolderContents(map[count:1000 fi_pid:121376951 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/02/10 08:11:47 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': >rpc: result=Success (ok), err= 2022/02/10 08:11:47 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': rpc: getFolderContents(map[count:1000 fi_pid:121376936 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/02/10 08:11:47 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': >rpc: result=Success (ok), err= 2022/02/10 08:11:47 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': rpc: getFolderContents(map[count:1000 fi_pid:121376936 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/02/10 08:11:56 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': >rpc: result=Success (ok), err= 2022/02/10 08:11:56 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': rpc: doDeleteFile(map[completedeletion:n fi_id:121376950]) options=[] 2022/02/10 08:12:32 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': >rpc: result=Success (ok), err= 2022/02/10 08:12:32 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': rpc: doDeleteFile(map[completedeletion:n fi_id:121376949]) options=[] 2022/02/10 08:13:01 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': >rpc: result=Success (ok), err= 2022/02/10 08:13:01 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': rpc: getFolderContents(map[count:1000 fi_pid:121376951 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/02/10 08:13:11 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': >rpc: result=Success (ok), err= 2022/02/10 08:13:11 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': rpc: doDeleteFile(map[completedeletion:n fi_id:121376952]) options=[] 2022/02/10 08:13:33 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': >rpc: result=Success (ok), err= 2022/02/10 08:13:33 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': rpc: getFolderContents(map[count:1000 fi_pid:121376951 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/02/10 08:13:42 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': >rpc: result=Success (ok), err= 2022/02/10 08:13:42 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': rpc: doDeleteFolder(map[fi_id:121376951]) options=[] 2022/02/10 08:13:53 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': >rpc: result=Success (ok), err= 2022/02/10 08:13:53 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': rpc: getFolderContents(map[count:1000 fi_pid:121376936 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/02/10 08:13:53 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': >rpc: result=Success (ok), err= --- PASS: TestCount (177.99s) === RUN TestDelete run.go:181: Remote "filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2'", Local "Local file system at /tmp/rclone1580985328", Modify Window "1s" 2022/02/10 08:13:53 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': rpc: doInitUpload(map[directuploadsupport:n fi_contenttype:application/octet-stream fi_filename:small fi_localtime:2011-12-25 12:59:59 fi_modified:2011-12-25 12:59:59 fi_name:small fi_pid:121376936 fi_size:10 responsetype:json]) options=[] 2022/02/10 08:13:54 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': >rpc: result=Success (ok), err= 2022/02/10 08:13:56 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': rpc: doCompleteUpload(map[fi_size:10 remotetime:2011-12-25 12:59:59 uploadcode:c93dc5626ae05fd40c082480e6da00f6]) options=[] 2022/02/10 08:13:59 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': >rpc: result=Success (ok), err= 2022/02/10 08:13:59 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': rpc: doInitUpload(map[directuploadsupport:n fi_contenttype:application/octet-stream fi_filename:medium fi_localtime:2001-02-03 04:05:06 fi_modified:2001-02-03 04:05:06 fi_name:medium fi_pid:121376936 fi_size:60 responsetype:json]) options=[] 2022/02/10 08:14:00 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': >rpc: result=Success (ok), err= 2022/02/10 08:14:01 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': rpc: doCompleteUpload(map[fi_size:60 remotetime:2001-02-03 04:05:06 uploadcode:19d688b0e894f2281059a9cfe1513860]) options=[] 2022/02/10 08:14:08 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': >rpc: result=Success (ok), err= 2022/02/10 08:14:08 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': rpc: doInitUpload(map[directuploadsupport:n fi_contenttype:application/octet-stream fi_filename:large fi_localtime:2001-02-03 04:05:06 fi_modified:2001-02-03 04:05:06 fi_name:large fi_pid:121376936 fi_size:100 responsetype:json]) options=[] 2022/02/10 08:14:11 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': >rpc: result=Success (ok), err= 2022/02/10 08:14:12 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': rpc: doCompleteUpload(map[fi_size:100 remotetime:2001-02-03 04:05:06 uploadcode:a98b9968553a4ec6d025846270852578]) options=[] 2022/02/10 08:14:18 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': >rpc: result=Success (ok), err= 2022/02/10 08:14:18 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': rpc: getFolderContents(map[count:1000 fi_pid:121376936 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/02/10 08:14:20 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': >rpc: result=Success (ok), err= 2022/02/10 08:14:20 DEBUG : Waiting for deletions to finish 2022/02/10 08:14:20 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': rpc: getFolderContents(map[count:1000 fi_pid:121376936 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/02/10 08:14:20 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': >rpc: result=Success (ok), err= 2022/02/10 08:14:20 DEBUG : large: Excluded 2022/02/10 08:14:20 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': rpc: doDeleteFile(map[completedeletion:n fi_id:121376966]) options=[] 2022/02/10 08:14:20 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': rpc: doDeleteFile(map[completedeletion:n fi_id:121376965]) options=[] 2022/02/10 08:14:43 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': >rpc: result=Success (ok), err= 2022/02/10 08:14:43 INFO : medium: Deleted 2022/02/10 08:14:43 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': >rpc: result=Success (ok), err= 2022/02/10 08:14:43 INFO : small: Deleted 2022/02/10 08:14:43 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': rpc: getFolderContents(map[count:1000 fi_pid:121376936 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/02/10 08:14:44 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': >rpc: result=Success (ok), err= 2022/02/10 08:14:44 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': rpc: getFolderContents(map[count:1000 fi_pid:121376936 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/02/10 08:14:44 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': >rpc: result=Success (ok), err= 2022/02/10 08:14:44 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': rpc: doDeleteFile(map[completedeletion:n fi_id:121376968]) options=[] 2022/02/10 08:15:11 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': >rpc: result=Success (ok), err= 2022/02/10 08:15:11 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': rpc: getFolderContents(map[count:1000 fi_pid:121376936 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/02/10 08:15:22 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': >rpc: result=Success (ok), err= --- PASS: TestDelete (88.81s) === RUN TestCat run.go:181: Remote "filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2'", Local "Local file system at /tmp/rclone1580985328", Modify Window "1s" 2022/02/10 08:15:22 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': rpc: doInitUpload(map[directuploadsupport:n fi_contenttype:application/octet-stream fi_filename:file1 fi_localtime:2001-02-03 04:05:06 fi_modified:2001-02-03 04:05:06 fi_name:file1 fi_pid:121376936 fi_size:10 responsetype:json]) options=[] 2022/02/10 08:15:23 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': >rpc: result=Success (ok), err= 2022/02/10 08:15:25 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': rpc: doCompleteUpload(map[fi_size:10 remotetime:2001-02-03 04:05:06 uploadcode:acdf3efe49bce0c748c643470df36202]) options=[] 2022/02/10 08:15:30 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': >rpc: result=Success (ok), err= 2022/02/10 08:15:30 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': rpc: doInitUpload(map[directuploadsupport:n fi_contenttype:application/octet-stream fi_filename:file2 fi_localtime:2011-12-25 12:59:59 fi_modified:2011-12-25 12:59:59 fi_name:file2 fi_pid:121376936 fi_size:9 responsetype:json]) options=[] 2022/02/10 08:15:31 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': >rpc: result=Success (ok), err= 2022/02/10 08:15:32 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': rpc: doCompleteUpload(map[fi_size:9 remotetime:2011-12-25 12:59:59 uploadcode:b8832090963e3a7a2f2b0448a1a4d674]) options=[] 2022/02/10 08:15:40 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': >rpc: result=Success (ok), err= 2022/02/10 08:15:40 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': rpc: getFolderContents(map[count:1000 fi_pid:121376936 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/02/10 08:15:47 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': >rpc: result=Success (ok), err= 2022/02/10 08:15:47 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': rpc: getFolderContents(map[count:1000 fi_pid:121376936 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/02/10 08:15:48 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': >rpc: result=Success (ok), err= 2022/02/10 08:15:48 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': rpc: getFile(map[fi_id:121376971]) options=[] 2022/02/10 08:15:51 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': >rpc: result=, err= 2022/02/10 08:15:51 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': rpc: getFile(map[fi_id:121376972]) options=[] 2022/02/10 08:15:53 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': >rpc: result=, err= 2022/02/10 08:15:53 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': rpc: getFolderContents(map[count:1000 fi_pid:121376936 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/02/10 08:15:54 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': >rpc: result=Success (ok), err= 2022/02/10 08:15:54 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': rpc: getFile(map[fi_id:121376971]) options=[RangeOption(0,4)] 2022/02/10 08:15:56 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': >rpc: result=, err= 2022/02/10 08:15:56 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': rpc: getFile(map[fi_id:121376972]) options=[RangeOption(0,4)] 2022/02/10 08:15:59 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': >rpc: result=, err= 2022/02/10 08:15:59 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': rpc: getFolderContents(map[count:1000 fi_pid:121376936 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/02/10 08:15:59 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': >rpc: result=Success (ok), err= 2022/02/10 08:15:59 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': rpc: getFile(map[fi_id:121376971]) options=[RangeOption(7,-1)] 2022/02/10 08:16:01 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': >rpc: result=, err= 2022/02/10 08:16:01 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': rpc: getFile(map[fi_id:121376972]) options=[RangeOption(6,-1)] 2022/02/10 08:16:06 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': >rpc: result=, err= 2022/02/10 08:16:06 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': rpc: getFolderContents(map[count:1000 fi_pid:121376936 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/02/10 08:16:06 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': >rpc: result=Success (ok), err= 2022/02/10 08:16:06 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': rpc: getFile(map[fi_id:121376971]) options=[RangeOption(1,3)] 2022/02/10 08:16:09 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': >rpc: result=, err= 2022/02/10 08:16:09 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': rpc: getFile(map[fi_id:121376972]) options=[RangeOption(1,3)] 2022/02/10 08:16:13 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': >rpc: result=, err= 2022/02/10 08:16:13 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': rpc: getFolderContents(map[count:1000 fi_pid:121376936 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/02/10 08:16:13 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': >rpc: result=Success (ok), err= 2022/02/10 08:16:13 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': rpc: doDeleteFile(map[completedeletion:n fi_id:121376971]) options=[] 2022/02/10 08:16:37 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': >rpc: result=Success (ok), err= 2022/02/10 08:16:37 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': rpc: doDeleteFile(map[completedeletion:n fi_id:121376972]) options=[] 2022/02/10 08:17:00 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': >rpc: result=Success (ok), err= 2022/02/10 08:17:00 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': rpc: getFolderContents(map[count:1000 fi_pid:121376936 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/02/10 08:17:07 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': >rpc: result=Success (ok), err= --- PASS: TestCat (104.72s) PASS 2022/02/10 08:17:07 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': Purge remote 2022/02/10 08:17:07 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': rpc: doDeleteFolder(map[fi_id:121376936]) options=[] 2022/02/10 08:17:17 DEBUG : filefabric root 'rclone-test-bajilab1nuriqeg7fipacup2': >rpc: result=Success (ok), err= "./operations.test -test.v -test.timeout 2h0m0s -remote TestFileFabric: -verbose -test.run '^(TestCat|TestCount|TestDelete|TestHashSums|TestLsLong)$'" - Finished OK in 9m37.728696846s (try 2/5)