"go test -v -timeout 1h0m0s -remote TestFileFabric: -verbose -test.run '^TestIntegration$/^FsMkdir$/^FsPutFiles$/^(FsCopy|ObjectMimeType)$'" - Starting (try 2/5)
=== RUN   TestIntegration
    fstests.go:418: Using remote "TestFileFabric:"
2021/04/05 06:23:16 DEBUG : Creating backend with remote "TestFileFabric:rclone-test-heduhog4muxoxud3tifipun2"
2021/04/05 06:23:16 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': rpc: checkPathExists(map[path:rclone-test-heduhog4muxoxud3tifipun2 pid:120673762]) options=[]
2021/04/05 06:23:17 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': >rpc: result=Success (ok), err=<nil>
2021/04/05 06:23:17 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': rpc: getFolderContents(map[count:1000 fi_pid:120673762 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[]
2021/04/05 06:23:17 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': >rpc: result=Success (ok), err=<nil>
2021/04/05 06:23:17 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': rpc: doCreateNewFolder(map[fi_name:rclone-test-heduhog4muxoxud3tifipun2 fi_pid:120673762]) options=[]
2021/04/05 06:23:20 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': >rpc: result=Success (ok), err=<nil>
2021/04/05 06:23:20 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': rpc: getFolderContents(map[count:1000 fi_pid:120895733 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[]
2021/04/05 06:23:22 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': >rpc: result=Success (ok), err=<nil>
=== RUN   TestIntegration/FsMkdir
2021/04/05 06:23:22 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': rpc: getFolderContents(map[count:1000 fi_pid:120895733 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[]
2021/04/05 06:23:22 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': >rpc: result=Success (ok), err=<nil>
=== RUN   TestIntegration/FsMkdir/FsPutFiles
2021/04/05 06:23:22 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': rpc: doInitUpload(map[directuploadsupport:n fi_contenttype:text/plain; charset=utf-8 fi_filename:file name.txt fi_localtime:2001-02-03 04:05:06 fi_modified:2001-02-03 04:05:06 fi_name:file name.txt fi_pid:120895733 fi_size:100 responsetype:json]) options=[]
2021/04/05 06:23:23 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': >rpc: result=Success (ok), err=<nil>
2021/04/05 06:23:24 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': rpc: doCompleteUpload(map[fi_size:100 remotetime:2001-02-03 04:05:06 uploadcode:6328b8400be6b6a863fae7b56e8a7101]) options=[]
2021/04/05 06:23:26 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': >rpc: result=Success (ok), err=<nil>
2021/04/05 06:23:26 DEBUG : file name.txt: Correcting mime type from "text/plain" to "text/plain; charset=utf-8"
2021/04/05 06:23:26 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': rpc: doModifyFile(map[data:fi_contenttype=text/plain; charset=utf-8
 fi_id:120895734]) options=[]
