"./operations.test -test.v -test.timeout 2h0m0s -remote TestFileFabric: -verbose -test.run '^TestCheckDownload$'" - Starting (try 2/5) 2022/01/13 08:33:30 DEBUG : Creating backend with remote "TestFileFabric:rclone-test-yobomof1sonuker6fafopev4" 2022/01/13 08:33:30 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2022/01/13 08:33:30 DEBUG : filefabric root 'rclone-test-yobomof1sonuker6fafopev4': rpc: checkPathExists(map[path:rclone-test-yobomof1sonuker6fafopev4 pid:120673762]) options=[] 2022/01/13 08:33:30 DEBUG : filefabric root 'rclone-test-yobomof1sonuker6fafopev4': >rpc: result=Success (ok), err= 2022/01/13 08:33:30 DEBUG : Creating backend with remote "/tmp/rclone4110526596" === RUN TestCheckDownload run.go:181: Remote "filefabric root 'rclone-test-yobomof1sonuker6fafopev4'", Local "Local file system at /tmp/rclone4110526596", Modify Window "1s" 2022/01/13 08:33:30 DEBUG : filefabric root 'rclone-test-yobomof1sonuker6fafopev4': 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/13 08:33:32 DEBUG : filefabric root 'rclone-test-yobomof1sonuker6fafopev4': >rpc: result=Success (ok), err= 2022/01/13 08:33:32 DEBUG : filefabric root 'rclone-test-yobomof1sonuker6fafopev4': rpc: doCreateNewFolder(map[fi_name:rclone-test-yobomof1sonuker6fafopev4 fi_pid:120673762]) options=[] 2022/01/13 08:33:40 DEBUG : filefabric root 'rclone-test-yobomof1sonuker6fafopev4': >rpc: result=Success (ok), err= 2022/01/13 08:33:40 DEBUG : filefabric root 'rclone-test-yobomof1sonuker6fafopev4': 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:121319186 fi_size:8 responsetype:json]) options=[] 2022/01/13 08:33:41 DEBUG : filefabric root 'rclone-test-yobomof1sonuker6fafopev4': >rpc: result=Success (ok), err= 2022/01/13 08:33:43 DEBUG : filefabric root 'rclone-test-yobomof1sonuker6fafopev4': rpc: doCompleteUpload(map[fi_size:8 remotetime:2011-12-30 12:59:59 uploadcode:274dc7c14bf928a8f5bf6c19f0055785]) options=[] 2022/01/13 08:33:45 DEBUG : filefabric root 'rclone-test-yobomof1sonuker6fafopev4': >rpc: result=Success (ok), err= 2022/01/13 08:33:45 DEBUG : filefabric root 'rclone-test-yobomof1sonuker6fafopev4': rpc: getFolderContents(map[count:1000 fi_pid:121319186 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/13 08:33:54 DEBUG : filefabric root 'rclone-test-yobomof1sonuker6fafopev4': >rpc: result=Success (ok), err= === RUN TestCheckDownload/1 === RUN TestCheckDownload/2 2022/01/13 08:34:01 DEBUG : filefabric root 'rclone-test-yobomof1sonuker6fafopev4': 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:121319186 fi_size:1 responsetype:json]) options=[] 2022/01/13 08:34:04 DEBUG : filefabric root 'rclone-test-yobomof1sonuker6fafopev4': >rpc: result=Success (ok), err= 2022/01/13 08:34:05 DEBUG : filefabric root 'rclone-test-yobomof1sonuker6fafopev4': rpc: doCompleteUpload(map[fi_size:1 remotetime:2011-12-25 12:59:59 uploadcode:3641e1509dae92b70b282cbcc34bc54c]) options=[] 2022/01/13 08:34:09 DEBUG : filefabric root 'rclone-test-yobomof1sonuker6fafopev4': >rpc: result=Success (ok), err= 2022/01/13 08:34:09 DEBUG : filefabric root 'rclone-test-yobomof1sonuker6fafopev4': rpc: getFolderContents(map[count:1000 fi_pid:121319186 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/13 08:34:14 DEBUG : filefabric root 'rclone-test-yobomof1sonuker6fafopev4': >rpc: result=Success (ok), err= === RUN TestCheckDownload/3 2022/01/13 08:34:18 DEBUG : filefabric root 'rclone-test-yobomof1sonuker6fafopev4': 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:121319186 fi_size:60 responsetype:json]) options=[] 2022/01/13 08:34:21 DEBUG : filefabric root 'rclone-test-yobomof1sonuker6fafopev4': >rpc: result=Success (ok), err= 2022/01/13 08:34:22 DEBUG : filefabric root 'rclone-test-yobomof1sonuker6fafopev4': rpc: doCompleteUpload(map[fi_size:60 remotetime:2001-02-03 04:05:06 uploadcode:a4d23c911f8cfb4aea5b0ec0d979a350]) options=[] 2022/01/13 08:34:33 DEBUG : filefabric root 'rclone-test-yobomof1sonuker6fafopev4': >rpc: result=Success (ok), err= 2022/01/13 08:34:33 DEBUG : filefabric root 'rclone-test-yobomof1sonuker6fafopev4': rpc: getFolderContents(map[count:1000 fi_pid:121319186 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/13 08:34:37 DEBUG : filefabric root 'rclone-test-yobomof1sonuker6fafopev4': >rpc: result=Success (ok), err= === RUN TestCheckDownload/4 === RUN TestCheckDownload/5 2022/01/13 08:34:43 DEBUG : filefabric root 'rclone-test-yobomof1sonuker6fafopev4': 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:121319186 fi_size:60 responsetype:json]) options=[] 2022/01/13 08:34:45 DEBUG : filefabric root 'rclone-test-yobomof1sonuker6fafopev4': >rpc: result=Success (ok), err= 2022/01/13 08:34:46 DEBUG : filefabric root 'rclone-test-yobomof1sonuker6fafopev4': rpc: doCompleteUpload(map[fi_size:60 remotetime:2001-02-03 04:05:06 uploadcode:2438b065d673eabe6f6b46432068c535]) options=[] 2022/01/13 08:34:51 DEBUG : filefabric root 'rclone-test-yobomof1sonuker6fafopev4': >rpc: result=Success (ok), err= 2022/01/13 08:34:51 DEBUG : filefabric root 'rclone-test-yobomof1sonuker6fafopev4': rpc: getFolderContents(map[count:1000 fi_pid:121319186 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/13 08:34:56 DEBUG : filefabric root 'rclone-test-yobomof1sonuker6fafopev4': >rpc: result=Success (ok), err= === RUN TestCheckDownload/6 === RUN TestCheckDownload/7 2022/01/13 08:35:02 DEBUG : filefabric root 'rclone-test-yobomof1sonuker6fafopev4': rpc: getFolderContents(map[count:1000 fi_pid:121319186 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/13 08:35:02 DEBUG : filefabric root 'rclone-test-yobomof1sonuker6fafopev4': >rpc: result=Success (ok), err= 2022/01/13 08:35:02 DEBUG : filefabric root 'rclone-test-yobomof1sonuker6fafopev4': rpc: doDeleteFile(map[completedeletion:n fi_id:121319191]) options=[] 2022/01/13 08:35:26 DEBUG : filefabric root 'rclone-test-yobomof1sonuker6fafopev4': >rpc: result=Success (ok), err= 2022/01/13 08:35:26 DEBUG : filefabric root 'rclone-test-yobomof1sonuker6fafopev4': rpc: doDeleteFile(map[completedeletion:n fi_id:121319193]) options=[] 2022/01/13 08:35:44 DEBUG : filefabric root 'rclone-test-yobomof1sonuker6fafopev4': >rpc: result=Success (ok), err= 2022/01/13 08:35:44 DEBUG : filefabric root 'rclone-test-yobomof1sonuker6fafopev4': rpc: doDeleteFile(map[completedeletion:n fi_id:121319196]) options=[] 2022/01/13 08:36:03 DEBUG : filefabric root 'rclone-test-yobomof1sonuker6fafopev4': >rpc: result=Success (ok), err= 2022/01/13 08:36:03 DEBUG : filefabric root 'rclone-test-yobomof1sonuker6fafopev4': rpc: doDeleteFile(map[completedeletion:n fi_id:121319188]) options=[] 2022/01/13 08:36:23 DEBUG : filefabric root 'rclone-test-yobomof1sonuker6fafopev4': >rpc: result=Success (ok), err= 2022/01/13 08:36:23 DEBUG : filefabric root 'rclone-test-yobomof1sonuker6fafopev4': rpc: getFolderContents(map[count:1000 fi_pid:121319186 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/13 08:36:23 DEBUG : filefabric root 'rclone-test-yobomof1sonuker6fafopev4': Token expired - refreshing 2022/01/13 08:36:23 DEBUG : filefabric root 'rclone-test-yobomof1sonuker6fafopev4': rpc: getTokenByAuthToken(map[authtoken:622506e6346e7ad6-c48bf9558d23a2ae token:*]) options=[] 2022/01/13 08:36:23 DEBUG : filefabric root 'rclone-test-yobomof1sonuker6fafopev4': >rpc: result=Success (ok), err= 2022/01/13 08:36:23 DEBUG : Saving config "token" in section "TestFileFabric" of the config file 2022/01/13 08:36:23 DEBUG : Config file has changed externaly - reloading 2022/01/13 08:36:23 DEBUG : Saving config "token_expiry" in section "TestFileFabric" of the config file 2022/01/13 08:36:23 DEBUG : filefabric root 'rclone-test-yobomof1sonuker6fafopev4': rpc: getApplianceInfo(map[token:*]) options=[] 2022/01/13 08:36:24 DEBUG : filefabric root 'rclone-test-yobomof1sonuker6fafopev4': >rpc: result=Success (ok), err= 2022/01/13 08:36:24 DEBUG : Saving config "version" in section "TestFileFabric" of the config file 2022/01/13 08:36:31 DEBUG : filefabric root 'rclone-test-yobomof1sonuker6fafopev4': >rpc: result=Success (ok), err= --- PASS: TestCheckDownload (180.28s) --- PASS: TestCheckDownload/1 (3.13s) --- PASS: TestCheckDownload/2 (3.90s) --- PASS: TestCheckDownload/3 (4.16s) --- PASS: TestCheckDownload/4 (3.23s) --- PASS: TestCheckDownload/5 (2.87s) --- PASS: TestCheckDownload/6 (3.11s) --- PASS: TestCheckDownload/7 (2.95s) PASS 2022/01/13 08:36:31 DEBUG : filefabric root 'rclone-test-yobomof1sonuker6fafopev4': Purge remote 2022/01/13 08:36:31 DEBUG : filefabric root 'rclone-test-yobomof1sonuker6fafopev4': rpc: doDeleteFolder(map[fi_id:121319186]) options=[] 2022/01/13 08:36:37 DEBUG : filefabric root 'rclone-test-yobomof1sonuker6fafopev4': >rpc: result=Success (ok), err= "./operations.test -test.v -test.timeout 2h0m0s -remote TestFileFabric: -verbose -test.run '^TestCheckDownload$'" - Finished OK in 3m7.2335812s (try 2/5)