"./bisync.test -test.v -test.timeout 1h0m0s -remote TestUlozto: -verbose" - Starting (try 1/5) 2025/09/17 03:13:17 DEBUG : Creating backend with remote "TestUlozto:rclone-test-labapup5muqe" 2025/09/17 03:13:17 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/09/17 03:13:20 DEBUG : Creating backend with remote "/tmp/rclone754416913" === RUN TestBisyncRemoteLocal 2025/09/17 03:13:20 DEBUG : Creating backend with remote "TestUlozto:rclone-test-kusogos0boto" 2025/09/17 03:13:22 NOTICE: remote: TestUlozto:rclone-test-kusogos0boto === RUN TestBisyncRemoteLocal/all_changed 2025/09/17 03:13:22 DEBUG : Creating backend with remote "TestUlozto:rclone-test-kusogos0boto/031322qa" 2025/09/17 03:13:24 INFO : path1: Making directory 2025/09/17 03:13:26 DEBUG : Creating backend with remote "TestUlozto:rclone-test-kusogos0boto/031322qa/all_changed/path1" 2025/09/17 03:13:29 DEBUG : Creating backend with remote "/tmp/031322qa" 2025/09/17 03:13:29 DEBUG : Creating backend with remote "/tmp/031322qa/all_changed/path2" 2025/09/17 03:13:29 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_all_changed/initial" 2025/09/17 03:13:29 DEBUG : Creating backend with remote "/tmp/031322qa/initdir/test_all_changed-kowifiz0" 2025/09/17 03:13:29 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2025/09/17 03:13:29 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination 2025/09/17 03:13:29 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination 2025/09/17 03:13:29 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination 2025/09/17 03:13:29 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination 2025/09/17 03:13:29 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination 2025/09/17 03:13:29 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/09/17 03:13:29 DEBUG : subdir: Making directory with metadata 2025/09/17 03:13:29 INFO : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z) 2025/09/17 03:13:29 DEBUG : Added delayed dir = "subdir", newDst=subdir 2025/09/17 03:13:29 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination 2025/09/17 03:13:29 DEBUG : file1.copy2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:13:29 DEBUG : file1.copy1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:13:29 DEBUG : Local file system at /tmp/031322qa/initdir/test_all_changed-kowifiz0: Waiting for checks to finish 2025/09/17 03:13:29 DEBUG : Local file system at /tmp/031322qa/initdir/test_all_changed-kowifiz0: Waiting for transfers to finish 2025/09/17 03:13:29 DEBUG : file1.copy1.txt.21b8fa2b.partial: renamed to: file1.copy1.txt 2025/09/17 03:13:29 DEBUG : file1.copy3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:13:29 INFO : file1.copy1.txt: Copied (new) 2025/09/17 03:13:29 DEBUG : file1.copy2.txt.455881d5.partial: renamed to: file1.copy2.txt 2025/09/17 03:13:29 INFO : file1.copy2.txt: Copied (new) 2025/09/17 03:13:29 DEBUG : file1.copy3.txt.d0285540.partial: renamed to: file1.copy3.txt 2025/09/17 03:13:29 INFO : file1.copy3.txt: Copied (new) 2025/09/17 03:13:29 DEBUG : file1.copy4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:13:29 DEBUG : file1.copy5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:13:29 DEBUG : file1.copy4.txt.8c987629.partial: renamed to: file1.copy4.txt 2025/09/17 03:13:29 INFO : file1.copy4.txt: Copied (new) 2025/09/17 03:13:29 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:13:29 DEBUG : file1.copy5.txt.19e8a2bc.partial: renamed to: file1.copy5.txt 2025/09/17 03:13:29 INFO : file1.copy5.txt: Copied (new) 2025/09/17 03:13:29 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2025/09/17 03:13:29 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt 2025/09/17 03:13:29 INFO : file1.txt: Copied (new) 2025/09/17 03:13:29 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:13:29 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST 2025/09/17 03:13:29 INFO : RCLONE_TEST: Copied (new) 2025/09/17 03:13:29 DEBUG : subdir/file20.txt.1ac3db2f.partial: renamed to: subdir/file20.txt 2025/09/17 03:13:29 INFO : subdir/file20.txt: Copied (new) 2025/09/17 03:13:29 INFO : subdir: Set directory modification time (using SetModTime) 2025/09/17 03:13:29 DEBUG : Waiting for deletions to finish 2025/09/17 03:13:29 ERROR : error listing: directory not found 2025/09/17 03:13:29 ERROR : uloz.to root 'rclone-test-kusogos0boto/031322qa/all_changed/path1': Failed to list "": directory not found 2025/09/17 03:13:29 DEBUG : removing 1 level 0 directories 2025/09/17 03:13:29 INFO : uloz.to root 'rclone-test-kusogos0boto/031322qa/all_changed/path1': Removing directory 2025/09/17 03:13:30 ERROR : Failed to rmdir: directory not found 2025/09/17 03:13:30 DEBUG : Waiting for deletions to finish 2025/09/17 03:13:30 ERROR : error listing: directory not found 2025/09/17 03:13:30 ERROR : Local file system at /tmp/031322qa/all_changed/path2: Failed to list "": directory not found 2025/09/17 03:13:30 DEBUG : removing 1 level 0 directories 2025/09/17 03:13:30 INFO : Local file system at /tmp/031322qa/all_changed/path2: Removing directory 2025/09/17 03:13:30 ERROR : Failed to rmdir: stat /tmp/031322qa/all_changed/path2: no such file or directory 2025/09/17 03:13:30 ERROR : error listing: directory not found 2025/09/17 03:13:30 ERROR : error listing: directory not found 2025/09/17 03:13:30 NOTICE: checking initFs Local file system at /tmp/031322qa/initdir/test_all_changed-kowifiz0 2025/09/17 03:13:30 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2025/09/17 03:13:30 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination 2025/09/17 03:13:30 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination 2025/09/17 03:13:30 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination 2025/09/17 03:13:30 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination 2025/09/17 03:13:30 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination 2025/09/17 03:13:30 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/09/17 03:13:30 INFO : subdir: Making directory 2025/09/17 03:13:31 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination 2025/09/17 03:13:31 DEBUG : uloz.to root 'rclone-test-kusogos0boto/031322qa/all_changed/path1': Waiting for checks to finish 2025/09/17 03:13:31 DEBUG : uloz.to root 'rclone-test-kusogos0boto/031322qa/all_changed/path1': Waiting for transfers to finish 2025/09/17 03:13:34 DEBUG : file1.copy1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:13:34 INFO : file1.copy1.txt: Copied (new) 2025/09/17 03:13:34 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2025/09/17 03:13:34 INFO : RCLONE_TEST: Copied (new) 2025/09/17 03:13:34 DEBUG : file1.copy3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:13:34 INFO : file1.copy3.txt: Copied (new) 2025/09/17 03:13:34 DEBUG : file1.copy2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:13:34 INFO : file1.copy2.txt: Copied (new) 2025/09/17 03:13:35 DEBUG : file1.copy4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:13:35 INFO : file1.copy4.txt: Copied (new) 2025/09/17 03:13:35 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:13:35 INFO : file1.txt: Copied (new) 2025/09/17 03:13:35 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:13:35 INFO : subdir/file20.txt: Copied (new) 2025/09/17 03:13:35 DEBUG : file1.copy5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:13:35 INFO : file1.copy5.txt: Copied (new) 2025/09/17 03:13:35 NOTICE: checking Path1 uloz.to root 'rclone-test-kusogos0boto/031322qa/all_changed/path1' 2025/09/17 03:13:36 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2025/09/17 03:13:36 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination 2025/09/17 03:13:36 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination 2025/09/17 03:13:36 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination 2025/09/17 03:13:36 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination 2025/09/17 03:13:36 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination 2025/09/17 03:13:36 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/09/17 03:13:36 DEBUG : subdir: Making directory with metadata 2025/09/17 03:13:36 INFO : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z) 2025/09/17 03:13:36 DEBUG : Added delayed dir = "subdir", newDst=subdir 2025/09/17 03:13:36 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination 2025/09/17 03:13:36 DEBUG : Local file system at /tmp/031322qa/all_changed/path2: Waiting for checks to finish 2025/09/17 03:13:36 DEBUG : Local file system at /tmp/031322qa/all_changed/path2: Waiting for transfers to finish 2025/09/17 03:13:36 DEBUG : file1.copy1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:13:36 DEBUG : file1.copy1.txt.21b8fa2b.partial: renamed to: file1.copy1.txt 2025/09/17 03:13:36 INFO : file1.copy1.txt: Copied (new) 2025/09/17 03:13:36 DEBUG : file1.copy3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:13:36 DEBUG : file1.copy2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:13:36 DEBUG : file1.copy3.txt.d0285540.partial: renamed to: file1.copy3.txt 2025/09/17 03:13:36 INFO : file1.copy3.txt: Copied (new) 2025/09/17 03:13:36 DEBUG : file1.copy2.txt.455881d5.partial: renamed to: file1.copy2.txt 2025/09/17 03:13:36 INFO : file1.copy2.txt: Copied (new) 2025/09/17 03:13:36 DEBUG : file1.copy4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:13:36 DEBUG : file1.copy4.txt.8c987629.partial: renamed to: file1.copy4.txt 2025/09/17 03:13:36 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:13:36 INFO : file1.copy4.txt: Copied (new) 2025/09/17 03:13:36 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2025/09/17 03:13:36 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt 2025/09/17 03:13:36 INFO : file1.txt: Copied (new) 2025/09/17 03:13:36 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST 2025/09/17 03:13:36 INFO : RCLONE_TEST: Copied (new) 2025/09/17 03:13:36 DEBUG : file1.copy5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:13:36 DEBUG : file1.copy5.txt.19e8a2bc.partial: renamed to: file1.copy5.txt 2025/09/17 03:13:36 INFO : file1.copy5.txt: Copied (new) 2025/09/17 03:13:36 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:13:36 DEBUG : subdir/file20.txt.1ac3db2f.partial: renamed to: subdir/file20.txt 2025/09/17 03:13:36 INFO : subdir/file20.txt: Copied (new) 2025/09/17 03:13:36 INFO : subdir: Set directory modification time (using SetModTime) 2025/09/17 03:13:36 NOTICE: checking path2 Local file system at /tmp/031322qa/all_changed/path2 2025/09/17 03:13:36 NOTICE: (01) : test all-changed 2025/09/17 03:13:36 NOTICE: (02) : test initial bisync 2025/09/17 03:13:36 NOTICE: (03) : bisync resync 2025/09/17 03:13:40 DEBUG : uloz.to root 'rclone-test-kusogos0boto/031322qa/all_changed/path1': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2025/09/17 03:13:40 INFO : subdir: Set directory modification time (using SetModTime) 2025/09/17 03:13:40 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/09/17 03:13:40 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2025/09/17 03:13:40 INFO : Synching Path1 "TestUlozto:rclone-test-kusogos0boto/031322qa/all_changed/path1/" with Path2 "/tmp/031322qa/all_changed/path2/" 2025/09/17 03:13:40 INFO : Copying Path2 files to Path1 2025/09/17 03:13:40 INFO : - Path2 Resync is copying files to - Path1 2025/09/17 03:13:40 INFO : There was nothing to transfer 2025/09/17 03:13:40 INFO : - Path1 Resync is copying files to - Path2 2025/09/17 03:13:41 INFO : subdir: Set directory modification time (using SetModTime) 2025/09/17 03:13:41 INFO : There was nothing to transfer 2025/09/17 03:13:41 INFO : Resync updating listings 2025/09/17 03:13:41 INFO : Validating listings for Path1 "TestUlozto:rclone-test-kusogos0boto/031322qa/all_changed/path1/" vs Path2 "/tmp/031322qa/all_changed/path2/" 2025/09/17 03:13:41 INFO : Bisync successful 2025/09/17 03:13:41 NOTICE: (04) : test change timestamp on all files except RCLONE_TEST 2025/09/17 03:13:41 NOTICE: (05) : touch-glob 2005-01-02 TestUlozto:rclone-test-kusogos0boto/031322qa/all_changed/path1/ file* 2025/09/17 03:13:41 DEBUG : Creating backend with remote "TestUlozto:rclone-test-kusogos0boto/031322qa/all_changed/path1/" 2025/09/17 03:13:44 DEBUG : fs cache: renaming cache item "TestUlozto:rclone-test-kusogos0boto/031322qa/all_changed/path1/" to be canonical "TestUlozto:rclone-test-kusogos0boto/031322qa/all_changed/path1" 2025/09/17 03:13:44 DEBUG : file1.copy1.txt: Set modification time 2005-01-02 2025/09/17 03:13:44 DEBUG : file1.copy2.txt: Set modification time 2005-01-02 2025/09/17 03:13:44 DEBUG : file1.copy3.txt: Set modification time 2005-01-02 2025/09/17 03:13:44 DEBUG : file1.copy4.txt: Set modification time 2005-01-02 2025/09/17 03:13:45 DEBUG : file1.copy5.txt: Set modification time 2005-01-02 2025/09/17 03:13:45 DEBUG : file1.txt: Set modification time 2005-01-02 2025/09/17 03:13:45 NOTICE: (06) : touch-glob 2005-01-02 TestUlozto:rclone-test-kusogos0boto/031322qa/all_changed/path1/subdir file* 2025/09/17 03:13:45 DEBUG : Creating backend with remote "TestUlozto:rclone-test-kusogos0boto/031322qa/all_changed/path1/subdir" 2025/09/17 03:13:48 DEBUG : file20.txt: Set modification time 2005-01-02 2025/09/17 03:13:48 NOTICE: (07) : test sync should pass 2025/09/17 03:13:48 NOTICE: (08) : bisync 2025/09/17 03:13:51 DEBUG : uloz.to root 'rclone-test-kusogos0boto/031322qa/all_changed/path1': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2025/09/17 03:13:51 INFO : subdir: Set directory modification time (using SetModTime) 2025/09/17 03:13:51 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/09/17 03:13:51 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2025/09/17 03:13:51 INFO : Synching Path1 "TestUlozto:rclone-test-kusogos0boto/031322qa/all_changed/path1/" with Path2 "/tmp/031322qa/all_changed/path2/" 2025/09/17 03:13:51 INFO : Building Path1 and Path2 listings 2025/09/17 03:13:52 INFO : Path1 checking for diffs 2025/09/17 03:13:52 INFO : - Path1 File changed: time (newer) - file1.copy1.txt 2025/09/17 03:13:52 INFO : - Path1 File changed: time (newer) - file1.copy2.txt 2025/09/17 03:13:52 INFO : - Path1 File changed: time (newer) - file1.copy3.txt 2025/09/17 03:13:52 INFO : - Path1 File changed: time (newer) - file1.copy4.txt 2025/09/17 03:13:52 INFO : - Path1 File changed: time (newer) - file1.copy5.txt 2025/09/17 03:13:52 INFO : - Path1 File changed: time (newer) - file1.txt 2025/09/17 03:13:52 INFO : - Path1 File changed: time (newer) - subdir/file20.txt 2025/09/17 03:13:52 INFO : Path1: 7 changes:  0 new,  7 modified,  0 deleted 2025/09/17 03:13:52 INFO : (Modified:  7 newer,  0 older) 2025/09/17 03:13:52 INFO : Path2 checking for diffs 2025/09/17 03:13:52 INFO : Applying changes 2025/09/17 03:13:52 INFO : - Path1 Queue copy to Path2 - /tmp/031322qa/all_changed/path2/file1.copy1.txt 2025/09/17 03:13:52 INFO : - Path1 Queue copy to Path2 - /tmp/031322qa/all_changed/path2/file1.copy2.txt 2025/09/17 03:13:52 INFO : - Path1 Queue copy to Path2 - /tmp/031322qa/all_changed/path2/file1.copy3.txt 2025/09/17 03:13:52 INFO : - Path1 Queue copy to Path2 - /tmp/031322qa/all_changed/path2/file1.copy4.txt 2025/09/17 03:13:52 INFO : - Path1 Queue copy to Path2 - /tmp/031322qa/all_changed/path2/file1.copy5.txt 2025/09/17 03:13:52 INFO : - Path1 Queue copy to Path2 - /tmp/031322qa/all_changed/path2/file1.txt 2025/09/17 03:13:52 INFO : - Path1 Queue copy to Path2 - /tmp/031322qa/all_changed/path2/subdir/file20.txt 2025/09/17 03:13:52 INFO : - Path1 Do queued copies to - Path2 2025/09/17 03:13:52 INFO : subdir: Set directory modification time (using SetModTime) 2025/09/17 03:13:52 INFO : file1.copy3.txt: Updated modification time in destination 2025/09/17 03:13:52 INFO : file1.copy2.txt: Updated modification time in destination 2025/09/17 03:13:52 INFO : file1.copy1.txt: Updated modification time in destination 2025/09/17 03:13:52 INFO : file1.copy4.txt: Updated modification time in destination 2025/09/17 03:13:52 INFO : file1.copy5.txt: Updated modification time in destination 2025/09/17 03:13:52 INFO : file1.txt: Updated modification time in destination 2025/09/17 03:13:52 INFO : subdir/file20.txt: Updated modification time in destination 2025/09/17 03:13:52 INFO : There was nothing to transfer 2025/09/17 03:13:52 INFO : Updating listings 2025/09/17 03:13:52 INFO : Validating listings for Path1 "TestUlozto:rclone-test-kusogos0boto/031322qa/all_changed/path1/" vs Path2 "/tmp/031322qa/all_changed/path2/" 2025/09/17 03:13:52 INFO : Bisync successful 2025/09/17 03:13:52 NOTICE: (09) : test change timestamp on all files including RCLONE_TEST 2025/09/17 03:13:52 NOTICE: (10) : touch-glob 2004-01-02 TestUlozto:rclone-test-kusogos0boto/031322qa/all_changed/path1/ * 2025/09/17 03:13:52 DEBUG : fs cache: switching user supplied name "TestUlozto:rclone-test-kusogos0boto/031322qa/all_changed/path1/" for canonical name "TestUlozto:rclone-test-kusogos0boto/031322qa/all_changed/path1" 2025/09/17 03:13:53 DEBUG : file1.copy1.txt: Set modification time 2004-01-02 2025/09/17 03:13:53 DEBUG : file1.copy2.txt: Set modification time 2004-01-02 2025/09/17 03:13:53 DEBUG : file1.copy3.txt: Set modification time 2004-01-02 2025/09/17 03:13:53 DEBUG : file1.copy4.txt: Set modification time 2004-01-02 2025/09/17 03:13:53 DEBUG : file1.copy5.txt: Set modification time 2004-01-02 2025/09/17 03:13:53 DEBUG : file1.txt: Set modification time 2004-01-02 2025/09/17 03:13:53 DEBUG : RCLONE_TEST: Set modification time 2004-01-02 2025/09/17 03:13:54 NOTICE: (11) : touch-glob 2004-01-02 TestUlozto:rclone-test-kusogos0boto/031322qa/all_changed/path1/subdir * 2025/09/17 03:13:54 DEBUG : file20.txt: Set modification time 2004-01-02 2025/09/17 03:13:54 NOTICE: (12) : test sync should fail 2025/09/17 03:13:54 NOTICE: (13) : bisync 2025/09/17 03:13:59 DEBUG : uloz.to root 'rclone-test-kusogos0boto/031322qa/all_changed/path1': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2025/09/17 03:13:59 INFO : subdir: Set directory modification time (using SetModTime) 2025/09/17 03:13:59 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/09/17 03:13:59 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2025/09/17 03:13:59 INFO : Synching Path1 "TestUlozto:rclone-test-kusogos0boto/031322qa/all_changed/path1/" with Path2 "/tmp/031322qa/all_changed/path2/" 2025/09/17 03:13:59 INFO : Building Path1 and Path2 listings 2025/09/17 03:13:59 INFO : Path1 checking for diffs 2025/09/17 03:13:59 INFO : - Path1 File changed: time (newer) - RCLONE_TEST 2025/09/17 03:13:59 INFO : - Path1 File changed: time (older) - file1.copy1.txt 2025/09/17 03:13:59 INFO : - Path1 File changed: time (older) - file1.copy2.txt 2025/09/17 03:13:59 INFO : - Path1 File changed: time (older) - file1.copy3.txt 2025/09/17 03:13:59 INFO : - Path1 File changed: time (older) - file1.copy4.txt 2025/09/17 03:13:59 INFO : - Path1 File changed: time (older) - file1.copy5.txt 2025/09/17 03:13:59 INFO : - Path1 File changed: time (older) - file1.txt 2025/09/17 03:13:59 INFO : - Path1 File changed: time (older) - subdir/file20.txt 2025/09/17 03:13:59 INFO : Path1: 8 changes:  0 new,  8 modified,  0 deleted 2025/09/17 03:13:59 INFO : (Modified:  1 newer,  7 older) 2025/09/17 03:13:59 INFO : Path2 checking for diffs 2025/09/17 03:13:59 ERROR : Safety abort: all files were changed on Path1 "TestUlozto:rclone-test-kusogos0boto/031322qa/all_changed/path1/". Run with --force if desired. 2025/09/17 03:13:59 NOTICE: Bisync aborted. Please try again. 2025/09/17 03:13:59 NOTICE: Bisync error: all files were changed 2025/09/17 03:13:59 NOTICE: (14) : test sync with force should pass 2025/09/17 03:13:59 NOTICE: (15) : bisync force 2025/09/17 03:14:03 DEBUG : uloz.to root 'rclone-test-kusogos0boto/031322qa/all_changed/path1': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2025/09/17 03:14:03 INFO : subdir: Set directory modification time (using SetModTime) 2025/09/17 03:14:03 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/09/17 03:14:03 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2025/09/17 03:14:03 INFO : Synching Path1 "TestUlozto:rclone-test-kusogos0boto/031322qa/all_changed/path1/" with Path2 "/tmp/031322qa/all_changed/path2/" 2025/09/17 03:14:03 INFO : Building Path1 and Path2 listings 2025/09/17 03:14:03 INFO : Path1 checking for diffs 2025/09/17 03:14:03 INFO : - Path1 File changed: time (newer) - RCLONE_TEST 2025/09/17 03:14:03 INFO : - Path1 File changed: time (older) - file1.copy1.txt 2025/09/17 03:14:03 INFO : - Path1 File changed: time (older) - file1.copy2.txt 2025/09/17 03:14:03 INFO : - Path1 File changed: time (older) - file1.copy3.txt 2025/09/17 03:14:03 INFO : - Path1 File changed: time (older) - file1.copy4.txt 2025/09/17 03:14:03 INFO : - Path1 File changed: time (older) - file1.copy5.txt 2025/09/17 03:14:03 INFO : - Path1 File changed: time (older) - file1.txt 2025/09/17 03:14:03 INFO : - Path1 File changed: time (older) - subdir/file20.txt 2025/09/17 03:14:03 INFO : Path1: 8 changes:  0 new,  8 modified,  0 deleted 2025/09/17 03:14:03 INFO : (Modified:  1 newer,  7 older) 2025/09/17 03:14:03 INFO : Path2 checking for diffs 2025/09/17 03:14:03 INFO : Applying changes 2025/09/17 03:14:03 INFO : - Path1 Queue copy to Path2 - /tmp/031322qa/all_changed/path2/RCLONE_TEST 2025/09/17 03:14:03 INFO : - Path1 Queue copy to Path2 - /tmp/031322qa/all_changed/path2/file1.copy1.txt 2025/09/17 03:14:03 INFO : - Path1 Queue copy to Path2 - /tmp/031322qa/all_changed/path2/file1.copy2.txt 2025/09/17 03:14:03 INFO : - Path1 Queue copy to Path2 - /tmp/031322qa/all_changed/path2/file1.copy3.txt 2025/09/17 03:14:03 INFO : - Path1 Queue copy to Path2 - /tmp/031322qa/all_changed/path2/file1.copy4.txt 2025/09/17 03:14:03 INFO : - Path1 Queue copy to Path2 - /tmp/031322qa/all_changed/path2/file1.copy5.txt 2025/09/17 03:14:03 INFO : - Path1 Queue copy to Path2 - /tmp/031322qa/all_changed/path2/file1.txt 2025/09/17 03:14:03 INFO : - Path1 Queue copy to Path2 - /tmp/031322qa/all_changed/path2/subdir/file20.txt 2025/09/17 03:14:03 INFO : - Path1 Do queued copies to - Path2 2025/09/17 03:14:04 INFO : subdir: Set directory modification time (using SetModTime) 2025/09/17 03:14:04 INFO : file1.copy1.txt: Updated modification time in destination 2025/09/17 03:14:04 INFO : file1.copy3.txt: Updated modification time in destination 2025/09/17 03:14:04 INFO : file1.copy4.txt: Updated modification time in destination 2025/09/17 03:14:04 INFO : file1.txt: Updated modification time in destination 2025/09/17 03:14:04 INFO : file1.copy2.txt: Updated modification time in destination 2025/09/17 03:14:04 INFO : file1.copy5.txt: Updated modification time in destination 2025/09/17 03:14:04 INFO : RCLONE_TEST: Updated modification time in destination 2025/09/17 03:14:04 INFO : subdir/file20.txt: Updated modification time in destination 2025/09/17 03:14:04 INFO : There was nothing to transfer 2025/09/17 03:14:04 INFO : Updating listings 2025/09/17 03:14:04 INFO : Validating listings for Path1 "TestUlozto:rclone-test-kusogos0boto/031322qa/all_changed/path1/" vs Path2 "/tmp/031322qa/all_changed/path2/" 2025/09/17 03:14:04 INFO : Bisync successful 2025/09/17 03:14:04 DEBUG : Waiting for deletions to finish 2025/09/17 03:14:04 INFO : file1.copy2.txt: Deleted 2025/09/17 03:14:04 INFO : file1.copy1.txt: Deleted 2025/09/17 03:14:04 INFO : file1.copy5.txt: Deleted 2025/09/17 03:14:04 INFO : file1.copy4.txt: Deleted 2025/09/17 03:14:04 INFO : file1.copy3.txt: Deleted 2025/09/17 03:14:04 INFO : file1.txt: Deleted 2025/09/17 03:14:04 INFO : RCLONE_TEST: Deleted 2025/09/17 03:14:05 INFO : subdir/file20.txt: Deleted 2025/09/17 03:14:05 DEBUG : removing 1 level 1 directories 2025/09/17 03:14:05 INFO : subdir: Removing directory 2025/09/17 03:14:06 DEBUG : removing 1 level 0 directories 2025/09/17 03:14:06 INFO : uloz.to root 'rclone-test-kusogos0boto/031322qa/all_changed/path1': Removing directory 2025/09/17 03:14:06 DEBUG : Waiting for deletions to finish 2025/09/17 03:14:06 INFO : file1.copy3.txt: Deleted 2025/09/17 03:14:06 INFO : file1.copy4.txt: Deleted 2025/09/17 03:14:06 INFO : file1.copy1.txt: Deleted 2025/09/17 03:14:06 INFO : subdir/file20.txt: Deleted 2025/09/17 03:14:06 INFO : file1.copy2.txt: Deleted 2025/09/17 03:14:06 INFO : file1.copy5.txt: Deleted 2025/09/17 03:14:06 INFO : file1.txt: Deleted 2025/09/17 03:14:06 INFO : RCLONE_TEST: Deleted 2025/09/17 03:14:06 DEBUG : removing 1 level 1 directories 2025/09/17 03:14:06 INFO : subdir: Removing directory 2025/09/17 03:14:06 DEBUG : removing 1 level 0 directories 2025/09/17 03:14:06 INFO : Local file system at /tmp/031322qa/all_changed/path2: Removing directory bisync_test.go:608: TEST all_changed PASSED === RUN TestBisyncRemoteLocal/backupdir 2025/09/17 03:14:06 INFO : path1: Making directory 2025/09/17 03:14:06 DEBUG : Creating backend with remote "TestUlozto:rclone-test-kusogos0boto/031322qa/backupdir/path1" 2025/09/17 03:14:09 DEBUG : Creating backend with remote "/tmp/031322qa/backupdir/path2" 2025/09/17 03:14:09 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_backupdir/initial" 2025/09/17 03:14:09 DEBUG : Creating backend with remote "/tmp/031322qa/initdir/test_backupdir-veretiy3" 2025/09/17 03:14:09 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2025/09/17 03:14:09 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/09/17 03:14:09 DEBUG : file2.txt: Need to transfer - File not found at Destination 2025/09/17 03:14:09 DEBUG : file3.txt: Need to transfer - File not found at Destination 2025/09/17 03:14:09 DEBUG : file4.txt: Need to transfer - File not found at Destination 2025/09/17 03:14:09 DEBUG : file5.txt: Need to transfer - File not found at Destination 2025/09/17 03:14:09 DEBUG : file6.txt: Need to transfer - File not found at Destination 2025/09/17 03:14:09 DEBUG : file7.txt: Need to transfer - File not found at Destination 2025/09/17 03:14:09 DEBUG : file8.txt: Need to transfer - File not found at Destination 2025/09/17 03:14:09 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:14:09 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:14:09 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt 2025/09/17 03:14:09 INFO : file1.txt: Copied (new) 2025/09/17 03:14:09 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt 2025/09/17 03:14:09 INFO : file2.txt: Copied (new) 2025/09/17 03:14:09 DEBUG : Local file system at /tmp/031322qa/initdir/test_backupdir-veretiy3: Waiting for checks to finish 2025/09/17 03:14:09 DEBUG : Local file system at /tmp/031322qa/initdir/test_backupdir-veretiy3: Waiting for transfers to finish 2025/09/17 03:14:09 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:14:09 DEBUG : file3.txt.a74079f2.partial: renamed to: file3.txt 2025/09/17 03:14:09 INFO : file3.txt: Copied (new) 2025/09/17 03:14:09 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:14:09 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:14:09 DEBUG : file5.txt.6e808e0e.partial: renamed to: file5.txt 2025/09/17 03:14:09 INFO : file5.txt: Copied (new) 2025/09/17 03:14:09 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2025/09/17 03:14:09 DEBUG : file4.txt.fbf05a9b.partial: renamed to: file4.txt 2025/09/17 03:14:09 INFO : file4.txt: Copied (new) 2025/09/17 03:14:09 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:14:09 DEBUG : file6.txt.0a60f5f0.partial: renamed to: file6.txt 2025/09/17 03:14:09 INFO : file6.txt: Copied (new) 2025/09/17 03:14:09 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST 2025/09/17 03:14:09 INFO : RCLONE_TEST: Copied (new) 2025/09/17 03:14:09 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:14:09 DEBUG : file8.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:14:09 DEBUG : file7.txt.9f102165.partial: renamed to: file7.txt 2025/09/17 03:14:09 INFO : file7.txt: Copied (new) 2025/09/17 03:14:09 DEBUG : file8.txt.b300b322.partial: renamed to: file8.txt 2025/09/17 03:14:09 INFO : file8.txt: Copied (new) 2025/09/17 03:14:09 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_backupdir/modfiles" 2025/09/17 03:14:09 DEBUG : Creating backend with remote "/tmp/031322qa/datadir/test_backupdir-daperup6" 2025/09/17 03:14:09 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/09/17 03:14:09 DEBUG : file10.txt: Need to transfer - File not found at Destination 2025/09/17 03:14:09 DEBUG : file11.txt: Need to transfer - File not found at Destination 2025/09/17 03:14:09 DEBUG : file2.txt: Need to transfer - File not found at Destination 2025/09/17 03:14:09 DEBUG : file5L.txt: Need to transfer - File not found at Destination 2025/09/17 03:14:09 DEBUG : file5R.txt: Need to transfer - File not found at Destination 2025/09/17 03:14:09 DEBUG : file6.txt: Need to transfer - File not found at Destination 2025/09/17 03:14:09 DEBUG : file7.txt: Need to transfer - File not found at Destination 2025/09/17 03:14:09 DEBUG : Local file system at /tmp/031322qa/datadir/test_backupdir-daperup6: Waiting for checks to finish 2025/09/17 03:14:09 DEBUG : Local file system at /tmp/031322qa/datadir/test_backupdir-daperup6: Waiting for transfers to finish 2025/09/17 03:14:09 DEBUG : file2.txt: md5 = fb3ecfb2800400fb01b0bfd39903e9fb OK 2025/09/17 03:14:09 DEBUG : file1.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2025/09/17 03:14:09 DEBUG : file2.txt.4526d70e.partial: renamed to: file2.txt 2025/09/17 03:14:09 INFO : file2.txt: Copied (new) 2025/09/17 03:14:09 DEBUG : file1.txt.75b77f44.partial: renamed to: file1.txt 2025/09/17 03:14:09 INFO : file1.txt: Copied (new) 2025/09/17 03:14:09 DEBUG : file10.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2025/09/17 03:14:09 DEBUG : file11.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2025/09/17 03:14:09 DEBUG : file10.txt.c02780cb.partial: renamed to: file10.txt 2025/09/17 03:14:09 INFO : file10.txt: Copied (new) 2025/09/17 03:14:09 DEBUG : file11.txt.40d797d4.partial: renamed to: file11.txt 2025/09/17 03:14:09 INFO : file11.txt: Copied (new) 2025/09/17 03:14:09 DEBUG : file5L.txt: md5 = 0860a03592626642f8fd6c8bfb447d2a OK 2025/09/17 03:14:09 DEBUG : file5L.txt.8dd6541a.partial: renamed to: file5L.txt 2025/09/17 03:14:09 INFO : file5L.txt: Copied (new) 2025/09/17 03:14:09 DEBUG : file7.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2025/09/17 03:14:09 DEBUG : file5R.txt: md5 = 979a803b15d27df0c31ad7d29006d10b OK 2025/09/17 03:14:09 DEBUG : file7.txt.c1750184.partial: renamed to: file7.txt 2025/09/17 03:14:09 INFO : file7.txt: Copied (new) 2025/09/17 03:14:09 DEBUG : file6.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2025/09/17 03:14:09 DEBUG : file5R.txt.ee3b9525.partial: renamed to: file5R.txt 2025/09/17 03:14:09 INFO : file5R.txt: Copied (new) 2025/09/17 03:14:09 DEBUG : file6.txt.4185169b.partial: renamed to: file6.txt 2025/09/17 03:14:09 INFO : file6.txt: Copied (new) 2025/09/17 03:14:09 DEBUG : Waiting for deletions to finish 2025/09/17 03:14:09 ERROR : error listing: directory not found 2025/09/17 03:14:09 ERROR : uloz.to root 'rclone-test-kusogos0boto/031322qa/backupdir/path1': Failed to list "": directory not found 2025/09/17 03:14:09 DEBUG : removing 1 level 0 directories 2025/09/17 03:14:09 INFO : uloz.to root 'rclone-test-kusogos0boto/031322qa/backupdir/path1': Removing directory 2025/09/17 03:14:09 ERROR : Failed to rmdir: directory not found 2025/09/17 03:14:09 DEBUG : Waiting for deletions to finish 2025/09/17 03:14:09 ERROR : error listing: directory not found 2025/09/17 03:14:09 ERROR : Local file system at /tmp/031322qa/backupdir/path2: Failed to list "": directory not found 2025/09/17 03:14:09 DEBUG : removing 1 level 0 directories 2025/09/17 03:14:09 INFO : Local file system at /tmp/031322qa/backupdir/path2: Removing directory 2025/09/17 03:14:09 ERROR : Failed to rmdir: stat /tmp/031322qa/backupdir/path2: no such file or directory 2025/09/17 03:14:10 ERROR : error listing: directory not found 2025/09/17 03:14:10 ERROR : error listing: directory not found 2025/09/17 03:14:10 NOTICE: checking initFs Local file system at /tmp/031322qa/initdir/test_backupdir-veretiy3 2025/09/17 03:14:10 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2025/09/17 03:14:10 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/09/17 03:14:10 DEBUG : file2.txt: Need to transfer - File not found at Destination 2025/09/17 03:14:10 DEBUG : file3.txt: Need to transfer - File not found at Destination 2025/09/17 03:14:10 DEBUG : file4.txt: Need to transfer - File not found at Destination 2025/09/17 03:14:10 DEBUG : file5.txt: Need to transfer - File not found at Destination 2025/09/17 03:14:10 DEBUG : file6.txt: Need to transfer - File not found at Destination 2025/09/17 03:14:10 DEBUG : file7.txt: Need to transfer - File not found at Destination 2025/09/17 03:14:10 DEBUG : file8.txt: Need to transfer - File not found at Destination 2025/09/17 03:14:10 DEBUG : uloz.to root 'rclone-test-kusogos0boto/031322qa/backupdir/path1': Waiting for checks to finish 2025/09/17 03:14:10 DEBUG : uloz.to root 'rclone-test-kusogos0boto/031322qa/backupdir/path1': Waiting for transfers to finish 2025/09/17 03:14:12 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2025/09/17 03:14:12 INFO : RCLONE_TEST: Copied (new) 2025/09/17 03:14:12 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:14:12 INFO : file1.txt: Copied (new) 2025/09/17 03:14:12 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:14:12 INFO : file2.txt: Copied (new) 2025/09/17 03:14:12 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:14:12 INFO : file3.txt: Copied (new) 2025/09/17 03:14:13 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:14:13 INFO : file4.txt: Copied (new) 2025/09/17 03:14:13 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:14:13 INFO : file5.txt: Copied (new) 2025/09/17 03:14:13 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:14:13 INFO : file6.txt: Copied (new) 2025/09/17 03:14:13 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:14:13 INFO : file7.txt: Copied (new) 2025/09/17 03:14:13 DEBUG : file8.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:14:13 INFO : file8.txt: Copied (new) 2025/09/17 03:14:13 NOTICE: checking Path1 uloz.to root 'rclone-test-kusogos0boto/031322qa/backupdir/path1' 2025/09/17 03:14:14 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2025/09/17 03:14:14 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/09/17 03:14:14 DEBUG : file2.txt: Need to transfer - File not found at Destination 2025/09/17 03:14:14 DEBUG : file3.txt: Need to transfer - File not found at Destination 2025/09/17 03:14:14 DEBUG : file4.txt: Need to transfer - File not found at Destination 2025/09/17 03:14:14 DEBUG : file5.txt: Need to transfer - File not found at Destination 2025/09/17 03:14:14 DEBUG : file6.txt: Need to transfer - File not found at Destination 2025/09/17 03:14:14 DEBUG : file7.txt: Need to transfer - File not found at Destination 2025/09/17 03:14:14 DEBUG : file8.txt: Need to transfer - File not found at Destination 2025/09/17 03:14:14 DEBUG : Local file system at /tmp/031322qa/backupdir/path2: Waiting for checks to finish 2025/09/17 03:14:14 DEBUG : Local file system at /tmp/031322qa/backupdir/path2: Waiting for transfers to finish 2025/09/17 03:14:14 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:14:14 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:14:14 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:14:14 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt 2025/09/17 03:14:14 INFO : file1.txt: Copied (new) 2025/09/17 03:14:14 DEBUG : file3.txt.a74079f2.partial: renamed to: file3.txt 2025/09/17 03:14:14 INFO : file3.txt: Copied (new) 2025/09/17 03:14:14 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt 2025/09/17 03:14:14 INFO : file2.txt: Copied (new) 2025/09/17 03:14:14 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:14:14 DEBUG : file6.txt.0a60f5f0.partial: renamed to: file6.txt 2025/09/17 03:14:14 INFO : file6.txt: Copied (new) 2025/09/17 03:14:14 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:14:14 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:14:14 DEBUG : file7.txt.9f102165.partial: renamed to: file7.txt 2025/09/17 03:14:14 INFO : file7.txt: Copied (new) 2025/09/17 03:14:14 DEBUG : file8.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:14:14 DEBUG : file8.txt.b300b322.partial: renamed to: file8.txt 2025/09/17 03:14:14 INFO : file8.txt: Copied (new) 2025/09/17 03:14:14 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2025/09/17 03:14:14 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST 2025/09/17 03:14:14 INFO : RCLONE_TEST: Copied (new) 2025/09/17 03:14:14 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:14:14 DEBUG : file5.txt.6e808e0e.partial: renamed to: file5.txt 2025/09/17 03:14:14 INFO : file5.txt: Copied (new) 2025/09/17 03:14:14 DEBUG : file4.txt.fbf05a9b.partial: renamed to: file4.txt 2025/09/17 03:14:14 INFO : file4.txt: Copied (new) 2025/09/17 03:14:14 NOTICE: checking path2 Local file system at /tmp/031322qa/backupdir/path2 2025/09/17 03:14:14 NOTICE: (01) : test backupdir 2025/09/17 03:14:14 NOTICE: (02) : test initial bisync 2025/09/17 03:14:14 NOTICE: (03) : bisync resync backupdir1=/tmp/031322qa/workdir/backupdirs/backupdir1 backupdir2=/tmp/031322qa/workdir/backupdirs/backupdir2 bisync_test.go:975: backupdir test currently only works on local (it uses the workdir) === RUN TestBisyncRemoteLocal/basic 2025/09/17 03:14:14 INFO : path1: Making directory 2025/09/17 03:14:14 DEBUG : Creating backend with remote "TestUlozto:rclone-test-kusogos0boto/031322qa/basic/path1" 2025/09/17 03:14:16 DEBUG : Creating backend with remote "/tmp/031322qa/basic/path2" 2025/09/17 03:14:16 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_basic/initial" 2025/09/17 03:14:16 DEBUG : Creating backend with remote "/tmp/031322qa/initdir/test_basic-xulixoh3" 2025/09/17 03:14:16 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2025/09/17 03:14:16 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination 2025/09/17 03:14:16 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination 2025/09/17 03:14:16 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination 2025/09/17 03:14:16 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination 2025/09/17 03:14:16 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination 2025/09/17 03:14:16 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/09/17 03:14:16 DEBUG : subdir: Making directory with metadata 2025/09/17 03:14:16 DEBUG : file1.copy2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:14:16 INFO : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z) 2025/09/17 03:14:16 DEBUG : Added delayed dir = "subdir", newDst=subdir 2025/09/17 03:14:16 DEBUG : file1.copy1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:14:16 DEBUG : file1.copy2.txt.455881d5.partial: renamed to: file1.copy2.txt 2025/09/17 03:14:16 INFO : file1.copy2.txt: Copied (new) 2025/09/17 03:14:16 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination 2025/09/17 03:14:16 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2025/09/17 03:14:16 DEBUG : file1.copy1.txt.21b8fa2b.partial: renamed to: file1.copy1.txt 2025/09/17 03:14:16 DEBUG : file1.copy3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:14:16 INFO : file1.copy1.txt: Copied (new) 2025/09/17 03:14:16 DEBUG : Local file system at /tmp/031322qa/initdir/test_basic-xulixoh3: Waiting for checks to finish 2025/09/17 03:14:16 DEBUG : file1.copy3.txt.d0285540.partial: renamed to: file1.copy3.txt 2025/09/17 03:14:16 DEBUG : file1.copy5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:14:16 DEBUG : file1.copy4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:14:16 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST 2025/09/17 03:14:16 INFO : file1.copy3.txt: Copied (new) 2025/09/17 03:14:16 DEBUG : file1.copy5.txt.19e8a2bc.partial: renamed to: file1.copy5.txt 2025/09/17 03:14:16 INFO : RCLONE_TEST: Copied (new) 2025/09/17 03:14:16 INFO : file1.copy5.txt: Copied (new) 2025/09/17 03:14:16 DEBUG : file1.copy4.txt.8c987629.partial: renamed to: file1.copy4.txt 2025/09/17 03:14:16 INFO : file1.copy4.txt: Copied (new) 2025/09/17 03:14:16 DEBUG : Local file system at /tmp/031322qa/initdir/test_basic-xulixoh3: Waiting for transfers to finish 2025/09/17 03:14:16 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:14:16 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:14:16 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt 2025/09/17 03:14:16 INFO : file1.txt: Copied (new) 2025/09/17 03:14:16 DEBUG : subdir/file20.txt.1ac3db2f.partial: renamed to: subdir/file20.txt 2025/09/17 03:14:16 INFO : subdir/file20.txt: Copied (new) 2025/09/17 03:14:16 INFO : subdir: Set directory modification time (using SetModTime) 2025/09/17 03:14:16 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_basic/modfiles" 2025/09/17 03:14:16 DEBUG : Creating backend with remote "/tmp/031322qa/datadir/test_basic-cozufos8" 2025/09/17 03:14:16 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/09/17 03:14:16 DEBUG : Local file system at /tmp/031322qa/datadir/test_basic-cozufos8: Waiting for checks to finish 2025/09/17 03:14:16 DEBUG : Local file system at /tmp/031322qa/datadir/test_basic-cozufos8: Waiting for transfers to finish 2025/09/17 03:14:16 DEBUG : file1.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2025/09/17 03:14:16 DEBUG : file1.txt.75b77f44.partial: renamed to: file1.txt 2025/09/17 03:14:16 INFO : file1.txt: Copied (new) 2025/09/17 03:14:16 DEBUG : Waiting for deletions to finish 2025/09/17 03:14:17 ERROR : error listing: directory not found 2025/09/17 03:14:17 ERROR : uloz.to root 'rclone-test-kusogos0boto/031322qa/basic/path1': Failed to list "": directory not found 2025/09/17 03:14:17 DEBUG : removing 1 level 0 directories 2025/09/17 03:14:17 INFO : uloz.to root 'rclone-test-kusogos0boto/031322qa/basic/path1': Removing directory 2025/09/17 03:14:17 ERROR : Failed to rmdir: directory not found 2025/09/17 03:14:17 DEBUG : Waiting for deletions to finish 2025/09/17 03:14:17 ERROR : error listing: directory not found 2025/09/17 03:14:17 ERROR : Local file system at /tmp/031322qa/basic/path2: Failed to list "": directory not found 2025/09/17 03:14:17 DEBUG : removing 1 level 0 directories 2025/09/17 03:14:17 INFO : Local file system at /tmp/031322qa/basic/path2: Removing directory 2025/09/17 03:14:17 ERROR : Failed to rmdir: stat /tmp/031322qa/basic/path2: no such file or directory 2025/09/17 03:14:17 ERROR : error listing: directory not found 2025/09/17 03:14:17 ERROR : error listing: directory not found 2025/09/17 03:14:17 NOTICE: checking initFs Local file system at /tmp/031322qa/initdir/test_basic-xulixoh3 2025/09/17 03:14:18 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2025/09/17 03:14:18 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination 2025/09/17 03:14:18 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination 2025/09/17 03:14:18 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination 2025/09/17 03:14:18 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination 2025/09/17 03:14:18 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination 2025/09/17 03:14:18 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/09/17 03:14:18 INFO : subdir: Making directory 2025/09/17 03:14:19 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination 2025/09/17 03:14:19 DEBUG : uloz.to root 'rclone-test-kusogos0boto/031322qa/basic/path1': Waiting for checks to finish 2025/09/17 03:14:19 DEBUG : uloz.to root 'rclone-test-kusogos0boto/031322qa/basic/path1': Waiting for transfers to finish 2025/09/17 03:14:20 DEBUG : file1.copy3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:14:20 INFO : file1.copy3.txt: Copied (new) 2025/09/17 03:14:20 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2025/09/17 03:14:20 INFO : RCLONE_TEST: Copied (new) 2025/09/17 03:14:20 DEBUG : file1.copy2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:14:20 INFO : file1.copy2.txt: Copied (new) 2025/09/17 03:14:20 DEBUG : file1.copy1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:14:20 INFO : file1.copy1.txt: Copied (new) 2025/09/17 03:14:21 DEBUG : file1.copy4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:14:21 INFO : file1.copy4.txt: Copied (new) 2025/09/17 03:14:21 DEBUG : file1.copy5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:14:21 INFO : file1.copy5.txt: Copied (new) 2025/09/17 03:14:21 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:14:21 INFO : subdir/file20.txt: Copied (new) 2025/09/17 03:14:21 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:14:21 INFO : file1.txt: Copied (new) 2025/09/17 03:14:21 NOTICE: checking Path1 uloz.to root 'rclone-test-kusogos0boto/031322qa/basic/path1' 2025/09/17 03:14:22 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2025/09/17 03:14:22 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination 2025/09/17 03:14:22 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination 2025/09/17 03:14:22 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination 2025/09/17 03:14:22 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination 2025/09/17 03:14:22 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination 2025/09/17 03:14:22 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/09/17 03:14:22 DEBUG : subdir: Making directory with metadata 2025/09/17 03:14:22 INFO : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z) 2025/09/17 03:14:22 DEBUG : Added delayed dir = "subdir", newDst=subdir 2025/09/17 03:14:22 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination 2025/09/17 03:14:22 DEBUG : Local file system at /tmp/031322qa/basic/path2: Waiting for checks to finish 2025/09/17 03:14:22 DEBUG : Local file system at /tmp/031322qa/basic/path2: Waiting for transfers to finish 2025/09/17 03:14:22 DEBUG : file1.copy1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:14:22 DEBUG : file1.copy2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:14:22 DEBUG : file1.copy1.txt.21b8fa2b.partial: renamed to: file1.copy1.txt 2025/09/17 03:14:22 INFO : file1.copy1.txt: Copied (new) 2025/09/17 03:14:22 DEBUG : file1.copy3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:14:22 DEBUG : file1.copy2.txt.455881d5.partial: renamed to: file1.copy2.txt 2025/09/17 03:14:22 INFO : file1.copy2.txt: Copied (new) 2025/09/17 03:14:22 DEBUG : file1.copy3.txt.d0285540.partial: renamed to: file1.copy3.txt 2025/09/17 03:14:22 INFO : file1.copy3.txt: Copied (new) 2025/09/17 03:14:22 DEBUG : file1.copy4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:14:22 DEBUG : file1.copy4.txt.8c987629.partial: renamed to: file1.copy4.txt 2025/09/17 03:14:22 INFO : file1.copy4.txt: Copied (new) 2025/09/17 03:14:22 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:14:22 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt 2025/09/17 03:14:22 DEBUG : file1.copy5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:14:22 INFO : file1.txt: Copied (new) 2025/09/17 03:14:22 DEBUG : file1.copy5.txt.19e8a2bc.partial: renamed to: file1.copy5.txt 2025/09/17 03:14:22 INFO : file1.copy5.txt: Copied (new) 2025/09/17 03:14:22 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:14:22 DEBUG : subdir/file20.txt.1ac3db2f.partial: renamed to: subdir/file20.txt 2025/09/17 03:14:22 INFO : subdir/file20.txt: Copied (new) 2025/09/17 03:14:22 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2025/09/17 03:14:22 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST 2025/09/17 03:14:22 INFO : RCLONE_TEST: Copied (new) 2025/09/17 03:14:22 INFO : subdir: Set directory modification time (using SetModTime) 2025/09/17 03:14:22 NOTICE: checking path2 Local file system at /tmp/031322qa/basic/path2 2025/09/17 03:14:22 NOTICE: (01) : test basic 2025/09/17 03:14:22 NOTICE: (02) : test initial bisync 2025/09/17 03:14:22 NOTICE: (03) : bisync resync 2025/09/17 03:14:25 DEBUG : uloz.to root 'rclone-test-kusogos0boto/031322qa/basic/path1': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2025/09/17 03:14:25 INFO : subdir: Set directory modification time (using SetModTime) 2025/09/17 03:14:25 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/09/17 03:14:25 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2025/09/17 03:14:25 INFO : Synching Path1 "TestUlozto:rclone-test-kusogos0boto/031322qa/basic/path1/" with Path2 "/tmp/031322qa/basic/path2/" 2025/09/17 03:14:25 INFO : Copying Path2 files to Path1 2025/09/17 03:14:25 INFO : - Path2 Resync is copying files to - Path1 2025/09/17 03:14:26 INFO : There was nothing to transfer 2025/09/17 03:14:26 INFO : - Path1 Resync is copying files to - Path2 2025/09/17 03:14:26 INFO : subdir: Set directory modification time (using SetModTime) 2025/09/17 03:14:26 INFO : There was nothing to transfer 2025/09/17 03:14:26 INFO : Resync updating listings 2025/09/17 03:14:26 INFO : Validating listings for Path1 "TestUlozto:rclone-test-kusogos0boto/031322qa/basic/path1/" vs Path2 "/tmp/031322qa/basic/path2/" 2025/09/17 03:14:26 INFO : Bisync successful 2025/09/17 03:14:26 NOTICE: (04) : test place newer files on both paths 2025/09/17 03:14:26 NOTICE: (05) : touch-copy 2001-01-02 /tmp/031322qa/datadir/test_basic-cozufos8/file1.txt /tmp/031322qa/basic/path2/ 2025/09/17 03:14:26 DEBUG : Creating backend with remote "/tmp/031322qa/datadir/test_basic-cozufos8/" 2025/09/17 03:14:26 DEBUG : fs cache: renaming cache item "/tmp/031322qa/datadir/test_basic-cozufos8/" to be canonical "/tmp/031322qa/datadir/test_basic-cozufos8" 2025/09/17 03:14:26 DEBUG : file1.txt: Set modification time 2001-01-02 2025/09/17 03:14:26 DEBUG : copyFile "/tmp/031322qa/datadir/test_basic-cozufos8/file1.txt" to "/tmp/031322qa/basic/path2/" as "" 2025/09/17 03:14:26 DEBUG : Creating backend with remote "/tmp/031322qa/datadir/test_basic-cozufos8/file1.txt" 2025/09/17 03:14:26 DEBUG : Creating backend with remote "/tmp/031322qa/basic/path2/" 2025/09/17 03:14:26 DEBUG : operations.CopyFile "file1.txt" to "Local file system at /tmp/031322qa/basic/path2" as "file1.txt" 2025/09/17 03:14:26 DEBUG : file1.txt: Sizes differ (src 19 vs dst 0) 2025/09/17 03:14:26 DEBUG : file1.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2025/09/17 03:14:26 DEBUG : file1.txt.75b77f44.partial: renamed to: file1.txt 2025/09/17 03:14:26 INFO : file1.txt: Copied (replaced existing) 2025/09/17 03:14:26 NOTICE: (06) : copy-as /tmp/031322qa/datadir/test_basic-cozufos8/file1.txt TestUlozto:rclone-test-kusogos0boto/031322qa/basic/path1/subdir file20.txt 2025/09/17 03:14:26 DEBUG : copyFile "/tmp/031322qa/datadir/test_basic-cozufos8/file1.txt" to "TestUlozto:rclone-test-kusogos0boto/031322qa/basic/path1/subdir" as "file20.txt" 2025/09/17 03:14:26 DEBUG : Creating backend with remote "/tmp/031322qa/datadir/test_basic-cozufos8/file1.txt" 2025/09/17 03:14:26 DEBUG : Creating backend with remote "TestUlozto:rclone-test-kusogos0boto/031322qa/basic/path1/subdir" 2025/09/17 03:14:29 DEBUG : operations.CopyFile "file1.txt" to "uloz.to root 'rclone-test-kusogos0boto/031322qa/basic/path1/subdir'" as "file20.txt" 2025/09/17 03:14:29 DEBUG : file1.txt: Sizes differ (src 19 vs dst 0) 2025/09/17 03:14:32 DEBUG : file1.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2025/09/17 03:14:32 INFO : file1.txt: Copied (replaced existing) to: file20.txt 2025/09/17 03:14:32 NOTICE: (07) : test bisync run 2025/09/17 03:14:32 NOTICE: (08) : bisync 2025/09/17 03:14:35 DEBUG : uloz.to root 'rclone-test-kusogos0boto/031322qa/basic/path1': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2025/09/17 03:14:35 INFO : subdir: Set directory modification time (using SetModTime) 2025/09/17 03:14:35 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/09/17 03:14:35 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2025/09/17 03:14:35 INFO : Synching Path1 "TestUlozto:rclone-test-kusogos0boto/031322qa/basic/path1/" with Path2 "/tmp/031322qa/basic/path2/" 2025/09/17 03:14:35 INFO : Building Path1 and Path2 listings 2025/09/17 03:14:35 INFO : Path1 checking for diffs 2025/09/17 03:14:35 INFO : - Path1 File changed: size (larger), time (newer) - subdir/file20.txt 2025/09/17 03:14:35 INFO : Path1: 1 changes:  0 new,  1 modified,  0 deleted 2025/09/17 03:14:35 INFO : (Modified:  1 newer,  0 older,  1 larger,  0 smaller) 2025/09/17 03:14:35 INFO : Path2 checking for diffs 2025/09/17 03:14:35 INFO : - Path2 File changed: size (larger), time (newer) - file1.txt 2025/09/17 03:14:35 INFO : Path2: 1 changes:  0 new,  1 modified,  0 deleted 2025/09/17 03:14:35 INFO : (Modified:  1 newer,  0 older,  1 larger,  0 smaller) 2025/09/17 03:14:35 INFO : Applying changes 2025/09/17 03:14:35 INFO : - Path1 Queue copy to Path2 - /tmp/031322qa/basic/path2/subdir/file20.txt 2025/09/17 03:14:35 INFO : - Path2 Queue copy to Path1 - TestUlozto:rclone-test-kusogos0boto/031322qa/basic/path1/file1.txt 2025/09/17 03:14:35 INFO : - Path2 Do queued copies to - Path1 2025/09/17 03:14:36 INFO : file1.txt: Copied (replaced existing) 2025/09/17 03:14:36 INFO : - Path1 Do queued copies to - Path2 2025/09/17 03:14:37 INFO : subdir: Set directory modification time (using SetModTime) 2025/09/17 03:25:20 ERROR : subdir/file20.txt: Failed to copy: failed to open source object: HTTP error 400 (400 Bad Request) returned body: "\n\n\n \n Ulož.to Disk - Platnost odkazu na stránku vypršela\n \n \n \n \n\n\n\n
\n
\n \n \n \n \n \n \n \n \n \n
\n
\n\n
\n
\n\t\n\t

