"./bisync.test -test.v -test.timeout 1h0m0s -remote TestDropbox: -verbose -test.run '^TestBisyncRemoteRemote$/^resync_modes$'" - Starting (try 3/5) 2024/12/09 03:52:26 DEBUG : Creating backend with remote "TestDropbox:rclone-test-qilewem5rite" 2024/12/09 03:52:26 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/12/09 03:52:26 DEBUG : Creating backend with remote "/tmp/rclone3165704996" === RUN TestBisyncRemoteRemote 2024/12/09 03:52:26 DEBUG : Creating backend with remote "TestDropbox:rclone-test-comupul5wube" 2024/12/09 03:52:26 NOTICE: remote: TestDropbox:rclone-test-comupul5wube === RUN TestBisyncRemoteRemote/resync_modes 2024/12/09 03:52:26 DEBUG : Creating backend with remote "TestDropbox:rclone-test-comupul5wube/035226qi" 2024/12/09 03:52:26 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/12/09 03:52:27 DEBUG : path1: Making directory 2024/12/09 03:52:27 DEBUG : pacer: low level retry 1/10 (error too_many_write_operations/...) 2024/12/09 03:52:27 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2024/12/09 03:52:28 DEBUG : pacer: Reducing sleep to 15ms 2024/12/09 03:52:28 DEBUG : Creating backend with remote "TestDropbox:rclone-test-comupul5wube/035226qi/resync_modes/path1" 2024/12/09 03:52:28 DEBUG : path2: Making directory 2024/12/09 03:52:28 DEBUG : pacer: Reducing sleep to 11.25ms 2024/12/09 03:52:29 DEBUG : pacer: Reducing sleep to 10ms 2024/12/09 03:52:29 DEBUG : Creating backend with remote "TestDropbox:rclone-test-comupul5wube/035226qi/resync_modes/path2" 2024/12/09 03:52:29 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resync_modes/initial" 2024/12/09 03:52:29 DEBUG : Creating backend with remote "/tmp/035226qi/initdir/test_resync_modes-pedivoq8" 2024/12/09 03:52:29 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2024/12/09 03:52:29 DEBUG : file1.txt: Need to transfer - File not found at Destination 2024/12/09 03:52:29 DEBUG : file2.txt: Need to transfer - File not found at Destination 2024/12/09 03:52:29 DEBUG : Local file system at /tmp/035226qi/initdir/test_resync_modes-pedivoq8: Waiting for checks to finish 2024/12/09 03:52:29 DEBUG : Local file system at /tmp/035226qi/initdir/test_resync_modes-pedivoq8: Waiting for transfers to finish 2024/12/09 03:52:29 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/12/09 03:52:29 DEBUG : file2.txt.5028a97.partial: renamed to: file2.txt 2024/12/09 03:52:29 INFO : file2.txt: Copied (new) 2024/12/09 03:52:29 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/12/09 03:52:29 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2024/12/09 03:52:29 DEBUG : RCLONE_TEST.cf9a93ac.partial: renamed to: RCLONE_TEST 2024/12/09 03:52:29 INFO : RCLONE_TEST: Copied (new) 2024/12/09 03:52:29 DEBUG : file1.txt.5028a97.partial: renamed to: file1.txt 2024/12/09 03:52:29 INFO : file1.txt: Copied (new) 2024/12/09 03:52:29 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resync_modes/modfiles" 2024/12/09 03:52:29 DEBUG : Creating backend with remote "/tmp/035226qi/datadir/test_resync_modes-jifolek6" 2024/12/09 03:52:29 DEBUG : file1L.txt: Need to transfer - File not found at Destination 2024/12/09 03:52:29 DEBUG : file1R.txt: Need to transfer - File not found at Destination 2024/12/09 03:52:29 DEBUG : file2.txt: Need to transfer - File not found at Destination 2024/12/09 03:52:29 DEBUG : file2.txt: md5 = 9fe822ddd1cb81d83aae00fa48239bd3 OK 2024/12/09 03:52:29 DEBUG : file2.txt.7cc9b07.partial: renamed to: file2.txt 2024/12/09 03:52:29 INFO : file2.txt: Copied (new) 2024/12/09 03:52:29 DEBUG : file1R.txt: md5 = 705bb3c0ef02925739e834ca98c099c3 OK 2024/12/09 03:52:29 DEBUG : file1R.txt.cb75035e.partial: renamed to: file1R.txt 2024/12/09 03:52:29 INFO : file1R.txt: Copied (new) 2024/12/09 03:52:29 DEBUG : Local file system at /tmp/035226qi/datadir/test_resync_modes-jifolek6: Waiting for checks to finish 2024/12/09 03:52:29 DEBUG : Local file system at /tmp/035226qi/datadir/test_resync_modes-jifolek6: Waiting for transfers to finish 2024/12/09 03:52:29 DEBUG : file1L.txt: md5 = ea683c03f780b76a62405456b08ae6fd OK 2024/12/09 03:52:29 DEBUG : file1L.txt.7d07f91a.partial: renamed to: file1L.txt 2024/12/09 03:52:29 INFO : file1L.txt: Copied (new) 2024/12/09 03:52:30 ERROR : : error listing: directory not found 2024/12/09 03:52:30 ERROR : : error listing: directory not found 2024/12/09 03:52:30 NOTICE: checking initFs Local file system at /tmp/035226qi/initdir/test_resync_modes-pedivoq8 2024/12/09 03:52:30 DEBUG : file1.txt: Need to transfer - File not found at Destination 2024/12/09 03:52:30 DEBUG : file2.txt: Need to transfer - File not found at Destination 2024/12/09 03:52:30 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2024/12/09 03:52:30 DEBUG : Dropbox root 'rclone-test-comupul5wube/035226qi/resync_modes/path1': Waiting for checks to finish 2024/12/09 03:52:30 DEBUG : Dropbox root 'rclone-test-comupul5wube/035226qi/resync_modes/path1': Waiting for transfers to finish 2024/12/09 03:52:31 DEBUG : file1.txt: Uploading chunk 1/0 2024/12/09 03:52:31 DEBUG : RCLONE_TEST: Uploading chunk 1/1 2024/12/09 03:52:31 DEBUG : file2.txt: Uploading chunk 1/0 2024/12/09 03:52:31 DEBUG : file1.txt: Uploading chunk 2/0 2024/12/09 03:52:31 DEBUG : file2.txt: Uploading chunk 2/0 2024/12/09 03:52:31 DEBUG : RCLONE_TEST: Uploading chunk 2/1 2024/12/09 03:52:31 DEBUG : Dropbox root 'rclone-test-comupul5wube/035226qi/resync_modes/path1': Adding "file1.txt" to batch 2024/12/09 03:52:32 DEBUG : Dropbox root 'rclone-test-comupul5wube/035226qi/resync_modes/path1': Adding "file2.txt" to batch 2024/12/09 03:52:32 DEBUG : Dropbox root 'rclone-test-comupul5wube/035226qi/resync_modes/path1': Adding "RCLONE_TEST" to batch 2024/12/09 03:52:32 DEBUG : Dropbox root 'rclone-test-comupul5wube/035226qi/resync_modes/path1': Batch idle for 500ms so committing 2024/12/09 03:52:32 DEBUG : Dropbox root 'rclone-test-comupul5wube/035226qi/resync_modes/path1': Committing sync batch length 3 starting with: file1.txt 2024/12/09 03:52:33 DEBUG : Dropbox root 'rclone-test-comupul5wube/035226qi/resync_modes/path1': Committed sync batch length 3 starting with: file1.txt 2024/12/09 03:52:33 DEBUG : file1.txt: dropbox = e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855 OK 2024/12/09 03:52:33 INFO : file1.txt: Copied (new) 2024/12/09 03:52:33 DEBUG : file2.txt: dropbox = e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855 OK 2024/12/09 03:52:33 INFO : file2.txt: Copied (new) 2024/12/09 03:52:33 DEBUG : RCLONE_TEST: dropbox = c7ddcb950cf985d316be90e07c421557fe39529668db776ef2c16e0c1a1cddee OK 2024/12/09 03:52:33 INFO : RCLONE_TEST: Copied (new) 2024/12/09 03:52:33 NOTICE: checking Path1 Dropbox root 'rclone-test-comupul5wube/035226qi/resync_modes/path1' 2024/12/09 03:52:34 DEBUG : file1.txt: Need to transfer - File not found at Destination 2024/12/09 03:52:34 DEBUG : file2.txt: Need to transfer - File not found at Destination 2024/12/09 03:52:34 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2024/12/09 03:52:34 DEBUG : Dropbox root 'rclone-test-comupul5wube/035226qi/resync_modes/path2': Waiting for checks to finish 2024/12/09 03:52:34 DEBUG : Dropbox root 'rclone-test-comupul5wube/035226qi/resync_modes/path2': Waiting for transfers to finish 2024/12/09 03:52:34 DEBUG : RCLONE_TEST: Uploading chunk 1/1 2024/12/09 03:52:34 DEBUG : file2.txt: Uploading chunk 1/0 2024/12/09 03:52:34 DEBUG : file1.txt: Uploading chunk 1/0 2024/12/09 03:52:34 DEBUG : file2.txt: Uploading chunk 2/0 2024/12/09 03:52:34 DEBUG : file1.txt: Uploading chunk 2/0 2024/12/09 03:52:34 DEBUG : RCLONE_TEST: Uploading chunk 2/1 2024/12/09 03:52:35 DEBUG : Dropbox root 'rclone-test-comupul5wube/035226qi/resync_modes/path2': Adding "file1.txt" to batch 2024/12/09 03:52:35 DEBUG : Dropbox root 'rclone-test-comupul5wube/035226qi/resync_modes/path2': Adding "file2.txt" to batch 2024/12/09 03:52:35 DEBUG : Dropbox root 'rclone-test-comupul5wube/035226qi/resync_modes/path2': Adding "RCLONE_TEST" to batch 2024/12/09 03:52:36 DEBUG : Dropbox root 'rclone-test-comupul5wube/035226qi/resync_modes/path2': Batch idle for 500ms so committing 2024/12/09 03:52:36 DEBUG : Dropbox root 'rclone-test-comupul5wube/035226qi/resync_modes/path2': Committing sync batch length 3 starting with: file1.txt 2024/12/09 03:52:36 DEBUG : Dropbox root 'rclone-test-comupul5wube/035226qi/resync_modes/path2': Committed sync batch length 3 starting with: file1.txt 2024/12/09 03:52:36 DEBUG : RCLONE_TEST: dropbox = c7ddcb950cf985d316be90e07c421557fe39529668db776ef2c16e0c1a1cddee OK 2024/12/09 03:52:36 INFO : RCLONE_TEST: Copied (new) 2024/12/09 03:52:36 DEBUG : file1.txt: dropbox = e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855 OK 2024/12/09 03:52:36 INFO : file1.txt: Copied (new) 2024/12/09 03:52:36 DEBUG : file2.txt: dropbox = e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855 OK 2024/12/09 03:52:36 INFO : file2.txt: Copied (new) 2024/12/09 03:52:36 NOTICE: checking path2 Dropbox root 'rclone-test-comupul5wube/035226qi/resync_modes/path2' 2024/12/09 03:52:37 NOTICE: (01) : test resync-mode 2024/12/09 03:52:37 NOTICE: (02) : test changed on both paths and NOT identical - file1 (file1R, file1L) 2024/12/09 03:52:37 NOTICE: (03) : touch-glob 2001-01-02 /tmp/035226qi/datadir/test_resync_modes-jifolek6/ file1R.txt 2024/12/09 03:52:37 NOTICE: (04) : copy-as /tmp/035226qi/datadir/test_resync_modes-jifolek6/file1R.txt TestDropbox:rclone-test-comupul5wube/035226qi/resync_modes/path2/ file1.txt 2024/12/09 03:52:42 INFO : file1R.txt: Copied (replaced existing) to: file1.txt 2024/12/09 03:52:42 NOTICE: (05) : touch-glob 2001-03-04 /tmp/035226qi/datadir/test_resync_modes-jifolek6/ file1L.txt 2024/12/09 03:52:42 NOTICE: (06) : copy-as /tmp/035226qi/datadir/test_resync_modes-jifolek6/file1L.txt TestDropbox:rclone-test-comupul5wube/035226qi/resync_modes/path1/ file1.txt 2024/12/09 03:52:45 INFO : file1L.txt: Copied (replaced existing) to: file1.txt 2024/12/09 03:52:45 NOTICE: (07) : test bisync run with --resync-mode=newer 2024/12/09 03:52:45 NOTICE: (08) : bisync resync resync-mode=newer 2024/12/09 03:52:51 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/12/09 03:52:51 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": false, "DownloadHash": false } 2024/12/09 03:52:51 INFO : Synching Path1 "TestDropbox:rclone-test-comupul5wube/035226qi/resync_modes/path1/" with Path2 "TestDropbox:rclone-test-comupul5wube/035226qi/resync_modes/path2/" 2024/12/09 03:52:51 INFO : Copying Path2 files to Path1 2024/12/09 03:52:51 INFO : - Path2 Resync is copying files to - Path1 2024/12/09 03:52:52 INFO : - Path1 Resync is copying files to - Path2 2024/12/09 03:52:55 INFO : file1.txt: Copied (replaced existing) 2024/12/09 03:52:55 INFO : Resync updating listings 2024/12/09 03:52:55 INFO : Validating listings for Path1 "TestDropbox:rclone-test-comupul5wube/035226qi/resync_modes/path1/" vs Path2 "TestDropbox:rclone-test-comupul5wube/035226qi/resync_modes/path2/" 2024/12/09 03:52:55 INFO : Bisync successful 2024/12/09 03:52:55 NOTICE: (09) : test changed on both paths and NOT identical - file1 (file1R, file1L) 2024/12/09 03:52:55 NOTICE: (10) : touch-glob 2001-07-23 /tmp/035226qi/datadir/test_resync_modes-jifolek6/ file1R.txt 2024/12/09 03:52:55 NOTICE: (11) : copy-as /tmp/035226qi/datadir/test_resync_modes-jifolek6/file1R.txt TestDropbox:rclone-test-comupul5wube/035226qi/resync_modes/path2/ file1.txt 2024/12/09 03:52:58 INFO : file1R.txt: Copied (replaced existing) to: file1.txt 2024/12/09 03:52:58 NOTICE: (12) : touch-glob 2001-08-26 /tmp/035226qi/datadir/test_resync_modes-jifolek6/ file1L.txt 2024/12/09 03:52:58 NOTICE: (13) : copy-as /tmp/035226qi/datadir/test_resync_modes-jifolek6/file1L.txt TestDropbox:rclone-test-comupul5wube/035226qi/resync_modes/path1/ file1.txt 2024/12/09 03:52:59 NOTICE: Dropbox root 'rclone-test-comupul5wube/035226qi/resync_modes/path1': Forced to upload files to set modification times on this backend. 2024/12/09 03:52:59 INFO : file1.txt: src and dst identical but can't set mod time without deleting and re-uploading 2024/12/09 03:53:02 INFO : file1L.txt: Copied (replaced existing) to: file1.txt 2024/12/09 03:53:02 NOTICE: (14) : test bisync run with --resync-mode=path2 2024/12/09 03:53:02 NOTICE: (15) : bisync resync resync-mode=path2 2024/12/09 03:53:07 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/12/09 03:53:07 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": false, "DownloadHash": false } 2024/12/09 03:53:07 INFO : Synching Path1 "TestDropbox:rclone-test-comupul5wube/035226qi/resync_modes/path1/" with Path2 "TestDropbox:rclone-test-comupul5wube/035226qi/resync_modes/path2/" 2024/12/09 03:53:07 INFO : Copying Path2 files to Path1 2024/12/09 03:53:07 INFO : - Path2 Resync is copying files to - Path1 2024/12/09 03:53:11 INFO : file1.txt: Copied (replaced existing) 2024/12/09 03:53:11 INFO : - Path1 Resync is copying files to - Path2 2024/12/09 03:53:11 INFO : Resync updating listings 2024/12/09 03:53:11 INFO : Validating listings for Path1 "TestDropbox:rclone-test-comupul5wube/035226qi/resync_modes/path1/" vs Path2 "TestDropbox:rclone-test-comupul5wube/035226qi/resync_modes/path2/" 2024/12/09 03:53:11 INFO : Bisync successful 2024/12/09 03:53:11 NOTICE: (16) : test changed on both paths and NOT identical - file1 (file1R, file1L) 2024/12/09 03:53:11 NOTICE: (17) : touch-glob 2002-07-23 /tmp/035226qi/datadir/test_resync_modes-jifolek6/ file1R.txt 2024/12/09 03:53:11 NOTICE: (18) : copy-as /tmp/035226qi/datadir/test_resync_modes-jifolek6/file1R.txt TestDropbox:rclone-test-comupul5wube/035226qi/resync_modes/path2/ file1.txt 2024/12/09 03:53:11 INFO : file1.txt: src and dst identical but can't set mod time without deleting and re-uploading 2024/12/09 03:53:14 INFO : file1R.txt: Copied (replaced existing) to: file1.txt 2024/12/09 03:53:14 NOTICE: (19) : touch-glob 2002-08-26 /tmp/035226qi/datadir/test_resync_modes-jifolek6/ file1L.txt 2024/12/09 03:53:14 NOTICE: (20) : copy-as /tmp/035226qi/datadir/test_resync_modes-jifolek6/file1L.txt TestDropbox:rclone-test-comupul5wube/035226qi/resync_modes/path1/ file1.txt 2024/12/09 03:53:17 INFO : file1L.txt: Copied (replaced existing) to: file1.txt 2024/12/09 03:53:17 NOTICE: (21) : test bisync run with --resync-mode=larger 2024/12/09 03:53:17 NOTICE: (22) : bisync resync resync-mode=larger 2024/12/09 03:53:23 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/12/09 03:53:23 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": false, "DownloadHash": false } 2024/12/09 03:53:23 INFO : Synching Path1 "TestDropbox:rclone-test-comupul5wube/035226qi/resync_modes/path1/" with Path2 "TestDropbox:rclone-test-comupul5wube/035226qi/resync_modes/path2/" 2024/12/09 03:53:23 INFO : Copying Path2 files to Path1 2024/12/09 03:53:23 INFO : - Path2 Resync is copying files to - Path1 2024/12/09 03:53:23 INFO : file1.txt: Path2 is larger. Path1: 33, Path2: 42, Difference: 9 2024/12/09 03:53:29 INFO : file1.txt: Copied (replaced existing) 2024/12/09 03:53:29 INFO : - Path1 Resync is copying files to - Path2 2024/12/09 03:53:29 INFO : Resync updating listings 2024/12/09 03:53:29 INFO : Validating listings for Path1 "TestDropbox:rclone-test-comupul5wube/035226qi/resync_modes/path1/" vs Path2 "TestDropbox:rclone-test-comupul5wube/035226qi/resync_modes/path2/" 2024/12/09 03:53:29 INFO : Bisync successful 2024/12/09 03:53:29 NOTICE: (23) : test changed on both paths and NOT identical - file1 (file1R, file1L) 2024/12/09 03:53:29 NOTICE: (24) : touch-glob 2003-07-23 /tmp/035226qi/datadir/test_resync_modes-jifolek6/ file1R.txt 2024/12/09 03:53:29 NOTICE: (25) : copy-as /tmp/035226qi/datadir/test_resync_modes-jifolek6/file1R.txt TestDropbox:rclone-test-comupul5wube/035226qi/resync_modes/path2/ file1.txt 2024/12/09 03:53:30 INFO : file1.txt: src and dst identical but can't set mod time without deleting and re-uploading 2024/12/09 03:53:33 INFO : file1R.txt: Copied (replaced existing) to: file1.txt 2024/12/09 03:53:33 NOTICE: (26) : touch-glob 2003-09-04 /tmp/035226qi/datadir/test_resync_modes-jifolek6/ file1L.txt 2024/12/09 03:53:33 NOTICE: (27) : copy-as /tmp/035226qi/datadir/test_resync_modes-jifolek6/file1L.txt TestDropbox:rclone-test-comupul5wube/035226qi/resync_modes/path1/ file1.txt 2024/12/09 03:53:37 ERROR : Dropbox root 'rclone-test-comupul5wube/035226qi/resync_modes/path1': sync batch commit: failed to commit batch length 1: batch had 1 errors: last error: upload failed: too_many_write_operations 2024/12/09 03:53:37 ERROR : file1L.txt: Failed to copy: upload failed: batch upload failed: upload failed: too_many_write_operations bisync_test.go:493: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:493 /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:356 Error: test step failed Test: TestBisyncRemoteRemote/resync_modes Messages: step 27 failed: upload failed: batch upload failed: upload failed: too_many_write_operations 2024/12/09 03:53:37 DEBUG : Dropbox root 'rclone-test-comupul5wube': Purge remote --- FAIL: TestBisyncRemoteRemote (71.83s) --- FAIL: TestBisyncRemoteRemote/resync_modes (70.73s) FAIL 2024/12/09 03:53:38 DEBUG : Dropbox root 'rclone-test-qilewem5rite': Purge remote 2024/12/09 03:53:38 NOTICE: purge failed: path_lookup/not_found/... 2024/12/09 03:53:38 INFO : Dropbox root 'rclone-test-comupul5wube/035226qi': Committing uploads - please wait... 2024/12/09 03:53:38 INFO : Dropbox root 'rclone-test-comupul5wube/035226qi/resync_modes/path1': Committing uploads - please wait... 2024/12/09 03:53:38 INFO : Dropbox root 'rclone-test-comupul5wube/035226qi/resync_modes/path2': Committing uploads - please wait... "./bisync.test -test.v -test.timeout 1h0m0s -remote TestDropbox: -verbose -test.run '^TestBisyncRemoteRemote$/^resync_modes$'" - Finished ERROR in 1m12.526602698s (try 3/5): exit status 1: Failed [TestBisyncRemoteRemote/resync_modes]