"./sync.test -test.v -test.timeout 1h0m0s -remote TestProtonDrive: -verbose -test.run '^(TestMoveWithDeleteEmptySrcDirs|TestServerSideMove|TestServerSideMoveDeleteEmptySourceDirs|TestServerSideMoveOverSelf|TestServerSideMoveWithFilter|TestSyncBackupDir|TestSyncBackupDirSuffixOnly|TestSyncBackupDirWithSuffix|TestSyncBackupDirWithSuffixKeepExtension|TestSyncConcurrentTruncate|TestSyncSuffix|TestSyncSuffixKeepExtension|TestSyncWithTrackRenames|TestSyncWithTrackRenamesStrategyLeaf|TestSyncWithTrackRenamesStrategyModtime)$'" - Starting (try 2/5) 2025/10/28 02:17:51 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-roponih5teva" 2025/10/28 02:17:51 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/10/28 02:17:51 DEBUG : proton drive root link ID 'rclone-test-roponih5teva': Has cached credentials 2025/10/28 02:17:52 DEBUG : proton drive root link ID 'rclone-test-roponih5teva': Used cached credential to initialize the ProtonDrive API 2025/10/28 02:17:52 DEBUG : Creating backend with remote "/tmp/rclone2658699198" === RUN TestServerSideMoveOverSelf run.go:180: Remote "proton drive root link ID 'rclone-test-roponih5teva'", Local "Local file system at /tmp/rclone2658699198", Modify Window "1s" 2025/10/28 02:17:59 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-moveget2beki" 2025/10/28 02:17:59 DEBUG : proton drive root link ID 'rclone-test-moveget2beki': Has cached credentials 2025/10/28 02:18:00 DEBUG : proton drive root link ID 'rclone-test-moveget2beki': Used cached credential to initialize the ProtonDrive API sync_test.go:701: Server side copy (if possible) proton drive root link ID 'rclone-test-roponih5teva' -> proton drive root link ID 'rclone-test-moveget2beki' 2025/10/28 02:18:01 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/10/28 02:18:01 DEBUG : proton drive root link ID 'rclone-test-moveget2beki': Waiting for checks to finish 2025/10/28 02:18:01 DEBUG : proton drive root link ID 'rclone-test-moveget2beki': Waiting for transfers to finish 2025/10/28 02:18:04 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2025/10/28 02:18:04 INFO : sub dir/hello world: Copied (new) 2025/10/28 02:18:05.400562 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/10/28 02:18:05.400598 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/10/28 02:18:07 DEBUG : proton drive root link ID 'rclone-test-moveget2beki': Using server-side directory move 2025/10/28 02:18:07 INFO : proton drive root link ID 'rclone-test-moveget2beki': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/10/28 02:18:07 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/10/28 02:18:07 DEBUG : proton drive root link ID 'rclone-test-moveget2beki': Waiting for checks to finish 2025/10/28 02:18:07 DEBUG : proton drive root link ID 'rclone-test-moveget2beki': Waiting for transfers to finish 2025/10/28 02:18:07 INFO : sub dir/hello world: Deleted 2025/10/28 02:18:08.418649 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/II7hWiV1SPOEX6eSEF7t_29ikUP1TbQcsSG2IpFR7F0dcpLoyFUbaXJx2kAxAdebi5dJpf-NzFa00ZP7nRGpOg==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/10/28 02:18:08.418689 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/II7hWiV1SPOEX6eSEF7t_29ikUP1TbQcsSG2IpFR7F0dcpLoyFUbaXJx2kAxAdebi5dJpf-NzFa00ZP7nRGpOg==/move: This value should not be blank. (Code=2000, Status=400) 2025/10/28 02:18:08 ERROR : sub dir/hello world: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/II7hWiV1SPOEX6eSEF7t_29ikUP1TbQcsSG2IpFR7F0dcpLoyFUbaXJx2kAxAdebi5dJpf-NzFa00ZP7nRGpOg==/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/II7hWiV1SPOEX6eSEF7t_29ikUP1TbQcsSG2IpFR7F0dcpLoyFUbaXJx2kAxAdebi5dJpf-NzFa00ZP7nRGpOg==/move: This value should not be blank. (Code=2000, Status=400) Test: TestServerSideMoveOverSelf 2025/10/28 02:18:08 DEBUG : proton drive root link ID 'rclone-test-moveget2beki': Purge remote --- FAIL: TestServerSideMoveOverSelf (17.39s) === RUN TestSyncWithTrackRenames run.go:180: Remote "proton drive root link ID 'rclone-test-roponih5teva'", Local "Local file system at /tmp/rclone2658699198", Modify Window "1s" sync_test.go:1568: Can track renames: true 2025/10/28 02:18:10 INFO : proton drive root link ID 'rclone-test-roponih5teva': Making map for --track-renames 2025/10/28 02:18:10 INFO : proton drive root link ID 'rclone-test-roponih5teva': Finished making map for --track-renames 2025/10/28 02:18:10 DEBUG : proton drive root link ID 'rclone-test-roponih5teva': Waiting for checks to finish 2025/10/28 02:18:10 DEBUG : proton drive root link ID 'rclone-test-roponih5teva': Waiting for renames to finish 2025/10/28 02:18:10 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/10/28 02:18:10 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/10/28 02:18:10 DEBUG : proton drive root link ID 'rclone-test-roponih5teva': Waiting for transfers to finish 2025/10/28 02:18:11 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/10/28 02:18:11 INFO : potato: Copied (new) 2025/10/28 02:18:11 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/10/28 02:18:11 INFO : yam: Copied (new) 2025/10/28 02:18:11 DEBUG : Waiting for deletions to finish 2025/10/28 02:18:12 DEBUG : potato: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/28 02:18:12 INFO : proton drive root link ID 'rclone-test-roponih5teva': Making map for --track-renames 2025/10/28 02:18:12 INFO : proton drive root link ID 'rclone-test-roponih5teva': Finished making map for --track-renames 2025/10/28 02:18:12 DEBUG : proton drive root link ID 'rclone-test-roponih5teva': Waiting for checks to finish 2025/10/28 02:18:12 DEBUG : potato: Unchanged skipping 2025/10/28 02:18:12 DEBUG : proton drive root link ID 'rclone-test-roponih5teva': Waiting for renames to finish 2025/10/28 02:18:12.565112 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/mwgpiyqUe5AE4JBxMUSnisNqDs3yeSidjFxdXdEZmk4zFVRJnqHZc5qyb8nYZ9NU-Cj9kOuw2itvByKM4nLGMg==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/10/28 02:18:12.565145 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/mwgpiyqUe5AE4JBxMUSnisNqDs3yeSidjFxdXdEZmk4zFVRJnqHZc5qyb8nYZ9NU-Cj9kOuw2itvByKM4nLGMg==/move: This value should not be blank. (Code=2000, Status=400) 2025/10/28 02:18:12 ERROR : yam: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/mwgpiyqUe5AE4JBxMUSnisNqDs3yeSidjFxdXdEZmk4zFVRJnqHZc5qyb8nYZ9NU-Cj9kOuw2itvByKM4nLGMg==/move: This value should not be blank. (Code=2000, Status=400) 2025/10/28 02:18:12 DEBUG : yaml: Failed to rename to "yam": 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/mwgpiyqUe5AE4JBxMUSnisNqDs3yeSidjFxdXdEZmk4zFVRJnqHZc5qyb8nYZ9NU-Cj9kOuw2itvByKM4nLGMg==/move: This value should not be blank. (Code=2000, Status=400) 2025/10/28 02:18:12 DEBUG : yaml: Need to transfer - No matching file found at Destination 2025/10/28 02:18:12 DEBUG : proton drive root link ID 'rclone-test-roponih5teva': Waiting for transfers to finish 2025/10/28 02:18:13 DEBUG : yaml: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/10/28 02:18:13 INFO : yaml: Copied (new) 2025/10/28 02:18:13 ERROR : proton drive root link ID 'rclone-test-roponih5teva': not deleting files as there were IO errors 2025/10/28 02:18:13 ERROR : proton drive root link ID 'rclone-test-roponih5teva': 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 (4.90s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:180: Remote "proton drive root link ID 'rclone-test-roponih5teva'", Local "Local file system at /tmp/rclone2658699198", Modify Window "1s" sync_test.go:1640: Can track renames: true 2025/10/28 02:18:14 INFO : proton drive root link ID 'rclone-test-roponih5teva': Making map for --track-renames 2025/10/28 02:18:14 INFO : proton drive root link ID 'rclone-test-roponih5teva': Finished making map for --track-renames 2025/10/28 02:18:14 DEBUG : proton drive root link ID 'rclone-test-roponih5teva': Waiting for checks to finish 2025/10/28 02:18:14 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/10/28 02:18:14 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/10/28 02:18:14 DEBUG : proton drive root link ID 'rclone-test-roponih5teva': Waiting for renames to finish 2025/10/28 02:18:14 DEBUG : proton drive root link ID 'rclone-test-roponih5teva': Waiting for transfers to finish 2025/10/28 02:18:15 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/10/28 02:18:15 INFO : potato: Copied (new) 2025/10/28 02:18:16 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/10/28 02:18:16 INFO : yam: Copied (new) 2025/10/28 02:18:16 DEBUG : Waiting for deletions to finish 2025/10/28 02:18:17 DEBUG : potato: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/28 02:18:17 INFO : proton drive root link ID 'rclone-test-roponih5teva': Making map for --track-renames 2025/10/28 02:18:17 INFO : proton drive root link ID 'rclone-test-roponih5teva': Finished making map for --track-renames 2025/10/28 02:18:17 DEBUG : proton drive root link ID 'rclone-test-roponih5teva': Waiting for checks to finish 2025/10/28 02:18:17 DEBUG : potato: Unchanged skipping 2025/10/28 02:18:17 DEBUG : proton drive root link ID 'rclone-test-roponih5teva': Waiting for renames to finish 2025/10/28 02:18:17.516643 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/Q8x_Mlls7IPXTwOpTzrh6gt-RM3Mv_PmKpgdxmxxtHzf74JqQFmujbDW5YSp1VaGewXQ7KSwOLvKuf8PwzeQqw==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/10/28 02:18:17.516698 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/Q8x_Mlls7IPXTwOpTzrh6gt-RM3Mv_PmKpgdxmxxtHzf74JqQFmujbDW5YSp1VaGewXQ7KSwOLvKuf8PwzeQqw==/move: This value should not be blank. (Code=2000, Status=400) 2025/10/28 02:18:17 ERROR : yam: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/Q8x_Mlls7IPXTwOpTzrh6gt-RM3Mv_PmKpgdxmxxtHzf74JqQFmujbDW5YSp1VaGewXQ7KSwOLvKuf8PwzeQqw==/move: This value should not be blank. (Code=2000, Status=400) 2025/10/28 02:18:17 DEBUG : yaml: Failed to rename to "yam": 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/Q8x_Mlls7IPXTwOpTzrh6gt-RM3Mv_PmKpgdxmxxtHzf74JqQFmujbDW5YSp1VaGewXQ7KSwOLvKuf8PwzeQqw==/move: This value should not be blank. (Code=2000, Status=400) 2025/10/28 02:18:17 DEBUG : yaml: Need to transfer - No matching file found at Destination 2025/10/28 02:18:17 DEBUG : proton drive root link ID 'rclone-test-roponih5teva': Waiting for transfers to finish 2025/10/28 02:18:18 DEBUG : yaml: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/10/28 02:18:18 INFO : yaml: Copied (new) 2025/10/28 02:18:18 ERROR : proton drive root link ID 'rclone-test-roponih5teva': not deleting files as there were IO errors 2025/10/28 02:18:18 ERROR : proton drive root link ID 'rclone-test-roponih5teva': 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 (4.90s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:180: Remote "proton drive root link ID 'rclone-test-roponih5teva'", Local "Local file system at /tmp/rclone2658699198", Modify Window "1s" sync_test.go:1679: Can track renames: true 2025/10/28 02:18:19 INFO : proton drive root link ID 'rclone-test-roponih5teva': Making map for --track-renames 2025/10/28 02:18:19 INFO : proton drive root link ID 'rclone-test-roponih5teva': Finished making map for --track-renames 2025/10/28 02:18:19 DEBUG : proton drive root link ID 'rclone-test-roponih5teva': Waiting for checks to finish 2025/10/28 02:18:19 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/10/28 02:18:19 DEBUG : sub/yam: Need to transfer - No matching file found at Destination 2025/10/28 02:18:19 DEBUG : proton drive root link ID 'rclone-test-roponih5teva': Waiting for renames to finish 2025/10/28 02:18:19 DEBUG : proton drive root link ID 'rclone-test-roponih5teva': Waiting for transfers to finish 2025/10/28 02:18:21 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/10/28 02:18:21 INFO : potato: Copied (new) 2025/10/28 02:18:21 DEBUG : sub/yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/10/28 02:18:21 INFO : sub/yam: Copied (new) 2025/10/28 02:18:21 DEBUG : Waiting for deletions to finish 2025/10/28 02:18:22 DEBUG : potato: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/28 02:18:22 DEBUG : potato: Unchanged skipping 2025/10/28 02:18:22 INFO : proton drive root link ID 'rclone-test-roponih5teva': Making map for --track-renames 2025/10/28 02:18:22 INFO : proton drive root link ID 'rclone-test-roponih5teva': Finished making map for --track-renames 2025/10/28 02:18:22 DEBUG : proton drive root link ID 'rclone-test-roponih5teva': Waiting for checks to finish 2025/10/28 02:18:22 DEBUG : proton drive root link ID 'rclone-test-roponih5teva': Waiting for renames to finish 2025/10/28 02:18:22.676016 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/j1tl-f8StxXwc-iAN44lHWybbcdag-_2WUZDX6hS_61M799c2Cv2NQBndjowwXk7JChWutbv3CPibaM7dfI21g==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/10/28 02:18:22.676055 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/j1tl-f8StxXwc-iAN44lHWybbcdag-_2WUZDX6hS_61M799c2Cv2NQBndjowwXk7JChWutbv3CPibaM7dfI21g==/move: This value should not be blank. (Code=2000, Status=400) 2025/10/28 02:18:22 ERROR : sub/yam: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/j1tl-f8StxXwc-iAN44lHWybbcdag-_2WUZDX6hS_61M799c2Cv2NQBndjowwXk7JChWutbv3CPibaM7dfI21g==/move: This value should not be blank. (Code=2000, Status=400) 2025/10/28 02:18:22 DEBUG : yam: Failed to rename to "sub/yam": 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/j1tl-f8StxXwc-iAN44lHWybbcdag-_2WUZDX6hS_61M799c2Cv2NQBndjowwXk7JChWutbv3CPibaM7dfI21g==/move: This value should not be blank. (Code=2000, Status=400) 2025/10/28 02:18:22 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/10/28 02:18:22 DEBUG : proton drive root link ID 'rclone-test-roponih5teva': Waiting for transfers to finish 2025/10/28 02:18:23 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/10/28 02:18:23 INFO : yam: Copied (new) 2025/10/28 02:18:23 ERROR : proton drive root link ID 'rclone-test-roponih5teva': not deleting files as there were IO errors 2025/10/28 02:18:23 ERROR : proton drive root link ID 'rclone-test-roponih5teva': 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 (5.87s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:180: Remote "proton drive root link ID 'rclone-test-roponih5teva'", Local "Local file system at /tmp/rclone2658699198", Modify Window "1s" 2025/10/28 02:18:25 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/10/28 02:18:25 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2025/10/28 02:18:25 DEBUG : proton drive root link ID 'rclone-test-roponih5teva': Waiting for checks to finish 2025/10/28 02:18:25 DEBUG : proton drive root link ID 'rclone-test-roponih5teva': Waiting for transfers to finish 2025/10/28 02:18:28 DEBUG : nested/sub dir/file: sha1 = b4b3e0a278988bc15f2913af3f4153ccef74e465 OK 2025/10/28 02:18:28 INFO : nested/sub dir/file: Copied (new) 2025/10/28 02:18:28 INFO : nested/sub dir/file: Deleted 2025/10/28 02:18:28 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2025/10/28 02:18:28 INFO : sub dir/hello world: Copied (new) 2025/10/28 02:18:28 INFO : sub dir/hello world: Deleted 2025/10/28 02:18:28 ERROR : Local file system at /tmp/rclone2658699198: 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.51s) === RUN TestServerSideMove run.go:180: Remote "proton drive root link ID 'rclone-test-roponih5teva'", Local "Local file system at /tmp/rclone2658699198", Modify Window "1s" 2025/10/28 02:18:30 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-xitefol2zidi" 2025/10/28 02:18:30 DEBUG : proton drive root link ID 'rclone-test-xitefol2zidi': Has cached credentials 2025/10/28 02:18:30 DEBUG : proton drive root link ID 'rclone-test-xitefol2zidi': Used cached credential to initialize the ProtonDrive API sync_test.go:1735: Server side move (if possible) proton drive root link ID 'rclone-test-roponih5teva' -> proton drive root link ID 'rclone-test-xitefol2zidi' 2025/10/28 02:18:38 DEBUG : proton drive root link ID 'rclone-test-xitefol2zidi': Using server-side directory move 2025/10/28 02:18:38 INFO : proton drive root link ID 'rclone-test-xitefol2zidi': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/10/28 02:18:38 DEBUG : potato2: Need to transfer - File not found at Destination 2025/10/28 02:18:38 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1s) 2025/10/28 02:18:38 DEBUG : empty space: Unchanged skipping 2025/10/28 02:18:38 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/10/28 02:18:38 DEBUG : proton drive root link ID 'rclone-test-xitefol2zidi': Waiting for checks to finish 2025/10/28 02:18:38 INFO : empty space: Deleted 2025/10/28 02:18:38 DEBUG : proton drive root link ID 'rclone-test-xitefol2zidi': Waiting for transfers to finish 2025/10/28 02:18:38 INFO : potato3: Deleted 2025/10/28 02:18:39.267787 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/F4M6pDlN6tG9X7__yLDyIzaI8S2UlF3hSHvtzOl4hE50nyIdbkIkfR1LRAzjYYpxq-ifKlJtwXnAix2fLBSkOw==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/10/28 02:18:39.267825 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/F4M6pDlN6tG9X7__yLDyIzaI8S2UlF3hSHvtzOl4hE50nyIdbkIkfR1LRAzjYYpxq-ifKlJtwXnAix2fLBSkOw==/move: This value should not be blank. (Code=2000, Status=400) 2025/10/28 02:18:39 ERROR : potato3: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/F4M6pDlN6tG9X7__yLDyIzaI8S2UlF3hSHvtzOl4hE50nyIdbkIkfR1LRAzjYYpxq-ifKlJtwXnAix2fLBSkOw==/move: This value should not be blank. (Code=2000, Status=400) 2025/10/28 02:18:39.289473 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/iUdrApj9TRLJJx4mgj-1_nP2a3nO6wyr9e8HlXvFq91SIIVUHWrD6_fze007cOWM71La_dSuOPWZ7JY_USPCRQ==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/10/28 02:18:39.289505 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/iUdrApj9TRLJJx4mgj-1_nP2a3nO6wyr9e8HlXvFq91SIIVUHWrD6_fze007cOWM71La_dSuOPWZ7JY_USPCRQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/10/28 02:18:39 ERROR : potato2: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/iUdrApj9TRLJJx4mgj-1_nP2a3nO6wyr9e8HlXvFq91SIIVUHWrD6_fze007cOWM71La_dSuOPWZ7JY_USPCRQ==/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/iUdrApj9TRLJJx4mgj-1_nP2a3nO6wyr9e8HlXvFq91SIIVUHWrD6_fze007cOWM71La_dSuOPWZ7JY_USPCRQ==/move: This value should not be blank. (Code=2000, Status=400) Test: TestServerSideMove 2025/10/28 02:18:39 DEBUG : proton drive root link ID 'rclone-test-xitefol2zidi': Purge remote --- FAIL: TestServerSideMove (10.51s) === RUN TestServerSideMoveWithFilter run.go:180: Remote "proton drive root link ID 'rclone-test-roponih5teva'", Local "Local file system at /tmp/rclone2658699198", Modify Window "1s" 2025/10/28 02:18:40 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-qeteric8teji" 2025/10/28 02:18:40 DEBUG : Config file has changed externally - reloading 2025/10/28 02:18:40 DEBUG : proton drive root link ID 'rclone-test-qeteric8teji': Has cached credentials 2025/10/28 02:18:41 DEBUG : proton drive root link ID 'rclone-test-qeteric8teji': Used cached credential to initialize the ProtonDrive API sync_test.go:1735: Server side move (if possible) proton drive root link ID 'rclone-test-roponih5teva' -> proton drive root link ID 'rclone-test-qeteric8teji' 2025/10/28 02:18:48 DEBUG : empty space: Excluded (Size Filter) 2025/10/28 02:18:48 DEBUG : empty space: Excluded 2025/10/28 02:18:48 DEBUG : empty space: Excluded (Size Filter) 2025/10/28 02:18:48 DEBUG : empty space: Excluded 2025/10/28 02:18:48 DEBUG : potato2: Need to transfer - File not found at Destination 2025/10/28 02:18:48 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/10/28 02:18:48 DEBUG : proton drive root link ID 'rclone-test-qeteric8teji': Waiting for checks to finish 2025/10/28 02:18:48 DEBUG : proton drive root link ID 'rclone-test-qeteric8teji': Waiting for transfers to finish 2025/10/28 02:18:48 INFO : potato3: Deleted 2025/10/28 02:18:49.094164 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/C2szTCsKJiRlmsjha9U9Up1dReBJuSWiIJUW1RuJDLhRm_JfBaPfKK_7tTw_CLE-XKMIG_NO_jmYdwsMpC2F2Q==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/10/28 02:18:49.094209 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/C2szTCsKJiRlmsjha9U9Up1dReBJuSWiIJUW1RuJDLhRm_JfBaPfKK_7tTw_CLE-XKMIG_NO_jmYdwsMpC2F2Q==/move: This value should not be blank. (Code=2000, Status=400) 2025/10/28 02:18:49 ERROR : potato2: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/C2szTCsKJiRlmsjha9U9Up1dReBJuSWiIJUW1RuJDLhRm_JfBaPfKK_7tTw_CLE-XKMIG_NO_jmYdwsMpC2F2Q==/move: This value should not be blank. (Code=2000, Status=400) 2025/10/28 02:18:49.207008 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/CWTj-W87Mk_YzfC8B0kqc10XUNRMu6CEuQ77-PO9Y91holUtMddgyACSbm5FNTtigTVtA31KQDhj0DFnj28ELw==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/10/28 02:18:49.207171 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/CWTj-W87Mk_YzfC8B0kqc10XUNRMu6CEuQ77-PO9Y91holUtMddgyACSbm5FNTtigTVtA31KQDhj0DFnj28ELw==/move: This value should not be blank. (Code=2000, Status=400) 2025/10/28 02:18:49 ERROR : potato3: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/CWTj-W87Mk_YzfC8B0kqc10XUNRMu6CEuQ77-PO9Y91holUtMddgyACSbm5FNTtigTVtA31KQDhj0DFnj28ELw==/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/CWTj-W87Mk_YzfC8B0kqc10XUNRMu6CEuQ77-PO9Y91holUtMddgyACSbm5FNTtigTVtA31KQDhj0DFnj28ELw==/move: This value should not be blank. (Code=2000, Status=400) Test: TestServerSideMoveWithFilter 2025/10/28 02:18:49 DEBUG : proton drive root link ID 'rclone-test-qeteric8teji': Purge remote --- FAIL: TestServerSideMoveWithFilter (10.20s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:180: Remote "proton drive root link ID 'rclone-test-roponih5teva'", Local "Local file system at /tmp/rclone2658699198", Modify Window "1s" 2025/10/28 02:18:50 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-sopiqaz2tefo" 2025/10/28 02:18:50 DEBUG : proton drive root link ID 'rclone-test-sopiqaz2tefo': Has cached credentials 2025/10/28 02:18:51 DEBUG : proton drive root link ID 'rclone-test-sopiqaz2tefo': Used cached credential to initialize the ProtonDrive API 2025/10/28 02:18:54 INFO : tomatoDir: Making directory sync_test.go:1735: Server side move (if possible) proton drive root link ID 'rclone-test-roponih5teva' -> proton drive root link ID 'rclone-test-sopiqaz2tefo' 2025/10/28 02:18:58 DEBUG : proton drive root link ID 'rclone-test-sopiqaz2tefo': Using server-side directory move 2025/10/28 02:18:58 INFO : proton drive root link ID 'rclone-test-sopiqaz2tefo': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/10/28 02:18:59 DEBUG : potato2: Need to transfer - File not found at Destination 2025/10/28 02:18:59 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/10/28 02:18:59 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1s) 2025/10/28 02:18:59 DEBUG : empty space: Unchanged skipping 2025/10/28 02:18:59 DEBUG : proton drive root link ID 'rclone-test-sopiqaz2tefo': Waiting for checks to finish 2025/10/28 02:18:59 INFO : potato3: Deleted 2025/10/28 02:18:59 INFO : empty space: Deleted 2025/10/28 02:18:59 DEBUG : proton drive root link ID 'rclone-test-sopiqaz2tefo': Waiting for transfers to finish 2025/10/28 02:18:59.674531 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/3vrYvtDHKAn16I1GwwrE7rhie5aSH7_Yk4-Z8AP0xxT2ZDV_lAG51VWY27RM6grzElpH9bDGUT49k9pTRKC3Dg==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/10/28 02:18:59.674592 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/3vrYvtDHKAn16I1GwwrE7rhie5aSH7_Yk4-Z8AP0xxT2ZDV_lAG51VWY27RM6grzElpH9bDGUT49k9pTRKC3Dg==/move: This value should not be blank. (Code=2000, Status=400) 2025/10/28 02:18:59 ERROR : potato2: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/3vrYvtDHKAn16I1GwwrE7rhie5aSH7_Yk4-Z8AP0xxT2ZDV_lAG51VWY27RM6grzElpH9bDGUT49k9pTRKC3Dg==/move: This value should not be blank. (Code=2000, Status=400) 2025/10/28 02:18:59.758746 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/zGd0Ek9Nl5RqHIkuLDnHMVy4d7tidhNm-M5eoh2aqEE6V-JxsU2jNxGzmlKvzEHBsBJjdw8yVLHBtDscLB_tJA==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/10/28 02:18:59.758777 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/zGd0Ek9Nl5RqHIkuLDnHMVy4d7tidhNm-M5eoh2aqEE6V-JxsU2jNxGzmlKvzEHBsBJjdw8yVLHBtDscLB_tJA==/move: This value should not be blank. (Code=2000, Status=400) 2025/10/28 02:18:59 ERROR : potato3: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/zGd0Ek9Nl5RqHIkuLDnHMVy4d7tidhNm-M5eoh2aqEE6V-JxsU2jNxGzmlKvzEHBsBJjdw8yVLHBtDscLB_tJA==/move: This value should not be blank. (Code=2000, Status=400) 2025/10/28 02:18:59 ERROR : proton drive root link ID 'rclone-test-roponih5teva': 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/10/28 02:18:59 DEBUG : proton drive root link ID 'rclone-test-sopiqaz2tefo': Purge remote --- FAIL: TestServerSideMoveDeleteEmptySourceDirs (10.83s) === RUN TestSyncBackupDir run.go:180: Remote "proton drive root link ID 'rclone-test-roponih5teva'", Local "Local file system at /tmp/rclone2658699198", Modify Window "1s" 2025/10/28 02:19:05 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-roponih5teva/dst" 2025/10/28 02:19:05 DEBUG : proton drive root link ID 'rclone-test-roponih5teva/dst': Has cached credentials 2025/10/28 02:19:06 DEBUG : proton drive root link ID 'rclone-test-roponih5teva/dst': Used cached credential to initialize the ProtonDrive API 2025/10/28 02:20:42 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-roponih5teva/backup" 2025/10/28 02:20:42 DEBUG : proton drive root link ID 'rclone-test-roponih5teva/backup': Has cached credentials 2025/10/28 02:20:43 DEBUG : proton drive root link ID 'rclone-test-roponih5teva/backup': Used cached credential to initialize the ProtonDrive API 2025/10/28 02:23:52 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/10/28 02:23:52 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/28 02:23:52 DEBUG : proton drive root link ID 'rclone-test-roponih5teva/dst': Waiting for checks to finish 2025/10/28 02:23:52 DEBUG : two: Unchanged skipping 2025/10/28 02:23:53.062397 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/IeZIpdMp9dz5fzqMsIs3qYXPlgQI83qvPVC05KYaxvbJxMFtTEtBj2e-gGwLpC7rRMAUrK3OkZwXY4O2jEnK6Q==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/10/28 02:23:53.062432 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/IeZIpdMp9dz5fzqMsIs3qYXPlgQI83qvPVC05KYaxvbJxMFtTEtBj2e-gGwLpC7rRMAUrK3OkZwXY4O2jEnK6Q==/move: This value should not be blank. (Code=2000, Status=400) 2025/10/28 02:23:53 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/IeZIpdMp9dz5fzqMsIs3qYXPlgQI83qvPVC05KYaxvbJxMFtTEtBj2e-gGwLpC7rRMAUrK3OkZwXY4O2jEnK6Q==/move: This value should not be blank. (Code=2000, Status=400) 2025/10/28 02:23:53 DEBUG : proton drive root link ID 'rclone-test-roponih5teva/dst': Waiting for transfers to finish 2025/10/28 02:23:53 ERROR : proton drive root link ID 'rclone-test-roponih5teva/dst': not deleting files as there were IO errors 2025/10/28 02:23:53 ERROR : proton drive root link ID 'rclone-test-roponih5teva/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/IeZIpdMp9dz5fzqMsIs3qYXPlgQI83qvPVC05KYaxvbJxMFtTEtBj2e-gGwLpC7rRMAUrK3OkZwXY4O2jEnK6Q==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncBackupDir --- FAIL: TestSyncBackupDir (293.47s) === RUN TestSyncBackupDirWithSuffix run.go:180: Remote "proton drive root link ID 'rclone-test-roponih5teva'", Local "Local file system at /tmp/rclone2658699198", Modify Window "1s" 2025/10/28 02:23:59 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-roponih5teva/dst" 2025/10/28 02:23:59 DEBUG : Config file has changed externally - reloading 2025/10/28 02:23:59 DEBUG : proton drive root link ID 'rclone-test-roponih5teva/dst': Has cached credentials 2025/10/28 02:24:00 DEBUG : proton drive root link ID 'rclone-test-roponih5teva/dst': Used cached credential to initialize the ProtonDrive API 2025/10/28 02:25:35 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-roponih5teva/backup" 2025/10/28 02:25:35 DEBUG : proton drive root link ID 'rclone-test-roponih5teva/backup': Has cached credentials 2025/10/28 02:25:36 DEBUG : proton drive root link ID 'rclone-test-roponih5teva/backup': Used cached credential to initialize the ProtonDrive API 2025/10/28 02:28:43 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/10/28 02:28:43 DEBUG : proton drive root link ID 'rclone-test-roponih5teva/dst': Waiting for checks to finish 2025/10/28 02:28:43 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/28 02:28:43 DEBUG : two: Unchanged skipping 2025/10/28 02:28:44.138433 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/XeT1eIV9brD_HJZXnktcidbaRik_H4TQhcFmwuZ-rYSS11T4cj0KhNz58mXIUo-nyGCKr-AuasNvj2q2YQsFgg==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/10/28 02:28:44.138454 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/XeT1eIV9brD_HJZXnktcidbaRik_H4TQhcFmwuZ-rYSS11T4cj0KhNz58mXIUo-nyGCKr-AuasNvj2q2YQsFgg==/move: This value should not be blank. (Code=2000, Status=400) 2025/10/28 02:28:44 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/XeT1eIV9brD_HJZXnktcidbaRik_H4TQhcFmwuZ-rYSS11T4cj0KhNz58mXIUo-nyGCKr-AuasNvj2q2YQsFgg==/move: This value should not be blank. (Code=2000, Status=400) 2025/10/28 02:28:44 DEBUG : proton drive root link ID 'rclone-test-roponih5teva/dst': Waiting for transfers to finish 2025/10/28 02:28:44 ERROR : proton drive root link ID 'rclone-test-roponih5teva/dst': not deleting files as there were IO errors 2025/10/28 02:28:44 ERROR : proton drive root link ID 'rclone-test-roponih5teva/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/XeT1eIV9brD_HJZXnktcidbaRik_H4TQhcFmwuZ-rYSS11T4cj0KhNz58mXIUo-nyGCKr-AuasNvj2q2YQsFgg==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncBackupDirWithSuffix --- FAIL: TestSyncBackupDirWithSuffix (291.00s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:180: Remote "proton drive root link ID 'rclone-test-roponih5teva'", Local "Local file system at /tmp/rclone2658699198", Modify Window "1s" 2025/10/28 02:28:50 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-roponih5teva/dst" 2025/10/28 02:28:50 DEBUG : Config file has changed externally - reloading 2025/10/28 02:28:50 DEBUG : proton drive root link ID 'rclone-test-roponih5teva/dst': Has cached credentials 2025/10/28 02:28:51 DEBUG : proton drive root link ID 'rclone-test-roponih5teva/dst': Used cached credential to initialize the ProtonDrive API 2025/10/28 02:30:26 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-roponih5teva/backup" 2025/10/28 02:30:26 DEBUG : Config file has changed externally - reloading 2025/10/28 02:30:26 DEBUG : proton drive root link ID 'rclone-test-roponih5teva/backup': Has cached credentials 2025/10/28 02:30:27 DEBUG : proton drive root link ID 'rclone-test-roponih5teva/backup': Used cached credential to initialize the ProtonDrive API 2025/10/28 02:33:35 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/10/28 02:33:35 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/28 02:33:35 DEBUG : two: Unchanged skipping 2025/10/28 02:33:35 DEBUG : proton drive root link ID 'rclone-test-roponih5teva/dst': Waiting for checks to finish 2025/10/28 02:33:36.305944 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/5IwdsA1E1Y4OYF5naFJjQitlcDYzPGhAzXSMyJ92NkVTLT073Ede78xugI8F0qnEuRvvYhnz4lwFs8sNfaSn3w==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/10/28 02:33:36.305968 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/5IwdsA1E1Y4OYF5naFJjQitlcDYzPGhAzXSMyJ92NkVTLT073Ede78xugI8F0qnEuRvvYhnz4lwFs8sNfaSn3w==/move: This value should not be blank. (Code=2000, Status=400) 2025/10/28 02:33:36 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/5IwdsA1E1Y4OYF5naFJjQitlcDYzPGhAzXSMyJ92NkVTLT073Ede78xugI8F0qnEuRvvYhnz4lwFs8sNfaSn3w==/move: This value should not be blank. (Code=2000, Status=400) 2025/10/28 02:33:36 DEBUG : proton drive root link ID 'rclone-test-roponih5teva/dst': Waiting for transfers to finish 2025/10/28 02:33:36 ERROR : proton drive root link ID 'rclone-test-roponih5teva/dst': not deleting files as there were IO errors 2025/10/28 02:33:36 ERROR : proton drive root link ID 'rclone-test-roponih5teva/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/5IwdsA1E1Y4OYF5naFJjQitlcDYzPGhAzXSMyJ92NkVTLT073Ede78xugI8F0qnEuRvvYhnz4lwFs8sNfaSn3w==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncBackupDirWithSuffixKeepExtension --- FAIL: TestSyncBackupDirWithSuffixKeepExtension (292.57s) === RUN TestSyncBackupDirSuffixOnly run.go:180: Remote "proton drive root link ID 'rclone-test-roponih5teva'", Local "Local file system at /tmp/rclone2658699198", Modify Window "1s" 2025/10/28 02:33:42 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-roponih5teva/dst" 2025/10/28 02:33:42 DEBUG : Config file has changed externally - reloading 2025/10/28 02:33:42 DEBUG : proton drive root link ID 'rclone-test-roponih5teva/dst': Has cached credentials 2025/10/28 02:33:43 DEBUG : proton drive root link ID 'rclone-test-roponih5teva/dst': Used cached credential to initialize the ProtonDrive API 2025/10/28 02:35:20 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/10/28 02:35:20 DEBUG : proton drive root link ID 'rclone-test-roponih5teva/dst': Waiting for checks to finish 2025/10/28 02:35:20 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/28 02:35:20 DEBUG : two: Unchanged skipping 2025/10/28 02:35:20.678133 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/ZbA8CJKqfuIaSlEkdABGmQBy1TLZMiQp34WjVFbImma82mEj9irmN6rLkHn3I-vqZjlWO6OxjST2F1n95ECdEg==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/10/28 02:35:20.678173 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/ZbA8CJKqfuIaSlEkdABGmQBy1TLZMiQp34WjVFbImma82mEj9irmN6rLkHn3I-vqZjlWO6OxjST2F1n95ECdEg==/move: This value should not be blank. (Code=2000, Status=400) 2025/10/28 02:35:20 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/ZbA8CJKqfuIaSlEkdABGmQBy1TLZMiQp34WjVFbImma82mEj9irmN6rLkHn3I-vqZjlWO6OxjST2F1n95ECdEg==/move: This value should not be blank. (Code=2000, Status=400) 2025/10/28 02:35:20 DEBUG : proton drive root link ID 'rclone-test-roponih5teva/dst': Waiting for transfers to finish 2025/10/28 02:35:20 ERROR : proton drive root link ID 'rclone-test-roponih5teva/dst': not deleting files as there were IO errors 2025/10/28 02:35:20 ERROR : proton drive root link ID 'rclone-test-roponih5teva/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/ZbA8CJKqfuIaSlEkdABGmQBy1TLZMiQp34WjVFbImma82mEj9irmN6rLkHn3I-vqZjlWO6OxjST2F1n95ECdEg==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncBackupDirSuffixOnly --- FAIL: TestSyncBackupDirSuffixOnly (103.80s) === RUN TestSyncSuffix run.go:180: Remote "proton drive root link ID 'rclone-test-roponih5teva'", Local "Local file system at /tmp/rclone2658699198", Modify Window "1s" 2025/10/28 02:35:26 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-roponih5teva/dst" 2025/10/28 02:35:26 DEBUG : proton drive root link ID 'rclone-test-roponih5teva/dst': Has cached credentials 2025/10/28 02:35:27 DEBUG : proton drive root link ID 'rclone-test-roponih5teva/dst': Used cached credential to initialize the ProtonDrive API 2025/10/28 02:37:02 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/10/28 02:37:03.305299 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/-JDHyL0NKsozCwUHGdLQOqWukVi0vaN0ob5i_nV3fe38p4wOwcn7lR2EWIMFd5WeBPOXwEm4hoJGHKpHCHWkYQ==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/10/28 02:37:03.305342 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/-JDHyL0NKsozCwUHGdLQOqWukVi0vaN0ob5i_nV3fe38p4wOwcn7lR2EWIMFd5WeBPOXwEm4hoJGHKpHCHWkYQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/10/28 02:37:03 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/-JDHyL0NKsozCwUHGdLQOqWukVi0vaN0ob5i_nV3fe38p4wOwcn7lR2EWIMFd5WeBPOXwEm4hoJGHKpHCHWkYQ==/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/-JDHyL0NKsozCwUHGdLQOqWukVi0vaN0ob5i_nV3fe38p4wOwcn7lR2EWIMFd5WeBPOXwEm4hoJGHKpHCHWkYQ==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncSuffix --- FAIL: TestSyncSuffix (102.54s) === RUN TestSyncSuffixKeepExtension run.go:180: Remote "proton drive root link ID 'rclone-test-roponih5teva'", Local "Local file system at /tmp/rclone2658699198", Modify Window "1s" 2025/10/28 02:37:09 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-roponih5teva/dst" 2025/10/28 02:37:09 DEBUG : proton drive root link ID 'rclone-test-roponih5teva/dst': Has cached credentials 2025/10/28 02:37:10 DEBUG : proton drive root link ID 'rclone-test-roponih5teva/dst': Used cached credential to initialize the ProtonDrive API 2025/10/28 02:38:46 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/10/28 02:38:46.518317 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/rr4V_AZ8MI3yKpAD__9pSN6GdMM9r_MQct-ud0zPvVHmeEitrazZF3w2e92_sQIMefprplbElKXlA8YOZZp3Fw==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/10/28 02:38:46.518359 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/rr4V_AZ8MI3yKpAD__9pSN6GdMM9r_MQct-ud0zPvVHmeEitrazZF3w2e92_sQIMefprplbElKXlA8YOZZp3Fw==/move: This value should not be blank. (Code=2000, Status=400) 2025/10/28 02:38:46 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/rr4V_AZ8MI3yKpAD__9pSN6GdMM9r_MQct-ud0zPvVHmeEitrazZF3w2e92_sQIMefprplbElKXlA8YOZZp3Fw==/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/rr4V_AZ8MI3yKpAD__9pSN6GdMM9r_MQct-ud0zPvVHmeEitrazZF3w2e92_sQIMefprplbElKXlA8YOZZp3Fw==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncSuffixKeepExtension --- FAIL: TestSyncSuffixKeepExtension (103.24s) === RUN TestSyncConcurrentTruncate run.go:180: Remote "proton drive root link ID 'rclone-test-roponih5teva'", Local "Local file system at /tmp/rclone2658699198", Modify Window "1s" 2025/10/28 02:39:37 DEBUG : both0: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/28 02:39:37 DEBUG : both0: Unchanged skipping 2025/10/28 02:39:37 DEBUG : both10: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/28 02:39:37 DEBUG : both10: Unchanged skipping 2025/10/28 02:39:37 DEBUG : both11: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/28 02:39:37 DEBUG : both11: Unchanged skipping 2025/10/28 02:39:37 DEBUG : both12: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/28 02:39:37 DEBUG : both12: Unchanged skipping 2025/10/28 02:39:37 DEBUG : both13: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/28 02:39:37 DEBUG : both13: Unchanged skipping 2025/10/28 02:39:37 DEBUG : both14: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/28 02:39:37 DEBUG : both14: Unchanged skipping 2025/10/28 02:39:37 DEBUG : both15: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/28 02:39:37 DEBUG : both15: Unchanged skipping 2025/10/28 02:39:37 DEBUG : both16: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/28 02:39:37 DEBUG : both16: Unchanged skipping 2025/10/28 02:39:37 DEBUG : both17: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/28 02:39:37 DEBUG : both17: Unchanged skipping 2025/10/28 02:39:37 DEBUG : both18: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/28 02:39:37 DEBUG : both18: Unchanged skipping 2025/10/28 02:39:37 DEBUG : both19: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/28 02:39:37 DEBUG : both19: Unchanged skipping 2025/10/28 02:39:37 DEBUG : both1: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/28 02:39:37 DEBUG : both1: Unchanged skipping 2025/10/28 02:39:37 DEBUG : both2: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/28 02:39:37 DEBUG : both2: Unchanged skipping 2025/10/28 02:39:37 DEBUG : both3: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/28 02:39:37 DEBUG : both3: Unchanged skipping 2025/10/28 02:39:37 DEBUG : both4: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/28 02:39:37 DEBUG : both4: Unchanged skipping 2025/10/28 02:39:37 DEBUG : both5: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/28 02:39:37 DEBUG : both5: Unchanged skipping 2025/10/28 02:39:37 DEBUG : both6: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/28 02:39:37 DEBUG : both6: Unchanged skipping 2025/10/28 02:39:37 DEBUG : both7: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/28 02:39:37 DEBUG : both7: Unchanged skipping 2025/10/28 02:39:37 DEBUG : both8: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/28 02:39:37 DEBUG : both8: Unchanged skipping 2025/10/28 02:39:37 DEBUG : both9: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/28 02:39:37 DEBUG : both9: Unchanged skipping 2025/10/28 02:39:37 DEBUG : only0: Sizes differ (src 0 vs dst 6) 2025/10/28 02:39:37 DEBUG : only10: Sizes differ (src 0 vs dst 6) 2025/10/28 02:39:37 DEBUG : only11: Sizes differ (src 0 vs dst 6) 2025/10/28 02:39:37 DEBUG : only12: Sizes differ (src 0 vs dst 6) 2025/10/28 02:39:37 DEBUG : only13: Sizes differ (src 0 vs dst 6) 2025/10/28 02:39:37 DEBUG : only14: Sizes differ (src 0 vs dst 6) 2025/10/28 02:39:37 DEBUG : only15: Sizes differ (src 0 vs dst 6) 2025/10/28 02:39:37 DEBUG : only16: Sizes differ (src 0 vs dst 6) 2025/10/28 02:39:37 DEBUG : only17: Sizes differ (src 0 vs dst 6) 2025/10/28 02:39:37 DEBUG : only18: Sizes differ (src 0 vs dst 6) 2025/10/28 02:39:37 DEBUG : only19: Sizes differ (src 0 vs dst 6) 2025/10/28 02:39:37 DEBUG : only1: Sizes differ (src 0 vs dst 6) 2025/10/28 02:39:37 DEBUG : only2: Sizes differ (src 0 vs dst 6) 2025/10/28 02:39:37 DEBUG : only3: Sizes differ (src 0 vs dst 6) 2025/10/28 02:39:37 DEBUG : only4: Sizes differ (src 0 vs dst 6) 2025/10/28 02:39:37 DEBUG : only5: Sizes differ (src 0 vs dst 6) 2025/10/28 02:39:37 DEBUG : proton drive root link ID 'rclone-test-roponih5teva': Waiting for checks to finish 2025/10/28 02:39:37 DEBUG : only6: Sizes differ (src 0 vs dst 6) 2025/10/28 02:39:37 DEBUG : only7: Sizes differ (src 0 vs dst 6) 2025/10/28 02:39:37 DEBUG : only8: Sizes differ (src 0 vs dst 6) 2025/10/28 02:39:37 DEBUG : only9: Sizes differ (src 0 vs dst 6) 2025/10/28 02:39:37 DEBUG : proton drive root link ID 'rclone-test-roponih5teva': Waiting for transfers to finish 2025/10/28 02:39:37.572150 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/10/28 02:39:37.572176 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/10/28 02:39:37.599176 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/10/28 02:39:37.599192 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/10/28 02:39:37.603024 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/10/28 02:39:37.603033 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/10/28 02:39:37.610854 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/10/28 02:39:37.610932 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/10/28 02:39:38 DEBUG : only0: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/10/28 02:39:38 INFO : only0: Copied (replaced existing) 2025/10/28 02:39:38 DEBUG : only10: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/10/28 02:39:38 INFO : only10: Copied (replaced existing) 2025/10/28 02:39:38 DEBUG : only11: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/10/28 02:39:38 INFO : only11: Copied (replaced existing) 2025/10/28 02:39:38 DEBUG : only12: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/10/28 02:39:38 INFO : only12: Copied (replaced existing) 2025/10/28 02:39:38.382107 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/10/28 02:39:38.382134 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/10/28 02:39:38.450340 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/10/28 02:39:38.450369 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/10/28 02:39:38.464637 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/10/28 02:39:38.464680 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/10/28 02:39:38.474155 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/10/28 02:39:38.474176 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/10/28 02:39:39 DEBUG : only13: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/10/28 02:39:39 INFO : only13: Copied (replaced existing) 2025/10/28 02:39:39 DEBUG : only16: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/10/28 02:39:39 INFO : only16: Copied (replaced existing) 2025/10/28 02:39:39 DEBUG : only15: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/10/28 02:39:39 INFO : only15: Copied (replaced existing) 2025/10/28 02:39:39.267918 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/10/28 02:39:39.267942 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/10/28 02:39:39 DEBUG : only14: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/10/28 02:39:39 INFO : only14: Copied (replaced existing) 2025/10/28 02:39:39.315505 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/10/28 02:39:39.315520 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/10/28 02:39:39.332077 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/10/28 02:39:39.332741 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/10/28 02:39:39.434380 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/10/28 02:39:39.434402 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/10/28 02:39:40 DEBUG : only17: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/10/28 02:39:40 INFO : only17: Copied (replaced existing) 2025/10/28 02:39:40 DEBUG : only19: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/10/28 02:39:40 INFO : only19: Copied (replaced existing) 2025/10/28 02:39:40 DEBUG : only1: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/10/28 02:39:40 INFO : only1: Copied (replaced existing) 2025/10/28 02:39:40.206512 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/10/28 02:39:40.206563 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/10/28 02:39:40 DEBUG : only18: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/10/28 02:39:40 INFO : only18: Copied (replaced existing) 2025/10/28 02:39:40.278576 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/10/28 02:39:40.278610 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/10/28 02:39:40.455789 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/10/28 02:39:40.455811 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/10/28 02:39:40.664950 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/10/28 02:39:40.664965 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/10/28 02:39:40 DEBUG : only6: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/10/28 02:39:40 INFO : only6: Copied (replaced existing) 2025/10/28 02:39:41 DEBUG : only7: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/10/28 02:39:41 INFO : only7: Copied (replaced existing) 2025/10/28 02:39:41.101155 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/10/28 02:39:41.101609 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/10/28 02:39:41.161205 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/10/28 02:39:41.161230 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/10/28 02:39:41 DEBUG : only2: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/10/28 02:39:41 INFO : only2: Copied (replaced existing) 2025/10/28 02:39:41 DEBUG : only8: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/10/28 02:39:41 INFO : only8: Copied (replaced existing) 2025/10/28 02:39:41.366367 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/10/28 02:39:41.366399 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/10/28 02:39:41.473646 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/10/28 02:39:41.473672 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/10/28 02:39:41 DEBUG : only4: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/10/28 02:39:41 INFO : only4: Copied (replaced existing) 2025/10/28 02:39:41 DEBUG : only3: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/10/28 02:39:41 INFO : only3: Copied (replaced existing) 2025/10/28 02:39:42 DEBUG : only5: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/10/28 02:39:42 INFO : only5: Copied (replaced existing) 2025/10/28 02:39:42 DEBUG : only9: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/10/28 02:39:42 INFO : only9: Copied (replaced existing) 2025/10/28 02:39:42 DEBUG : Waiting for deletions to finish --- PASS: TestSyncConcurrentTruncate (70.98s) FAIL 2025/10/28 02:39:59 DEBUG : proton drive root link ID 'rclone-test-roponih5teva': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestProtonDrive: -verbose -test.run '^(TestMoveWithDeleteEmptySrcDirs|TestServerSideMove|TestServerSideMoveDeleteEmptySourceDirs|TestServerSideMoveOverSelf|TestServerSideMoveWithFilter|TestSyncBackupDir|TestSyncBackupDirSuffixOnly|TestSyncBackupDirWithSuffix|TestSyncBackupDirWithSuffixKeepExtension|TestSyncConcurrentTruncate|TestSyncSuffix|TestSyncSuffixKeepExtension|TestSyncWithTrackRenames|TestSyncWithTrackRenamesStrategyLeaf|TestSyncWithTrackRenamesStrategyModtime)$'" - Finished ERROR in 22m8.554800992s (try 2/5): exit status 1: Failed [TestServerSideMoveOverSelf TestSyncWithTrackRenames TestSyncWithTrackRenamesStrategyModtime TestSyncWithTrackRenamesStrategyLeaf TestMoveWithDeleteEmptySrcDirs TestServerSideMove TestServerSideMoveWithFilter TestServerSideMoveDeleteEmptySourceDirs TestSyncBackupDir TestSyncBackupDirWithSuffix TestSyncBackupDirWithSuffixKeepExtension TestSyncBackupDirSuffixOnly TestSyncSuffix TestSyncSuffixKeepExtension]