2021/04/05 06:23:26 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': >rpc: result=Success (ok), err=<nil>
2021/04/05 06:23:26 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': rpc: checkPathExists(map[path:file name.txt pid:120895733]) options=[]
2021/04/05 06:23:27 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': >rpc: result=Success (ok), err=<nil>
2021/04/05 06:23:27 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': rpc: getFolderContents(map[count:1000 fi_pid:120895733 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[]
2021/04/05 06:23:28 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': >rpc: result=Success (ok), err=<nil>
2021/04/05 06:23:28 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': rpc: doCreateNewFolder(map[fi_name:hello? sausage fi_pid:120895733]) options=[]
2021/04/05 06:23:33 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': >rpc: result=Success (ok), err=<nil>
2021/04/05 06:23:33 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': rpc: getFolderContents(map[count:1000 fi_pid:120895735 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[]
2021/04/05 06:23:34 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': >rpc: result=Success (ok), err=<nil>
2021/04/05 06:23:34 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': rpc: doCreateNewFolder(map[fi_name:êé fi_pid:120895735]) options=[]
2021/04/05 06:23:37 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': >rpc: result=Success (ok), err=<nil>
2021/04/05 06:23:37 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': rpc: getFolderContents(map[count:1000 fi_pid:120895736 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[]
2021/04/05 06:23:39 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': >rpc: result=Success (ok), err=<nil>
2021/04/05 06:23:39 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': rpc: doCreateNewFolder(map[fi_name:Hello, 世界 fi_pid:120895736]) options=[]
2021/04/05 06:23:44 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': >rpc: result=Success (ok), err=<nil>
2021/04/05 06:23:44 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': rpc: getFolderContents(map[count:1000 fi_pid:120895737 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[]
2021/04/05 06:23:45 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': >rpc: result=Success (ok), err=<nil>
2021/04/05 06:23:45 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': rpc: doCreateNewFolder(map[fi_name: " ' @ < > & ? + ≠ fi_pid:120895737]) options=[]
2021/04/05 06:23:48 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': >rpc: result=Success (ok), err=<nil>
2021/04/05 06:23:48 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': rpc: doInitUpload(map[directuploadsupport:n fi_contenttype:text/plain; charset=utf-8 fi_filename:z.txt fi_localtime:2001-02-03 04:05:10 fi_modified:2001-02-03 04:05:10 fi_name:z.txt fi_pid:120895738 fi_size:100 responsetype:json]) options=[]
2021/04/05 06:23:50 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': >rpc: result=Success (ok), err=<nil>
2021/04/05 06:23:51 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': rpc: doCompleteUpload(map[fi_size:100 remotetime:2001-02-03 04:05:10 uploadcode:abdd49822f65d3b16fe2c640dc6e06ff]) options=[]
2021/04/05 06:23:52 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': >rpc: result=Success (ok), err=<nil>
2021/04/05 06:23:52 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Correcting mime type from "text/plain" to "text/plain; charset=utf-8"
2021/04/05 06:23:52 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': rpc: doModifyFile(map[data:fi_contenttype=text/plain; charset=utf-8
 fi_id:120895740]) options=[]
2021/04/05 06:23:52 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': >rpc: result=Success (ok), err=<nil>
2021/04/05 06:23:52 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': rpc: checkPathExists(map[path:hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt pid:120895733]) options=[]
2021/04/05 06:23:53 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': >rpc: result=Success (ok), err=<nil>
2021/04/05 06:23:53 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': rpc: doInitUpload(map[directuploadsupport:n fi_contenttype:text/csv fi_filename:file name.txt fi_localtime:2001-02-03 04:05:06 fi_modified:2001-02-03 04:05:06 fi_name:file name.txt fi_pid:120895733 fi_size:100 responsetype:json]) options=[]
2021/04/05 06:23:54 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': >rpc: result=Success (ok), err=<nil>
2021/04/05 06:23:55 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': rpc: doCompleteUpload(map[fi_size:100 remotetime:2001-02-03 04:05:06 uploadcode:7408e340decdb0f173f32aee4efe8a98]) options=[]
2021/04/05 06:24:19 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': >rpc: result=Success (ok), err=<nil>
2021/04/05 06:24:19 DEBUG : file name.txt: Correcting mime type from "text/plain" to "text/csv"
2021/04/05 06:24:19 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': rpc: doModifyFile(map[data:fi_contenttype=text/csv
 fi_id:120895734]) options=[]