Platnost odkazu na stránku vypršela

\n

\n

Link to the page has expired

\n\t\n
\n
\n\n \n\n\n\n\n\n" 2025/09/17 03:25:20 ERROR : Local file system at /tmp/031322qa/basic/path2: not deleting files as there were IO errors 2025/09/17 03:25:20 INFO : subdir: Set directory modification time (using SetModTime) 2025/09/17 03:25:20 ERROR : Local file system at /tmp/031322qa/basic/path2: not deleting directories as there were IO errors 2025/09/17 03:25:20 ERROR : Bisync critical error: failed to open source object: HTTP error 400 (400 Bad Request) returned body: "\n\n\n \n Ulož.to Disk - Platnost odkazu na stránku vypršela\n \n \n \n \n\n\n\n
\n
\n \n \n \n \n \n \n \n \n \n
\n
\n\n
\n
\n\t\n\t

Platnost odkazu na stránku vypršela

\n

\n

Link to the page has expired

\n\t\n
\n
\n\n \n\n\n\n\n\n" 2025/09/17 03:25:20 ERROR : Bisync aborted. Must run --resync to recover. 2025/09/17 03:25:20 NOTICE: Bisync error: bisync aborted 2025/09/17 03:25:20 NOTICE: ---------------------------------------------------------- 2025/09/17 03:25:20 NOTICE: MISCOMPARE - Number of Golden and Results files do not match: 2025/09/17 03:25:20 NOTICE: Golden count: 9 2025/09/17 03:25:20 NOTICE: Result count: 7 2025/09/17 03:25:20 NOTICE: Golden files: _testdir_path1.._testdir_path2.copy1to2.que, _testdir_path1.._testdir_path2.copy2to1.que, _testdir_path1.._testdir_path2.path1.lst, _testdir_path1.._testdir_path2.path1.lst-new, _testdir_path1.._testdir_path2.path1.lst-old, _testdir_path1.._testdir_path2.path2.lst, _testdir_path1.._testdir_path2.path2.lst-new, _testdir_path1.._testdir_path2.path2.lst-old, test.log 2025/09/17 03:25:20 NOTICE: Result files: _testdir_path1.._testdir_path2.copy1to2.que, _testdir_path1.._testdir_path2.copy2to1.que, _testdir_path1.._testdir_path2.path1.lst-err, _testdir_path1.._testdir_path2.path1.lst-new, _testdir_path1.._testdir_path2.path2.lst-err, _testdir_path1.._testdir_path2.path2.lst-new, test.log 2025/09/17 03:25:20 NOTICE: File found in Golden but not in Results: _testdir_path1.._testdir_path2.path1.lst 2025/09/17 03:25:20 NOTICE: File found in Golden but not in Results: _testdir_path1.._testdir_path2.path1.lst-old 2025/09/17 03:25:20 NOTICE: File found in Golden but not in Results: _testdir_path1.._testdir_path2.path2.lst 2025/09/17 03:25:20 NOTICE: File found in Golden but not in Results: _testdir_path1.._testdir_path2.path2.lst-old 2025/09/17 03:25:20 NOTICE: File found in Results but not in Golden: _testdir_path1.._testdir_path2.path1.lst-err 2025/09/17 03:25:20 NOTICE: File found in Results but not in Golden: _testdir_path1.._testdir_path2.path2.lst-err 2025/09/17 03:25:20 NOTICE: ---------------------------------------------------------- 2025/09/17 03:25:20 NOTICE: | MISCOMPARE -Golden vs +Results for test.log 2025/09/17 03:25:20 NOTICE: | @@ -53,3 +53,6 @@ 2025/09/17 03:25:20 NOTICE: | -INFO : Updating listings 2025/09/17 03:25:20 NOTICE: | -INFO : Validating listings for Path1 "{path1/}" vs Path2 "{path2/}" 2025/09/17 03:25:20 NOTICE: | -INFO : Bisync successful 2025/09/17 03:25:20 NOTICE: | +ERROR : subdir/file20.txt: Failed to copy: failed to open source object: HTTP error 400 (400 Bad Request) returned body: "\n\n\n \n Ulož.to Disk - Platnost odkazu na stránku vypršela\n \n \n \n \n\n\n\n
\n
\n \n \n \n \n \n \n \n \n \n
\n
\n\n
\n
\n\t\n\t

