"./operations.test -test.v -test.timeout 2h0m0s -remote TestFileFabric: -verbose -test.run '^TestCheckDownload$'" - Starting (try 3/5) 2022/01/12 09:19:47 DEBUG : Creating backend with remote "TestFileFabric:rclone-test-kequkam1lucuxom1lejipab4" 2022/01/12 09:19:47 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2022/01/12 09:19:47 DEBUG : filefabric root 'rclone-test-kequkam1lucuxom1lejipab4': rpc: checkPathExists(map[path:rclone-test-kequkam1lucuxom1lejipab4 pid:120673762]) options=[] 2022/01/12 09:19:47 DEBUG : filefabric root 'rclone-test-kequkam1lucuxom1lejipab4': >rpc: result=Success (ok), err= 2022/01/12 09:19:47 DEBUG : Creating backend with remote "/tmp/rclone246483485" === RUN TestCheckDownload run.go:181: Remote "filefabric root 'rclone-test-kequkam1lucuxom1lejipab4'", Local "Local file system at /tmp/rclone246483485", Modify Window "1s" 2022/01/12 09:19:47 DEBUG : filefabric root 'rclone-test-kequkam1lucuxom1lejipab4': 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/01/12 09:19:51 DEBUG : filefabric root 'rclone-test-kequkam1lucuxom1lejipab4': >rpc: result=Success (ok), err= 2022/01/12 09:19:51 DEBUG : filefabric root 'rclone-test-kequkam1lucuxom1lejipab4': rpc: doCreateNewFolder(map[fi_name:rclone-test-kequkam1lucuxom1lejipab4 fi_pid:120673762]) options=[] 2022/01/12 09:20:01 DEBUG : filefabric root 'rclone-test-kequkam1lucuxom1lejipab4': >rpc: result=Success (ok), err= 2022/01/12 09:20:01 DEBUG : filefabric root 'rclone-test-kequkam1lucuxom1lejipab4': rpc: doInitUpload(map[directuploadsupport:n fi_contenttype:application/octet-stream fi_filename:rutabaga fi_localtime:2011-12-30 12:59:59 fi_modified:2011-12-30 12:59:59 fi_name:rutabaga fi_pid:121318153 fi_size:8 responsetype:json]) options=[] 2022/01/12 09:20:03 DEBUG : filefabric root 'rclone-test-kequkam1lucuxom1lejipab4': >rpc: result=Success (ok), err= 2022/01/12 09:20:05 DEBUG : filefabric root 'rclone-test-kequkam1lucuxom1lejipab4': rpc: doCompleteUpload(map[fi_size:8 remotetime:2011-12-30 12:59:59 uploadcode:eb2fe2c8eb5815fd292c2c3f74c06be3]) options=[] 2022/01/12 09:20:07 DEBUG : filefabric root 'rclone-test-kequkam1lucuxom1lejipab4': >rpc: result=Success (ok), err= 2022/01/12 09:20:07 DEBUG : filefabric root 'rclone-test-kequkam1lucuxom1lejipab4': rpc: getFolderContents(map[count:1000 fi_pid:121318153 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/01/12 09:20:14 DEBUG : filefabric root 'rclone-test-kequkam1lucuxom1lejipab4': >rpc: result=Success (ok), err= === RUN TestCheckDownload/1 === RUN TestCheckDownload/2 2022/01/12 09:20:21 DEBUG : filefabric root 'rclone-test-kequkam1lucuxom1lejipab4': 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:121318153 fi_size:1 responsetype:json]) options=[] 2022/01/12 09:20:22 DEBUG : filefabric root 'rclone-test-kequkam1lucuxom1lejipab4': >rpc: result=Success (ok), err= 2022/01/12 09:20:23 DEBUG : filefabric root 'rclone-test-kequkam1lucuxom1lejipab4': rpc: doCompleteUpload(map[fi_size:1 remotetime:2011-12-25 12:59:59 uploadcode:619e35848816d9de96a807701b2f6dd6]) options=[] 2022/01/12 09:20:29 DEBUG : filefabric root 'rclone-test-kequkam1lucuxom1lejipab4': >rpc: result=Success (ok), err= 2022/01/12 09:20:29 DEBUG : filefabric root 'rclone-test-kequkam1lucuxom1lejipab4': rpc: getFolderContents(map[count:1000 fi_pid:121318153 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/01/12 09:20:35 DEBUG : filefabric root 'rclone-test-kequkam1lucuxom1lejipab4': >rpc: result=Success (ok), err= === RUN TestCheckDownload/3 2022/01/12 09:20:38 DEBUG : filefabric root 'rclone-test-kequkam1lucuxom1lejipab4': 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:121318153 fi_size:60 responsetype:json]) options=[] 2022/01/12 09:20:39 DEBUG : filefabric root 'rclone-test-kequkam1lucuxom1lejipab4': >rpc: result=Success (ok), err= 2022/01/12 09:20:40 DEBUG : filefabric root 'rclone-test-kequkam1lucuxom1lejipab4': rpc: doCompleteUpload(map[fi_size:60 remotetime:2001-02-03 04:05:06 uploadcode:21c38fc4c1b2892a86969444ff8f05c3]) options=[] 2022/01/12 09:20:47 DEBUG : filefabric root 'rclone-test-kequkam1lucuxom1lejipab4': >rpc: result=Success (ok), err= 2022/01/12 09:20:47 DEBUG : filefabric root 'rclone-test-kequkam1lucuxom1lejipab4': rpc: getFolderContents(map[count:1000 fi_pid:121318153 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/01/12 09:20:53 DEBUG : filefabric root 'rclone-test-kequkam1lucuxom1lejipab4': >rpc: result=Success (ok), err= === RUN TestCheckDownload/4 === RUN TestCheckDownload/5 2022/01/12 09:20:59 DEBUG : filefabric root 'rclone-test-kequkam1lucuxom1lejipab4': rpc: doInitUpload(map[directuploadsupport:n fi_contenttype:application/octet-stream fi_filename:remotepotato fi_localtime:2001-02-03 04:05:06 fi_modified:2001-02-03 04:05:06 fi_name:remotepotato fi_pid:121318153 fi_size:60 responsetype:json]) options=[] 2022/01/12 09:21:00 DEBUG : filefabric root 'rclone-test-kequkam1lucuxom1lejipab4': >rpc: result=Success (ok), err= 2022/01/12 09:21:01 DEBUG : filefabric root 'rclone-test-kequkam1lucuxom1lejipab4': rpc: doCompleteUpload(map[fi_size:60 remotetime:2001-02-03 04:05:06 uploadcode:fef0adfd75e320524d18135c440fed87]) options=[] 2022/01/12 09:21:07 DEBUG : filefabric root 'rclone-test-kequkam1lucuxom1lejipab4': >rpc: result=Success (ok), err= 2022/01/12 09:21:07 DEBUG : filefabric root 'rclone-test-kequkam1lucuxom1lejipab4': rpc: getFolderContents(map[count:1000 fi_pid:121318153 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/01/12 09:21:12 DEBUG : filefabric root 'rclone-test-kequkam1lucuxom1lejipab4': >rpc: result=Success (ok), err= === RUN TestCheckDownload/6 === RUN TestCheckDownload/7 2022/01/12 09:21:25 DEBUG : filefabric root 'rclone-test-kequkam1lucuxom1lejipab4': rpc: getFolderContents(map[count:1000 fi_pid:121318153 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/01/12 09:21:25 DEBUG : filefabric root 'rclone-test-kequkam1lucuxom1lejipab4': >rpc: result=Success (ok), err= 2022/01/12 09:21:25 DEBUG : filefabric root 'rclone-test-kequkam1lucuxom1lejipab4': rpc: doDeleteFile(map[completedeletion:n fi_id:121318156]) options=[] 2022/01/12 09:21:50 DEBUG : filefabric root 'rclone-test-kequkam1lucuxom1lejipab4': >rpc: result=Success (ok), err= 2022/01/12 09:21:50 DEBUG : filefabric root 'rclone-test-kequkam1lucuxom1lejipab4': rpc: doDeleteFile(map[completedeletion:n fi_id:121318157]) options=[] 2022/01/12 09:22:07 DEBUG : filefabric root 'rclone-test-kequkam1lucuxom1lejipab4': >rpc: result=Success (ok), err= 2022/01/12 09:22:07 DEBUG : filefabric root 'rclone-test-kequkam1lucuxom1lejipab4': rpc: doDeleteFile(map[completedeletion:n fi_id:121318158]) options=[] 2022/01/12 09:22:23 DEBUG : filefabric root 'rclone-test-kequkam1lucuxom1lejipab4': >rpc: result=Success (ok), err= 2022/01/12 09:22:23 DEBUG : filefabric root 'rclone-test-kequkam1lucuxom1lejipab4': rpc: doDeleteFile(map[completedeletion:n fi_id:121318154]) options=[] 2022/01/12 09:22:40 DEBUG : filefabric root 'rclone-test-kequkam1lucuxom1lejipab4': >rpc: result=Success (ok), err= 2022/01/12 09:22:40 DEBUG : filefabric root 'rclone-test-kequkam1lucuxom1lejipab4': rpc: getFolderContents(map[count:1000 fi_pid:121318153 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/01/12 09:22:44 DEBUG : filefabric root 'rclone-test-kequkam1lucuxom1lejipab4': >rpc: result=Success (ok), err= --- PASS: TestCheckDownload (177.35s) --- PASS: TestCheckDownload/1 (3.24s) --- PASS: TestCheckDownload/2 (3.00s) --- PASS: TestCheckDownload/3 (3.05s) --- PASS: TestCheckDownload/4 (3.33s) --- PASS: TestCheckDownload/5 (2.97s) --- PASS: TestCheckDownload/6 (9.43s) --- PASS: TestCheckDownload/7 (3.03s) PASS 2022/01/12 09:22:44 DEBUG : filefabric root 'rclone-test-kequkam1lucuxom1lejipab4': Purge remote 2022/01/12 09:22:44 DEBUG : filefabric root 'rclone-test-kequkam1lucuxom1lejipab4': rpc: doDeleteFolder(map[fi_id:121318153]) options=[] 2022/01/12 09:22:51 DEBUG : filefabric root 'rclone-test-kequkam1lucuxom1lejipab4': >rpc: result=Success (ok), err= "./operations.test -test.v -test.timeout 2h0m0s -remote TestFileFabric: -verbose -test.run '^TestCheckDownload$'" - Finished OK in 3m4.254772674s (try 3/5)