2021/04/05 06:24:19 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': >rpc: result=Success (ok), err=<nil>
2021/04/05 06:24:19 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': rpc: checkPathExists(map[path:file name.txt pid:120895733]) options=[]
2021/04/05 06:24:20 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': >rpc: result=Success (ok), err=<nil>
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsCopy
2021/04/05 06:24:20 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': rpc: checkPathExists(map[path:hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt pid:120895733]) options=[]
2021/04/05 06:24:20 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': >rpc: result=Success (ok), err=<nil>
2021/04/05 06:24:20 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': rpc: doCopyFile(map[fi_id:120895740 fi_name:z.txt-copy fi_pid:120895738 force:y options:allownoextension]) options=[]
2021/04/05 06:24:25 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': >rpc: result=Success (ok), err=<nil>
2021/04/05 06:24:25 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': rpc: getFolderContents(map[count:1000 fi_pid:120895733 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[]
2021/04/05 06:24:26 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': >rpc: result=Success (ok), err=<nil>
2021/04/05 06:24:26 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': rpc: getFolderContents(map[count:1000 fi_pid:120895735 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[]
2021/04/05 06:24:28 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': >rpc: result=Success (ok), err=<nil>
2021/04/05 06:24:28 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': rpc: getFolderContents(map[count:1000 fi_pid:120895736 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[]
2021/04/05 06:24:29 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': >rpc: result=Success (ok), err=<nil>
2021/04/05 06:24:29 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': rpc: getFolderContents(map[count:1000 fi_pid:120895737 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[]
2021/04/05 06:24:31 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': >rpc: result=Success (ok), err=<nil>
2021/04/05 06:24:31 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': rpc: getFolderContents(map[count:1000 fi_pid:120895738 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[]
2021/04/05 06:24:32 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': >rpc: result=Success (ok), err=<nil>
    fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3
    fstest.go:302: Flushing the directory cache
2021/04/05 06:24:33 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': rpc: getFolderContents(map[count:1000 fi_pid:120673762 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[]
2021/04/05 06:24:35 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': >rpc: result=Success (ok), err=<nil>
2021/04/05 06:24:35 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': rpc: getFolderContents(map[count:1000 fi_pid:120895733 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[]
2021/04/05 06:24:36 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': >rpc: result=Success (ok), err=<nil>
2021/04/05 06:24:36 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': rpc: getFolderContents(map[count:1000 fi_pid:120895735 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[]
2021/04/05 06:24:37 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': >rpc: result=Success (ok), err=<nil>
2021/04/05 06:24:37 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': rpc: getFolderContents(map[count:1000 fi_pid:120895736 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[]
2021/04/05 06:24:37 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': >rpc: result=Success (ok), err=<nil>
2021/04/05 06:24:37 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': rpc: getFolderContents(map[count:1000 fi_pid:120895737 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[]
2021/04/05 06:24:37 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': >rpc: result=Success (ok), err=<nil>
2021/04/05 06:24:37 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': rpc: getFolderContents(map[count:1000 fi_pid:120895738 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[]
2021/04/05 06:24:38 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': >rpc: result=Success (ok), err=<nil>
    fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3
    fstest.go:302: Flushing the directory cache