Platnost odkazu na stránku vypršela

\n

\n

Link to the page has expired

\n\t\n
\n
\n\n \n\n\n\n\n\n" 2025/09/17 03:25:20 NOTICE: | +ERROR : {path2String}: not deleting files as there were IO errors 2025/09/17 03:25:20 NOTICE: | +ERROR : {path2String}: not deleting directories as there were IO errors 2025/09/17 03:25:20 NOTICE: | +ERROR : Bisync critical error: failed to open source object: HTTP error 400 (400 Bad Request) returned body: "\n\n\n \n Ulož.to Disk - Platnost odkazu na stránku vypršela\n \n \n \n \n\n\n\n
\n
\n \n \n \n \n \n \n \n \n \n
\n
\n\n
\n
\n\t\n\t

Platnost odkazu na stránku vypršela

\n

\n

Link to the page has expired

\n\t\n
\n
\n\n \n\n\n\n\n\n" 2025/09/17 03:25:20 NOTICE: | +ERROR : Bisync aborted. Must run --resync to recover. 2025/09/17 03:25:20 NOTICE: | +Bisync error: bisync aborted 2025/09/17 03:25:20 NOTICE: ---------------------------------------------------------- 2025/09/17 03:25:20 DEBUG : Waiting for deletions to finish 2025/09/17 03:25:20 DEBUG : pacer: Reducing sleep to 360ms 2025/09/17 03:25:20 DEBUG : pacer: Reducing sleep to 270ms 2025/09/17 03:25:21 DEBUG : pacer: Reducing sleep to 202.5ms 2025/09/17 03:25:21 DEBUG : pacer: Reducing sleep to 151.875ms 2025/09/17 03:25:21 DEBUG : pacer: Reducing sleep to 113.90625ms 2025/09/17 03:25:21 DEBUG : pacer: Reducing sleep to 85.429687ms 2025/09/17 03:25:21 DEBUG : pacer: Reducing sleep to 64.072265ms 2025/09/17 03:25:22 DEBUG : pacer: Reducing sleep to 48.054198ms 2025/09/17 03:25:22 DEBUG : pacer: Reducing sleep to 36.040648ms 2025/09/17 03:25:22 DEBUG : pacer: Reducing sleep to 27.030486ms 2025/09/17 03:25:22 DEBUG : pacer: Reducing sleep to 20.272864ms 2025/09/17 03:25:22 INFO : file1.copy3.txt: Deleted 2025/09/17 03:25:22 DEBUG : pacer: Reducing sleep to 15.204648ms 2025/09/17 03:25:22 DEBUG : pacer: Reducing sleep to 11.403486ms 2025/09/17 03:25:22 INFO : file1.copy2.txt: Deleted 2025/09/17 03:25:22 DEBUG : pacer: Reducing sleep to 10ms 2025/09/17 03:25:22 INFO : file1.copy1.txt: Deleted 2025/09/17 03:25:22 INFO : file1.copy4.txt: Deleted 2025/09/17 03:25:22 INFO : file1.copy5.txt: Deleted 2025/09/17 03:25:22 INFO : file1.txt: Deleted 2025/09/17 03:25:22 INFO : RCLONE_TEST: Deleted 2025/09/17 03:25:22 INFO : subdir/file20.txt: Deleted 2025/09/17 03:25:22 DEBUG : removing 1 level 1 directories 2025/09/17 03:25:22 INFO : subdir: Removing directory 2025/09/17 03:25:23 DEBUG : removing 1 level 0 directories 2025/09/17 03:25:23 INFO : uloz.to root 'rclone-test-kusogos0boto/031322qa/basic/path1': Removing directory 2025/09/17 03:25:23 DEBUG : Waiting for deletions to finish 2025/09/17 03:25:23 INFO : file1.copy1.txt: Deleted 2025/09/17 03:25:23 INFO : file1.copy2.txt: Deleted 2025/09/17 03:25:23 INFO : file1.copy3.txt: Deleted 2025/09/17 03:25:23 INFO : file1.copy4.txt: Deleted 2025/09/17 03:25:23 INFO : file1.copy5.txt: Deleted 2025/09/17 03:25:23 INFO : file1.txt: Deleted 2025/09/17 03:25:23 INFO : subdir/file20.txt: Deleted 2025/09/17 03:25:23 INFO : RCLONE_TEST: Deleted 2025/09/17 03:25:23 DEBUG : removing 1 level 1 directories 2025/09/17 03:25:23 INFO : subdir: Removing directory 2025/09/17 03:25:23 DEBUG : removing 1 level 0 directories 2025/09/17 03:25:23 INFO : Local file system at /tmp/031322qa/basic/path2: Removing directory bisync_test.go:608: TEST basic FAILED - 7 MISCOMPARED FILES === RUN TestBisyncRemoteLocal/changes 2025/09/17 03:25:23 DEBUG : Creating backend with remote "TestUlozto:rclone-test-kusogos0boto/031322qa" 2025/09/17 03:25:23 DEBUG : Config file has changed externally - reloading 2025/09/17 03:25:26 INFO : path1: Making directory 2025/09/17 03:25:26 DEBUG : Creating backend with remote "TestUlozto:rclone-test-kusogos0boto/031322qa/changes/path1" 2025/09/17 03:25:28 DEBUG : Creating backend with remote "/tmp/031322qa" 2025/09/17 03:25:28 DEBUG : Creating backend with remote "/tmp/031322qa/changes/path2" 2025/09/17 03:25:28 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_changes/initial" 2025/09/17 03:25:28 DEBUG : Creating backend with remote "/tmp/031322qa/initdir/test_changes-hawebid2" 2025/09/17 03:25:28 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2025/09/17 03:25:28 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/09/17 03:25:28 DEBUG : file2.txt: Need to transfer - File not found at Destination 2025/09/17 03:25:28 DEBUG : file3.txt: Need to transfer - File not found at Destination 2025/09/17 03:25:28 DEBUG : file4.txt: Need to transfer - File not found at Destination 2025/09/17 03:25:28 DEBUG : file5.txt: Need to transfer - File not found at Destination 2025/09/17 03:25:28 DEBUG : file6.txt: Need to transfer - File not found at Destination 2025/09/17 03:25:28 DEBUG : file7.txt: Need to transfer - File not found at Destination 2025/09/17 03:25:28 DEBUG : file8.txt: Need to transfer - File not found at Destination 2025/09/17 03:25:28 DEBUG : Local file system at /tmp/031322qa/initdir/test_changes-hawebid2: Waiting for checks to finish 2025/09/17 03:25:28 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:25:28 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2025/09/17 03:25:28 DEBUG : Local file system at /tmp/031322qa/initdir/test_changes-hawebid2: Waiting for transfers to finish 2025/09/17 03:25:28 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:25:28 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST 2025/09/17 03:25:28 INFO : RCLONE_TEST: Copied (new) 2025/09/17 03:25:28 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:25:28 DEBUG : file4.txt.fbf05a9b.partial: renamed to: file4.txt 2025/09/17 03:25:28 INFO : file4.txt: Copied (new) 2025/09/17 03:25:28 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:25:28 DEBUG : file3.txt.a74079f2.partial: renamed to: file3.txt 2025/09/17 03:25:28 INFO : file3.txt: Copied (new) 2025/09/17 03:25:28 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt 2025/09/17 03:25:28 INFO : file2.txt: Copied (new) 2025/09/17 03:25:28 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt 2025/09/17 03:25:28 INFO : file1.txt: Copied (new) 2025/09/17 03:25:28 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:25:28 DEBUG : file6.txt.0a60f5f0.partial: renamed to: file6.txt 2025/09/17 03:25:28 INFO : file6.txt: Copied (new) 2025/09/17 03:25:28 DEBUG : file8.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:25:28 DEBUG : file8.txt.b300b322.partial: renamed to: file8.txt 2025/09/17 03:25:28 INFO : file8.txt: Copied (new) 2025/09/17 03:25:28 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:25:28 DEBUG : file7.txt.9f102165.partial: renamed to: file7.txt 2025/09/17 03:25:28 INFO : file7.txt: Copied (new) 2025/09/17 03:25:28 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:25:28 DEBUG : file5.txt.6e808e0e.partial: renamed to: file5.txt 2025/09/17 03:25:28 INFO : file5.txt: Copied (new) 2025/09/17 03:25:28 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_changes/modfiles" 2025/09/17 03:25:28 DEBUG : Creating backend with remote "/tmp/031322qa/datadir/test_changes-dufesiq1" 2025/09/17 03:25:28 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/09/17 03:25:28 DEBUG : file10.txt: Need to transfer - File not found at Destination 2025/09/17 03:25:28 DEBUG : file11.txt: Need to transfer - File not found at Destination 2025/09/17 03:25:28 DEBUG : file2.txt: Need to transfer - File not found at Destination 2025/09/17 03:25:28 DEBUG : file5L.txt: Need to transfer - File not found at Destination 2025/09/17 03:25:28 DEBUG : file5R.txt: Need to transfer - File not found at Destination 2025/09/17 03:25:28 DEBUG : file6.txt: Need to transfer - File not found at Destination 2025/09/17 03:25:28 DEBUG : file7.txt: Need to transfer - File not found at Destination 2025/09/17 03:25:28 DEBUG : file10.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2025/09/17 03:25:28 DEBUG : file10.txt.c02780cb.partial: renamed to: file10.txt 2025/09/17 03:25:28 INFO : file10.txt: Copied (new) 2025/09/17 03:25:28 DEBUG : file2.txt: md5 = fb3ecfb2800400fb01b0bfd39903e9fb OK 2025/09/17 03:25:28 DEBUG : file2.txt.4526d70e.partial: renamed to: file2.txt 2025/09/17 03:25:28 INFO : file2.txt: Copied (new) 2025/09/17 03:25:28 DEBUG : file11.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2025/09/17 03:25:28 DEBUG : file11.txt.40d797d4.partial: renamed to: file11.txt 2025/09/17 03:25:28 INFO : file11.txt: Copied (new) 2025/09/17 03:25:28 DEBUG : Local file system at /tmp/031322qa/datadir/test_changes-dufesiq1: Waiting for checks to finish 2025/09/17 03:25:28 DEBUG : Local file system at /tmp/031322qa/datadir/test_changes-dufesiq1: Waiting for transfers to finish 2025/09/17 03:25:28 DEBUG : file1.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2025/09/17 03:25:28 DEBUG : file1.txt.75b77f44.partial: renamed to: file1.txt 2025/09/17 03:25:28 INFO : file1.txt: Copied (new) 2025/09/17 03:25:28 DEBUG : file6.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2025/09/17 03:25:28 DEBUG : file6.txt.4185169b.partial: renamed to: file6.txt 2025/09/17 03:25:28 INFO : file6.txt: Copied (new) 2025/09/17 03:25:28 DEBUG : file5L.txt: md5 = 0860a03592626642f8fd6c8bfb447d2a OK 2025/09/17 03:25:28 DEBUG : file5L.txt.8dd6541a.partial: renamed to: file5L.txt 2025/09/17 03:25:28 INFO : file5L.txt: Copied (new) 2025/09/17 03:25:28 DEBUG : file5R.txt: md5 = 979a803b15d27df0c31ad7d29006d10b OK 2025/09/17 03:25:28 DEBUG : file5R.txt.ee3b9525.partial: renamed to: file5R.txt 2025/09/17 03:25:28 INFO : file5R.txt: Copied (new) 2025/09/17 03:25:28 DEBUG : file7.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2025/09/17 03:25:28 DEBUG : file7.txt.c1750184.partial: renamed to: file7.txt 2025/09/17 03:25:28 INFO : file7.txt: Copied (new) 2025/09/17 03:25:28 DEBUG : Waiting for deletions to finish 2025/09/17 03:25:28 ERROR : error listing: directory not found 2025/09/17 03:25:29 ERROR : uloz.to root 'rclone-test-kusogos0boto/031322qa/changes/path1': Failed to list "": directory not found 2025/09/17 03:25:29 DEBUG : removing 1 level 0 directories 2025/09/17 03:25:29 INFO : uloz.to root 'rclone-test-kusogos0boto/031322qa/changes/path1': Removing directory 2025/09/17 03:25:29 ERROR : Failed to rmdir: directory not found 2025/09/17 03:25:29 DEBUG : Waiting for deletions to finish 2025/09/17 03:25:29 ERROR : error listing: directory not found 2025/09/17 03:25:29 ERROR : Local file system at /tmp/031322qa/changes/path2: Failed to list "": directory not found 2025/09/17 03:25:29 DEBUG : removing 1 level 0 directories 2025/09/17 03:25:29 INFO : Local file system at /tmp/031322qa/changes/path2: Removing directory 2025/09/17 03:25:29 ERROR : Failed to rmdir: stat /tmp/031322qa/changes/path2: no such file or directory 2025/09/17 03:25:29 ERROR : error listing: directory not found 2025/09/17 03:25:29 ERROR : error listing: directory not found 2025/09/17 03:25:29 NOTICE: checking initFs Local file system at /tmp/031322qa/initdir/test_changes-hawebid2 2025/09/17 03:25:29 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2025/09/17 03:25:29 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/09/17 03:25:29 DEBUG : file2.txt: Need to transfer - File not found at Destination 2025/09/17 03:25:29 DEBUG : file3.txt: Need to transfer - File not found at Destination 2025/09/17 03:25:29 DEBUG : file4.txt: Need to transfer - File not found at Destination 2025/09/17 03:25:29 DEBUG : file5.txt: Need to transfer - File not found at Destination 2025/09/17 03:25:29 DEBUG : file6.txt: Need to transfer - File not found at Destination 2025/09/17 03:25:29 DEBUG : file7.txt: Need to transfer - File not found at Destination 2025/09/17 03:25:29 DEBUG : file8.txt: Need to transfer - File not found at Destination 2025/09/17 03:25:29 DEBUG : uloz.to root 'rclone-test-kusogos0boto/031322qa/changes/path1': Waiting for checks to finish 2025/09/17 03:25:29 DEBUG : uloz.to root 'rclone-test-kusogos0boto/031322qa/changes/path1': Waiting for transfers to finish 2025/09/17 03:25:32 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:25:32 INFO : file3.txt: Copied (new) 2025/09/17 03:25:32 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:25:32 INFO : file2.txt: Copied (new) 2025/09/17 03:25:32 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:25:32 INFO : file1.txt: Copied (new) 2025/09/17 03:25:33 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2025/09/17 03:25:33 INFO : RCLONE_TEST: Copied (new) 2025/09/17 03:25:33 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:25:33 INFO : file4.txt: Copied (new) 2025/09/17 03:25:33 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:25:33 INFO : file5.txt: Copied (new) 2025/09/17 03:25:33 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:25:33 INFO : file6.txt: Copied (new) 2025/09/17 03:25:33 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:25:33 INFO : file7.txt: Copied (new) 2025/09/17 03:25:34 DEBUG : file8.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:25:34 INFO : file8.txt: Copied (new) 2025/09/17 03:25:34 NOTICE: checking Path1 uloz.to root 'rclone-test-kusogos0boto/031322qa/changes/path1' 2025/09/17 03:25:34 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2025/09/17 03:25:34 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/09/17 03:25:34 DEBUG : file2.txt: Need to transfer - File not found at Destination 2025/09/17 03:25:34 DEBUG : file3.txt: Need to transfer - File not found at Destination 2025/09/17 03:25:34 DEBUG : file4.txt: Need to transfer - File not found at Destination 2025/09/17 03:25:34 DEBUG : file5.txt: Need to transfer - File not found at Destination 2025/09/17 03:25:34 DEBUG : file6.txt: Need to transfer - File not found at Destination 2025/09/17 03:25:34 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:25:34 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt 2025/09/17 03:25:34 INFO : file1.txt: Copied (new) 2025/09/17 03:25:34 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:25:34 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt 2025/09/17 03:25:34 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:25:34 INFO : file2.txt: Copied (new) 2025/09/17 03:25:34 DEBUG : file3.txt.a74079f2.partial: renamed to: file3.txt 2025/09/17 03:25:34 INFO : file3.txt: Copied (new) 2025/09/17 03:25:34 DEBUG : file7.txt: Need to transfer - File not found at Destination 2025/09/17 03:25:34 DEBUG : file8.txt: Need to transfer - File not found at Destination 2025/09/17 03:25:34 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:25:34 DEBUG : Local file system at /tmp/031322qa/changes/path2: Waiting for checks to finish 2025/09/17 03:25:34 DEBUG : file4.txt.fbf05a9b.partial: renamed to: file4.txt 2025/09/17 03:25:34 INFO : file4.txt: Copied (new) 2025/09/17 03:25:34 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:25:34 DEBUG : Local file system at /tmp/031322qa/changes/path2: Waiting for transfers to finish 2025/09/17 03:25:34 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:25:34 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:25:34 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2025/09/17 03:25:34 DEBUG : file7.txt.9f102165.partial: renamed to: file7.txt 2025/09/17 03:25:34 INFO : file7.txt: Copied (new) 2025/09/17 03:25:34 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST 2025/09/17 03:25:34 INFO : RCLONE_TEST: Copied (new) 2025/09/17 03:25:34 DEBUG : file8.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 03:25:34 DEBUG : file8.txt.b300b322.partial: renamed to: file8.txt 2025/09/17 03:25:34 INFO : file8.txt: Copied (new) 2025/09/17 03:25:34 DEBUG : file5.txt.6e808e0e.partial: renamed to: file5.txt 2025/09/17 03:25:34 INFO : file5.txt: Copied (new) 2025/09/17 03:25:34 DEBUG : file6.txt.0a60f5f0.partial: renamed to: file6.txt 2025/09/17 03:25:34 INFO : file6.txt: Copied (new) 2025/09/17 03:25:34 NOTICE: checking path2 Local file system at /tmp/031322qa/changes/path2 2025/09/17 03:25:34 NOTICE: (01) : test changes 2025/09/17 03:25:34 NOTICE: (02) : test initial bisync 2025/09/17 03:25:34 NOTICE: (03) : bisync resync 2025/09/17 03:25:37 DEBUG : uloz.to root 'rclone-test-kusogos0boto/031322qa/changes/path1': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2025/09/17 03:25:37 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/09/17 03:25:37 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2025/09/17 03:25:37 INFO : Synching Path1 "TestUlozto:rclone-test-kusogos0boto/031322qa/changes/path1/" with Path2 "/tmp/031322qa/changes/path2/" 2025/09/17 03:25:37 INFO : Copying Path2 files to Path1 2025/09/17 03:25:37 INFO : - Path2 Resync is copying files to - Path1 2025/09/17 03:25:38 INFO : There was nothing to transfer 2025/09/17 03:25:38 INFO : - Path1 Resync is copying files to - Path2 2025/09/17 03:25:38 INFO : There was nothing to transfer 2025/09/17 03:25:38 INFO : Resync updating listings 2025/09/17 03:25:38 INFO : Validating listings for Path1 "TestUlozto:rclone-test-kusogos0boto/031322qa/changes/path1/" vs Path2 "/tmp/031322qa/changes/path2/" 2025/09/17 03:25:38 INFO : Bisync successful 2025/09/17 03:25:38 NOTICE: (04) : test make modifications on both paths 2025/09/17 03:25:38 NOTICE: (05) : test new on path2 - file10 2025/09/17 03:25:38 NOTICE: (06) : touch-copy 2001-01-02 /tmp/031322qa/datadir/test_changes-dufesiq1/file10.txt /tmp/031322qa/changes/path2/ 2025/09/17 03:25:38 DEBUG : Creating backend with remote "/tmp/031322qa/datadir/test_changes-dufesiq1/" 2025/09/17 03:25:38 DEBUG : fs cache: renaming cache item "/tmp/031322qa/datadir/test_changes-dufesiq1/" to be canonical "/tmp/031322qa/datadir/test_changes-dufesiq1" 2025/09/17 03:25:38 DEBUG : file10.txt: Set modification time 2001-01-02 2025/09/17 03:25:38 DEBUG : copyFile "/tmp/031322qa/datadir/test_changes-dufesiq1/file10.txt" to "/tmp/031322qa/changes/path2/" as "" 2025/09/17 03:25:38 DEBUG : Creating backend with remote "/tmp/031322qa/datadir/test_changes-dufesiq1/file10.txt" 2025/09/17 03:25:38 DEBUG : Creating backend with remote "/tmp/031322qa/changes/path2/" 2025/09/17 03:25:38 DEBUG : operations.CopyFile "file10.txt" to "Local file system at /tmp/031322qa/changes/path2" as "file10.txt" 2025/09/17 03:25:38 DEBUG : file10.txt: Need to transfer - File not found at Destination 2025/09/17 03:25:38 DEBUG : file10.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2025/09/17 03:25:38 DEBUG : file10.txt.c02780cb.partial: renamed to: file10.txt 2025/09/17 03:25:38 INFO : file10.txt: Copied (new) 2025/09/17 03:25:38 NOTICE: (07) : test newer on path2 - file1 2025/09/17 03:25:38 NOTICE: (08) : touch-copy 2001-01-02 /tmp/031322qa/datadir/test_changes-dufesiq1/file1.txt /tmp/031322qa/changes/path2/ 2025/09/17 03:25:38 DEBUG : fs cache: switching user supplied name "/tmp/031322qa/datadir/test_changes-dufesiq1/" for canonical name "/tmp/031322qa/datadir/test_changes-dufesiq1" 2025/09/17 03:25:38 DEBUG : file1.txt: Set modification time 2001-01-02 2025/09/17 03:25:38 DEBUG : copyFile "/tmp/031322qa/datadir/test_changes-dufesiq1/file1.txt" to "/tmp/031322qa/changes/path2/" as "" 2025/09/17 03:25:38 DEBUG : Creating backend with remote "/tmp/031322qa/datadir/test_changes-dufesiq1/file1.txt" 2025/09/17 03:25:38 DEBUG : Creating backend with remote "/tmp/031322qa/changes/path2/" 2025/09/17 03:25:38 DEBUG : operations.CopyFile "file1.txt" to "Local file system at /tmp/031322qa/changes/path2" as "file1.txt" 2025/09/17 03:25:38 DEBUG : file1.txt: Sizes differ (src 19 vs dst 0) 2025/09/17 03:25:38 DEBUG : file1.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2025/09/17 03:25:38 DEBUG : file1.txt.75b77f44.partial: renamed to: file1.txt 2025/09/17 03:25:38 INFO : file1.txt: Copied (replaced existing) 2025/09/17 03:25:38 NOTICE: (09) : test new on path1 - file11 2025/09/17 03:25:38 NOTICE: (10) : touch-copy 2001-01-02 /tmp/031322qa/datadir/test_changes-dufesiq1/file11.txt TestUlozto:rclone-test-kusogos0boto/031322qa/changes/path1/ 2025/09/17 03:25:38 DEBUG : fs cache: switching user supplied name "/tmp/031322qa/datadir/test_changes-dufesiq1/" for canonical name "/tmp/031322qa/datadir/test_changes-dufesiq1" 2025/09/17 03:25:38 DEBUG : file11.txt: Set modification time 2001-01-02 2025/09/17 03:25:38 DEBUG : copyFile "/tmp/031322qa/datadir/test_changes-dufesiq1/file11.txt" to "TestUlozto:rclone-test-kusogos0boto/031322qa/changes/path1/" as "" 2025/09/17 03:25:38 DEBUG : Creating backend with remote "/tmp/031322qa/datadir/test_changes-dufesiq1/file11.txt" 2025/09/17 03:25:38 DEBUG : Creating backend with remote "TestUlozto:rclone-test-kusogos0boto/031322qa/changes/path1/" 2025/09/17 03:25:40 DEBUG : operations.CopyFile "file11.txt" to "uloz.to root 'rclone-test-kusogos0boto/031322qa/changes/path1'" as "file11.txt" 2025/09/17 03:25:41 DEBUG : file11.txt: Need to transfer - File not found at Destination 2025/09/17 03:25:41 DEBUG : file11.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2025/09/17 03:25:41 INFO : file11.txt: Copied (new) 2025/09/17 03:25:41 NOTICE: (11) : test newer on path1 - file2 2025/09/17 03:25:41 NOTICE: (12) : touch-copy 2001-01-02 /tmp/031322qa/datadir/test_changes-dufesiq1/file2.txt TestUlozto:rclone-test-kusogos0boto/031322qa/changes/path1/ 2025/09/17 03:25:41 DEBUG : fs cache: switching user supplied name "/tmp/031322qa/datadir/test_changes-dufesiq1/" for canonical name "/tmp/031322qa/datadir/test_changes-dufesiq1" 2025/09/17 03:25:41 DEBUG : file2.txt: Set modification time 2001-01-02 2025/09/17 03:25:41 DEBUG : copyFile "/tmp/031322qa/datadir/test_changes-dufesiq1/file2.txt" to "TestUlozto:rclone-test-kusogos0boto/031322qa/changes/path1/" as "" 2025/09/17 03:25:41 DEBUG : Creating backend with remote "/tmp/031322qa/datadir/test_changes-dufesiq1/file2.txt" 2025/09/17 03:25:41 DEBUG : Creating backend with remote "TestUlozto:rclone-test-kusogos0boto/031322qa/changes/path1/" 2025/09/17 03:25:44 DEBUG : operations.CopyFile "file2.txt" to "uloz.to root 'rclone-test-kusogos0boto/031322qa/changes/path1'" as "file2.txt" 2025/09/17 03:25:44 DEBUG : file2.txt: Sizes differ (src 13 vs dst 0) 2025/09/17 03:25:51 DEBUG : file2.txt: md5 = fb3ecfb2800400fb01b0bfd39903e9fb OK 2025/09/17 03:25:51 INFO : file2.txt: Copied (replaced existing) 2025/09/17 03:25:51 NOTICE: (13) : test deleted on path2 - file3 2025/09/17 03:25:51 NOTICE: (14) : delete-file /tmp/031322qa/changes/path2/file3.txt 2025/09/17 03:25:51 DEBUG : Creating backend with remote "/tmp/031322qa/changes/path2/" 2025/09/17 03:25:51 DEBUG : Config file has changed externally - reloading 2025/09/17 03:25:51 DEBUG : fs cache: renaming cache item "/tmp/031322qa/changes/path2/" to be canonical "/tmp/031322qa/changes/path2" 2025/09/17 03:25:51 INFO : file3.txt: Deleted 2025/09/17 03:25:51 NOTICE: (15) : test deleted on path1 - file4 2025/09/17 03:25:51 NOTICE: (16) : delete-file TestUlozto:rclone-test-kusogos0boto/031322qa/changes/path1/file4.txt 2025/09/17 03:25:51 DEBUG : Creating backend with remote "TestUlozto:rclone-test-kusogos0boto/031322qa/changes/path1/" 2025/09/17 03:25:54 DEBUG : fs cache: renaming cache item "TestUlozto:rclone-test-kusogos0boto/031322qa/changes/path1/" to be canonical "TestUlozto:rclone-test-kusogos0boto/031322qa/changes/path1" 2025/09/17 03:25:54 INFO : file4.txt: Deleted 2025/09/17 03:25:54 NOTICE: (17) : test deleted on both paths - file8 2025/09/17 03:25:54 NOTICE: (18) : delete-file TestUlozto:rclone-test-kusogos0boto/031322qa/changes/path1/file8.txt 2025/09/17 03:25:54 DEBUG : fs cache: switching user supplied name "TestUlozto:rclone-test-kusogos0boto/031322qa/changes/path1/" for canonical name "TestUlozto:rclone-test-kusogos0boto/031322qa/changes/path1" 2025/09/17 03:25:55 INFO : file8.txt: Deleted 2025/09/17 03:25:55 NOTICE: (19) : delete-file /tmp/031322qa/changes/path2/file8.txt 2025/09/17 03:25:55 DEBUG : fs cache: switching user supplied name "/tmp/031322qa/changes/path2/" for canonical name "/tmp/031322qa/changes/path2" 2025/09/17 03:25:55 INFO : file8.txt: Deleted 2025/09/17 03:25:55 NOTICE: (20) : test changed on both paths - file5 (file5R, file5L) 2025/09/17 03:25:55 NOTICE: (21) : touch-glob 2001-01-02 /tmp/031322qa/datadir/test_changes-dufesiq1/ file5R.txt 2025/09/17 03:25:55 DEBUG : fs cache: switching user supplied name "/tmp/031322qa/datadir/test_changes-dufesiq1/" for canonical name "/tmp/031322qa/datadir/test_changes-dufesiq1" 2025/09/17 03:25:55 DEBUG : file5R.txt: Set modification time 2001-01-02 2025/09/17 03:25:55 NOTICE: (22) : copy-as /tmp/031322qa/datadir/test_changes-dufesiq1/file5R.txt /tmp/031322qa/changes/path2/ file5.txt 2025/09/17 03:25:55 DEBUG : copyFile "/tmp/031322qa/datadir/test_changes-dufesiq1/file5R.txt" to "/tmp/031322qa/changes/path2/" as "file5.txt" 2025/09/17 03:25:55 DEBUG : Creating backend with remote "/tmp/031322qa/datadir/test_changes-dufesiq1/file5R.txt" 2025/09/17 03:25:55 DEBUG : Creating backend with remote "/tmp/031322qa/changes/path2/" 2025/09/17 03:25:55 DEBUG : operations.CopyFile "file5R.txt" to "Local file system at /tmp/031322qa/changes/path2" as "file5.txt" 2025/09/17 03:25:55 DEBUG : file5R.txt: Sizes differ (src 39 vs dst 0) 2025/09/17 03:25:55 DEBUG : file5R.txt: md5 = 979a803b15d27df0c31ad7d29006d10b OK 2025/09/17 03:25:55 DEBUG : file5.txt.232124ee.partial: renamed to: file5.txt 2025/09/17 03:25:55 INFO : file5R.txt: Copied (replaced existing) to: file5.txt 2025/09/17 03:25:55 NOTICE: (23) : touch-glob 2001-03-04 /tmp/031322qa/datadir/test_changes-dufesiq1/ file5L.txt 2025/09/17 03:25:55 DEBUG : fs cache: switching user supplied name "/tmp/031322qa/datadir/test_changes-dufesiq1/" for canonical name "/tmp/031322qa/datadir/test_changes-dufesiq1" 2025/09/17 03:25:55 DEBUG : file5L.txt: Set modification time 2001-03-04 2025/09/17 03:25:55 NOTICE: (24) : copy-as /tmp/031322qa/datadir/test_changes-dufesiq1/file5L.txt TestUlozto:rclone-test-kusogos0boto/031322qa/changes/path1/ file5.txt 2025/09/17 03:25:55 DEBUG : copyFile "/tmp/031322qa/datadir/test_changes-dufesiq1/file5L.txt" to "TestUlozto:rclone-test-kusogos0boto/031322qa/changes/path1/" as "file5.txt" 2025/09/17 03:25:55 DEBUG : Creating backend with remote "/tmp/031322qa/datadir/test_changes-dufesiq1/file5L.txt" 2025/09/17 03:25:55 DEBUG : Creating backend with remote "TestUlozto:rclone-test-kusogos0boto/031322qa/changes/path1/" 2025/09/17 03:25:57 DEBUG : operations.CopyFile "file5L.txt" to "uloz.to root 'rclone-test-kusogos0boto/031322qa/changes/path1'" as "file5.txt" 2025/09/17 03:25:57 DEBUG : file5L.txt: Sizes differ (src 39 vs dst 0) 2025/09/17 03:25:58 DEBUG : file5L.txt: md5 = 0860a03592626642f8fd6c8bfb447d2a OK 2025/09/17 03:25:58 INFO : file5L.txt: Copied (replaced existing) to: file5.txt 2025/09/17 03:25:58 NOTICE: (25) : test newer on path2 and deleted on path1 - file6 2025/09/17 03:25:58 NOTICE: (26) : touch-copy 2001-01-02 /tmp/031322qa/datadir/test_changes-dufesiq1/file6.txt /tmp/031322qa/changes/path2/ 2025/09/17 03:25:58 DEBUG : fs cache: switching user supplied name "/tmp/031322qa/datadir/test_changes-dufesiq1/" for canonical name "/tmp/031322qa/datadir/test_changes-dufesiq1" 2025/09/17 03:25:58 DEBUG : file6.txt: Set modification time 2001-01-02 2025/09/17 03:25:58 DEBUG : copyFile "/tmp/031322qa/datadir/test_changes-dufesiq1/file6.txt" to "/tmp/031322qa/changes/path2/" as "" 2025/09/17 03:25:58 DEBUG : Creating backend with remote "/tmp/031322qa/datadir/test_changes-dufesiq1/file6.txt" 2025/09/17 03:25:58 DEBUG : Creating backend with remote "/tmp/031322qa/changes/path2/" 2025/09/17 03:25:58 DEBUG : operations.CopyFile "file6.txt" to "Local file system at /tmp/031322qa/changes/path2" as "file6.txt" 2025/09/17 03:25:58 DEBUG : file6.txt: Sizes differ (src 19 vs dst 0) 2025/09/17 03:25:58 DEBUG : file6.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2025/09/17 03:25:58 DEBUG : file6.txt.4185169b.partial: renamed to: file6.txt 2025/09/17 03:25:58 INFO : file6.txt: Copied (replaced existing) 2025/09/17 03:25:58 NOTICE: (27) : delete-file TestUlozto:rclone-test-kusogos0boto/031322qa/changes/path1/file6.txt 2025/09/17 03:25:58 DEBUG : fs cache: switching user supplied name "TestUlozto:rclone-test-kusogos0boto/031322qa/changes/path1/" for canonical name "TestUlozto:rclone-test-kusogos0boto/031322qa/changes/path1" 2025/09/17 03:25:58 INFO : file6.txt: Deleted 2025/09/17 03:25:58 NOTICE: (28) : test newer on path1 and deleted on path2 - file7 2025/09/17 03:25:58 NOTICE: (29) : touch-copy 2001-01-02 /tmp/031322qa/datadir/test_changes-dufesiq1/file7.txt TestUlozto:rclone-test-kusogos0boto/031322qa/changes/path1/ 2025/09/17 03:25:58 DEBUG : fs cache: switching user supplied name "/tmp/031322qa/datadir/test_changes-dufesiq1/" for canonical name "/tmp/031322qa/datadir/test_changes-dufesiq1" 2025/09/17 03:25:58 DEBUG : file7.txt: Set modification time 2001-01-02 2025/09/17 03:25:58 DEBUG : copyFile "/tmp/031322qa/datadir/test_changes-dufesiq1/file7.txt" to "TestUlozto:rclone-test-kusogos0boto/031322qa/changes/path1/" as "" 2025/09/17 03:25:58 DEBUG : Creating backend with remote "/tmp/031322qa/datadir/test_changes-dufesiq1/file7.txt" 2025/09/17 03:25:58 DEBUG : Creating backend with remote "TestUlozto:rclone-test-kusogos0boto/031322qa/changes/path1/" 2025/09/17 03:26:01 DEBUG : operations.CopyFile "file7.txt" to "uloz.to root 'rclone-test-kusogos0boto/031322qa/changes/path1'" as "file7.txt" 2025/09/17 03:26:01 DEBUG : file7.txt: Sizes differ (src 19 vs dst 0) 2025/09/17 03:26:03 DEBUG : file7.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2025/09/17 03:26:03 INFO : file7.txt: Copied (replaced existing) 2025/09/17 03:26:03 NOTICE: (30) : delete-file /tmp/031322qa/changes/path2/file7.txt 2025/09/17 03:26:03 DEBUG : fs cache: switching user supplied name "/tmp/031322qa/changes/path2/" for canonical name "/tmp/031322qa/changes/path2" 2025/09/17 03:26:03 INFO : file7.txt: Deleted 2025/09/17 03:26:03 NOTICE: (31) : test bisync run 2025/09/17 03:26:03 NOTICE: (32) : bisync 2025/09/17 03:26:06 DEBUG : uloz.to root 'rclone-test-kusogos0boto/031322qa/changes/path1': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata panic: test timed out after 1h0m0s running tests: TestBisyncRemoteLocal (1h0m0s) TestBisyncRemoteLocal/changes (47m57s) goroutine 6378 [running]: testing.(*M).startAlarm.func1() /usr/local/go/src/testing/testing.go:2484 +0x394 created by time.goFunc /usr/local/go/src/time/sleep.go:215 +0x2d goroutine 1 [chan receive, 60 minutes]: testing.(*T).Run(0xc000a8e1c0, {0x27eb191?, 0xc0006bd6e0?}, 0x2916c78) /usr/local/go/src/testing/testing.go:1859 +0x431 testing.runTests.func1(0xc000a8e1c0) /usr/local/go/src/testing/testing.go:2279 +0x37 testing.tRunner(0xc000a8e1c0, 0xc0006bd820) /usr/local/go/src/testing/testing.go:1792 +0xf4 testing.runTests(0xc00065f770, {0x407c7c0, 0x4, 0x4}, {0xee05c1e50?, 0x40baac0?, 0x40baac0?}) /usr/local/go/src/testing/testing.go:2277 +0x4b4 testing.(*M).Run(0xc00052fa40) /usr/local/go/src/testing/testing.go:2142 +0x64a github.com/rclone/rclone/fstest.TestMain(0xc00052fa40) /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:69 +0xa5 github.com/rclone/rclone/cmd/bisync_test.TestMain(0xc00052fa40) /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:243 +0xf2 main.main() _testmain.go:55 +0xa8 goroutine 68 [chan receive, 59 minutes]: github.com/rclone/rclone/lib/atexit.Register.func1.1() /home/rclone/go/src/github.com/rclone/rclone/lib/atexit/atexit.go:45 +0x29 created by github.com/rclone/rclone/lib/atexit.Register.func1 in goroutine 40 /home/rclone/go/src/github.com/rclone/rclone/lib/atexit/atexit.go:44 +0x68 goroutine 52 [syscall, 60 minutes]: os/signal.signal_recv() /usr/local/go/src/runtime/sigqueue.go:152 +0x29 os/signal.loop() /usr/local/go/src/os/signal/signal_unix.go:23 +0x13 created by os/signal.Notify.func1.1 in goroutine 1 /usr/local/go/src/os/signal/signal.go:152 +0x1f goroutine 53 [chan receive, 60 minutes]: github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:24 +0x27 created by github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler in goroutine 1 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7 goroutine 3497 [sync.WaitGroup.Wait, 47 minutes]: sync.runtime_SemacquireWaitGroup(0x0?) /usr/local/go/src/runtime/sema.go:110 +0x25 sync.(*WaitGroup).Wait(0xc000652c07?) /usr/local/go/src/sync/waitgroup.go:118 +0x48 github.com/rclone/rclone/fs/sync.(*syncCopyMove).stopTransfers(0xc000b1d688) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync.go:543 +0x6f github.com/rclone/rclone/fs/sync.(*syncCopyMove).run(0xc000b1d688) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync.go:978 +0x325 github.com/rclone/rclone/fs/sync.runSyncCopyMove({0x2c6e1c0, 0xc000652c30}, {0x2c85650, 0xc000679d40}, {0x2c86d10, 0xc000a1d400}, 0x3, 0x0, 0x0, 0x0, ...) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync.go:1369 +0x225 github.com/rclone/rclone/fs/sync.Sync({0x2c6e1c0, 0xc000652c30}, {0x2c85650, 0xc000679d40}, {0x2c86d10, 0xc000a1d400}, 0x0) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync.go:1375 +0x77 github.com/rclone/rclone/cmd/bisync.(*bisyncRun).fastCopy(0xc000c08008, {0x2c6e188?, 0xc0000de420?}, {0x2c86d10, 0xc000a1d400}, {0x2c85650, 0xc000679d40}, 0xc000486e10, {0x27c4773, 0x8}) /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/queue.go:254 +0x4e5 github.com/rclone/rclone/cmd/bisync.(*bisyncRun).applyDeltas(0xc000c08008, {0x2c6e188, 0xc0000de420}, 0xc0003b4000, 0xc0003b4cb0) /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/deltas.go:505 +0x2287 github.com/rclone/rclone/cmd/bisync.(*bisyncRun).runLocked(0xc000c08008, {0x2c6e188, 0xc0000de420}) /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/operations.go:382 +0x1331 github.com/rclone/rclone/cmd/bisync.Bisync({0x2c6e188, 0xc0000de420}, {0x2c86d10, 0xc000a1d400}, {0x2c85650, 0xc000679d40}, 0xc00006d0c8) /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/operations.go:180 +0xa58 github.com/rclone/rclone/cmd/bisync_test.(*bisyncTest).runBisync.func3() /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:1218 +0x38 github.com/rclone/rclone/cmd/bisync/bilib.CaptureOutput(0xc00006d1e0) /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bilib/output.go:22 +0xd4 github.com/rclone/rclone/cmd/bisync_test.(*bisyncTest).runBisync(0xc000b22c40, {0x2c6e188?, 0xc0003a4510?}, {0xc0005dc1c0, 0x0, 0xc0005dc140?}) /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:1217 +0x12c5 github.com/rclone/rclone/cmd/bisync_test.(*bisyncTest).runTestStep(0xc000b22c40, {0x2c6e188, 0xc0000df380}, {0xc0008937d0, 0x6}) /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:788 +0x3af github.com/rclone/rclone/cmd/bisync_test.(*bisyncTest).runTestCase-range1({0xc0008646af, 0x6}) /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:542 +0x1ed github.com/rclone/rclone/cmd/bisync_test.(*bisyncTest).runTestCase.SplitSeq.splitSeq.func3(0xc00041c680) /usr/local/go/src/strings/iter.go:61 +0x8a github.com/rclone/rclone/cmd/bisync_test.(*bisyncTest).runTestCase(0xc000b22c40, {0x2c6e188, 0xc0009a25d0}, 0xc000b22540, {0xc00064a015, 0x7}) /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:525 +0x165b github.com/rclone/rclone/cmd/bisync_test.testBisync.func4(0xc000b22540) /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:405 +0x234 testing.tRunner(0xc000b22540, 0xc000116f00) /usr/local/go/src/testing/testing.go:1792 +0xf4 created by testing.(*T).Run in goroutine 40 /usr/local/go/src/testing/testing.go:1851 +0x413 goroutine 54 [chan receive, 60 minutes]: github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:24 +0x27 created by github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler in goroutine 1 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7 goroutine 40 [chan receive, 47 minutes]: testing.(*T).Run(0xc000a8e380, {0xc00064a015?, 0x2c33678?}, 0xc000116f00) /usr/local/go/src/testing/testing.go:1859 +0x431 github.com/rclone/rclone/cmd/bisync_test.testBisync({0x2c6e188, 0xc0009a25d0}, 0xc000a8e380, {0xc000a83350, 0x23}, {0x0, 0x0}) /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:398 +0x94f github.com/rclone/rclone/cmd/bisync_test.TestBisyncRemoteLocal(0xc000a8e380) /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:256 +0x175 testing.tRunner(0xc000a8e380, 0x2916c78) /usr/local/go/src/testing/testing.go:1792 +0xf4 created by testing.(*T).Run in goroutine 1 /usr/local/go/src/testing/testing.go:1851 +0x413 goroutine 4844 [select]: net/http.(*Transport).getConn(0xc0007b21a0, 0xc0009cc0a0, {{}, 0x0, {0xc0001f0c60, 0x5}, {0xc000dc6020, 0x1a}, 0x0}) /usr/local/go/src/net/http/transport.go:1520 +0x4ae net/http.(*Transport).roundTrip(0xc0007b21a0, 0xc000656000) /usr/local/go/src/net/http/transport.go:685 +0xb57 net/http.(*Transport).RoundTrip(...) /usr/local/go/src/net/http/roundtrip.go:30 github.com/rclone/rclone/fs/fshttp.(*Transport).RoundTrip(0xc000b92660, 0xc000656000) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/http.go:496 +0x345 net/http.send(0xc000656000, {0x2c469a0, 0xc000b92660}, {0x6fe101?, 0xc0001f0c68?, 0x0?}) /usr/local/go/src/net/http/client.go:259 +0x5e2 net/http.(*Client).send(0xc000026c00, 0xc000656000, {0xc000de7128?, 0x422eb6?, 0x0?}) /usr/local/go/src/net/http/client.go:180 +0x91 net/http.(*Client).do(0xc000026c00, 0xc000656000) /usr/local/go/src/net/http/client.go:728 +0x989 net/http.(*Client).Do(...) /usr/local/go/src/net/http/client.go:587 github.com/rclone/rclone/lib/rest.(*Client).Call(0xc0009cc280, {0x2c6e1c0, 0xc000652dc0}, 0xc000656140) /home/rclone/go/src/github.com/rclone/rclone/lib/rest/rest.go:353 +0xd67 github.com/rclone/rclone/backend/ulozto.(*Object).Open.func2() /home/rclone/go/src/github.com/rclone/rclone/backend/ulozto/ulozto.go:804 +0x52 github.com/rclone/rclone/fs.pacerInvoker(0x7, 0xa, 0x2c45460?) /home/rclone/go/src/github.com/rclone/rclone/fs/pacer.go:86 +0x32 github.com/rclone/rclone/lib/pacer.(*Pacer).call(0xc000b928a0, 0xc000c6e240, 0xa) /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:199 +0x7c github.com/rclone/rclone/lib/pacer.(*Pacer).Call(0xc000b928a0, 0xc000c6e240) /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:218 +0x93 github.com/rclone/rclone/backend/ulozto.(*Object).Open(0xc000a25e60, {0x2c6e1c0, 0xc000652dc0}, {0xc000a10510, 0x1, 0x1}) /home/rclone/go/src/github.com/rclone/rclone/backend/ulozto/ulozto.go:803 +0x428 github.com/rclone/rclone/fs/operations.(*ReOpen).open(0xc00087e0e0) /home/rclone/go/src/github.com/rclone/rclone/fs/operations/reopen.go:147 +0x109 github.com/rclone/rclone/fs/operations.NewReOpen({0x2c6e1c0, 0xc000652dc0}, {0x2c86d80, 0xc000a25e60}, 0xa, {0xc000a10500, 0x1, 0x1}) /home/rclone/go/src/github.com/rclone/rclone/fs/operations/reopen.go:102 +0x4f5 github.com/rclone/rclone/fs/operations.Open({0x2c6e1c0, 0xc000652dc0}, {0x2c86d80, 0xc000a25e60}, {0xc000a10500, 0x1, 0x1}) /home/rclone/go/src/github.com/rclone/rclone/fs/operations/reopen.go:124 +0x6b github.com/rclone/rclone/fs/operations.(*copy).manualCopy(0xc0009b9e60, {0x2c6e1c0, 0xc000652dc0}) /home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy.go:265 +0x4dc github.com/rclone/rclone/fs/operations.(*copy).copy(0xc0009b9e60, {0x2c6e1c0, 0xc000652dc0}) /home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy.go:314 +0x170 github.com/rclone/rclone/fs/operations.Copy({0x2c6e1c0, 0xc000652dc0}, {0x2c85650, 0xc000679d40}, {0x0, 0x0}, {0xc0009d64c8, 0x13}, {0x2c86d80, 0xc000a25e60}) /home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy.go:416 +0x4db github.com/rclone/rclone/fs/sync.(*syncCopyMove).pairCopyOrMove(0xc000b1d688, {0x2c6e1c0, 0xc000652dc0}, 0xc000652cd0, {0x2c85650, 0xc000679d40}, 0x4b, 0xc000a9d7a8?) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync.go:505 +0x1fe created by github.com/rclone/rclone/fs/sync.(*syncCopyMove).startTransfers in goroutine 3497 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync.go:535 +0x7d goroutine 2954 [select]: github.com/rclone/rclone/fs/accounting.(*StatsInfo).averageLoop(0xc000770f00, {0x2c6e1c0, 0xc0007121e0}) /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/stats.go:339 +0x145 created by github.com/rclone/rclone/fs/accounting.(*StatsInfo)._startAverageLoop in goroutine 2953 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/stats.go:376 +0x11a goroutine 6412 [IO wait]: internal/poll.runtime_pollWait(0x7a04b4107d18, 0x72) /usr/local/go/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc000130a00?, 0xc000ac0000?, 0x0) /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 internal/poll.(*pollDesc).waitRead(...) /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 internal/poll.(*FD).Read(0xc000130a00, {0xc000ac0000, 0x4d0, 0x4d0}) /usr/local/go/src/internal/poll/fd_unix.go:165 +0x27a net.(*netFD).Read(0xc000130a00, {0xc000ac0000?, 0xc000a94f60?, 0x47e549?}) /usr/local/go/src/net/fd_posix.go:55 +0x25 net.(*conn).Read(0xc000422028, {0xc000ac0000?, 0x0?, 0x0?}) /usr/local/go/src/net/net.go:194 +0x45 net.dnsPacketRoundTrip({_, _}, _, {{{0x64, 0x6f, 0x77, 0x6e, 0x6c, 0x6f, 0x61, ...}, ...}, ...}, ...) /usr/local/go/src/net/dnsclient_unix.go:116 +0x123 net.(*Resolver).exchange(_, {_, _}, {_, _}, {{{0x64, 0x6f, 0x77, 0x6e, 0x6c, ...}, ...}, ...}, ...) /usr/local/go/src/net/dnsclient_unix.go:195 +0x4e5 net.(*Resolver).tryOneName(0x40b79a0, {0x2c6e1c0, 0xc0009cc140}, 0xc00052fea0?, {0xc0009d6048, 0x17}, 0x1) /usr/local/go/src/net/dnsclient_unix.go:316 +0x4c6 net.(*Resolver).goLookupIPCNAMEOrder.func3.1(0xe1c0?) /usr/local/go/src/net/dnsclient_unix.go:667 +0x85 created by net.(*Resolver).goLookupIPCNAMEOrder.func3 in goroutine 6411 /usr/local/go/src/net/dnsclient_unix.go:666 +0x13f goroutine 6408 [sleep]: time.Sleep(0x77359400) /usr/local/go/src/runtime/time.go:338 +0x165 github.com/rclone/rclone/lib/pacer.(*Pacer).beginCall.func1(0xc000d28708?) /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:169 +0x1c created by github.com/rclone/rclone/lib/pacer.(*Pacer).beginCall in goroutine 4844 /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:168 +0xef goroutine 839 [select]: github.com/rclone/rclone/fs/accounting.(*StatsInfo).averageLoop(0xc0006c23c0, {0x2c6e1c0, 0xc0007120a0}) /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/stats.go:339 +0x145 created by github.com/rclone/rclone/fs/accounting.(*StatsInfo)._startAverageLoop in goroutine 838 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/stats.go:376 +0x11a goroutine 4339 [select]: github.com/rclone/rclone/fs/accounting.(*StatsInfo).averageLoop(0xc0005e74a0, {0x2c6e1c0, 0xc000c542d0}) /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/stats.go:339 +0x145 created by github.com/rclone/rclone/fs/accounting.(*StatsInfo)._startAverageLoop in goroutine 4338 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/stats.go:376 +0x11a goroutine 995 [chan receive (nil chan), 59 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 69 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378 goroutine 3116 [chan receive (nil chan), 58 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 2074 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378 goroutine 4518 [select]: github.com/rclone/rclone/fs/accounting.(*StatsInfo).averageLoop(0xc000771680, {0x2c6e1c0, 0xc000c7a0f0}) /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/stats.go:339 +0x145 created by github.com/rclone/rclone/fs/accounting.(*StatsInfo)._startAverageLoop in goroutine 3497 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/stats.go:376 +0x11a goroutine 4621 [select]: github.com/rclone/rclone/fs/accounting.(*StatsInfo).averageLoop(0xc0008c4000, {0x2c6e1c0, 0xc000c7b450}) /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/stats.go:339 +0x145 created by github.com/rclone/rclone/fs/accounting.(*StatsInfo)._startAverageLoop in goroutine 3497 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/stats.go:376 +0x11a goroutine 4702 [chan receive (nil chan), 47 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 3497 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378 goroutine 4595 [select]: github.com/rclone/rclone/fs/accounting.(*StatsInfo).averageLoop(0xc000771c20, {0x2c6e1c0, 0xc000c7ae60}) /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/stats.go:339 +0x145 created by github.com/rclone/rclone/fs/accounting.(*StatsInfo)._startAverageLoop in goroutine 3497 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/stats.go:376 +0x11a goroutine 985 [select]: github.com/rclone/rclone/fs/accounting.(*StatsInfo).averageLoop(0xc0007705a0, {0x2c6e1c0, 0xc0005024b0}) /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/stats.go:339 +0x145 created by github.com/rclone/rclone/fs/accounting.(*StatsInfo)._startAverageLoop in goroutine 984 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/stats.go:376 +0x11a goroutine 6411 [chan receive]: net.(*Resolver).goLookupIPCNAMEOrder.func4({0xc000acf4f8?, 0x41b125?}, 0x6a78?) /usr/local/go/src/net/dnsclient_unix.go:673 +0x65 net.(*Resolver).goLookupIPCNAMEOrder(_, {_, _}, {_, _}, {_, _}, _, _) /usr/local/go/src/net/dnsclient_unix.go:683 +0xbe6 net.(*Resolver).lookupIP(0x40b79a0, {0x2c6e1c0, 0xc0009cc140}, {0x27b6061, 0x3}, {0xc000dc6020, 0x16}) /usr/local/go/src/net/lookup_unix.go:68 +0x131 net.init.func1({0x2c6e1c0?, 0xc0009cc140?}, 0xc0003cce00?, {0x27b6061?, 0xc000b95f70?}, {0xc000dc6020?, 0xc000b95f40?}) /usr/local/go/src/net/hook.go:21 +0x37 net.(*Resolver).lookupIPAddr.func1() /usr/local/go/src/net/lookup.go:334 +0x37 internal/singleflight.(*Group).doCall(0x40b79b0, 0xc0009cc190, {0xc000dc6040, 0x1a}, 0x40de1c0?) /usr/local/go/src/internal/singleflight/singleflight.go:93 +0x34 created by internal/singleflight.(*Group).DoChan in goroutine 6410 /usr/local/go/src/internal/singleflight/singleflight.go:86 +0x2d3 goroutine 4691 [chan receive (nil chan), 47 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 3497 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378 goroutine 4505 [select]: github.com/rclone/rclone/fs/accounting.(*StatsInfo).averageLoop(0xc0006c2960, {0x2c6e1c0, 0xc000c7a410}) /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/stats.go:339 +0x145 created by github.com/rclone/rclone/fs/accounting.(*StatsInfo)._startAverageLoop in goroutine 3497 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/stats.go:376 +0x11a goroutine 4487 [select]: github.com/rclone/rclone/fs/accounting.(*StatsInfo).averageLoop(0xc0005e7860, {0x2c6e1c0, 0xc0006b2230}) /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/stats.go:339 +0x145 created by github.com/rclone/rclone/fs/accounting.(*StatsInfo)._startAverageLoop in goroutine 3497 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/stats.go:376 +0x11a goroutine 1119 [select]: github.com/rclone/rclone/fs/accounting.(*StatsInfo).averageLoop(0xc0006c25a0, {0x2c6e1c0, 0xc0006b36d0}) /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/stats.go:339 +0x145 created by github.com/rclone/rclone/fs/accounting.(*StatsInfo)._startAverageLoop in goroutine 1118 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/stats.go:376 +0x11a goroutine 1145 [chan receive (nil chan), 59 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 69 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378 goroutine 1251 [select]: github.com/rclone/rclone/fs/accounting.(*StatsInfo).averageLoop(0xc0006c2780, {0x2c6e1c0, 0xc000502500}) /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/stats.go:339 +0x145 created by github.com/rclone/rclone/fs/accounting.(*StatsInfo)._startAverageLoop in goroutine 1278 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/stats.go:376 +0x11a goroutine 6410 [select]: net.(*Resolver).lookupIPAddr(0x40b79a0, {0x2c6e230, 0xc0003c81c0}, {0x27b6061, 0x3}, {0xc000dc6020, 0x16}) /usr/local/go/src/net/lookup.go:342 +0x3d9 net.(*Resolver).internetAddrList(0x40b79a0?, {0x2c6e230?, 0xc0003c81c0?}, {0x27b6061, 0x3}, {0xc000dc6020?, 0x41bb05?}) /usr/local/go/src/net/ipsock.go:289 +0x4c5 net.(*Resolver).resolveAddrList(0x40b79a0, {0x2c6e230, 0xc0003c81c0}, {0x27b71d2, 0x4}, {0x27b6061?, 0x3a00000500?}, {0xc000dc6020, 0x1a}, {0x0, ...}) /usr/local/go/src/net/dial.go:353 +0x3e5 net.(*Dialer).DialContext(0xc0007ac1e0, {0x2c6e1c0, 0xc0009cc0f0}, {0x27b6061, 0x3}, {0xc000dc6020, 0x1a}) /usr/local/go/src/net/dial.go:560 +0x408 github.com/rclone/rclone/fs/fshttp.(*Dialer).DialContext(0xc0007ac1e0, {0x2c6e1c0, 0xc0009cc0f0}, {0x27b6061?, 0x7a04fe05f350?}, {0xc000dc6020, 0x1a}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:62 +0x125 github.com/rclone/rclone/fs/fshttp.NewTransportCustom.func2({0x2c6e1c0, 0xc0009cc0f0}, {0x27b6061, 0x3}, {0xc000dc6020, 0x1a}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/http.go:269 +0x5c net/http.(*Transport).dial(0xe9?, {0x2c6e1c0?, 0xc0009cc0f0?}, {0x27b6061?, 0xc22aab6d49adf505?}, {0xc000dc6020?, 0x40baac0?}) /usr/local/go/src/net/http/transport.go:1278 +0xd2 net/http.(*Transport).dialConn(0xc0007b21a0, {0x2c6e1c0, 0xc0009cc0f0}, {{}, 0x0, {0xc0001f0c60, 0x5}, {0xc000dc6020, 0x1a}, 0x0}) /usr/local/go/src/net/http/transport.go:1780 +0x7e5 net/http.(*Transport).dialConnFor(0xc0007b21a0, 0xc000c46160) /usr/local/go/src/net/http/transport.go:1615 +0xb8 net/http.(*Transport).startDialConnForLocked.func1() /usr/local/go/src/net/http/transport.go:1597 +0x35 created by net/http.(*Transport).startDialConnForLocked in goroutine 4844 /usr/local/go/src/net/http/transport.go:1596 +0x112 goroutine 3295 [select]: github.com/rclone/rclone/fs/accounting.(*StatsInfo).averageLoop(0xc0005e63c0, {0x2c6e1c0, 0xc0009cc640}) /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/stats.go:339 +0x145 created by github.com/rclone/rclone/fs/accounting.(*StatsInfo)._startAverageLoop in goroutine 3382 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/stats.go:376 +0x11a goroutine 4490 [select]: github.com/rclone/rclone/fs/accounting.(*StatsInfo).averageLoop(0xc0005e7a40, {0x2c6e1c0, 0xc0006b22d0}) /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/stats.go:339 +0x145 created by github.com/rclone/rclone/fs/accounting.(*StatsInfo)._startAverageLoop in goroutine 3497 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/stats.go:376 +0x11a goroutine 1190 [select]: github.com/rclone/rclone/fs/accounting.(*StatsInfo).averageLoop(0xc000770780, {0x2c6e1c0, 0xc000435d10}) /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/stats.go:339 +0x145 created by github.com/rclone/rclone/fs/accounting.(*StatsInfo)._startAverageLoop in goroutine 1189 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/stats.go:376 +0x11a goroutine 1200 [chan receive (nil chan), 59 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 69 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378 goroutine 4819 [select]: github.com/rclone/rclone/fs/accounting.(*StatsInfo).averageLoop(0xc000d0c960, {0x2c6e1c0, 0xc000c7a2d0}) /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/stats.go:339 +0x145 created by github.com/rclone/rclone/fs/accounting.(*StatsInfo)._startAverageLoop in goroutine 4841 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/stats.go:376 +0x11a goroutine 4853 [chan receive, 47 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 3497 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378 "./bisync.test -test.v -test.timeout 1h0m0s -remote TestUlozto: -verbose" - Finished ERROR in 1h0m2.465088687s (try 1/5): exit status 2: Failed []