"./sync.test -test.v -test.timeout 1h0m0s -remote TestProtonDrive: -verbose -test.run '^(TestMoveWithDeleteEmptySrcDirs|TestServerSideMove|TestServerSideMoveDeleteEmptySourceDirs|TestServerSideMoveOverSelf|TestServerSideMoveWithFilter|TestSyncBackupDir|TestSyncBackupDirSuffixOnly|TestSyncBackupDirWithSuffix|TestSyncBackupDirWithSuffixKeepExtension|TestSyncSuffix|TestSyncSuffixKeepExtension|TestSyncWithTrackRenames|TestSyncWithTrackRenamesStrategyLeaf|TestSyncWithTrackRenamesStrategyModtime)$'" - Starting (try 2/5) 2025/09/22 03:42:12 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-bafatoz7yija" 2025/09/22 03:42:12 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/09/22 03:42:12 DEBUG : proton drive root link ID 'rclone-test-bafatoz7yija': Has cached credentials 2025/09/22 03:42:14 DEBUG : proton drive root link ID 'rclone-test-bafatoz7yija': Used cached credential to initialize the ProtonDrive API 2025/09/22 03:42:14 DEBUG : Creating backend with remote "/tmp/rclone3167937552" === RUN TestServerSideMoveOverSelf run.go:180: Remote "proton drive root link ID 'rclone-test-bafatoz7yija'", Local "Local file system at /tmp/rclone3167937552", Modify Window "1s" 2025/09/22 03:42:20 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-hefocas1cuba" 2025/09/22 03:42:20 DEBUG : proton drive root link ID 'rclone-test-hefocas1cuba': Has cached credentials 2025/09/22 03:42:21 DEBUG : proton drive root link ID 'rclone-test-hefocas1cuba': Used cached credential to initialize the ProtonDrive API sync_test.go:701: Server side copy (if possible) proton drive root link ID 'rclone-test-bafatoz7yija' -> proton drive root link ID 'rclone-test-hefocas1cuba' 2025/09/22 03:42:22 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/09/22 03:42:22 DEBUG : proton drive root link ID 'rclone-test-hefocas1cuba': Waiting for checks to finish 2025/09/22 03:42:22 DEBUG : proton drive root link ID 'rclone-test-hefocas1cuba': Waiting for transfers to finish 2025/09/22 03:42:25 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2025/09/22 03:42:25 INFO : sub dir/hello world: Copied (new) 2025/09/22 03:42:26.936390 WARN RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422), Attempt 1 2025/09/22 03:42:26.936432 ERROR RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422) 2025/09/22 03:42:28 DEBUG : proton drive root link ID 'rclone-test-hefocas1cuba': Using server-side directory move 2025/09/22 03:42:28 INFO : proton drive root link ID 'rclone-test-hefocas1cuba': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/09/22 03:42:29 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/09/22 03:42:29 DEBUG : proton drive root link ID 'rclone-test-hefocas1cuba': Waiting for checks to finish 2025/09/22 03:42:29 DEBUG : proton drive root link ID 'rclone-test-hefocas1cuba': Waiting for transfers to finish 2025/09/22 03:42:29 INFO : sub dir/hello world: Deleted 2025/09/22 03:42:30.077694 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/lh1U-fHygzJM0UxzcZ7b72l_Hr_b5R-JpJ-xlD0GOygkviWGmtW13I7U2UH2i42B5fEh6Cju9B8fhNffy3vZ3g==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/22 03:42:30.077745 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/lh1U-fHygzJM0UxzcZ7b72l_Hr_b5R-JpJ-xlD0GOygkviWGmtW13I7U2UH2i42B5fEh6Cju9B8fhNffy3vZ3g==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/22 03:42:30 ERROR : sub dir/hello world: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/lh1U-fHygzJM0UxzcZ7b72l_Hr_b5R-JpJ-xlD0GOygkviWGmtW13I7U2UH2i42B5fEh6Cju9B8fhNffy3vZ3g==/move: This value should not be blank. (Code=2000, Status=400) sync_test.go:714: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:714 Error: Received unexpected error: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/lh1U-fHygzJM0UxzcZ7b72l_Hr_b5R-JpJ-xlD0GOygkviWGmtW13I7U2UH2i42B5fEh6Cju9B8fhNffy3vZ3g==/move: This value should not be blank. (Code=2000, Status=400) Test: TestServerSideMoveOverSelf 2025/09/22 03:42:30 DEBUG : proton drive root link ID 'rclone-test-hefocas1cuba': Purge remote --- FAIL: TestServerSideMoveOverSelf (17.49s) === RUN TestSyncWithTrackRenames run.go:180: Remote "proton drive root link ID 'rclone-test-bafatoz7yija'", Local "Local file system at /tmp/rclone3167937552", Modify Window "1s" sync_test.go:1568: Can track renames: true 2025/09/22 03:42:32 INFO : proton drive root link ID 'rclone-test-bafatoz7yija': Making map for --track-renames 2025/09/22 03:42:32 INFO : proton drive root link ID 'rclone-test-bafatoz7yija': Finished making map for --track-renames 2025/09/22 03:42:32 DEBUG : proton drive root link ID 'rclone-test-bafatoz7yija': Waiting for checks to finish 2025/09/22 03:42:32 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/09/22 03:42:32 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/09/22 03:42:32 DEBUG : proton drive root link ID 'rclone-test-bafatoz7yija': Waiting for renames to finish 2025/09/22 03:42:32 DEBUG : proton drive root link ID 'rclone-test-bafatoz7yija': Waiting for transfers to finish 2025/09/22 03:42:33 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/09/22 03:42:33 INFO : potato: Copied (new) 2025/09/22 03:42:33 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/09/22 03:42:33 INFO : yam: Copied (new) 2025/09/22 03:42:33 DEBUG : Waiting for deletions to finish 2025/09/22 03:42:34 DEBUG : potato: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/22 03:42:34 INFO : proton drive root link ID 'rclone-test-bafatoz7yija': Making map for --track-renames 2025/09/22 03:42:34 INFO : proton drive root link ID 'rclone-test-bafatoz7yija': Finished making map for --track-renames 2025/09/22 03:42:34 DEBUG : proton drive root link ID 'rclone-test-bafatoz7yija': Waiting for checks to finish 2025/09/22 03:42:34 DEBUG : potato: Unchanged skipping 2025/09/22 03:42:34 DEBUG : proton drive root link ID 'rclone-test-bafatoz7yija': Waiting for renames to finish 2025/09/22 03:42:34.511556 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/LUmRgdAhn-hw61YUKK-Ma9yxhcL7ltjpigYGz2kA_RJEFS7iel8gAETdGX1bMZeIXGDMArOP_fIVw7IFrGa_YA==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/22 03:42:34.511606 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/LUmRgdAhn-hw61YUKK-Ma9yxhcL7ltjpigYGz2kA_RJEFS7iel8gAETdGX1bMZeIXGDMArOP_fIVw7IFrGa_YA==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/22 03:42:34 ERROR : yam: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/LUmRgdAhn-hw61YUKK-Ma9yxhcL7ltjpigYGz2kA_RJEFS7iel8gAETdGX1bMZeIXGDMArOP_fIVw7IFrGa_YA==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/22 03:42:34 DEBUG : yaml: Failed to rename to "yam": 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/LUmRgdAhn-hw61YUKK-Ma9yxhcL7ltjpigYGz2kA_RJEFS7iel8gAETdGX1bMZeIXGDMArOP_fIVw7IFrGa_YA==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/22 03:42:34 DEBUG : yaml: Need to transfer - No matching file found at Destination 2025/09/22 03:42:34 DEBUG : proton drive root link ID 'rclone-test-bafatoz7yija': Waiting for transfers to finish 2025/09/22 03:42:35 DEBUG : yaml: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/09/22 03:42:35 INFO : yaml: Copied (new) 2025/09/22 03:42:35 ERROR : proton drive root link ID 'rclone-test-bafatoz7yija': not deleting files as there were IO errors 2025/09/22 03:42:35 ERROR : proton drive root link ID 'rclone-test-bafatoz7yija': not deleting directories as there were IO errors sync_test.go:1586: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1586 Error: Received unexpected error: not deleting files as there were IO errors Test: TestSyncWithTrackRenames --- FAIL: TestSyncWithTrackRenames (5.13s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:180: Remote "proton drive root link ID 'rclone-test-bafatoz7yija'", Local "Local file system at /tmp/rclone3167937552", Modify Window "1s" sync_test.go:1640: Can track renames: true 2025/09/22 03:42:37 INFO : proton drive root link ID 'rclone-test-bafatoz7yija': Making map for --track-renames 2025/09/22 03:42:37 INFO : proton drive root link ID 'rclone-test-bafatoz7yija': Finished making map for --track-renames 2025/09/22 03:42:37 DEBUG : proton drive root link ID 'rclone-test-bafatoz7yija': Waiting for checks to finish 2025/09/22 03:42:37 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/09/22 03:42:37 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/09/22 03:42:37 DEBUG : proton drive root link ID 'rclone-test-bafatoz7yija': Waiting for renames to finish 2025/09/22 03:42:37 DEBUG : proton drive root link ID 'rclone-test-bafatoz7yija': Waiting for transfers to finish 2025/09/22 03:42:38 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/09/22 03:42:38 INFO : potato: Copied (new) 2025/09/22 03:42:38 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/09/22 03:42:38 INFO : yam: Copied (new) 2025/09/22 03:42:38 DEBUG : Waiting for deletions to finish 2025/09/22 03:42:39 DEBUG : potato: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/22 03:42:39 INFO : proton drive root link ID 'rclone-test-bafatoz7yija': Making map for --track-renames 2025/09/22 03:42:39 INFO : proton drive root link ID 'rclone-test-bafatoz7yija': Finished making map for --track-renames 2025/09/22 03:42:39 DEBUG : proton drive root link ID 'rclone-test-bafatoz7yija': Waiting for checks to finish 2025/09/22 03:42:39 DEBUG : potato: Unchanged skipping 2025/09/22 03:42:39 DEBUG : proton drive root link ID 'rclone-test-bafatoz7yija': Waiting for renames to finish 2025/09/22 03:42:39.655623 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/Dkf32dm698LR5Jxsas613eT54nYBs62FM75uzqw7n3n_35rKIf54w2sPr4c8zozCp3bsq9oFBI5UC2fyvae4hw==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/22 03:42:39.655671 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/Dkf32dm698LR5Jxsas613eT54nYBs62FM75uzqw7n3n_35rKIf54w2sPr4c8zozCp3bsq9oFBI5UC2fyvae4hw==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/22 03:42:39 ERROR : yam: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/Dkf32dm698LR5Jxsas613eT54nYBs62FM75uzqw7n3n_35rKIf54w2sPr4c8zozCp3bsq9oFBI5UC2fyvae4hw==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/22 03:42:39 DEBUG : yaml: Failed to rename to "yam": 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/Dkf32dm698LR5Jxsas613eT54nYBs62FM75uzqw7n3n_35rKIf54w2sPr4c8zozCp3bsq9oFBI5UC2fyvae4hw==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/22 03:42:39 DEBUG : yaml: Need to transfer - No matching file found at Destination 2025/09/22 03:42:39 DEBUG : proton drive root link ID 'rclone-test-bafatoz7yija': Waiting for transfers to finish 2025/09/22 03:42:40 DEBUG : yaml: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/09/22 03:42:40 INFO : yaml: Copied (new) 2025/09/22 03:42:40 ERROR : proton drive root link ID 'rclone-test-bafatoz7yija': not deleting files as there were IO errors 2025/09/22 03:42:40 ERROR : proton drive root link ID 'rclone-test-bafatoz7yija': not deleting directories as there were IO errors sync_test.go:1658: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1658 Error: Received unexpected error: not deleting files as there were IO errors Test: TestSyncWithTrackRenamesStrategyModtime --- FAIL: TestSyncWithTrackRenamesStrategyModtime (5.23s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:180: Remote "proton drive root link ID 'rclone-test-bafatoz7yija'", Local "Local file system at /tmp/rclone3167937552", Modify Window "1s" sync_test.go:1679: Can track renames: true 2025/09/22 03:42:42 INFO : proton drive root link ID 'rclone-test-bafatoz7yija': Making map for --track-renames 2025/09/22 03:42:42 INFO : proton drive root link ID 'rclone-test-bafatoz7yija': Finished making map for --track-renames 2025/09/22 03:42:42 DEBUG : proton drive root link ID 'rclone-test-bafatoz7yija': Waiting for checks to finish 2025/09/22 03:42:42 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/09/22 03:42:42 DEBUG : sub/yam: Need to transfer - No matching file found at Destination 2025/09/22 03:42:42 DEBUG : proton drive root link ID 'rclone-test-bafatoz7yija': Waiting for renames to finish 2025/09/22 03:42:42 DEBUG : proton drive root link ID 'rclone-test-bafatoz7yija': Waiting for transfers to finish 2025/09/22 03:42:43 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/09/22 03:42:43 INFO : potato: Copied (new) 2025/09/22 03:42:43 DEBUG : sub/yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/09/22 03:42:43 INFO : sub/yam: Copied (new) 2025/09/22 03:42:43 DEBUG : Waiting for deletions to finish 2025/09/22 03:42:45 DEBUG : potato: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/22 03:42:45 DEBUG : potato: Unchanged skipping 2025/09/22 03:42:45 INFO : proton drive root link ID 'rclone-test-bafatoz7yija': Making map for --track-renames 2025/09/22 03:42:45 INFO : proton drive root link ID 'rclone-test-bafatoz7yija': Finished making map for --track-renames 2025/09/22 03:42:45 DEBUG : proton drive root link ID 'rclone-test-bafatoz7yija': Waiting for checks to finish 2025/09/22 03:42:45 DEBUG : proton drive root link ID 'rclone-test-bafatoz7yija': Waiting for renames to finish 2025/09/22 03:42:45.797552 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/2IRJ3tmwRav26sjVV4Iuhv7iREcipHmPIt0sLN0d5ll5Jog3ymumTVA1gcTcG49PZK47ovgC55pizoz-uvYLpA==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/22 03:42:45.797925 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/2IRJ3tmwRav26sjVV4Iuhv7iREcipHmPIt0sLN0d5ll5Jog3ymumTVA1gcTcG49PZK47ovgC55pizoz-uvYLpA==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/22 03:42:45 ERROR : sub/yam: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/2IRJ3tmwRav26sjVV4Iuhv7iREcipHmPIt0sLN0d5ll5Jog3ymumTVA1gcTcG49PZK47ovgC55pizoz-uvYLpA==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/22 03:42:45 DEBUG : yam: Failed to rename to "sub/yam": 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/2IRJ3tmwRav26sjVV4Iuhv7iREcipHmPIt0sLN0d5ll5Jog3ymumTVA1gcTcG49PZK47ovgC55pizoz-uvYLpA==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/22 03:42:45 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/09/22 03:42:45 DEBUG : proton drive root link ID 'rclone-test-bafatoz7yija': Waiting for transfers to finish 2025/09/22 03:42:46 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/09/22 03:42:46 INFO : yam: Copied (new) 2025/09/22 03:42:46 ERROR : proton drive root link ID 'rclone-test-bafatoz7yija': not deleting files as there were IO errors 2025/09/22 03:42:46 ERROR : proton drive root link ID 'rclone-test-bafatoz7yija': not deleting directories as there were IO errors sync_test.go:1697: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1697 Error: Received unexpected error: not deleting files as there were IO errors Test: TestSyncWithTrackRenamesStrategyLeaf --- FAIL: TestSyncWithTrackRenamesStrategyLeaf (6.67s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:180: Remote "proton drive root link ID 'rclone-test-bafatoz7yija'", Local "Local file system at /tmp/rclone3167937552", Modify Window "1s" 2025/09/22 03:42:49 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/09/22 03:42:49 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2025/09/22 03:42:49 DEBUG : proton drive root link ID 'rclone-test-bafatoz7yija': Waiting for checks to finish 2025/09/22 03:42:49 DEBUG : proton drive root link ID 'rclone-test-bafatoz7yija': Waiting for transfers to finish 2025/09/22 03:42:51 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2025/09/22 03:42:51 INFO : sub dir/hello world: Copied (new) 2025/09/22 03:42:51 INFO : sub dir/hello world: Deleted 2025/09/22 03:42:51 DEBUG : nested/sub dir/file: sha1 = b4b3e0a278988bc15f2913af3f4153ccef74e465 OK 2025/09/22 03:42:51 INFO : nested/sub dir/file: Copied (new) 2025/09/22 03:42:51 INFO : nested/sub dir/file: Deleted 2025/09/22 03:42:51 ERROR : Local file system at /tmp/rclone3167937552: not deleting directories as there were IO errors sync_test.go:1818: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1818 Error: Received unexpected error: not deleting directories as there were IO errors Test: TestMoveWithDeleteEmptySrcDirs --- FAIL: TestMoveWithDeleteEmptySrcDirs (4.88s) === RUN TestServerSideMove run.go:180: Remote "proton drive root link ID 'rclone-test-bafatoz7yija'", Local "Local file system at /tmp/rclone3167937552", Modify Window "1s" 2025/09/22 03:42:53 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-falezuh9ceyi" 2025/09/22 03:42:53 DEBUG : proton drive root link ID 'rclone-test-falezuh9ceyi': Has cached credentials 2025/09/22 03:42:54 DEBUG : proton drive root link ID 'rclone-test-falezuh9ceyi': Used cached credential to initialize the ProtonDrive API sync_test.go:1735: Server side move (if possible) proton drive root link ID 'rclone-test-bafatoz7yija' -> proton drive root link ID 'rclone-test-falezuh9ceyi' 2025/09/22 03:43:01 DEBUG : proton drive root link ID 'rclone-test-falezuh9ceyi': Using server-side directory move 2025/09/22 03:43:01 INFO : proton drive root link ID 'rclone-test-falezuh9ceyi': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/09/22 03:43:02 DEBUG : potato2: Need to transfer - File not found at Destination 2025/09/22 03:43:02 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1s) 2025/09/22 03:43:02 DEBUG : empty space: Unchanged skipping 2025/09/22 03:43:02 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/09/22 03:43:02 DEBUG : proton drive root link ID 'rclone-test-falezuh9ceyi': Waiting for checks to finish 2025/09/22 03:43:02 INFO : empty space: Deleted 2025/09/22 03:43:02 DEBUG : proton drive root link ID 'rclone-test-falezuh9ceyi': Waiting for transfers to finish 2025/09/22 03:43:02 INFO : potato3: Deleted 2025/09/22 03:43:02.865879 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/4vDpsLQ_bAtjcL1RGprb8ZOg1wA1TGzU1sl4HGhrIEJqwVIbmYYygT8QiBheKiQ0r7oR6fHeok4wiUwHgUmhOA==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/22 03:43:02.865906 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/4vDpsLQ_bAtjcL1RGprb8ZOg1wA1TGzU1sl4HGhrIEJqwVIbmYYygT8QiBheKiQ0r7oR6fHeok4wiUwHgUmhOA==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/22 03:43:02 ERROR : potato2: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/4vDpsLQ_bAtjcL1RGprb8ZOg1wA1TGzU1sl4HGhrIEJqwVIbmYYygT8QiBheKiQ0r7oR6fHeok4wiUwHgUmhOA==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/22 03:43:03.010556 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/QbIdHETmOhLQuqavaZrDC-M1rMCOCRAdqd0i12BC0bDqxpeuceUg4Ofts0pywLutwxiB4VfFyJkwAG3OsMg7YA==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/22 03:43:03.010586 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/QbIdHETmOhLQuqavaZrDC-M1rMCOCRAdqd0i12BC0bDqxpeuceUg4Ofts0pywLutwxiB4VfFyJkwAG3OsMg7YA==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/22 03:43:03 ERROR : potato3: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/QbIdHETmOhLQuqavaZrDC-M1rMCOCRAdqd0i12BC0bDqxpeuceUg4Ofts0pywLutwxiB4VfFyJkwAG3OsMg7YA==/move: This value should not be blank. (Code=2000, Status=400) sync_test.go:1746: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1746 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1911 Error: Received unexpected error: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/QbIdHETmOhLQuqavaZrDC-M1rMCOCRAdqd0i12BC0bDqxpeuceUg4Ofts0pywLutwxiB4VfFyJkwAG3OsMg7YA==/move: This value should not be blank. (Code=2000, Status=400) Test: TestServerSideMove 2025/09/22 03:43:03 DEBUG : proton drive root link ID 'rclone-test-falezuh9ceyi': Purge remote --- FAIL: TestServerSideMove (10.80s) === RUN TestServerSideMoveWithFilter run.go:180: Remote "proton drive root link ID 'rclone-test-bafatoz7yija'", Local "Local file system at /tmp/rclone3167937552", Modify Window "1s" 2025/09/22 03:43:04 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-zobujap0xeja" 2025/09/22 03:43:04 DEBUG : proton drive root link ID 'rclone-test-zobujap0xeja': Has cached credentials 2025/09/22 03:43:05 DEBUG : proton drive root link ID 'rclone-test-zobujap0xeja': Used cached credential to initialize the ProtonDrive API sync_test.go:1735: Server side move (if possible) proton drive root link ID 'rclone-test-bafatoz7yija' -> proton drive root link ID 'rclone-test-zobujap0xeja' 2025/09/22 03:43:12 DEBUG : empty space: Excluded (Size Filter) 2025/09/22 03:43:12 DEBUG : empty space: Excluded 2025/09/22 03:43:13 DEBUG : empty space: Excluded (Size Filter) 2025/09/22 03:43:13 DEBUG : empty space: Excluded 2025/09/22 03:43:13 DEBUG : potato2: Need to transfer - File not found at Destination 2025/09/22 03:43:13 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/09/22 03:43:13 DEBUG : proton drive root link ID 'rclone-test-zobujap0xeja': Waiting for checks to finish 2025/09/22 03:43:13 DEBUG : proton drive root link ID 'rclone-test-zobujap0xeja': Waiting for transfers to finish 2025/09/22 03:43:13 INFO : potato3: Deleted 2025/09/22 03:43:13.613271 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/WzRMaARyqRsvD7hiu-8HGM4X9YhEtkxqyMsTYBisGg2R_cx-4095wRxH7svFP58syQwSloUqwQGG7VzVAxedow==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/22 03:43:13.614355 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/WzRMaARyqRsvD7hiu-8HGM4X9YhEtkxqyMsTYBisGg2R_cx-4095wRxH7svFP58syQwSloUqwQGG7VzVAxedow==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/22 03:43:13 ERROR : potato2: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/WzRMaARyqRsvD7hiu-8HGM4X9YhEtkxqyMsTYBisGg2R_cx-4095wRxH7svFP58syQwSloUqwQGG7VzVAxedow==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/22 03:43:13.723965 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/5NBZtTEO8CSrkBMJZCOHkgFzWJ74S6ZO6H8H4bS0djhyFL7OHW04v4CEc_HfBTpmFsgZae5otKiomixkD3l7ZA==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/22 03:43:13.724000 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/5NBZtTEO8CSrkBMJZCOHkgFzWJ74S6ZO6H8H4bS0djhyFL7OHW04v4CEc_HfBTpmFsgZae5otKiomixkD3l7ZA==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/22 03:43:13 ERROR : potato3: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/5NBZtTEO8CSrkBMJZCOHkgFzWJ74S6ZO6H8H4bS0djhyFL7OHW04v4CEc_HfBTpmFsgZae5otKiomixkD3l7ZA==/move: This value should not be blank. (Code=2000, Status=400) sync_test.go:1746: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1746 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1924 Error: Received unexpected error: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/5NBZtTEO8CSrkBMJZCOHkgFzWJ74S6ZO6H8H4bS0djhyFL7OHW04v4CEc_HfBTpmFsgZae5otKiomixkD3l7ZA==/move: This value should not be blank. (Code=2000, Status=400) Test: TestServerSideMoveWithFilter 2025/09/22 03:43:13 DEBUG : proton drive root link ID 'rclone-test-zobujap0xeja': Purge remote --- FAIL: TestServerSideMoveWithFilter (11.02s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:180: Remote "proton drive root link ID 'rclone-test-bafatoz7yija'", Local "Local file system at /tmp/rclone3167937552", Modify Window "1s" 2025/09/22 03:43:15 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-bakoqiy1mure" 2025/09/22 03:43:15 DEBUG : proton drive root link ID 'rclone-test-bakoqiy1mure': Has cached credentials 2025/09/22 03:43:16 DEBUG : proton drive root link ID 'rclone-test-bakoqiy1mure': Used cached credential to initialize the ProtonDrive API 2025/09/22 03:43:19 INFO : tomatoDir: Making directory sync_test.go:1735: Server side move (if possible) proton drive root link ID 'rclone-test-bafatoz7yija' -> proton drive root link ID 'rclone-test-bakoqiy1mure' 2025/09/22 03:43:23 DEBUG : proton drive root link ID 'rclone-test-bakoqiy1mure': Using server-side directory move 2025/09/22 03:43:23 INFO : proton drive root link ID 'rclone-test-bakoqiy1mure': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/09/22 03:43:24 DEBUG : potato2: Need to transfer - File not found at Destination 2025/09/22 03:43:24 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1s) 2025/09/22 03:43:24 DEBUG : empty space: Unchanged skipping 2025/09/22 03:43:24 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/09/22 03:43:24 DEBUG : proton drive root link ID 'rclone-test-bakoqiy1mure': Waiting for checks to finish 2025/09/22 03:43:24 INFO : potato3: Deleted 2025/09/22 03:43:24 INFO : empty space: Deleted 2025/09/22 03:43:24 DEBUG : proton drive root link ID 'rclone-test-bakoqiy1mure': Waiting for transfers to finish 2025/09/22 03:43:24.830765 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/KyZXvz4Ah4L6ruFuToe1LLK0e-9kphrb7VcdvVWkTDghfCsgW1aSC7AaMm2ce-8UOGrzmm9X9LrdL2wuIW4TaQ==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/22 03:43:24.830796 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/KyZXvz4Ah4L6ruFuToe1LLK0e-9kphrb7VcdvVWkTDghfCsgW1aSC7AaMm2ce-8UOGrzmm9X9LrdL2wuIW4TaQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/22 03:43:24 ERROR : potato2: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/KyZXvz4Ah4L6ruFuToe1LLK0e-9kphrb7VcdvVWkTDghfCsgW1aSC7AaMm2ce-8UOGrzmm9X9LrdL2wuIW4TaQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/22 03:43:24.946467 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/KM9I99pfWfjRT0eAgGoQIJqYA3zKTAWYOYI1ZZwJZ8D7UG-80aqS4wUY-X-OcmUy2wULRzIBxpWWX492-EQ0ZA==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/22 03:43:24.946537 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/KM9I99pfWfjRT0eAgGoQIJqYA3zKTAWYOYI1ZZwJZ8D7UG-80aqS4wUY-X-OcmUy2wULRzIBxpWWX492-EQ0ZA==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/22 03:43:24 ERROR : potato3: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/KM9I99pfWfjRT0eAgGoQIJqYA3zKTAWYOYI1ZZwJZ8D7UG-80aqS4wUY-X-OcmUy2wULRzIBxpWWX492-EQ0ZA==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/22 03:43:24 ERROR : proton drive root link ID 'rclone-test-bafatoz7yija': not deleting directories as there were IO errors sync_test.go:1746: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1746 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1931 Error: Received unexpected error: not deleting directories as there were IO errors Test: TestServerSideMoveDeleteEmptySourceDirs 2025/09/22 03:43:24 DEBUG : proton drive root link ID 'rclone-test-bakoqiy1mure': Purge remote --- FAIL: TestServerSideMoveDeleteEmptySourceDirs (11.37s) === RUN TestSyncBackupDir run.go:180: Remote "proton drive root link ID 'rclone-test-bafatoz7yija'", Local "Local file system at /tmp/rclone3167937552", Modify Window "1s" 2025/09/22 03:43:31 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-bafatoz7yija/dst" 2025/09/22 03:43:31 DEBUG : proton drive root link ID 'rclone-test-bafatoz7yija/dst': Has cached credentials 2025/09/22 03:43:32 DEBUG : proton drive root link ID 'rclone-test-bafatoz7yija/dst': Used cached credential to initialize the ProtonDrive API 2025/09/22 03:45:24 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-bafatoz7yija/backup" 2025/09/22 03:45:24 DEBUG : proton drive root link ID 'rclone-test-bafatoz7yija/backup': Has cached credentials 2025/09/22 03:45:25 DEBUG : proton drive root link ID 'rclone-test-bafatoz7yija/backup': Used cached credential to initialize the ProtonDrive API 2025/09/22 03:49:06 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/09/22 03:49:06 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/22 03:49:06 DEBUG : two: Unchanged skipping 2025/09/22 03:49:06 DEBUG : proton drive root link ID 'rclone-test-bafatoz7yija/dst': Waiting for checks to finish 2025/09/22 03:49:07.656345 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/984w2W4B9D3YzjgcEyvi970dOBfhc7ShQbY1phQ9iZQ1qg55SA8u_MHRbHLCzmfvkgNI9KBOG5Sm_TWd2MILZg==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/22 03:49:07.656377 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/984w2W4B9D3YzjgcEyvi970dOBfhc7ShQbY1phQ9iZQ1qg55SA8u_MHRbHLCzmfvkgNI9KBOG5Sm_TWd2MILZg==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/22 03:49:07 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/984w2W4B9D3YzjgcEyvi970dOBfhc7ShQbY1phQ9iZQ1qg55SA8u_MHRbHLCzmfvkgNI9KBOG5Sm_TWd2MILZg==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/22 03:49:07 DEBUG : proton drive root link ID 'rclone-test-bafatoz7yija/dst': Waiting for transfers to finish 2025/09/22 03:49:07 ERROR : proton drive root link ID 'rclone-test-bafatoz7yija/dst': not deleting files as there were IO errors 2025/09/22 03:49:07 ERROR : proton drive root link ID 'rclone-test-bafatoz7yija/dst': not deleting directories as there were IO errors sync_test.go:2415: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2415 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2457 Error: Received unexpected error: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/984w2W4B9D3YzjgcEyvi970dOBfhc7ShQbY1phQ9iZQ1qg55SA8u_MHRbHLCzmfvkgNI9KBOG5Sm_TWd2MILZg==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncBackupDir --- FAIL: TestSyncBackupDir (343.14s) === RUN TestSyncBackupDirWithSuffix run.go:180: Remote "proton drive root link ID 'rclone-test-bafatoz7yija'", Local "Local file system at /tmp/rclone3167937552", Modify Window "1s" 2025/09/22 03:49:14 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-bafatoz7yija/dst" 2025/09/22 03:49:14 DEBUG : proton drive root link ID 'rclone-test-bafatoz7yija/dst': Has cached credentials 2025/09/22 03:49:15 DEBUG : proton drive root link ID 'rclone-test-bafatoz7yija/dst': Used cached credential to initialize the ProtonDrive API 2025/09/22 03:51:07 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-bafatoz7yija/backup" 2025/09/22 03:51:07 DEBUG : proton drive root link ID 'rclone-test-bafatoz7yija/backup': Has cached credentials 2025/09/22 03:51:07 DEBUG : proton drive root link ID 'rclone-test-bafatoz7yija/backup': Used cached credential to initialize the ProtonDrive API 2025/09/22 03:54:50 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/09/22 03:54:50 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/22 03:54:50 DEBUG : two: Unchanged skipping 2025/09/22 03:54:50 DEBUG : proton drive root link ID 'rclone-test-bafatoz7yija/dst': Waiting for checks to finish 2025/09/22 03:54:51.554532 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/wnjvsCC7VOqCeP9zk8IxVd8x4ZljT_l4QLrICuOvyNvImd2UmKHa2756j4bYRrMlyWEdJ_pJFDU00Mbh1m4ttQ==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/22 03:54:51.554567 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/wnjvsCC7VOqCeP9zk8IxVd8x4ZljT_l4QLrICuOvyNvImd2UmKHa2756j4bYRrMlyWEdJ_pJFDU00Mbh1m4ttQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/22 03:54:51 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/wnjvsCC7VOqCeP9zk8IxVd8x4ZljT_l4QLrICuOvyNvImd2UmKHa2756j4bYRrMlyWEdJ_pJFDU00Mbh1m4ttQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/22 03:54:51 DEBUG : proton drive root link ID 'rclone-test-bafatoz7yija/dst': Waiting for transfers to finish 2025/09/22 03:54:51 ERROR : proton drive root link ID 'rclone-test-bafatoz7yija/dst': not deleting files as there were IO errors 2025/09/22 03:54:51 ERROR : proton drive root link ID 'rclone-test-bafatoz7yija/dst': not deleting directories as there were IO errors sync_test.go:2415: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2415 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2461 Error: Received unexpected error: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/wnjvsCC7VOqCeP9zk8IxVd8x4ZljT_l4QLrICuOvyNvImd2UmKHa2756j4bYRrMlyWEdJ_pJFDU00Mbh1m4ttQ==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncBackupDirWithSuffix --- FAIL: TestSyncBackupDirWithSuffix (343.83s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:180: Remote "proton drive root link ID 'rclone-test-bafatoz7yija'", Local "Local file system at /tmp/rclone3167937552", Modify Window "1s" 2025/09/22 03:54:58 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-bafatoz7yija/dst" 2025/09/22 03:54:58 DEBUG : proton drive root link ID 'rclone-test-bafatoz7yija/dst': Has cached credentials 2025/09/22 03:54:59 DEBUG : proton drive root link ID 'rclone-test-bafatoz7yija/dst': Used cached credential to initialize the ProtonDrive API 2025/09/22 03:56:50 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-bafatoz7yija/backup" 2025/09/22 03:56:50 DEBUG : proton drive root link ID 'rclone-test-bafatoz7yija/backup': Has cached credentials 2025/09/22 03:56:52 DEBUG : proton drive root link ID 'rclone-test-bafatoz7yija/backup': Used cached credential to initialize the ProtonDrive API 2025/09/22 04:00:35 DEBUG : proton drive root link ID 'rclone-test-bafatoz7yija/dst': Waiting for checks to finish 2025/09/22 04:00:35 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/09/22 04:00:35 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/22 04:00:35 DEBUG : two: Unchanged skipping 2025/09/22 04:00:36.155137 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/BjnKAuyn3vDEkVcZ2xohfZ53R9h7tG9k_iHWtH00NDobD4kZ5KuuWa2pFug2AlV-CfYxQYmprBmzP1NaUjkaZg==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/22 04:00:36.155193 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/BjnKAuyn3vDEkVcZ2xohfZ53R9h7tG9k_iHWtH00NDobD4kZ5KuuWa2pFug2AlV-CfYxQYmprBmzP1NaUjkaZg==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/22 04:00:36 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/BjnKAuyn3vDEkVcZ2xohfZ53R9h7tG9k_iHWtH00NDobD4kZ5KuuWa2pFug2AlV-CfYxQYmprBmzP1NaUjkaZg==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/22 04:00:36 DEBUG : proton drive root link ID 'rclone-test-bafatoz7yija/dst': Waiting for transfers to finish 2025/09/22 04:00:36 ERROR : proton drive root link ID 'rclone-test-bafatoz7yija/dst': not deleting files as there were IO errors 2025/09/22 04:00:36 ERROR : proton drive root link ID 'rclone-test-bafatoz7yija/dst': not deleting directories as there were IO errors sync_test.go:2415: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2415 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2465 Error: Received unexpected error: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/BjnKAuyn3vDEkVcZ2xohfZ53R9h7tG9k_iHWtH00NDobD4kZ5KuuWa2pFug2AlV-CfYxQYmprBmzP1NaUjkaZg==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncBackupDirWithSuffixKeepExtension --- FAIL: TestSyncBackupDirWithSuffixKeepExtension (344.59s) === RUN TestSyncBackupDirSuffixOnly run.go:180: Remote "proton drive root link ID 'rclone-test-bafatoz7yija'", Local "Local file system at /tmp/rclone3167937552", Modify Window "1s" 2025/09/22 04:00:42 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-bafatoz7yija/dst" 2025/09/22 04:00:42 DEBUG : proton drive root link ID 'rclone-test-bafatoz7yija/dst': Has cached credentials 2025/09/22 04:00:43 DEBUG : proton drive root link ID 'rclone-test-bafatoz7yija/dst': Used cached credential to initialize the ProtonDrive API 2025/09/22 04:02:35 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/09/22 04:02:35 DEBUG : proton drive root link ID 'rclone-test-bafatoz7yija/dst': Waiting for checks to finish 2025/09/22 04:02:35 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/22 04:02:35 DEBUG : two: Unchanged skipping 2025/09/22 04:02:36.141139 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/tYFRsmiPs4Hvyis-rOIqJUH5PvOU7D81JYjoXVR8NS3ohikQHP2vvUZug3zvnV3yqGTVL9hoeuxJRhTvRmhH4Q==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/22 04:02:36.141176 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/tYFRsmiPs4Hvyis-rOIqJUH5PvOU7D81JYjoXVR8NS3ohikQHP2vvUZug3zvnV3yqGTVL9hoeuxJRhTvRmhH4Q==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/22 04:02:36 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/tYFRsmiPs4Hvyis-rOIqJUH5PvOU7D81JYjoXVR8NS3ohikQHP2vvUZug3zvnV3yqGTVL9hoeuxJRhTvRmhH4Q==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/22 04:02:36 DEBUG : proton drive root link ID 'rclone-test-bafatoz7yija/dst': Waiting for transfers to finish 2025/09/22 04:02:36 ERROR : proton drive root link ID 'rclone-test-bafatoz7yija/dst': not deleting files as there were IO errors 2025/09/22 04:02:36 ERROR : proton drive root link ID 'rclone-test-bafatoz7yija/dst': not deleting directories as there were IO errors sync_test.go:2415: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2415 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2469 Error: Received unexpected error: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/tYFRsmiPs4Hvyis-rOIqJUH5PvOU7D81JYjoXVR8NS3ohikQHP2vvUZug3zvnV3yqGTVL9hoeuxJRhTvRmhH4Q==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncBackupDirSuffixOnly --- FAIL: TestSyncBackupDirSuffixOnly (119.63s) === RUN TestSyncSuffix run.go:180: Remote "proton drive root link ID 'rclone-test-bafatoz7yija'", Local "Local file system at /tmp/rclone3167937552", Modify Window "1s" 2025/09/22 04:02:42 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-bafatoz7yija/dst" 2025/09/22 04:02:42 DEBUG : Config file has changed externally - reloading 2025/09/22 04:02:42 DEBUG : proton drive root link ID 'rclone-test-bafatoz7yija/dst': Has cached credentials 2025/09/22 04:02:43 DEBUG : proton drive root link ID 'rclone-test-bafatoz7yija/dst': Used cached credential to initialize the ProtonDrive API 2025/09/22 04:04:35 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/09/22 04:04:36.132536 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/Q3jyFp8FJpkAT-TfVW_-FwZqQbUCcq03gevuImGm2mHi8U4ULX83e3FGXjEmOqWdFj9nlBolrhpfh3U8e9mKwA==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/22 04:04:36.132566 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/Q3jyFp8FJpkAT-TfVW_-FwZqQbUCcq03gevuImGm2mHi8U4ULX83e3FGXjEmOqWdFj9nlBolrhpfh3U8e9mKwA==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/22 04:04:36 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/Q3jyFp8FJpkAT-TfVW_-FwZqQbUCcq03gevuImGm2mHi8U4ULX83e3FGXjEmOqWdFj9nlBolrhpfh3U8e9mKwA==/move: This value should not be blank. (Code=2000, Status=400) sync_test.go:2503: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2503 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2553 Error: Received unexpected error: moving to --backup-dir failed: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/Q3jyFp8FJpkAT-TfVW_-FwZqQbUCcq03gevuImGm2mHi8U4ULX83e3FGXjEmOqWdFj9nlBolrhpfh3U8e9mKwA==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncSuffix --- FAIL: TestSyncSuffix (119.94s) === RUN TestSyncSuffixKeepExtension run.go:180: Remote "proton drive root link ID 'rclone-test-bafatoz7yija'", Local "Local file system at /tmp/rclone3167937552", Modify Window "1s" 2025/09/22 04:04:42 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-bafatoz7yija/dst" 2025/09/22 04:04:42 DEBUG : proton drive root link ID 'rclone-test-bafatoz7yija/dst': Has cached credentials 2025/09/22 04:04:43 DEBUG : proton drive root link ID 'rclone-test-bafatoz7yija/dst': Used cached credential to initialize the ProtonDrive API 2025/09/22 04:06:36 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/09/22 04:06:36.622532 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/CcbT2Fufq2LYlTxq9mwcVnD44O6Syk9bQpAeG26YrKiF-R29o20Jv5wXNEqRLjGlfR8H17d0XZTWjrVGYEv1Wg==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/22 04:06:36.622567 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/CcbT2Fufq2LYlTxq9mwcVnD44O6Syk9bQpAeG26YrKiF-R29o20Jv5wXNEqRLjGlfR8H17d0XZTWjrVGYEv1Wg==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/22 04:06:36 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/CcbT2Fufq2LYlTxq9mwcVnD44O6Syk9bQpAeG26YrKiF-R29o20Jv5wXNEqRLjGlfR8H17d0XZTWjrVGYEv1Wg==/move: This value should not be blank. (Code=2000, Status=400) sync_test.go:2503: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2503 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2554 Error: Received unexpected error: moving to --backup-dir failed: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/CcbT2Fufq2LYlTxq9mwcVnD44O6Syk9bQpAeG26YrKiF-R29o20Jv5wXNEqRLjGlfR8H17d0XZTWjrVGYEv1Wg==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncSuffixKeepExtension --- FAIL: TestSyncSuffixKeepExtension (120.49s) FAIL 2025/09/22 04:06:38 DEBUG : proton drive root link ID 'rclone-test-bafatoz7yija': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestProtonDrive: -verbose -test.run '^(TestMoveWithDeleteEmptySrcDirs|TestServerSideMove|TestServerSideMoveDeleteEmptySourceDirs|TestServerSideMoveOverSelf|TestServerSideMoveWithFilter|TestSyncBackupDir|TestSyncBackupDirSuffixOnly|TestSyncBackupDirWithSuffix|TestSyncBackupDirWithSuffixKeepExtension|TestSyncSuffix|TestSyncSuffixKeepExtension|TestSyncWithTrackRenames|TestSyncWithTrackRenamesStrategyLeaf|TestSyncWithTrackRenamesStrategyModtime)$'" - Finished ERROR in 24m25.993432365s (try 2/5): exit status 1: Failed [TestServerSideMoveOverSelf TestSyncWithTrackRenames TestSyncWithTrackRenamesStrategyModtime TestSyncWithTrackRenamesStrategyLeaf TestMoveWithDeleteEmptySrcDirs TestServerSideMove TestServerSideMoveWithFilter TestServerSideMoveDeleteEmptySourceDirs TestSyncBackupDir TestSyncBackupDirWithSuffix TestSyncBackupDirWithSuffixKeepExtension TestSyncBackupDirSuffixOnly TestSyncSuffix TestSyncSuffixKeepExtension]