2021/04/05 06:24:40 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': rpc: getFolderContents(map[count:1000 fi_pid:120673762 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[]
2021/04/05 06:24:40 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': >rpc: result=Success (ok), err=<nil>
2021/04/05 06:24:40 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': rpc: getFolderContents(map[count:1000 fi_pid:120895733 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[]
2021/04/05 06:24:41 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': >rpc: result=Success (ok), err=<nil>
2021/04/05 06:24:41 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': rpc: getFolderContents(map[count:1000 fi_pid:120895735 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[]
2021/04/05 06:24:41 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': >rpc: result=Success (ok), err=<nil>
2021/04/05 06:24:41 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': rpc: getFolderContents(map[count:1000 fi_pid:120895736 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[]
2021/04/05 06:24:41 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': >rpc: result=Success (ok), err=<nil>
2021/04/05 06:24:41 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': rpc: getFolderContents(map[count:1000 fi_pid:120895737 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[]
2021/04/05 06:24:41 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': >rpc: result=Success (ok), err=<nil>
2021/04/05 06:24:41 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': rpc: getFolderContents(map[count:1000 fi_pid:120895738 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[]
2021/04/05 06:24:42 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': >rpc: result=Success (ok), err=<nil>
    fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3
    fstest.go:302: Flushing the directory cache
    fstest.go:306: 
        	Error Trace:	fstest.go:306
        	            				fstest.go:339
        	            				fstest.go:345
        	            				fstests.go:1116
        	Error:      	Should be true
        	Test:       	TestIntegration/FsMkdir/FsPutFiles/FsCopy
        	Messages:   	listing wrong, want
        	            	  file name.txt (100), hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt (100), hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt-copy (100) got
        	            	  file name.txt (100), hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt (100), hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt-copy. (100)
    fstest.go:192: 
        	Error Trace:	fstest.go:192
        	            				fstest.go:309
        	            				fstest.go:339
        	            				fstest.go:345
        	            				fstests.go:1116
        	Error:      	Should be true
        	Test:       	TestIntegration/FsMkdir/FsPutFiles/FsCopy
        	Messages:   	Unexpected file "hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠/z.txt-copy."
    fstest.go:204: Not found "hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠/z.txt-copy"
    fstest.go:207: 
        	Error Trace:	fstest.go:207
        	            				fstest.go:311
        	            				fstest.go:339
        	            				fstest.go:345
        	            				fstests.go:1116
        	Error:      	Not equal: 
        	            	expected: 0
        	            	actual  : 1
        	Test:       	TestIntegration/FsMkdir/FsPutFiles/FsCopy
        	Messages:   	1 objects not found
2021/04/05 06:24:46 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': rpc: doDeleteFile(map[completedeletion:n fi_id:120895744]) options=[]
2021/04/05 06:25:00 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': >rpc: result=Success (ok), err=<nil>
=== RUN   TestIntegration/FsMkdir/FsPutFiles/ObjectMimeType
2021/04/05 06:25:00 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': rpc: getFolderContents(map[count:1000 fi_pid:120673762 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[]
2021/04/05 06:25:01 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': >rpc: result=Success (ok), err=<nil>
2021/04/05 06:25:01 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': rpc: checkPathExists(map[path:file name.txt pid:120895733]) options=[]
2021/04/05 06:25:01 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': >rpc: result=Success (ok), err=<nil>
2021/04/05 06:25:01 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': rpc: doDeleteFolder(map[fi_id:120895733]) options=[]
2021/04/05 06:25:03 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': >rpc: result=Success (ok), err=<nil>
2021/04/05 06:25:03 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': rpc: getFolderContents(map[count:1000 fi_pid:120673762 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[]
2021/04/05 06:25:04 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': >rpc: result=Success (ok), err=<nil>
2021/04/05 06:25:04 ERROR : : error listing: directory not found
2021/04/05 06:25:04 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': rpc: getFolderContents(map[count:1000 fi_pid:120673762 options:filelist|fi_id|fi_pid|fi_name|fi_type|fi_size|fi_contenttype|fi_modified|fi_localtime|trash|subfolders subfolders:y]) options=[]
2021/04/05 06:25:05 DEBUG : filefabric root 'rclone-test-heduhog4muxoxud3tifipun2': >rpc: result=Success (ok), err=<nil>
--- FAIL: TestIntegration (108.49s)
    --- FAIL: TestIntegration/FsMkdir (103.37s)
        --- FAIL: TestIntegration/FsMkdir/FsPutFiles (99.12s)
            --- FAIL: TestIntegration/FsMkdir/FsPutFiles/FsCopy (40.35s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectMimeType (0.85s)
FAIL
exit status 1
FAIL	github.com/rclone/rclone/backend/filefabric	108.512s
"go test -v -timeout 1h0m0s -remote TestFileFabric: -verbose -test.run '^TestIntegration$/^FsMkdir$/^FsPutFiles$/^(FsCopy|ObjectMimeType)$'" - Finished ERROR in 1m50.23166122s (try 2/5): exit status 1: Failed [TestIntegration/FsMkdir/FsPutFiles/FsCopy]