"./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/06/19 05:27:38 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-bujepez7boye" 2025/06/19 05:27:38 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/06/19 05:27:38 DEBUG : proton drive root link ID 'rclone-test-bujepez7boye': Has cached credentials 2025/06/19 05:27:39 DEBUG : proton drive root link ID 'rclone-test-bujepez7boye': Used cached credential to initialize the ProtonDrive API 2025/06/19 05:27:40 DEBUG : Creating backend with remote "/tmp/rclone40743523" === RUN TestServerSideMoveOverSelf run.go:180: Remote "proton drive root link ID 'rclone-test-bujepez7boye'", Local "Local file system at /tmp/rclone40743523", Modify Window "1s" 2025/06/19 05:27:45 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-zeqiloc0heci" 2025/06/19 05:27:45 DEBUG : proton drive root link ID 'rclone-test-zeqiloc0heci': Has cached credentials 2025/06/19 05:27:46 DEBUG : proton drive root link ID 'rclone-test-zeqiloc0heci': Used cached credential to initialize the ProtonDrive API sync_test.go:672: Server side copy (if possible) proton drive root link ID 'rclone-test-bujepez7boye' -> proton drive root link ID 'rclone-test-zeqiloc0heci' 2025/06/19 05:27:48 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/19 05:27:48 DEBUG : proton drive root link ID 'rclone-test-zeqiloc0heci': Waiting for checks to finish 2025/06/19 05:27:48 DEBUG : proton drive root link ID 'rclone-test-zeqiloc0heci': Waiting for transfers to finish 2025/06/19 05:27:52 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2025/06/19 05:27:52 INFO : sub dir/hello world: Copied (new) 2025/06/19 05:27:55.040124 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/06/19 05:27:55.040160 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/06/19 05:27:58 DEBUG : proton drive root link ID 'rclone-test-zeqiloc0heci': Using server-side directory move 2025/06/19 05:27:58 INFO : proton drive root link ID 'rclone-test-zeqiloc0heci': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/06/19 05:27:59 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/06/19 05:27:59 DEBUG : proton drive root link ID 'rclone-test-zeqiloc0heci': Waiting for checks to finish 2025/06/19 05:27:59 DEBUG : proton drive root link ID 'rclone-test-zeqiloc0heci': Waiting for transfers to finish 2025/06/19 05:27:59 INFO : sub dir/hello world: Deleted 2025/06/19 05:28:00.784018 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/B8mjF2MhPjSbs8PeI5b6JN81z2lAdkkopj0J_uL5Xgxb1FHGMiPnglzeDYR7qAtLN4SfnZJ7Ne5hUxBrQtPesQ==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/06/19 05:28:00.784046 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/B8mjF2MhPjSbs8PeI5b6JN81z2lAdkkopj0J_uL5Xgxb1FHGMiPnglzeDYR7qAtLN4SfnZJ7Ne5hUxBrQtPesQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/06/19 05:28:00 ERROR : sub dir/hello world: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/B8mjF2MhPjSbs8PeI5b6JN81z2lAdkkopj0J_uL5Xgxb1FHGMiPnglzeDYR7qAtLN4SfnZJ7Ne5hUxBrQtPesQ==/move: This value should not be blank. (Code=2000, Status=400) sync_test.go:685: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:685 Error: Received unexpected error: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/B8mjF2MhPjSbs8PeI5b6JN81z2lAdkkopj0J_uL5Xgxb1FHGMiPnglzeDYR7qAtLN4SfnZJ7Ne5hUxBrQtPesQ==/move: This value should not be blank. (Code=2000, Status=400) Test: TestServerSideMoveOverSelf 2025/06/19 05:28:00 DEBUG : proton drive root link ID 'rclone-test-zeqiloc0heci': Purge remote --- FAIL: TestServerSideMoveOverSelf (23.58s) === RUN TestSyncWithTrackRenames run.go:180: Remote "proton drive root link ID 'rclone-test-bujepez7boye'", Local "Local file system at /tmp/rclone40743523", Modify Window "1s" sync_test.go:1539: Can track renames: true 2025/06/19 05:28:04 INFO : proton drive root link ID 'rclone-test-bujepez7boye': Making map for --track-renames 2025/06/19 05:28:04 INFO : proton drive root link ID 'rclone-test-bujepez7boye': Finished making map for --track-renames 2025/06/19 05:28:04 DEBUG : proton drive root link ID 'rclone-test-bujepez7boye': Waiting for checks to finish 2025/06/19 05:28:04 DEBUG : proton drive root link ID 'rclone-test-bujepez7boye': Waiting for renames to finish 2025/06/19 05:28:04 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/06/19 05:28:04 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/06/19 05:28:04 DEBUG : proton drive root link ID 'rclone-test-bujepez7boye': Waiting for transfers to finish 2025/06/19 05:28:06 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/06/19 05:28:06 INFO : potato: Copied (new) 2025/06/19 05:28:06 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/06/19 05:28:06 INFO : yam: Copied (new) 2025/06/19 05:28:06 DEBUG : Waiting for deletions to finish 2025/06/19 05:28:07 DEBUG : potato: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/06/19 05:28:07 INFO : proton drive root link ID 'rclone-test-bujepez7boye': Making map for --track-renames 2025/06/19 05:28:07 INFO : proton drive root link ID 'rclone-test-bujepez7boye': Finished making map for --track-renames 2025/06/19 05:28:07 DEBUG : proton drive root link ID 'rclone-test-bujepez7boye': Waiting for checks to finish 2025/06/19 05:28:07 DEBUG : potato: Unchanged skipping 2025/06/19 05:28:07 DEBUG : proton drive root link ID 'rclone-test-bujepez7boye': Waiting for renames to finish 2025/06/19 05:28:08.564586 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/jlATto4Wkbb-kCwpPYgAjyGU3lWpAHMGuWG9fAD_oV-PS3qWgS5bJ1NFbsOEILEnwNh5NDRzhfp2TUxj8-tbcw==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/06/19 05:28:08.564639 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/jlATto4Wkbb-kCwpPYgAjyGU3lWpAHMGuWG9fAD_oV-PS3qWgS5bJ1NFbsOEILEnwNh5NDRzhfp2TUxj8-tbcw==/move: This value should not be blank. (Code=2000, Status=400) 2025/06/19 05:28:08 ERROR : yam: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/jlATto4Wkbb-kCwpPYgAjyGU3lWpAHMGuWG9fAD_oV-PS3qWgS5bJ1NFbsOEILEnwNh5NDRzhfp2TUxj8-tbcw==/move: This value should not be blank. (Code=2000, Status=400) 2025/06/19 05:28:08 DEBUG : yaml: Failed to rename to "yam": 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/jlATto4Wkbb-kCwpPYgAjyGU3lWpAHMGuWG9fAD_oV-PS3qWgS5bJ1NFbsOEILEnwNh5NDRzhfp2TUxj8-tbcw==/move: This value should not be blank. (Code=2000, Status=400) 2025/06/19 05:28:08 DEBUG : yaml: Need to transfer - No matching file found at Destination 2025/06/19 05:28:08 DEBUG : proton drive root link ID 'rclone-test-bujepez7boye': Waiting for transfers to finish 2025/06/19 05:28:10 DEBUG : yaml: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/06/19 05:28:10 INFO : yaml: Copied (new) 2025/06/19 05:28:10 ERROR : proton drive root link ID 'rclone-test-bujepez7boye': not deleting files as there were IO errors 2025/06/19 05:28:10 ERROR : proton drive root link ID 'rclone-test-bujepez7boye': not deleting directories as there were IO errors sync_test.go:1557: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1557 Error: Received unexpected error: not deleting files as there were IO errors Test: TestSyncWithTrackRenames --- FAIL: TestSyncWithTrackRenames (9.43s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:180: Remote "proton drive root link ID 'rclone-test-bujepez7boye'", Local "Local file system at /tmp/rclone40743523", Modify Window "1s" sync_test.go:1611: Can track renames: true 2025/06/19 05:28:13 INFO : proton drive root link ID 'rclone-test-bujepez7boye': Making map for --track-renames 2025/06/19 05:28:13 INFO : proton drive root link ID 'rclone-test-bujepez7boye': Finished making map for --track-renames 2025/06/19 05:28:13 DEBUG : proton drive root link ID 'rclone-test-bujepez7boye': Waiting for checks to finish 2025/06/19 05:28:13 DEBUG : proton drive root link ID 'rclone-test-bujepez7boye': Waiting for renames to finish 2025/06/19 05:28:13 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/06/19 05:28:13 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/06/19 05:28:13 DEBUG : proton drive root link ID 'rclone-test-bujepez7boye': Waiting for transfers to finish 2025/06/19 05:28:15 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/06/19 05:28:15 INFO : yam: Copied (new) 2025/06/19 05:28:15 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/06/19 05:28:15 INFO : potato: Copied (new) 2025/06/19 05:28:15 DEBUG : Waiting for deletions to finish 2025/06/19 05:28:17 DEBUG : potato: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/06/19 05:28:17 INFO : proton drive root link ID 'rclone-test-bujepez7boye': Making map for --track-renames 2025/06/19 05:28:17 INFO : proton drive root link ID 'rclone-test-bujepez7boye': Finished making map for --track-renames 2025/06/19 05:28:17 DEBUG : proton drive root link ID 'rclone-test-bujepez7boye': Waiting for checks to finish 2025/06/19 05:28:17 DEBUG : potato: Unchanged skipping 2025/06/19 05:28:17 DEBUG : proton drive root link ID 'rclone-test-bujepez7boye': Waiting for renames to finish 2025/06/19 05:28:18.113801 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/X1VXvVR-ZijWcwSuVXdoJ_W137eLNRVUjNj0iiIXxYmyX0vX8vV2dMPD1FvajZ3sa9LKiHM5hfv-S2eLKSB8cg==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/06/19 05:28:18.113837 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/X1VXvVR-ZijWcwSuVXdoJ_W137eLNRVUjNj0iiIXxYmyX0vX8vV2dMPD1FvajZ3sa9LKiHM5hfv-S2eLKSB8cg==/move: This value should not be blank. (Code=2000, Status=400) 2025/06/19 05:28:18 ERROR : yam: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/X1VXvVR-ZijWcwSuVXdoJ_W137eLNRVUjNj0iiIXxYmyX0vX8vV2dMPD1FvajZ3sa9LKiHM5hfv-S2eLKSB8cg==/move: This value should not be blank. (Code=2000, Status=400) 2025/06/19 05:28:18 DEBUG : yaml: Failed to rename to "yam": 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/X1VXvVR-ZijWcwSuVXdoJ_W137eLNRVUjNj0iiIXxYmyX0vX8vV2dMPD1FvajZ3sa9LKiHM5hfv-S2eLKSB8cg==/move: This value should not be blank. (Code=2000, Status=400) 2025/06/19 05:28:18 DEBUG : yaml: Need to transfer - No matching file found at Destination 2025/06/19 05:28:18 DEBUG : proton drive root link ID 'rclone-test-bujepez7boye': Waiting for transfers to finish 2025/06/19 05:28:20 DEBUG : yaml: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/06/19 05:28:20 INFO : yaml: Copied (new) 2025/06/19 05:28:20 ERROR : proton drive root link ID 'rclone-test-bujepez7boye': not deleting files as there were IO errors 2025/06/19 05:28:20 ERROR : proton drive root link ID 'rclone-test-bujepez7boye': not deleting directories as there were IO errors sync_test.go:1629: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1629 Error: Received unexpected error: not deleting files as there were IO errors Test: TestSyncWithTrackRenamesStrategyModtime --- FAIL: TestSyncWithTrackRenamesStrategyModtime (9.56s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:180: Remote "proton drive root link ID 'rclone-test-bujepez7boye'", Local "Local file system at /tmp/rclone40743523", Modify Window "1s" sync_test.go:1650: Can track renames: true 2025/06/19 05:28:23 INFO : proton drive root link ID 'rclone-test-bujepez7boye': Making map for --track-renames 2025/06/19 05:28:23 INFO : proton drive root link ID 'rclone-test-bujepez7boye': Finished making map for --track-renames 2025/06/19 05:28:23 DEBUG : proton drive root link ID 'rclone-test-bujepez7boye': Waiting for checks to finish 2025/06/19 05:28:23 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/06/19 05:28:23 DEBUG : proton drive root link ID 'rclone-test-bujepez7boye': Waiting for renames to finish 2025/06/19 05:28:23 DEBUG : sub/yam: Need to transfer - No matching file found at Destination 2025/06/19 05:28:23 DEBUG : proton drive root link ID 'rclone-test-bujepez7boye': Waiting for transfers to finish 2025/06/19 05:28:25 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/06/19 05:28:25 INFO : potato: Copied (new) 2025/06/19 05:28:26 DEBUG : sub/yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/06/19 05:28:26 INFO : sub/yam: Copied (new) 2025/06/19 05:28:26 DEBUG : Waiting for deletions to finish 2025/06/19 05:28:27 DEBUG : potato: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/06/19 05:28:27 DEBUG : potato: Unchanged skipping 2025/06/19 05:28:28 INFO : proton drive root link ID 'rclone-test-bujepez7boye': Making map for --track-renames 2025/06/19 05:28:28 INFO : proton drive root link ID 'rclone-test-bujepez7boye': Finished making map for --track-renames 2025/06/19 05:28:28 DEBUG : proton drive root link ID 'rclone-test-bujepez7boye': Waiting for checks to finish 2025/06/19 05:28:28 DEBUG : proton drive root link ID 'rclone-test-bujepez7boye': Waiting for renames to finish 2025/06/19 05:28:29.206429 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/vU7dsGdYgg9Zsa91c-H2pZ9iXKOYlX9uGfmuhrPhUoWrw0mOiux88ldPAobc_eU9UyTxhjsLjdOJB59cihUeBw==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/06/19 05:28:29.206474 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/vU7dsGdYgg9Zsa91c-H2pZ9iXKOYlX9uGfmuhrPhUoWrw0mOiux88ldPAobc_eU9UyTxhjsLjdOJB59cihUeBw==/move: This value should not be blank. (Code=2000, Status=400) 2025/06/19 05:28:29 ERROR : sub/yam: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/vU7dsGdYgg9Zsa91c-H2pZ9iXKOYlX9uGfmuhrPhUoWrw0mOiux88ldPAobc_eU9UyTxhjsLjdOJB59cihUeBw==/move: This value should not be blank. (Code=2000, Status=400) 2025/06/19 05:28:29 DEBUG : yam: Failed to rename to "sub/yam": 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/vU7dsGdYgg9Zsa91c-H2pZ9iXKOYlX9uGfmuhrPhUoWrw0mOiux88ldPAobc_eU9UyTxhjsLjdOJB59cihUeBw==/move: This value should not be blank. (Code=2000, Status=400) 2025/06/19 05:28:29 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/06/19 05:28:29 DEBUG : proton drive root link ID 'rclone-test-bujepez7boye': Waiting for transfers to finish 2025/06/19 05:28:31 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/06/19 05:28:31 INFO : yam: Copied (new) 2025/06/19 05:28:31 ERROR : proton drive root link ID 'rclone-test-bujepez7boye': not deleting files as there were IO errors 2025/06/19 05:28:31 ERROR : proton drive root link ID 'rclone-test-bujepez7boye': not deleting directories as there were IO errors sync_test.go:1668: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1668 Error: Received unexpected error: not deleting files as there were IO errors Test: TestSyncWithTrackRenamesStrategyLeaf --- FAIL: TestSyncWithTrackRenamesStrategyLeaf (12.07s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:180: Remote "proton drive root link ID 'rclone-test-bujepez7boye'", Local "Local file system at /tmp/rclone40743523", Modify Window "1s" 2025/06/19 05:28:35 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/19 05:28:35 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2025/06/19 05:28:35 DEBUG : proton drive root link ID 'rclone-test-bujepez7boye': Waiting for checks to finish 2025/06/19 05:28:35 DEBUG : proton drive root link ID 'rclone-test-bujepez7boye': Waiting for transfers to finish 2025/06/19 05:28:40 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2025/06/19 05:28:40 INFO : sub dir/hello world: Copied (new) 2025/06/19 05:28:40 INFO : sub dir/hello world: Deleted 2025/06/19 05:28:40 DEBUG : nested/sub dir/file: sha1 = b4b3e0a278988bc15f2913af3f4153ccef74e465 OK 2025/06/19 05:28:40 INFO : nested/sub dir/file: Copied (new) 2025/06/19 05:28:40 INFO : nested/sub dir/file: Deleted 2025/06/19 05:28:40 ERROR : Local file system at /tmp/rclone40743523: not deleting directories as there were IO errors sync_test.go:1789: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1789 Error: Received unexpected error: not deleting directories as there were IO errors Test: TestMoveWithDeleteEmptySrcDirs --- FAIL: TestMoveWithDeleteEmptySrcDirs (9.70s) === RUN TestServerSideMove run.go:180: Remote "proton drive root link ID 'rclone-test-bujepez7boye'", Local "Local file system at /tmp/rclone40743523", Modify Window "1s" 2025/06/19 05:28:44 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-hubotoz8cire" 2025/06/19 05:28:44 DEBUG : proton drive root link ID 'rclone-test-hubotoz8cire': Has cached credentials 2025/06/19 05:28:46 DEBUG : proton drive root link ID 'rclone-test-hubotoz8cire': Used cached credential to initialize the ProtonDrive API sync_test.go:1706: Server side move (if possible) proton drive root link ID 'rclone-test-bujepez7boye' -> proton drive root link ID 'rclone-test-hubotoz8cire' 2025/06/19 05:28:59 DEBUG : proton drive root link ID 'rclone-test-hubotoz8cire': Using server-side directory move 2025/06/19 05:28:59 INFO : proton drive root link ID 'rclone-test-hubotoz8cire': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/06/19 05:29:00 DEBUG : potato2: Need to transfer - File not found at Destination 2025/06/19 05:29:00 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1s) 2025/06/19 05:29:00 DEBUG : empty space: Unchanged skipping 2025/06/19 05:29:00 DEBUG : proton drive root link ID 'rclone-test-hubotoz8cire': Waiting for checks to finish 2025/06/19 05:29:00 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/06/19 05:29:00 INFO : potato3: Deleted 2025/06/19 05:29:00 INFO : empty space: Deleted 2025/06/19 05:29:00 DEBUG : proton drive root link ID 'rclone-test-hubotoz8cire': Waiting for transfers to finish 2025/06/19 05:29:01.007871 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/QYhIZqEWAiu9tr-wtrYasx7YvO8bb2CThzsaEesqyrezVvKpAMdTctd3Fw9IbBMzPnKfWCkcAfONGNAQe4buQA==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/06/19 05:29:01.007972 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/QYhIZqEWAiu9tr-wtrYasx7YvO8bb2CThzsaEesqyrezVvKpAMdTctd3Fw9IbBMzPnKfWCkcAfONGNAQe4buQA==/move: This value should not be blank. (Code=2000, Status=400) 2025/06/19 05:29:01 ERROR : potato2: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/QYhIZqEWAiu9tr-wtrYasx7YvO8bb2CThzsaEesqyrezVvKpAMdTctd3Fw9IbBMzPnKfWCkcAfONGNAQe4buQA==/move: This value should not be blank. (Code=2000, Status=400) 2025/06/19 05:29:01.225778 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/qZWhwEtHCN7kHup9j2n3f9EhNk9BFIxG1zU553_DopCs8HjPH9bzwF_uT73AxFacnuBjLlSGCxLRP1wsUwVjdA==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/06/19 05:29:01.225808 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/qZWhwEtHCN7kHup9j2n3f9EhNk9BFIxG1zU553_DopCs8HjPH9bzwF_uT73AxFacnuBjLlSGCxLRP1wsUwVjdA==/move: This value should not be blank. (Code=2000, Status=400) 2025/06/19 05:29:01 ERROR : potato3: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/qZWhwEtHCN7kHup9j2n3f9EhNk9BFIxG1zU553_DopCs8HjPH9bzwF_uT73AxFacnuBjLlSGCxLRP1wsUwVjdA==/move: This value should not be blank. (Code=2000, Status=400) sync_test.go:1717: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1717 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1882 Error: Received unexpected error: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/qZWhwEtHCN7kHup9j2n3f9EhNk9BFIxG1zU553_DopCs8HjPH9bzwF_uT73AxFacnuBjLlSGCxLRP1wsUwVjdA==/move: This value should not be blank. (Code=2000, Status=400) Test: TestServerSideMove 2025/06/19 05:29:01 DEBUG : proton drive root link ID 'rclone-test-hubotoz8cire': Purge remote --- FAIL: TestServerSideMove (19.50s) === RUN TestServerSideMoveWithFilter run.go:180: Remote "proton drive root link ID 'rclone-test-bujepez7boye'", Local "Local file system at /tmp/rclone40743523", Modify Window "1s" 2025/06/19 05:29:04 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-jidulaz5bani" 2025/06/19 05:29:04 DEBUG : proton drive root link ID 'rclone-test-jidulaz5bani': Has cached credentials 2025/06/19 05:29:05 DEBUG : proton drive root link ID 'rclone-test-jidulaz5bani': Used cached credential to initialize the ProtonDrive API sync_test.go:1706: Server side move (if possible) proton drive root link ID 'rclone-test-bujepez7boye' -> proton drive root link ID 'rclone-test-jidulaz5bani' 2025/06/19 05:29:19 DEBUG : empty space: Excluded (Size Filter) 2025/06/19 05:29:19 DEBUG : empty space: Excluded 2025/06/19 05:29:20 DEBUG : empty space: Excluded (Size Filter) 2025/06/19 05:29:20 DEBUG : empty space: Excluded 2025/06/19 05:29:20 DEBUG : potato2: Need to transfer - File not found at Destination 2025/06/19 05:29:20 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/06/19 05:29:20 DEBUG : proton drive root link ID 'rclone-test-jidulaz5bani': Waiting for checks to finish 2025/06/19 05:29:20 DEBUG : proton drive root link ID 'rclone-test-jidulaz5bani': Waiting for transfers to finish 2025/06/19 05:29:21 INFO : potato3: Deleted 2025/06/19 05:29:21.346700 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/TQP6A6mh7PSDzcr8HAQwLHuzGD35oax0ctDVAiX8xzwRBsyuwOm-VpRASVTGDRSzIUg3BR5p5gkipdUU8L7Hag==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/06/19 05:29:21.346748 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/TQP6A6mh7PSDzcr8HAQwLHuzGD35oax0ctDVAiX8xzwRBsyuwOm-VpRASVTGDRSzIUg3BR5p5gkipdUU8L7Hag==/move: This value should not be blank. (Code=2000, Status=400) 2025/06/19 05:29:21 ERROR : potato2: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/TQP6A6mh7PSDzcr8HAQwLHuzGD35oax0ctDVAiX8xzwRBsyuwOm-VpRASVTGDRSzIUg3BR5p5gkipdUU8L7Hag==/move: This value should not be blank. (Code=2000, Status=400) 2025/06/19 05:29:21.622856 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/oOT5uLTxUHLyg3VHnvTPHYKc3rR7U-95fgJrSQoOFtR7JvQP7vaGlkeT-Lpm5eQC-r0hcJcoRpDBwF9uxL4IKA==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/06/19 05:29:21.622878 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/oOT5uLTxUHLyg3VHnvTPHYKc3rR7U-95fgJrSQoOFtR7JvQP7vaGlkeT-Lpm5eQC-r0hcJcoRpDBwF9uxL4IKA==/move: This value should not be blank. (Code=2000, Status=400) 2025/06/19 05:29:21 ERROR : potato3: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/oOT5uLTxUHLyg3VHnvTPHYKc3rR7U-95fgJrSQoOFtR7JvQP7vaGlkeT-Lpm5eQC-r0hcJcoRpDBwF9uxL4IKA==/move: This value should not be blank. (Code=2000, Status=400) sync_test.go:1717: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1717 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1895 Error: Received unexpected error: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/oOT5uLTxUHLyg3VHnvTPHYKc3rR7U-95fgJrSQoOFtR7JvQP7vaGlkeT-Lpm5eQC-r0hcJcoRpDBwF9uxL4IKA==/move: This value should not be blank. (Code=2000, Status=400) Test: TestServerSideMoveWithFilter 2025/06/19 05:29:21 DEBUG : proton drive root link ID 'rclone-test-jidulaz5bani': Purge remote --- FAIL: TestServerSideMoveWithFilter (20.98s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:180: Remote "proton drive root link ID 'rclone-test-bujepez7boye'", Local "Local file system at /tmp/rclone40743523", Modify Window "1s" 2025/06/19 05:29:25 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-setaxow8bado" 2025/06/19 05:29:25 DEBUG : proton drive root link ID 'rclone-test-setaxow8bado': Has cached credentials 2025/06/19 05:29:27 DEBUG : proton drive root link ID 'rclone-test-setaxow8bado': Used cached credential to initialize the ProtonDrive API 2025/06/19 05:29:33 INFO : tomatoDir: Making directory sync_test.go:1706: Server side move (if possible) proton drive root link ID 'rclone-test-bujepez7boye' -> proton drive root link ID 'rclone-test-setaxow8bado' 2025/06/19 05:29:41 DEBUG : proton drive root link ID 'rclone-test-setaxow8bado': Using server-side directory move 2025/06/19 05:29:41 INFO : proton drive root link ID 'rclone-test-setaxow8bado': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/06/19 05:29:42 DEBUG : potato2: Need to transfer - File not found at Destination 2025/06/19 05:29:42 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1s) 2025/06/19 05:29:42 DEBUG : empty space: Unchanged skipping 2025/06/19 05:29:42 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/06/19 05:29:42 DEBUG : proton drive root link ID 'rclone-test-setaxow8bado': Waiting for checks to finish 2025/06/19 05:29:42 INFO : potato3: Deleted 2025/06/19 05:29:42 INFO : empty space: Deleted 2025/06/19 05:29:42 DEBUG : proton drive root link ID 'rclone-test-setaxow8bado': Waiting for transfers to finish 2025/06/19 05:29:43.071183 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/16bF6IafH-WhCDUU9HpCe1ZWTar4bleKxmJScufajKsYzrC7FhiSGdkpTAp58Jltrxtv5l428XIf_qAVJbsaMA==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/06/19 05:29:43.071219 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/16bF6IafH-WhCDUU9HpCe1ZWTar4bleKxmJScufajKsYzrC7FhiSGdkpTAp58Jltrxtv5l428XIf_qAVJbsaMA==/move: This value should not be blank. (Code=2000, Status=400) 2025/06/19 05:29:43 ERROR : potato2: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/16bF6IafH-WhCDUU9HpCe1ZWTar4bleKxmJScufajKsYzrC7FhiSGdkpTAp58Jltrxtv5l428XIf_qAVJbsaMA==/move: This value should not be blank. (Code=2000, Status=400) 2025/06/19 05:29:43.372341 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/NN116cFWU7YPrFxuGNQTSMVP-1IGMBemA3dokDl46mRuBOXHh4C77NcTJJqVmfxPsLEzOa7ocb9hllFL9k-d8w==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/06/19 05:29:43.372362 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/NN116cFWU7YPrFxuGNQTSMVP-1IGMBemA3dokDl46mRuBOXHh4C77NcTJJqVmfxPsLEzOa7ocb9hllFL9k-d8w==/move: This value should not be blank. (Code=2000, Status=400) 2025/06/19 05:29:43 ERROR : potato3: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/NN116cFWU7YPrFxuGNQTSMVP-1IGMBemA3dokDl46mRuBOXHh4C77NcTJJqVmfxPsLEzOa7ocb9hllFL9k-d8w==/move: This value should not be blank. (Code=2000, Status=400) 2025/06/19 05:29:43 ERROR : proton drive root link ID 'rclone-test-bujepez7boye': not deleting directories as there were IO errors sync_test.go:1717: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1717 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1902 Error: Received unexpected error: not deleting directories as there were IO errors Test: TestServerSideMoveDeleteEmptySourceDirs 2025/06/19 05:29:43 DEBUG : proton drive root link ID 'rclone-test-setaxow8bado': Purge remote --- FAIL: TestServerSideMoveDeleteEmptySourceDirs (22.05s) === RUN TestSyncBackupDir run.go:180: Remote "proton drive root link ID 'rclone-test-bujepez7boye'", Local "Local file system at /tmp/rclone40743523", Modify Window "1s" 2025/06/19 05:29:55 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-bujepez7boye/dst" 2025/06/19 05:29:55 DEBUG : proton drive root link ID 'rclone-test-bujepez7boye/dst': Has cached credentials 2025/06/19 05:29:56 DEBUG : proton drive root link ID 'rclone-test-bujepez7boye/dst': Used cached credential to initialize the ProtonDrive API 2025/06/19 05:31:11 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-bujepez7boye/backup" 2025/06/19 05:31:11 DEBUG : proton drive root link ID 'rclone-test-bujepez7boye/backup': Has cached credentials 2025/06/19 05:31:12 DEBUG : proton drive root link ID 'rclone-test-bujepez7boye/backup': Used cached credential to initialize the ProtonDrive API 2025/06/19 05:33:41 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/06/19 05:33:41 DEBUG : proton drive root link ID 'rclone-test-bujepez7boye/dst': Waiting for checks to finish 2025/06/19 05:33:41 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/06/19 05:33:41 DEBUG : two: Unchanged skipping 2025/06/19 05:33:43.411987 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/G_Ol6p3Bzvl8Yky_X0omLKQYv6n9g4GuDGAVU04_ahZbdTCTSg9MH5LVPx1dBiMwwjbmH9fzoN-9_A7kKEmAvA==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/06/19 05:33:43.412007 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/G_Ol6p3Bzvl8Yky_X0omLKQYv6n9g4GuDGAVU04_ahZbdTCTSg9MH5LVPx1dBiMwwjbmH9fzoN-9_A7kKEmAvA==/move: This value should not be blank. (Code=2000, Status=400) 2025/06/19 05:33:43 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/G_Ol6p3Bzvl8Yky_X0omLKQYv6n9g4GuDGAVU04_ahZbdTCTSg9MH5LVPx1dBiMwwjbmH9fzoN-9_A7kKEmAvA==/move: This value should not be blank. (Code=2000, Status=400) 2025/06/19 05:33:43 DEBUG : proton drive root link ID 'rclone-test-bujepez7boye/dst': Waiting for transfers to finish 2025/06/19 05:33:43 ERROR : proton drive root link ID 'rclone-test-bujepez7boye/dst': not deleting files as there were IO errors 2025/06/19 05:33:43 ERROR : proton drive root link ID 'rclone-test-bujepez7boye/dst': not deleting directories as there were IO errors sync_test.go:2386: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2386 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2428 Error: Received unexpected error: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/G_Ol6p3Bzvl8Yky_X0omLKQYv6n9g4GuDGAVU04_ahZbdTCTSg9MH5LVPx1dBiMwwjbmH9fzoN-9_A7kKEmAvA==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncBackupDir --- FAIL: TestSyncBackupDir (240.77s) === RUN TestSyncBackupDirWithSuffix run.go:180: Remote "proton drive root link ID 'rclone-test-bujepez7boye'", Local "Local file system at /tmp/rclone40743523", Modify Window "1s" 2025/06/19 05:33:56 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-bujepez7boye/dst" 2025/06/19 05:33:56 DEBUG : proton drive root link ID 'rclone-test-bujepez7boye/dst': Has cached credentials 2025/06/19 05:33:57 DEBUG : proton drive root link ID 'rclone-test-bujepez7boye/dst': Used cached credential to initialize the ProtonDrive API 2025/06/19 05:35:13 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-bujepez7boye/backup" 2025/06/19 05:35:13 DEBUG : Config file has changed externally - reloading 2025/06/19 05:35:13 DEBUG : proton drive root link ID 'rclone-test-bujepez7boye/backup': Has cached credentials 2025/06/19 05:35:15 DEBUG : proton drive root link ID 'rclone-test-bujepez7boye/backup': Used cached credential to initialize the ProtonDrive API 2025/06/19 05:37:44 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/06/19 05:37:44 DEBUG : two: Unchanged skipping 2025/06/19 05:37:44 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/06/19 05:37:44 DEBUG : proton drive root link ID 'rclone-test-bujepez7boye/dst': Waiting for checks to finish 2025/06/19 05:37:46.936590 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/vqN-vnk1G4C4Gn8iVhFyRNFFdwdYifyL2thT0aArW9QexdQ26LiAZjHtZkSS6f-vr9N20VNUfI4NpjnmTI-GYw==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/06/19 05:37:46.936631 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/vqN-vnk1G4C4Gn8iVhFyRNFFdwdYifyL2thT0aArW9QexdQ26LiAZjHtZkSS6f-vr9N20VNUfI4NpjnmTI-GYw==/move: This value should not be blank. (Code=2000, Status=400) 2025/06/19 05:37:46 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/vqN-vnk1G4C4Gn8iVhFyRNFFdwdYifyL2thT0aArW9QexdQ26LiAZjHtZkSS6f-vr9N20VNUfI4NpjnmTI-GYw==/move: This value should not be blank. (Code=2000, Status=400) 2025/06/19 05:37:46 DEBUG : proton drive root link ID 'rclone-test-bujepez7boye/dst': Waiting for transfers to finish 2025/06/19 05:37:46 ERROR : proton drive root link ID 'rclone-test-bujepez7boye/dst': not deleting files as there were IO errors 2025/06/19 05:37:46 ERROR : proton drive root link ID 'rclone-test-bujepez7boye/dst': not deleting directories as there were IO errors sync_test.go:2386: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2386 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2432 Error: Received unexpected error: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/vqN-vnk1G4C4Gn8iVhFyRNFFdwdYifyL2thT0aArW9QexdQ26LiAZjHtZkSS6f-vr9N20VNUfI4NpjnmTI-GYw==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncBackupDirWithSuffix --- FAIL: TestSyncBackupDirWithSuffix (243.40s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:180: Remote "proton drive root link ID 'rclone-test-bujepez7boye'", Local "Local file system at /tmp/rclone40743523", Modify Window "1s" 2025/06/19 05:37:59 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-bujepez7boye/dst" 2025/06/19 05:37:59 DEBUG : proton drive root link ID 'rclone-test-bujepez7boye/dst': Has cached credentials 2025/06/19 05:38:01 DEBUG : proton drive root link ID 'rclone-test-bujepez7boye/dst': Used cached credential to initialize the ProtonDrive API 2025/06/19 05:39:15 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-bujepez7boye/backup" 2025/06/19 05:39:15 DEBUG : Config file has changed externally - reloading 2025/06/19 05:39:15 DEBUG : proton drive root link ID 'rclone-test-bujepez7boye/backup': Has cached credentials 2025/06/19 05:39:17 DEBUG : proton drive root link ID 'rclone-test-bujepez7boye/backup': Used cached credential to initialize the ProtonDrive API 2025/06/19 05:41:45 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/06/19 05:41:45 DEBUG : proton drive root link ID 'rclone-test-bujepez7boye/dst': Waiting for checks to finish 2025/06/19 05:41:45 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/06/19 05:41:45 DEBUG : two: Unchanged skipping 2025/06/19 05:41:48.091509 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/POaZWKkm-0zHKu41JQ14jR08GwwLP2c-3Z1_Z-Ts6w4vwVhfgpvM5jWvwTTtPyW3vG3g3S8vZ3GBtARUXkjDAA==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/06/19 05:41:48.091539 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/POaZWKkm-0zHKu41JQ14jR08GwwLP2c-3Z1_Z-Ts6w4vwVhfgpvM5jWvwTTtPyW3vG3g3S8vZ3GBtARUXkjDAA==/move: This value should not be blank. (Code=2000, Status=400) 2025/06/19 05:41:48 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/POaZWKkm-0zHKu41JQ14jR08GwwLP2c-3Z1_Z-Ts6w4vwVhfgpvM5jWvwTTtPyW3vG3g3S8vZ3GBtARUXkjDAA==/move: This value should not be blank. (Code=2000, Status=400) 2025/06/19 05:41:48 DEBUG : proton drive root link ID 'rclone-test-bujepez7boye/dst': Waiting for transfers to finish 2025/06/19 05:41:48 ERROR : proton drive root link ID 'rclone-test-bujepez7boye/dst': not deleting files as there were IO errors 2025/06/19 05:41:48 ERROR : proton drive root link ID 'rclone-test-bujepez7boye/dst': not deleting directories as there were IO errors sync_test.go:2386: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2386 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2436 Error: Received unexpected error: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/POaZWKkm-0zHKu41JQ14jR08GwwLP2c-3Z1_Z-Ts6w4vwVhfgpvM5jWvwTTtPyW3vG3g3S8vZ3GBtARUXkjDAA==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncBackupDirWithSuffixKeepExtension --- FAIL: TestSyncBackupDirWithSuffixKeepExtension (241.35s) === RUN TestSyncBackupDirSuffixOnly run.go:180: Remote "proton drive root link ID 'rclone-test-bujepez7boye'", Local "Local file system at /tmp/rclone40743523", Modify Window "1s" 2025/06/19 05:42:00 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-bujepez7boye/dst" 2025/06/19 05:42:00 DEBUG : proton drive root link ID 'rclone-test-bujepez7boye/dst': Has cached credentials 2025/06/19 05:42:02 DEBUG : proton drive root link ID 'rclone-test-bujepez7boye/dst': Used cached credential to initialize the ProtonDrive API 2025/06/19 05:43:18 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/06/19 05:43:18 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/06/19 05:43:18 DEBUG : two: Unchanged skipping 2025/06/19 05:43:18 DEBUG : proton drive root link ID 'rclone-test-bujepez7boye/dst': Waiting for checks to finish 2025/06/19 05:43:19.081171 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/RLM_qmjYlfYbZPbruhBIORYAO1Ywf36hT9KbYna8g_qXbMcNE_X_CsjqoT1-CoeNmYuWoqqOcCS-QbuJIolPww==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/06/19 05:43:19.081209 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/RLM_qmjYlfYbZPbruhBIORYAO1Ywf36hT9KbYna8g_qXbMcNE_X_CsjqoT1-CoeNmYuWoqqOcCS-QbuJIolPww==/move: This value should not be blank. (Code=2000, Status=400) 2025/06/19 05:43:19 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/RLM_qmjYlfYbZPbruhBIORYAO1Ywf36hT9KbYna8g_qXbMcNE_X_CsjqoT1-CoeNmYuWoqqOcCS-QbuJIolPww==/move: This value should not be blank. (Code=2000, Status=400) 2025/06/19 05:43:19 DEBUG : proton drive root link ID 'rclone-test-bujepez7boye/dst': Waiting for transfers to finish 2025/06/19 05:43:19 ERROR : proton drive root link ID 'rclone-test-bujepez7boye/dst': not deleting files as there were IO errors 2025/06/19 05:43:19 ERROR : proton drive root link ID 'rclone-test-bujepez7boye/dst': not deleting directories as there were IO errors sync_test.go:2386: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2386 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2440 Error: Received unexpected error: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/RLM_qmjYlfYbZPbruhBIORYAO1Ywf36hT9KbYna8g_qXbMcNE_X_CsjqoT1-CoeNmYuWoqqOcCS-QbuJIolPww==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncBackupDirSuffixOnly --- FAIL: TestSyncBackupDirSuffixOnly (90.22s) === RUN TestSyncSuffix run.go:180: Remote "proton drive root link ID 'rclone-test-bujepez7boye'", Local "Local file system at /tmp/rclone40743523", Modify Window "1s" 2025/06/19 05:43:31 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-bujepez7boye/dst" 2025/06/19 05:43:31 DEBUG : proton drive root link ID 'rclone-test-bujepez7boye/dst': Has cached credentials 2025/06/19 05:43:32 DEBUG : proton drive root link ID 'rclone-test-bujepez7boye/dst': Used cached credential to initialize the ProtonDrive API 2025/06/19 05:44:48 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/06/19 05:44:49.831204 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/7E3m5okSaXHCp4ewow6Ip4N8IJzOiPB3ThbYFn_8oCh0-5_16Lo2_9mqE-SgpxwfLbllJIUldIaQCfuxagZrcg==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/06/19 05:44:49.831240 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/7E3m5okSaXHCp4ewow6Ip4N8IJzOiPB3ThbYFn_8oCh0-5_16Lo2_9mqE-SgpxwfLbllJIUldIaQCfuxagZrcg==/move: This value should not be blank. (Code=2000, Status=400) 2025/06/19 05:44:49 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/7E3m5okSaXHCp4ewow6Ip4N8IJzOiPB3ThbYFn_8oCh0-5_16Lo2_9mqE-SgpxwfLbllJIUldIaQCfuxagZrcg==/move: This value should not be blank. (Code=2000, Status=400) sync_test.go:2474: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2474 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2524 Error: Received unexpected error: moving to --backup-dir failed: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/7E3m5okSaXHCp4ewow6Ip4N8IJzOiPB3ThbYFn_8oCh0-5_16Lo2_9mqE-SgpxwfLbllJIUldIaQCfuxagZrcg==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncSuffix --- FAIL: TestSyncSuffix (90.55s) === RUN TestSyncSuffixKeepExtension run.go:180: Remote "proton drive root link ID 'rclone-test-bujepez7boye'", Local "Local file system at /tmp/rclone40743523", Modify Window "1s" 2025/06/19 05:45:01 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-bujepez7boye/dst" 2025/06/19 05:45:01 DEBUG : Config file has changed externally - reloading 2025/06/19 05:45:01 DEBUG : proton drive root link ID 'rclone-test-bujepez7boye/dst': Has cached credentials 2025/06/19 05:45:02 DEBUG : proton drive root link ID 'rclone-test-bujepez7boye/dst': Used cached credential to initialize the ProtonDrive API 2025/06/19 05:46:19 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/06/19 05:46:19.928155 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/YQC15G1xdtARVMz3-qkBJ0McbSWJzsnLtTH9Fwp0qLXMAapYhXm1RVTP1uFCYMEI7L4s8unVkY2wljo1jfloLg==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/06/19 05:46:19.928188 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/YQC15G1xdtARVMz3-qkBJ0McbSWJzsnLtTH9Fwp0qLXMAapYhXm1RVTP1uFCYMEI7L4s8unVkY2wljo1jfloLg==/move: This value should not be blank. (Code=2000, Status=400) 2025/06/19 05:46:19 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/YQC15G1xdtARVMz3-qkBJ0McbSWJzsnLtTH9Fwp0qLXMAapYhXm1RVTP1uFCYMEI7L4s8unVkY2wljo1jfloLg==/move: This value should not be blank. (Code=2000, Status=400) sync_test.go:2474: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2474 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2525 Error: Received unexpected error: moving to --backup-dir failed: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/YQC15G1xdtARVMz3-qkBJ0McbSWJzsnLtTH9Fwp0qLXMAapYhXm1RVTP1uFCYMEI7L4s8unVkY2wljo1jfloLg==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncSuffixKeepExtension --- FAIL: TestSyncSuffixKeepExtension (90.27s) FAIL 2025/06/19 05:46:23 DEBUG : proton drive root link ID 'rclone-test-bujepez7boye': 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 18m46.433890288s (try 2/5): exit status 1: Failed [TestServerSideMoveOverSelf TestSyncWithTrackRenames TestSyncWithTrackRenamesStrategyModtime TestSyncWithTrackRenamesStrategyLeaf TestMoveWithDeleteEmptySrcDirs TestServerSideMove TestServerSideMoveWithFilter TestServerSideMoveDeleteEmptySourceDirs TestSyncBackupDir TestSyncBackupDirWithSuffix TestSyncBackupDirWithSuffixKeepExtension TestSyncBackupDirSuffixOnly TestSyncSuffix TestSyncSuffixKeepExtension]