"./operations.test -test.v -test.timeout 2h0m0s -remote TestFileFabric: -verbose" - Starting (try 2/5) 2022/03/02 07:31:42 DEBUG : Creating backend with remote "TestFileFabric:rclone-test-mujovim6zicuyeq5hacaxub6" 2022/03/02 07:31:42 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2022/03/02 07:31:42 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: checkPathExists(map[path:rclone-test-mujovim6zicuyeq5hacaxub6 pid:120673762]) options=[] 2022/03/02 07:31:43 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 07:31:43 DEBUG : Creating backend with remote "/tmp/rclone3416142788" === RUN TestDoMultiThreadCopy --- PASS: TestDoMultiThreadCopy (0.00s) === RUN TestMultithreadCalculateChunks === RUN TestMultithreadCalculateChunks/{size:1_streams:10_wantPartSize:65536_wantStreams:1} === RUN TestMultithreadCalculateChunks/{size:1048576_streams:1_wantPartSize:1048576_wantStreams:1} === RUN TestMultithreadCalculateChunks/{size:1048576_streams:2_wantPartSize:524288_wantStreams:2} === RUN TestMultithreadCalculateChunks/{size:1048577_streams:2_wantPartSize:589824_wantStreams:2} === RUN TestMultithreadCalculateChunks/{size:1048575_streams:2_wantPartSize:524288_wantStreams:2} --- PASS: TestMultithreadCalculateChunks (0.00s) --- PASS: TestMultithreadCalculateChunks/{size:1_streams:10_wantPartSize:65536_wantStreams:1} (0.00s) --- PASS: TestMultithreadCalculateChunks/{size:1048576_streams:1_wantPartSize:1048576_wantStreams:1} (0.00s) --- PASS: TestMultithreadCalculateChunks/{size:1048576_streams:2_wantPartSize:524288_wantStreams:2} (0.00s) --- PASS: TestMultithreadCalculateChunks/{size:1048577_streams:2_wantPartSize:589824_wantStreams:2} (0.00s) --- PASS: TestMultithreadCalculateChunks/{size:1048575_streams:2_wantPartSize:524288_wantStreams:2} (0.00s) === RUN TestMultithreadCopy run.go:181: Remote "filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6'", Local "Local file system at /tmp/rclone3416142788", Modify Window "1s" === RUN TestMultithreadCopy/{size:131071_streams:2} 2022/03/02 07:31:43 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': 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/03/02 07:31:48 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 07:31:48 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doCreateNewFolder(map[fi_name:rclone-test-mujovim6zicuyeq5hacaxub6 fi_pid:120673762]) options=[] 2022/03/02 07:32:44 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 07:32:44 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': 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:121389116 fi_size:131071 responsetype:json]) options=[] 2022/03/02 07:32:50 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 07:32:53 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doCompleteUpload(map[fi_size:131071 remotetime:2001-02-03 04:05:06 uploadcode:36c656ec38745aa2611c6fc105e41d46]) options=[] 2022/03/02 07:33:14 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 07:33:14 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389116 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 07:33:46 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 07:33:46 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: checkPathExists(map[path:file1 pid:121389116]) options=[] 2022/03/02 07:33:47 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 07:33:47 DEBUG : file1: Starting multi-thread copy with 2 parts of size 64Ki 2022/03/02 07:33:47 DEBUG : file1: multi-thread copy: stream 2/2 (65536-131071) size 63.999Ki starting 2022/03/02 07:33:47 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFile(map[fi_id:121389119]) options=[RangeOption(65536,131070)] 2022/03/02 07:33:47 DEBUG : file1: multi-thread copy: stream 1/2 (0-65536) size 64Ki starting 2022/03/02 07:33:47 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFile(map[fi_id:121389119]) options=[RangeOption(0,65535)] 2022/03/02 07:33:50 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=, err= 2022/03/02 07:33:50 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=, err= 2022/03/02 07:33:50 DEBUG : file1: multi-thread copy: stream 2/2 (65536-131071) size 63.999Ki finished 2022/03/02 07:33:50 DEBUG : file1: multi-thread copy: stream 1/2 (0-65536) size 64Ki finished 2022/03/02 07:33:50 DEBUG : file1: Finished multi-thread copy with 2 parts of size 64Ki === RUN TestMultithreadCopy/{size:131072_streams:2} 2022/03/02 07:33:50 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': 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:121389116 fi_size:131072 responsetype:json]) options=[] 2022/03/02 07:33:53 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 07:33:54 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doCompleteUpload(map[fi_size:131072 remotetime:2001-02-03 04:05:06 uploadcode:5d215871b420d820e064dfa650e4bafc]) options=[] 2022/03/02 07:37:59 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 07:37:59 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389116 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 07:38:27 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 07:38:27 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: checkPathExists(map[path:file1 pid:121389116]) options=[] 2022/03/02 07:38:27 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 07:38:27 DEBUG : file1: Starting multi-thread copy with 2 parts of size 64Ki 2022/03/02 07:38:27 DEBUG : file1: multi-thread copy: stream 2/2 (65536-131072) size 64Ki starting 2022/03/02 07:38:27 DEBUG : file1: multi-thread copy: stream 1/2 (0-65536) size 64Ki starting 2022/03/02 07:38:27 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFile(map[fi_id:121389119]) options=[RangeOption(65536,131071)] 2022/03/02 07:38:27 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFile(map[fi_id:121389119]) options=[RangeOption(0,65535)] 2022/03/02 07:38:30 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=, err= 2022/03/02 07:38:30 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=, err= 2022/03/02 07:38:30 DEBUG : file1: multi-thread copy: stream 2/2 (65536-131072) size 64Ki finished 2022/03/02 07:38:30 DEBUG : file1: multi-thread copy: stream 1/2 (0-65536) size 64Ki finished 2022/03/02 07:38:30 DEBUG : file1: Finished multi-thread copy with 2 parts of size 64Ki === RUN TestMultithreadCopy/{size:131073_streams:2} 2022/03/02 07:38:30 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': 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:121389116 fi_size:131073 responsetype:json]) options=[] 2022/03/02 07:38:33 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 07:38:34 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doCompleteUpload(map[fi_size:131073 remotetime:2001-02-03 04:05:06 uploadcode:997003379df8a00123cc433abbab622d]) options=[] 2022/03/02 07:42:43 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 07:42:43 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389116 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 07:43:18 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 07:43:18 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: checkPathExists(map[path:file1 pid:121389116]) options=[] 2022/03/02 07:43:18 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 07:43:18 DEBUG : file1: Starting multi-thread copy with 2 parts of size 128Ki 2022/03/02 07:43:18 DEBUG : file1: multi-thread copy: stream 2/2 (131072-131073) size 1 starting 2022/03/02 07:43:18 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFile(map[fi_id:121389119]) options=[RangeOption(131072,131072)] 2022/03/02 07:43:18 DEBUG : file1: multi-thread copy: stream 1/2 (0-131072) size 128Ki starting 2022/03/02 07:43:18 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFile(map[fi_id:121389119]) options=[RangeOption(0,131071)] 2022/03/02 07:43:21 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=, err= 2022/03/02 07:43:21 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=, err= 2022/03/02 07:43:21 DEBUG : file1: multi-thread copy: stream 2/2 (131072-131073) size 1 finished 2022/03/02 07:43:22 DEBUG : file1: multi-thread copy: stream 1/2 (0-131072) size 128Ki finished 2022/03/02 07:43:22 DEBUG : file1: Finished multi-thread copy with 2 parts of size 128Ki 2022/03/02 07:43:22 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389116 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 07:43:22 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 07:43:22 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doDeleteFile(map[completedeletion:n fi_id:121389119]) options=[] 2022/03/02 07:44:30 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 07:44:30 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389116 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 07:45:09 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= --- PASS: TestMultithreadCopy (805.81s) --- PASS: TestMultithreadCopy/{size:131071_streams:2} (126.95s) --- PASS: TestMultithreadCopy/{size:131072_streams:2} (280.45s) --- PASS: TestMultithreadCopy/{size:131073_streams:2} (291.30s) === RUN TestSizeDiffers --- PASS: TestSizeDiffers (0.00s) === RUN TestReOpen === RUN TestReOpen/Seek === RUN TestReOpen/Seek/Basics === RUN TestReOpen/Seek/ErrorAtStart === RUN TestReOpen/Seek/WithErrors 2022/03/02 07:45:09 DEBUG : potato: Reopening on read failure after 2 bytes: retry 1/10: test error 2022/03/02 07:45:09 DEBUG : potato: Reopening on read failure after 3 bytes: retry 2/10: test error 2022/03/02 07:45:09 DEBUG : potato: Reopening on read failure after 6 bytes: retry 3/10: test error === RUN TestReOpen/Seek/TooManyErrors 2022/03/02 07:45:09 DEBUG : potato: Reopening on read failure after 2 bytes: retry 1/3: test error 2022/03/02 07:45:09 DEBUG : potato: Reopening on read failure after 3 bytes: retry 2/3: test error 2022/03/02 07:45:09 DEBUG : potato: Reopening on read failure after 6 bytes: retry 3/3: test error 2022/03/02 07:45:09 DEBUG : potato: Reopen failed after 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/Range === RUN TestReOpen/Range/Basics === RUN TestReOpen/Range/ErrorAtStart === RUN TestReOpen/Range/WithErrors 2022/03/02 07:45:09 DEBUG : potato: Reopening on read failure after 2 bytes: retry 1/10: test error 2022/03/02 07:45:09 DEBUG : potato: Reopening on read failure after 3 bytes: retry 2/10: test error 2022/03/02 07:45:09 DEBUG : potato: Reopening on read failure after 6 bytes: retry 3/10: test error === RUN TestReOpen/Range/TooManyErrors 2022/03/02 07:45:09 DEBUG : potato: Reopening on read failure after 2 bytes: retry 1/3: test error 2022/03/02 07:45:09 DEBUG : potato: Reopening on read failure after 3 bytes: retry 2/3: test error 2022/03/02 07:45:09 DEBUG : potato: Reopening on read failure after 6 bytes: retry 3/3: test error 2022/03/02 07:45:09 DEBUG : potato: Reopen failed after 6 bytes read: failed to reopen: too many retries --- PASS: TestReOpen (0.00s) --- PASS: TestReOpen/Seek (0.00s) --- PASS: TestReOpen/Seek/Basics (0.00s) --- PASS: TestReOpen/Seek/ErrorAtStart (0.00s) --- PASS: TestReOpen/Seek/WithErrors (0.00s) --- PASS: TestReOpen/Seek/TooManyErrors (0.00s) --- PASS: TestReOpen/Range (0.00s) --- PASS: TestReOpen/Range/Basics (0.00s) --- PASS: TestReOpen/Range/ErrorAtStart (0.00s) --- PASS: TestReOpen/Range/WithErrors (0.00s) --- PASS: TestReOpen/Range/TooManyErrors (0.00s) === RUN TestCheck run.go:181: Remote "filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6'", Local "Local file system at /tmp/rclone3416142788", Modify Window "1s" 2022/03/02 07:45:09 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': 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:121389116 fi_size:8 responsetype:json]) options=[] 2022/03/02 07:45:15 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 07:45:17 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doCompleteUpload(map[fi_size:8 remotetime:2011-12-30 12:59:59 uploadcode:6f2de07864c6205e315d62d3941415a1]) options=[] 2022/03/02 07:45:46 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 07:45:46 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389116 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 07:46:14 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= === RUN TestCheck/1 === RUN TestCheck/2 2022/03/02 07:46:15 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': 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:121389116 fi_size:1 responsetype:json]) options=[] 2022/03/02 07:46:20 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 07:46:21 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doCompleteUpload(map[fi_size:1 remotetime:2011-12-25 12:59:59 uploadcode:8285c578edc9a6a7afacea6a05882058]) options=[] 2022/03/02 07:46:33 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 07:46:33 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389116 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 07:47:00 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= === RUN TestCheck/3 2022/03/02 07:47:00 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': 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:121389116 fi_size:60 responsetype:json]) options=[] 2022/03/02 07:47:05 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 07:47:07 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doCompleteUpload(map[fi_size:60 remotetime:2001-02-03 04:05:06 uploadcode:af1ab1b8a430996dd131d6b23e47f837]) options=[] 2022/03/02 07:47:33 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 07:47:33 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389116 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 07:48:01 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= === RUN TestCheck/4 === RUN TestCheck/5 2022/03/02 07:48:02 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': 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:121389116 fi_size:60 responsetype:json]) options=[] 2022/03/02 07:48:07 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 07:48:09 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doCompleteUpload(map[fi_size:60 remotetime:2001-02-03 04:05:06 uploadcode:a7d9f92117e7817eb9494dfa24c25940]) options=[] 2022/03/02 07:48:44 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 07:48:44 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389116 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 07:49:15 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= === RUN TestCheck/6 === RUN TestCheck/7 2022/03/02 07:49:16 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389116 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 07:49:17 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 07:49:17 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doDeleteFile(map[completedeletion:n fi_id:121389133]) options=[] 2022/03/02 07:50:23 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 07:50:23 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doDeleteFile(map[completedeletion:n fi_id:121389134]) options=[] 2022/03/02 07:51:45 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 07:51:45 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doDeleteFile(map[completedeletion:n fi_id:121389135]) options=[] 2022/03/02 07:52:56 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 07:52:56 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doDeleteFile(map[completedeletion:n fi_id:121389132]) options=[] 2022/03/02 07:54:19 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 07:54:19 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389116 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 07:54:46 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= --- PASS: TestCheck (577.02s) --- PASS: TestCheck/1 (0.56s) --- PASS: TestCheck/2 (0.58s) --- PASS: TestCheck/3 (0.57s) --- PASS: TestCheck/4 (0.60s) --- PASS: TestCheck/5 (0.58s) --- PASS: TestCheck/6 (0.58s) --- PASS: TestCheck/7 (0.57s) === RUN TestCheckFsError 2022/03/02 07:54:46 DEBUG : Creating backend with remote "non-existent" 2022/03/02 07:54:46 DEBUG : Config file has changed externaly - reloading 2022/03/02 07:54:46 DEBUG : Creating backend with remote "non-existent" 2022/03/02 07:54:46 DEBUG : Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/non-existent: Waiting for checks to finish 2022/03/02 07:54:46 ERROR : Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/non-existent: error reading source root directory: directory not found 2022/03/02 07:54:46 NOTICE: Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/non-existent: 2 differences found 2022/03/02 07:54:46 NOTICE: Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/non-existent: 2 errors while checking --- PASS: TestCheckFsError (0.00s) === RUN TestCheckDownload run.go:181: Remote "filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6'", Local "Local file system at /tmp/rclone3416142788", Modify Window "1s" 2022/03/02 07:54:46 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': 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:121389116 fi_size:8 responsetype:json]) options=[] 2022/03/02 07:54:52 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 07:54:53 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doCompleteUpload(map[fi_size:8 remotetime:2011-12-30 12:59:59 uploadcode:682b243e05405408215fe6cd5454881e]) options=[] 2022/03/02 07:55:22 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 07:55:22 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389116 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 07:55:54 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= === RUN TestCheckDownload/1 === RUN TestCheckDownload/2 2022/03/02 07:56:01 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': 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:121389116 fi_size:1 responsetype:json]) options=[] 2022/03/02 07:56:05 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 07:56:06 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doCompleteUpload(map[fi_size:1 remotetime:2011-12-25 12:59:59 uploadcode:453ebc4ae34339fcec9df7238436cb75]) options=[] 2022/03/02 07:56:44 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 07:56:44 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389116 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 07:57:15 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= === RUN TestCheckDownload/3 2022/03/02 07:57:19 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': 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:121389116 fi_size:60 responsetype:json]) options=[] 2022/03/02 07:57:21 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 07:57:23 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doCompleteUpload(map[fi_size:60 remotetime:2001-02-03 04:05:06 uploadcode:68f8ccbe1a4aeae8ba9296a0efce45f1]) options=[] 2022/03/02 07:57:45 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 07:57:45 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389116 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 07:58:16 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= === RUN TestCheckDownload/4 === RUN TestCheckDownload/5 2022/03/02 07:58:23 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': 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:121389116 fi_size:60 responsetype:json]) options=[] 2022/03/02 07:58:31 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 07:58:32 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doCompleteUpload(map[fi_size:60 remotetime:2001-02-03 04:05:06 uploadcode:42cdc14e5422aea2edb2e134db5bb53c]) options=[] 2022/03/02 07:59:03 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 07:59:03 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389116 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 07:59:30 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= === RUN TestCheckDownload/6 === RUN TestCheckDownload/7 2022/03/02 07:59:37 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389116 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 07:59:37 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 07:59:37 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doDeleteFile(map[completedeletion:n fi_id:121389139]) options=[] 2022/03/02 08:01:18 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 08:01:18 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doDeleteFile(map[completedeletion:n fi_id:121389140]) options=[] 2022/03/02 08:03:01 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 08:03:01 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doDeleteFile(map[completedeletion:n fi_id:121389141]) options=[] 2022/03/02 08:04:50 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 08:04:50 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doDeleteFile(map[completedeletion:n fi_id:121389138]) options=[] 2022/03/02 08:07:21 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 08:07:21 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389116 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 08:07:56 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= --- PASS: TestCheckDownload (790.68s) --- PASS: TestCheckDownload/1 (3.94s) --- PASS: TestCheckDownload/2 (3.33s) --- PASS: TestCheckDownload/3 (3.59s) --- PASS: TestCheckDownload/4 (3.65s) --- PASS: TestCheckDownload/5 (3.41s) --- PASS: TestCheckDownload/6 (3.59s) --- PASS: TestCheckDownload/7 (3.40s) === RUN TestCheckSizeOnly run.go:181: Remote "filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6'", Local "Local file system at /tmp/rclone3416142788", Modify Window "1s" 2022/03/02 08:07:56 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': 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:121389116 fi_size:8 responsetype:json]) options=[] 2022/03/02 08:08:03 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 08:08:04 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doCompleteUpload(map[fi_size:8 remotetime:2011-12-30 12:59:59 uploadcode:190c23b898e679f9156ada13e2bdfca2]) options=[] 2022/03/02 08:08:36 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 08:08:36 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389116 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 08:09:15 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= === RUN TestCheckSizeOnly/1 === RUN TestCheckSizeOnly/2 2022/03/02 08:09:16 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': 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:121389116 fi_size:1 responsetype:json]) options=[] 2022/03/02 08:09:21 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 08:09:22 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doCompleteUpload(map[fi_size:1 remotetime:2011-12-25 12:59:59 uploadcode:b0c73a3e498d381d02cbb6c7dedfa8bb]) options=[] 2022/03/02 08:10:01 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 08:10:01 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389116 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 08:10:40 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= === RUN TestCheckSizeOnly/3 2022/03/02 08:10:41 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': 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:121389116 fi_size:60 responsetype:json]) options=[] 2022/03/02 08:10:46 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 08:10:47 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doCompleteUpload(map[fi_size:60 remotetime:2001-02-03 04:05:06 uploadcode:ba1bd2bd0d45e093d63be01ab07e8e09]) options=[] 2022/03/02 08:11:25 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 08:11:25 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389116 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 08:12:00 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= === RUN TestCheckSizeOnly/4 === RUN TestCheckSizeOnly/5 2022/03/02 08:12:01 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': 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:121389116 fi_size:60 responsetype:json]) options=[] 2022/03/02 08:12:06 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 08:12:07 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doCompleteUpload(map[fi_size:60 remotetime:2001-02-03 04:05:06 uploadcode:d78993bcfe544b08a006635867ebdc35]) options=[] 2022/03/02 08:12:32 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 08:12:32 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389116 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 08:13:00 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= === RUN TestCheckSizeOnly/6 === RUN TestCheckSizeOnly/7 2022/03/02 08:13:01 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389116 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 08:13:01 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 08:13:01 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doDeleteFile(map[completedeletion:n fi_id:121389150]) options=[] 2022/03/02 08:14:48 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 08:14:48 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doDeleteFile(map[completedeletion:n fi_id:121389151]) options=[] 2022/03/02 08:16:55 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 08:16:55 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doDeleteFile(map[completedeletion:n fi_id:121389152]) options=[] 2022/03/02 08:18:49 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 08:18:49 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doDeleteFile(map[completedeletion:n fi_id:121389147]) options=[] 2022/03/02 08:20:52 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 08:20:52 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389116 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 08:20:52 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': Token expired - refreshing 2022/03/02 08:20:52 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getTokenByAuthToken(map[authtoken:622506e6346e7ad6-c48bf9558d23a2ae token:*]) options=[] 2022/03/02 08:20:53 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 08:20:53 DEBUG : Saving config "token" in section "TestFileFabric" of the config file 2022/03/02 08:20:53 DEBUG : Config file has changed externaly - reloading 2022/03/02 08:20:53 DEBUG : Saving config "token_expiry" in section "TestFileFabric" of the config file 2022/03/02 08:20:53 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getApplianceInfo(map[token:*]) options=[] 2022/03/02 08:20:53 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 08:20:53 DEBUG : Saving config "version" in section "TestFileFabric" of the config file 2022/03/02 08:21:23 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= --- PASS: TestCheckSizeOnly (806.89s) --- PASS: TestCheckSizeOnly/1 (0.56s) --- PASS: TestCheckSizeOnly/2 (0.56s) --- PASS: TestCheckSizeOnly/3 (0.57s) --- PASS: TestCheckSizeOnly/4 (0.57s) --- PASS: TestCheckSizeOnly/5 (0.58s) --- PASS: TestCheckSizeOnly/6 (0.57s) --- PASS: TestCheckSizeOnly/7 (0.58s) === RUN TestCheckEqualReaders --- PASS: TestCheckEqualReaders (0.00s) === RUN TestParseSumFile run.go:181: Remote "filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6'", Local "Local file system at /tmp/rclone3416142788", Modify Window "1s" 2022/03/02 08:21:23 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doInitUpload(map[directuploadsupport:n fi_contenttype:application/octet-stream fi_filename:test.sum fi_localtime:2001-02-03 04:05:06 fi_modified:2001-02-03 04:05:06 fi_name:test.sum fi_pid:121389116 fi_size:81 responsetype:json]) options=[] 2022/03/02 08:21:30 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 08:21:31 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doCompleteUpload(map[fi_size:81 remotetime:2001-02-03 04:05:06 uploadcode:7ab618c186a40689c4ec28c808bc21ec]) options=[] 2022/03/02 08:21:43 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 08:21:43 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: checkPathExists(map[path:test.sum pid:121389116]) options=[] 2022/03/02 08:21:44 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 08:21:44 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFile(map[fi_id:121389165]) options=[] 2022/03/02 08:21:46 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=, err= 2022/03/02 08:21:46 NOTICE: test.sum: improperly formatted checksum line 4 2022/03/02 08:21:46 NOTICE: test.sum: improperly formatted checksum line 5 2022/03/02 08:21:46 NOTICE: test.sum: improperly formatted checksum line 6 2022/03/02 08:21:46 NOTICE: test.sum: 2 warning(s) suppressed... 2022/03/02 08:21:46 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doInitUpload(map[directuploadsupport:n fi_contenttype:application/octet-stream fi_filename:test.sum fi_localtime:2001-02-03 04:05:06 fi_modified:2001-02-03 04:05:06 fi_name:test.sum fi_pid:121389116 fi_size:90 responsetype:json]) options=[] 2022/03/02 08:21:49 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 08:21:51 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doCompleteUpload(map[fi_size:90 remotetime:2001-02-03 04:05:06 uploadcode:02e29993df4812cd2393b9261a2840dc]) options=[] 2022/03/02 08:24:52 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 08:24:52 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: checkPathExists(map[path:test.sum pid:121389116]) options=[] 2022/03/02 08:24:52 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 08:24:52 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFile(map[fi_id:121389165]) options=[] 2022/03/02 08:24:55 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=, err= 2022/03/02 08:24:55 NOTICE: test.sum: improperly formatted checksum line 4 2022/03/02 08:24:55 NOTICE: test.sum: improperly formatted checksum line 5 2022/03/02 08:24:55 NOTICE: test.sum: improperly formatted checksum line 6 2022/03/02 08:24:55 NOTICE: test.sum: 2 warning(s) suppressed... 2022/03/02 08:24:55 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389116 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 08:25:20 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 08:25:20 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doDeleteFile(map[completedeletion:n fi_id:121389165]) options=[] 2022/03/02 08:26:33 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 08:26:33 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389116 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 08:27:08 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= --- PASS: TestParseSumFile (344.64s) === RUN TestCheckSum run.go:181: Remote "filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6'", Local "Local file system at /tmp/rclone3416142788", Modify Window "1s" 2022/03/02 08:27:08 DEBUG : Creating backend with remote "TestFileFabric:rclone-test-mujovim6zicuyeq5hacaxub6/data" 2022/03/02 08:27:08 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6/data': rpc: checkPathExists(map[path:rclone-test-mujovim6zicuyeq5hacaxub6/data pid:120673762]) options=[] 2022/03/02 08:27:09 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6/data': >rpc: result=Success (ok), err= check_test.go:356: filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6/data' lacks md5, skipping 2022/03/02 08:27:09 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389116 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 08:27:09 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 08:27:09 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389116 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 08:27:10 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= --- SKIP: TestCheckSum (1.61s) === RUN TestCheckSumDownload run.go:181: Remote "filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6'", Local "Local file system at /tmp/rclone3416142788", Modify Window "1s" 2022/03/02 08:27:10 DEBUG : Creating backend with remote "TestFileFabric:rclone-test-mujovim6zicuyeq5hacaxub6/data" 2022/03/02 08:27:10 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6/data': rpc: checkPathExists(map[path:rclone-test-mujovim6zicuyeq5hacaxub6/data pid:120673762]) options=[] 2022/03/02 08:27:10 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6/data': >rpc: result=Success (ok), err= 2022/03/02 08:27:10 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389116 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 08:27:11 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 08:27:11 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doCreateNewFolder(map[fi_name:data fi_pid:121389116]) options=[] 2022/03/02 08:27:59 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 08:27:59 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doInitUpload(map[directuploadsupport:n fi_contenttype:application/octet-stream fi_filename:banana fi_localtime:2001-02-03 04:05:06 fi_modified:2001-02-03 04:05:06 fi_name:banana fi_pid:121389171 fi_size:13 responsetype:json]) options=[] 2022/03/02 08:28:09 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 08:28:10 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doCompleteUpload(map[fi_size:13 remotetime:2001-02-03 04:05:06 uploadcode:96f4594b95595567b6b0e978521ae931]) options=[] 2022/03/02 08:28:45 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 08:28:45 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doInitUpload(map[directuploadsupport:n fi_contenttype:application/octet-stream fi_filename:test.sum fi_localtime:2001-02-03 04:05:06 fi_modified:2001-02-03 04:05:06 fi_name:test.sum fi_pid:121389116 fi_size:41 responsetype:json]) options=[] 2022/03/02 08:28:55 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 08:28:56 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doCompleteUpload(map[fi_size:41 remotetime:2001-02-03 04:05:06 uploadcode:38d499610d1c615dfee8e594fe2cf75f]) options=[] 2022/03/02 08:29:39 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 08:29:39 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389116 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 08:30:18 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 08:30:18 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389171 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 08:30:53 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= === RUN TestCheckSumDownload/subtest1 2022/03/02 08:31:03 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doInitUpload(map[directuploadsupport:n fi_contenttype:application/octet-stream fi_filename:potato fi_localtime:2001-02-03 04:05:06 fi_modified:2001-02-03 04:05:06 fi_name:potato fi_pid:121389171 fi_size:15 responsetype:json]) options=[] 2022/03/02 08:31:05 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 08:31:07 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doCompleteUpload(map[fi_size:15 remotetime:2001-02-03 04:05:06 uploadcode:6d88cdb858e90485d4e15c85cf2ec446]) options=[] 2022/03/02 08:31:45 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 08:31:45 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doInitUpload(map[directuploadsupport:n fi_contenttype:application/octet-stream fi_filename:test.sum fi_localtime:2001-02-03 04:05:06 fi_modified:2001-02-03 04:05:06 fi_name:test.sum fi_pid:121389116 fi_size:41 responsetype:json]) options=[] 2022/03/02 08:31:55 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 08:31:56 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doCompleteUpload(map[fi_size:41 remotetime:2001-02-03 04:05:06 uploadcode:74beea7b39fe89ba2ad7401042f0d7ce]) options=[] 2022/03/02 08:36:25 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 08:36:25 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389116 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 08:36:59 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 08:36:59 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389171 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 08:37:29 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= === RUN TestCheckSumDownload/subtest2 2022/03/02 08:37:36 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doInitUpload(map[directuploadsupport:n fi_contenttype:application/octet-stream fi_filename:test.sum fi_localtime:2001-02-03 04:05:06 fi_modified:2001-02-03 04:05:06 fi_name:test.sum fi_pid:121389116 fi_size:82 responsetype:json]) options=[] 2022/03/02 08:37:43 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 08:37:45 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doCompleteUpload(map[fi_size:82 remotetime:2001-02-03 04:05:06 uploadcode:b511503dae2c4c0fd6d87c6413235a45]) options=[] 2022/03/02 08:41:20 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 08:41:20 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389116 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 08:41:55 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 08:41:55 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389171 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 08:41:55 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= === RUN TestCheckSumDownload/subtest3 2022/03/02 08:42:02 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doInitUpload(map[directuploadsupport:n fi_contenttype:application/octet-stream fi_filename:test.sum fi_localtime:2001-02-03 04:05:06 fi_modified:2001-02-03 04:05:06 fi_name:test.sum fi_pid:121389116 fi_size:82 responsetype:json]) options=[] 2022/03/02 08:42:05 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 08:42:07 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doCompleteUpload(map[fi_size:82 remotetime:2001-02-03 04:05:06 uploadcode:5e49caf235567b06effa70de80f38abc]) options=[] 2022/03/02 08:45:04 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 08:45:04 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389116 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 08:45:35 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 08:45:35 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389171 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 08:45:36 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= === RUN TestCheckSumDownload/subtest4 2022/03/02 08:45:43 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doInitUpload(map[directuploadsupport:n fi_contenttype:application/octet-stream fi_filename:test.sum fi_localtime:2001-02-03 04:05:06 fi_modified:2001-02-03 04:05:06 fi_name:test.sum fi_pid:121389116 fi_size:123 responsetype:json]) options=[] 2022/03/02 08:45:46 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 08:45:47 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doCompleteUpload(map[fi_size:123 remotetime:2001-02-03 04:05:06 uploadcode:5115f3a1c5fce8fde2366df052a5dbaf]) options=[] 2022/03/02 08:49:43 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 08:49:43 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389116 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 08:50:10 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 08:50:10 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389171 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 08:50:10 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= === RUN TestCheckSumDownload/subtest5 2022/03/02 08:50:17 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doInitUpload(map[directuploadsupport:n fi_contenttype:application/octet-stream fi_filename:test.sum fi_localtime:2001-02-03 04:05:06 fi_modified:2001-02-03 04:05:06 fi_name:test.sum fi_pid:121389116 fi_size:123 responsetype:json]) options=[] 2022/03/02 08:50:20 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 08:50:22 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doCompleteUpload(map[fi_size:123 remotetime:2001-02-03 04:05:06 uploadcode:42a07257b08a33082718d975d553945a]) options=[] 2022/03/02 08:52:40 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 08:52:40 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389116 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 08:53:13 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 08:53:13 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389171 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 08:53:41 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= === RUN TestCheckSumDownload/subtest6 2022/03/02 08:53:48 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doInitUpload(map[directuploadsupport:n fi_contenttype:application/octet-stream fi_filename:banana fi_localtime:2001-02-03 04:05:06 fi_modified:2001-02-03 04:05:06 fi_name:banana fi_pid:121389171 fi_size:13 responsetype:json]) options=[] 2022/03/02 08:53:56 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 08:53:58 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doCompleteUpload(map[fi_size:13 remotetime:2001-02-03 04:05:06 uploadcode:1880be2b9296f2c40717c73b0f8c12f9]) options=[] 2022/03/02 08:58:11 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 08:58:11 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doInitUpload(map[directuploadsupport:n fi_contenttype:application/octet-stream fi_filename:potato fi_localtime:2001-02-03 04:05:06 fi_modified:2001-02-03 04:05:06 fi_name:potato fi_pid:121389171 fi_size:15 responsetype:json]) options=[] 2022/03/02 08:58:17 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 08:58:19 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doCompleteUpload(map[fi_size:15 remotetime:2001-02-03 04:05:06 uploadcode:4ef2451c330e1aa13edc2ca037492cf4]) options=[] 2022/03/02 09:01:56 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 09:01:56 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doInitUpload(map[directuploadsupport:n fi_contenttype:application/octet-stream fi_filename:test.sum fi_localtime:2001-02-03 04:05:06 fi_modified:2001-02-03 04:05:06 fi_name:test.sum fi_pid:121389116 fi_size:82 responsetype:json]) options=[] 2022/03/02 09:02:02 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 09:02:03 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doCompleteUpload(map[fi_size:82 remotetime:2001-02-03 04:05:06 uploadcode:58edd4e8bc7387fe9bdc657efe50fcb3]) options=[] 2022/03/02 09:05:24 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 09:05:24 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389116 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 09:05:56 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 09:05:56 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389171 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 09:06:31 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= === RUN TestCheckSumDownload/subtest7 2022/03/02 09:06:39 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389116 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 09:06:39 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 09:06:39 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doDeleteFile(map[completedeletion:n fi_id:121389174]) options=[] 2022/03/02 09:08:46 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 09:08:46 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389171 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 09:08:47 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 09:08:47 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doDeleteFile(map[completedeletion:n fi_id:121389173]) options=[] 2022/03/02 09:10:18 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 09:10:18 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doDeleteFile(map[completedeletion:n fi_id:121389179]) options=[] 2022/03/02 09:12:07 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 09:12:07 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389171 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 09:12:55 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 09:12:55 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doDeleteFolder(map[fi_id:121389171]) options=[] 2022/03/02 09:13:37 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 09:13:37 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389116 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 09:13:47 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= --- PASS: TestCheckSumDownload (2797.32s) --- PASS: TestCheckSumDownload/subtest1 (9.47s) --- PASS: TestCheckSumDownload/subtest2 (6.86s) --- PASS: TestCheckSumDownload/subtest3 (6.89s) --- PASS: TestCheckSumDownload/subtest4 (6.90s) --- PASS: TestCheckSumDownload/subtest5 (6.83s) --- PASS: TestCheckSumDownload/subtest6 (6.92s) --- PASS: TestCheckSumDownload/subtest7 (7.20s) === RUN TestDeduplicateInteractive run.go:181: Remote "filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6'", Local "Local file system at /tmp/rclone3416142788", Modify Window "1s" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible 2022/03/02 09:13:47 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389116 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 09:13:48 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 09:13:48 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389116 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 09:13:48 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= --- SKIP: TestDeduplicateInteractive (1.20s) === RUN TestDeduplicateSkip run.go:181: Remote "filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6'", Local "Local file system at /tmp/rclone3416142788", Modify Window "1s" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible 2022/03/02 09:13:48 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389116 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 09:13:49 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 09:13:49 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389116 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 09:13:49 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= --- SKIP: TestDeduplicateSkip (1.16s) === RUN TestDeduplicateSizeOnly run.go:181: Remote "filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6'", Local "Local file system at /tmp/rclone3416142788", Modify Window "1s" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible 2022/03/02 09:13:49 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389116 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 09:13:50 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 09:13:50 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389116 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 09:13:51 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= --- SKIP: TestDeduplicateSizeOnly (1.20s) === RUN TestDeduplicateFirst run.go:181: Remote "filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6'", Local "Local file system at /tmp/rclone3416142788", Modify Window "1s" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible 2022/03/02 09:13:51 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389116 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 09:13:51 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 09:13:51 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389116 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 09:13:52 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= --- SKIP: TestDeduplicateFirst (1.17s) === RUN TestDeduplicateNewest run.go:181: Remote "filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6'", Local "Local file system at /tmp/rclone3416142788", Modify Window "1s" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible 2022/03/02 09:13:52 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389116 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 09:13:52 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 09:13:52 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389116 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 09:13:53 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= --- SKIP: TestDeduplicateNewest (1.23s) === RUN TestDeduplicateNewestByHash run.go:181: Remote "filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6'", Local "Local file system at /tmp/rclone3416142788", Modify Window "1s" dedupe_test.go:36: Can't run this test without a hash 2022/03/02 09:13:53 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389116 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 09:13:54 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 09:13:54 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389116 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 09:13:54 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= --- SKIP: TestDeduplicateNewestByHash (1.33s) === RUN TestDeduplicateOldest run.go:181: Remote "filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6'", Local "Local file system at /tmp/rclone3416142788", Modify Window "1s" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible 2022/03/02 09:13:54 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389116 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 09:13:55 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 09:13:55 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389116 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 09:13:56 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= --- SKIP: TestDeduplicateOldest (1.38s) === RUN TestDeduplicateLargest run.go:181: Remote "filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6'", Local "Local file system at /tmp/rclone3416142788", Modify Window "1s" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible 2022/03/02 09:13:56 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389116 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 09:13:56 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 09:13:56 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389116 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 09:13:57 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= --- SKIP: TestDeduplicateLargest (1.62s) === RUN TestDeduplicateSmallest run.go:181: Remote "filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6'", Local "Local file system at /tmp/rclone3416142788", Modify Window "1s" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible 2022/03/02 09:13:57 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389116 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 09:13:58 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 09:13:58 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389116 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 09:13:59 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= --- SKIP: TestDeduplicateSmallest (1.58s) === RUN TestDeduplicateRename run.go:181: Remote "filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6'", Local "Local file system at /tmp/rclone3416142788", Modify Window "1s" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible 2022/03/02 09:13:59 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389116 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 09:14:00 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 09:14:00 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389116 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 09:14:00 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= --- SKIP: TestDeduplicateRename (1.31s) === RUN TestMergeDirs run.go:181: Remote "filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6'", Local "Local file system at /tmp/rclone3416142788", Modify Window "1s" dedupe_test.go:267: Can't merge directories 2022/03/02 09:14:00 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389116 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 09:14:01 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 09:14:01 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389116 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 09:14:02 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= --- SKIP: TestMergeDirs (1.39s) === RUN TestListDirSorted run.go:181: Remote "filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6'", Local "Local file system at /tmp/rclone3416142788", Modify Window "1s" 2022/03/02 09:14:02 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doInitUpload(map[directuploadsupport:n fi_contenttype:text/plain; charset=utf-8 fi_filename:a.txt fi_localtime:2001-02-03 04:05:06 fi_modified:2001-02-03 04:05:06 fi_name:a.txt fi_pid:121389116 fi_size:11 responsetype:json]) options=[] 2022/03/02 09:14:07 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 09:14:09 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doCompleteUpload(map[fi_size:11 remotetime:2001-02-03 04:05:06 uploadcode:797716afed035c28a15e1af1a9108fb4]) options=[] 2022/03/02 09:14:26 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 09:14:26 DEBUG : a.txt: Correcting mime type from "text/plain" to "text/plain; charset=utf-8" 2022/03/02 09:14:26 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doModifyFile(map[data:fi_contenttype=text/plain; charset=utf-8 fi_id:121389218]) options=[] 2022/03/02 09:14:26 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 09:14:26 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doInitUpload(map[directuploadsupport:n fi_contenttype:text/plain; charset=utf-8 fi_filename:zend.txt fi_localtime:2001-02-03 04:05:06 fi_modified:2001-02-03 04:05:06 fi_name:zend.txt fi_pid:121389116 fi_size:5 responsetype:json]) options=[] 2022/03/02 09:14:36 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 09:14:38 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doCompleteUpload(map[fi_size:5 remotetime:2001-02-03 04:05:06 uploadcode:bbc10b6cc77c089af422358a9853bb20]) options=[] 2022/03/02 09:15:17 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 09:15:17 DEBUG : zend.txt: Correcting mime type from "text/plain" to "text/plain; charset=utf-8" 2022/03/02 09:15:17 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doModifyFile(map[data:fi_contenttype=text/plain; charset=utf-8 fi_id:121389219]) options=[] 2022/03/02 09:15:17 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 09:15:17 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389116 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 09:15:26 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 09:15:26 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doCreateNewFolder(map[fi_name:sub dir fi_pid:121389116]) options=[] 2022/03/02 09:16:16 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 09:16:16 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doInitUpload(map[directuploadsupport:n fi_contenttype:application/octet-stream fi_filename:hello world fi_localtime:2001-02-03 04:05:06 fi_modified:2001-02-03 04:05:06 fi_name:hello world fi_pid:121389220 fi_size:11 responsetype:json]) options=[] 2022/03/02 09:16:16 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': Token expired - refreshing 2022/03/02 09:16:16 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getTokenByAuthToken(map[authtoken:622506e6346e7ad6-c48bf9558d23a2ae token:*]) options=[] 2022/03/02 09:16:17 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 09:16:17 DEBUG : Saving config "token" in section "TestFileFabric" of the config file 2022/03/02 09:16:17 DEBUG : Config file has changed externaly - reloading 2022/03/02 09:16:17 DEBUG : Saving config "token_expiry" in section "TestFileFabric" of the config file 2022/03/02 09:16:17 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getApplianceInfo(map[token:*]) options=[] 2022/03/02 09:16:17 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 09:16:17 DEBUG : Saving config "version" in section "TestFileFabric" of the config file 2022/03/02 09:16:27 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 09:16:29 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doCompleteUpload(map[fi_size:11 remotetime:2001-02-03 04:05:06 uploadcode:dc82d6f413d92eb90aa115fa4b9cfc1b]) options=[] 2022/03/02 09:17:15 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 09:17:15 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doInitUpload(map[directuploadsupport:n fi_contenttype:application/octet-stream fi_filename:hello world2 fi_localtime:2001-02-03 04:05:06 fi_modified:2001-02-03 04:05:06 fi_name:hello world2 fi_pid:121389220 fi_size:11 responsetype:json]) options=[] 2022/03/02 09:17:22 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 09:17:23 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doCompleteUpload(map[fi_size:11 remotetime:2001-02-03 04:05:06 uploadcode:70e3f4a2c1592075b38bbe2f948aa47f]) options=[] 2022/03/02 09:18:00 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 09:18:00 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389220 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 09:18:29 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 09:18:29 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doCreateNewFolder(map[fi_name:ignore dir fi_pid:121389220]) options=[] 2022/03/02 09:19:28 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 09:19:28 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doInitUpload(map[directuploadsupport:n fi_contenttype:application/octet-stream fi_filename:.ignore fi_localtime:2001-02-03 04:05:06 fi_modified:2001-02-03 04:05:06 fi_name:.ignore fi_pid:121389227 fi_size:1 responsetype:json]) options=[] 2022/03/02 09:19:38 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 09:19:40 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doCompleteUpload(map[fi_size:1 remotetime:2001-02-03 04:05:06 uploadcode:8f93f697838b68091066bc293efe9ddf]) options=[] 2022/03/02 09:20:25 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 09:20:25 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doInitUpload(map[directuploadsupport:n fi_contenttype:application/octet-stream fi_filename:should be ignored fi_localtime:2001-02-03 04:05:06 fi_modified:2001-02-03 04:05:06 fi_name:should be ignored fi_pid:121389227 fi_size:9 responsetype:json]) options=[] 2022/03/02 09:20:32 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 09:20:33 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doCompleteUpload(map[fi_size:9 remotetime:2001-02-03 04:05:06 uploadcode:9ddb8b985ca5122cef7c4b9219712aba]) options=[] 2022/03/02 09:21:06 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 09:21:06 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389220 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 09:21:41 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 09:21:41 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doCreateNewFolder(map[fi_name:sub sub dir fi_pid:121389220]) options=[] 2022/03/02 09:22:41 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 09:22:41 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doInitUpload(map[directuploadsupport:n fi_contenttype:application/octet-stream fi_filename:hello world3 fi_localtime:2001-02-03 04:05:06 fi_modified:2001-02-03 04:05:06 fi_name:hello world3 fi_pid:121389231 fi_size:11 responsetype:json]) options=[] 2022/03/02 09:22:51 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 09:22:53 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doCompleteUpload(map[fi_size:11 remotetime:2001-02-03 04:05:06 uploadcode:9973f8ec409d1049bfc712b28a7527d9]) options=[] 2022/03/02 09:23:21 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 09:23:21 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389116 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 09:23:50 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 09:23:50 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389220 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 09:24:24 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 09:24:24 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389231 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 09:24:24 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389227 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 09:24:57 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 09:24:57 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 09:24:57 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389116 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 09:24:58 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 09:24:58 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389116 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 09:24:58 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 09:24:58 DEBUG : a.txt: Excluded 2022/03/02 09:24:58 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389220 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 09:24:59 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 09:24:59 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389220 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 09:25:00 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 09:25:00 DEBUG : sub dir/hello world: Excluded 2022/03/02 09:25:00 DEBUG : sub dir/hello world2: Excluded 2022/03/02 09:25:00 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389220 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 09:25:00 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 09:25:00 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: checkPathExists(map[path:sub dir/ignore dir/.ignore pid:121389116]) options=[] 2022/03/02 09:25:01 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 09:25:01 DEBUG : sub dir/ignore dir: Excluded 2022/03/02 09:25:01 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: checkPathExists(map[path:sub dir/sub sub dir/.ignore pid:121389116]) options=[] 2022/03/02 09:25:01 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 09:25:01 DEBUG : sub dir/hello world: Excluded 2022/03/02 09:25:01 DEBUG : sub dir/hello world2: Excluded 2022/03/02 09:25:01 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389227 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 09:25:02 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 09:25:02 DEBUG : sub dir/ignore dir: Excluded 2022/03/02 09:25:02 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389227 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 09:25:02 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 09:25:02 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389227 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 09:25:03 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 09:25:03 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389116 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 09:25:04 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 09:25:04 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doDeleteFile(map[completedeletion:n fi_id:121389218]) options=[] 2022/03/02 09:25:52 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 09:25:52 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doDeleteFile(map[completedeletion:n fi_id:121389219]) options=[] 2022/03/02 09:26:22 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 09:26:22 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389220 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 09:26:22 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 09:26:22 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doDeleteFile(map[completedeletion:n fi_id:121389221]) options=[] 2022/03/02 09:26:51 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 09:26:51 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doDeleteFile(map[completedeletion:n fi_id:121389224]) options=[] 2022/03/02 09:27:18 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 09:27:18 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389231 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 09:27:18 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389227 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 09:27:18 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 09:27:18 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doDeleteFile(map[completedeletion:n fi_id:121389232]) options=[] 2022/03/02 09:27:18 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 09:27:49 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 09:27:49 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doDeleteFile(map[completedeletion:n fi_id:121389228]) options=[] 2022/03/02 09:28:19 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 09:28:19 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doDeleteFile(map[completedeletion:n fi_id:121389230]) options=[] 2022/03/02 09:28:43 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 09:28:43 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389231 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 09:28:56 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 09:28:56 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doDeleteFolder(map[fi_id:121389231]) options=[] 2022/03/02 09:29:06 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 09:29:06 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389227 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 09:29:16 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 09:29:16 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doDeleteFolder(map[fi_id:121389227]) options=[] 2022/03/02 09:29:27 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 09:29:27 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: getFolderContents(map[count:1000 fi_pid:121389220 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[] 2022/03/02 09:29:28 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': >rpc: result=Success (ok), err= 2022/03/02 09:29:28 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': rpc: doDeleteFolder(map[fi_id:121389220]) options=[] 2022/03/02 09:29:30 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': Sleeping for 1s to wait for "error_background" error to clear 2022/03/02 09:29:31 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)) 2022/03/02 09:29:31 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2022/03/02 09:29:31 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': Sleeping for 2s to wait for "error_background" error to clear 2022/03/02 09:29:33 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)) 2022/03/02 09:29:33 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2022/03/02 09:29:34 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': Sleeping for 4s to wait for "error_background" error to clear 2022/03/02 09:29:38 DEBUG : pacer: low level retry 3/10 (error The requested operation cannot be completed. A delete operation is already being processed in this folder. Please try again later. (error_background)) 2022/03/02 09:29:38 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2022/03/02 09:29:39 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': Sleeping for 8s to wait for "error_background" error to clear 2022/03/02 09:29:47 DEBUG : pacer: low level retry 4/10 (error The requested operation cannot be completed. A delete operation is already being processed in this folder. Please try again later. (error_background)) 2022/03/02 09:29:47 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2022/03/02 09:29:48 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': Sleeping for 16s to wait for "error_background" error to clear 2022/03/02 09:30:04 DEBUG : pacer: low level retry 5/10 (error The requested operation cannot be completed. A delete operation is already being processed in this folder. Please try again later. (error_background)) 2022/03/02 09:30:04 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2022/03/02 09:30:07 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': Sleeping for 32s to wait for "error_background" error to clear 2022/03/02 09:30:39 DEBUG : pacer: low level retry 6/10 (error The requested operation cannot be completed. A delete operation is already being processed in this folder. Please try again later. (error_background)) 2022/03/02 09:30:39 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2022/03/02 09:30:40 DEBUG : filefabric root 'rclone-test-mujovim6zicuyeq5hacaxub6': Sleeping for 1m4s to wait for "error_background" error to clear panic: test timed out after 2h0m0s goroutine 3716 [running]: testing.(*M).startAlarm.func1() /usr/local/go/src/testing/testing.go:1788 +0x8e created by time.goFunc /usr/local/go/src/time/sleep.go:180 +0x31 goroutine 1 [chan receive, 19 minutes]: testing.(*T).Run(0xc000424680, {0x18e176c, 0x46d573}, 0x197c4c0) /usr/local/go/src/testing/testing.go:1307 +0x375 testing.runTests.func1(0xc0007b04e0) /usr/local/go/src/testing/testing.go:1598 +0x6e testing.tRunner(0xc000424680, 0xc0006f5ca0) /usr/local/go/src/testing/testing.go:1259 +0x102 testing.runTests(0xc0000f7280, {0x28d82c0, 0x52, 0x52}, {0xec1960, 0xc0002278d8, 0x28e5440}) /usr/local/go/src/testing/testing.go:1596 +0x43f testing.(*M).Run(0xc0000f7280) /usr/local/go/src/testing/testing.go:1504 +0x51d github.com/rclone/rclone/fstest.TestMain(0xc0000001a0) /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:70 +0x9e github.com/rclone/rclone/fs/operations_test.TestMain(...) /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:61 main.main() _testmain.go:209 +0x166 goroutine 36 [select]: go.opencensus.io/stats/view.(*worker).start(0xc00015e780) /home/rclone/go/pkg/mod/go.opencensus.io@v0.23.0/stats/view/worker.go:276 +0xb9 created by go.opencensus.io/stats/view.init.0 /home/rclone/go/pkg/mod/go.opencensus.io@v0.23.0/stats/view/worker.go:34 +0x92 goroutine 3079 [sleep, 3 minutes]: time.Sleep(0xee6b28000) /usr/local/go/src/runtime/time.go:193 +0x12e github.com/rclone/rclone/backend/filefabric.(*Fs).shouldRetry(0xc00022d900, {0x1c7df30, 0xc000040058}, 0xc000780a20, {0x7f92b1def230, 0xc000780a20}, {0x1c692b0, 0xc000780a20}, 0x7) /home/rclone/go/src/github.com/rclone/rclone/backend/filefabric/filefabric.go:252 +0x357 github.com/rclone/rclone/backend/filefabric.(*Fs).rpc.func1() /home/rclone/go/src/github.com/rclone/rclone/backend/filefabric/filefabric.go:412 +0x205 github.com/rclone/rclone/fs.pacerInvoker(0xc00008b380, 0xc00087b401, 0x1c4d5e0) /home/rclone/go/src/github.com/rclone/rclone/fs/pacer.go:88 +0x38 github.com/rclone/rclone/lib/pacer.(*Pacer).call(0xc00008b380, 0xc00087b4e8, 0xa) /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:197 +0x85 github.com/rclone/rclone/lib/pacer.(*Pacer).Call(0xc00008b380, 0xc000780b10) /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:216 +0x90 github.com/rclone/rclone/backend/filefabric.(*Fs).rpc(0xc00022d900, {0x1c7df30, 0xc000040058}, {0x18dbee6, 0x1cad4f8}, 0xc000780a80, {0x1c692b0, 0xc000780a20}, {0x0, 0x0, ...}) /home/rclone/go/src/github.com/rclone/rclone/backend/filefabric/filefabric.go:407 +0x91c github.com/rclone/rclone/backend/filefabric.(*Fs).purgeCheck(0xc00022d900, {0x1c7df30, 0xc000040058}, {0xc00075f2c0, 0x7}, 0x1) /home/rclone/go/src/github.com/rclone/rclone/backend/filefabric/filefabric.go:761 +0x265 github.com/rclone/rclone/backend/filefabric.(*Fs).Rmdir(0x1010092b27a48c8, {0x1c7df30, 0xc000040058}, {0xc00075f2c0, 0x7f92df60e5b8}) /home/rclone/go/src/github.com/rclone/rclone/backend/filefabric/filefabric.go:776 +0x2e github.com/rclone/rclone/fstest.newRunIndividual.func1.2() /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:170 +0x36 github.com/rclone/rclone/fstest.retry(0xc000940340, {0xc0001483c0, 0x16}, 0xc00087baf0) /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:122 +0xe8 github.com/rclone/rclone/fstest.newRunIndividual.func1() /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:169 +0x2d0 github.com/rclone/rclone/fstest.(*Run).Finalise(0xc0005c5f10) /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:376 +0x22 github.com/rclone/rclone/fs/operations_test.TestListDirSorted(0xc000940340) /home/rclone/go/src/github.com/rclone/rclone/fs/operations/listdirsorted_test.go:107 +0x15b6 testing.tRunner(0xc000940340, 0x197c4c0) /usr/local/go/src/testing/testing.go:1259 +0x102 created by testing.(*T).Run /usr/local/go/src/testing/testing.go:1306 +0x35a goroutine 1522 [chan receive (nil chan), 83 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:208 +0x45 created by github.com/rclone/rclone/fs/march.(*March).Run /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:206 +0x3a5 goroutine 39 [chan receive, 120 minutes]: github.com/rclone/rclone/lib/atexit.Register.func1.1() /home/rclone/go/src/github.com/rclone/rclone/lib/atexit/atexit.go:45 +0x2f created by github.com/rclone/rclone/lib/atexit.Register.func1 /home/rclone/go/src/github.com/rclone/rclone/lib/atexit/atexit.go:44 +0x6d goroutine 725 [chan receive (nil chan), 103 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:208 +0x45 created by github.com/rclone/rclone/fs/march.(*March).Run /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:206 +0x3a5 goroutine 1217 [chan receive (nil chan), 93 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:208 +0x45 created by github.com/rclone/rclone/fs/march.(*March).Run /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:206 +0x3a5 goroutine 659 [chan receive (nil chan), 105 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:208 +0x45 created by github.com/rclone/rclone/fs/march.(*March).Run /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:206 +0x3a5 goroutine 79 [syscall, 120 minutes]: os/signal.signal_recv() /usr/local/go/src/runtime/sigqueue.go:169 +0x98 os/signal.loop() /usr/local/go/src/os/signal/signal_unix.go:24 +0x19 created by os/signal.Notify.func1.1 /usr/local/go/src/os/signal/signal.go:151 +0x2c goroutine 1425 [chan receive (nil chan), 83 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:208 +0x45 created by github.com/rclone/rclone/fs/march.(*March).Run /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:206 +0x3a5 goroutine 955 [chan receive (nil chan), 97 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:208 +0x45 created by github.com/rclone/rclone/fs/march.(*March).Run /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:206 +0x3a5 goroutine 586 [chan receive (nil chan), 105 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:208 +0x45 created by github.com/rclone/rclone/fs/march.(*March).Run /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:206 +0x3a5 goroutine 688 [chan receive (nil chan), 103 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:208 +0x45 created by github.com/rclone/rclone/fs/march.(*March).Run /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:206 +0x3a5 goroutine 464 [chan receive (nil chan), 105 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:208 +0x45 created by github.com/rclone/rclone/fs/march.(*March).Run /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:206 +0x3a5 goroutine 819 [chan receive (nil chan), 97 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:208 +0x45 created by github.com/rclone/rclone/fs/march.(*March).Run /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:206 +0x3a5 goroutine 430 [chan receive (nil chan), 105 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:208 +0x45 created by github.com/rclone/rclone/fs/march.(*March).Run /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:206 +0x3a5 goroutine 537 [chan receive (nil chan), 105 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:208 +0x45 created by github.com/rclone/rclone/fs/march.(*March).Run /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:206 +0x3a5 goroutine 1026 [chan receive (nil chan), 95 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:208 +0x45 created by github.com/rclone/rclone/fs/march.(*March).Run /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:206 +0x3a5 goroutine 1072 [chan receive (nil chan), 95 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:208 +0x45 created by github.com/rclone/rclone/fs/march.(*March).Run /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:206 +0x3a5 goroutine 1475 [chan receive (nil chan), 83 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:208 +0x45 created by github.com/rclone/rclone/fs/march.(*March).Run /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:206 +0x3a5 goroutine 886 [chan receive (nil chan), 97 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:208 +0x45 created by github.com/rclone/rclone/fs/march.(*March).Run /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:206 +0x3a5 goroutine 1583 [chan receive (nil chan), 80 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:208 +0x45 created by github.com/rclone/rclone/fs/march.(*March).Run /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:206 +0x3a5 goroutine 1150 [chan receive (nil chan), 93 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:208 +0x45 created by github.com/rclone/rclone/fs/march.(*March).Run /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:206 +0x3a5 goroutine 1642 [chan receive (nil chan), 80 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:208 +0x45 created by github.com/rclone/rclone/fs/march.(*March).Run /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:206 +0x3a5 goroutine 1282 [chan receive (nil chan), 93 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:208 +0x45 created by github.com/rclone/rclone/fs/march.(*March).Run /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:206 +0x3a5 goroutine 1706 [chan receive (nil chan), 78 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:208 +0x45 created by github.com/rclone/rclone/fs/march.(*March).Run /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:206 +0x3a5 goroutine 1723 [chan receive (nil chan), 78 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:208 +0x45 created by github.com/rclone/rclone/fs/march.(*March).Run /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:206 +0x3a5 "./operations.test -test.v -test.timeout 2h0m0s -remote TestFileFabric: -verbose" - Finished ERROR in 2h0m0.528726385s (try 2/5): exit status 2: Failed []