"./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/04/29 06:51:57 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-viponer6mexo" 2025/04/29 06:51:57 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/04/29 06:51:57 DEBUG : proton drive root link ID 'rclone-test-viponer6mexo': Has cached credentials 2025/04/29 06:51:58 DEBUG : proton drive root link ID 'rclone-test-viponer6mexo': Used cached credential to initialize the ProtonDrive API 2025/04/29 06:51:58 DEBUG : Creating backend with remote "/tmp/rclone1855972779" === RUN TestServerSideMoveOverSelf run.go:180: Remote "proton drive root link ID 'rclone-test-viponer6mexo'", Local "Local file system at /tmp/rclone1855972779", Modify Window "1s" 2025/04/29 06:52:02 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-metucay8meva" 2025/04/29 06:52:02 DEBUG : proton drive root link ID 'rclone-test-metucay8meva': Has cached credentials 2025/04/29 06:52:03 DEBUG : proton drive root link ID 'rclone-test-metucay8meva': Used cached credential to initialize the ProtonDrive API sync_test.go:671: Server side copy (if possible) proton drive root link ID 'rclone-test-viponer6mexo' -> proton drive root link ID 'rclone-test-metucay8meva' 2025/04/29 06:52:04 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/04/29 06:52:04 DEBUG : proton drive root link ID 'rclone-test-metucay8meva': Waiting for checks to finish 2025/04/29 06:52:04 DEBUG : proton drive root link ID 'rclone-test-metucay8meva': Waiting for transfers to finish 2025/04/29 06:52:08 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2025/04/29 06:52:08 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "proton drive root link ID 'rclone-test-viponer6mexo'", Local "Local file system at /tmp/rclone1855972779", Modify Window "1s" 2025/04/29 06:52:10.474204 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/04/29 06:52:10.474242 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/04/29 06:52:13 DEBUG : proton drive root link ID 'rclone-test-metucay8meva': Using server-side directory move 2025/04/29 06:52:13 INFO : proton drive root link ID 'rclone-test-metucay8meva': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/04/29 06:52:13 DEBUG : proton drive root link ID 'rclone-test-metucay8meva': Waiting for checks to finish 2025/04/29 06:52:13 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/04/29 06:52:13 DEBUG : proton drive root link ID 'rclone-test-metucay8meva': Waiting for transfers to finish 2025/04/29 06:52:14 INFO : sub dir/hello world: Deleted 2025/04/29 06:52:15.092818 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/Zdp8l2VJQmtQsVVXC5vo_DbtvD0x3jUP7qo4tBsMdIQ9c0YhwGS41k7yXLhZJmsKTXbDf9ECZ3cTeysIQdpzxw==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/04/29 06:52:15.092864 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/Zdp8l2VJQmtQsVVXC5vo_DbtvD0x3jUP7qo4tBsMdIQ9c0YhwGS41k7yXLhZJmsKTXbDf9ECZ3cTeysIQdpzxw==/move: This value should not be blank. (Code=2000, Status=400) 2025/04/29 06:52:15 ERROR : sub dir/hello world: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/Zdp8l2VJQmtQsVVXC5vo_DbtvD0x3jUP7qo4tBsMdIQ9c0YhwGS41k7yXLhZJmsKTXbDf9ECZ3cTeysIQdpzxw==/move: This value should not be blank. (Code=2000, Status=400) sync_test.go:684: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:684 Error: Received unexpected error: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/Zdp8l2VJQmtQsVVXC5vo_DbtvD0x3jUP7qo4tBsMdIQ9c0YhwGS41k7yXLhZJmsKTXbDf9ECZ3cTeysIQdpzxw==/move: This value should not be blank. (Code=2000, Status=400) Test: TestServerSideMoveOverSelf 2025/04/29 06:52:15 DEBUG : proton drive root link ID 'rclone-test-metucay8meva': Purge remote --- FAIL: TestServerSideMoveOverSelf (19.28s) === RUN TestSyncWithTrackRenames run.go:180: Remote "proton drive root link ID 'rclone-test-viponer6mexo'", Local "Local file system at /tmp/rclone1855972779", Modify Window "1s" sync_test.go:1538: Can track renames: true 2025/04/29 06:52:18 INFO : proton drive root link ID 'rclone-test-viponer6mexo': Making map for --track-renames 2025/04/29 06:52:18 INFO : proton drive root link ID 'rclone-test-viponer6mexo': Finished making map for --track-renames 2025/04/29 06:52:18 DEBUG : proton drive root link ID 'rclone-test-viponer6mexo': Waiting for checks to finish 2025/04/29 06:52:18 DEBUG : proton drive root link ID 'rclone-test-viponer6mexo': Waiting for renames to finish 2025/04/29 06:52:18 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/04/29 06:52:18 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/04/29 06:52:18 DEBUG : proton drive root link ID 'rclone-test-viponer6mexo': Waiting for transfers to finish 2025/04/29 06:52:19 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/04/29 06:52:19 INFO : yam: Copied (new) 2025/04/29 06:52:20 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/04/29 06:52:20 INFO : potato: Copied (new) 2025/04/29 06:52:20 DEBUG : Waiting for deletions to finish run.go:180: Remote "proton drive root link ID 'rclone-test-viponer6mexo'", Local "Local file system at /tmp/rclone1855972779", Modify Window "1s" 2025/04/29 06:52:21 DEBUG : potato: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/04/29 06:52:21 INFO : proton drive root link ID 'rclone-test-viponer6mexo': Making map for --track-renames 2025/04/29 06:52:21 INFO : proton drive root link ID 'rclone-test-viponer6mexo': Finished making map for --track-renames 2025/04/29 06:52:21 DEBUG : proton drive root link ID 'rclone-test-viponer6mexo': Waiting for checks to finish 2025/04/29 06:52:21 DEBUG : potato: Unchanged skipping 2025/04/29 06:52:21 DEBUG : proton drive root link ID 'rclone-test-viponer6mexo': Waiting for renames to finish 2025/04/29 06:52:22.409319 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/3LDd4d-brCsdQEesXiMvREdRkKlByfmktSONU0B16bErT7vbKP-kwiSLd3ykM2GP8SGxYBBs1fe8rJMxsfKWWA==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/04/29 06:52:22.409355 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/3LDd4d-brCsdQEesXiMvREdRkKlByfmktSONU0B16bErT7vbKP-kwiSLd3ykM2GP8SGxYBBs1fe8rJMxsfKWWA==/move: This value should not be blank. (Code=2000, Status=400) 2025/04/29 06:52:22 ERROR : yam: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/3LDd4d-brCsdQEesXiMvREdRkKlByfmktSONU0B16bErT7vbKP-kwiSLd3ykM2GP8SGxYBBs1fe8rJMxsfKWWA==/move: This value should not be blank. (Code=2000, Status=400) 2025/04/29 06:52:22 DEBUG : yaml: Failed to rename to "yam": 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/3LDd4d-brCsdQEesXiMvREdRkKlByfmktSONU0B16bErT7vbKP-kwiSLd3ykM2GP8SGxYBBs1fe8rJMxsfKWWA==/move: This value should not be blank. (Code=2000, Status=400) 2025/04/29 06:52:22 DEBUG : yaml: Need to transfer - No matching file found at Destination 2025/04/29 06:52:22 DEBUG : proton drive root link ID 'rclone-test-viponer6mexo': Waiting for transfers to finish 2025/04/29 06:52:24 DEBUG : yaml: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/04/29 06:52:24 INFO : yaml: Copied (new) 2025/04/29 06:52:24 ERROR : proton drive root link ID 'rclone-test-viponer6mexo': not deleting files as there were IO errors 2025/04/29 06:52:24 ERROR : proton drive root link ID 'rclone-test-viponer6mexo': not deleting directories as there were IO errors sync_test.go:1556: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1556 Error: Received unexpected error: not deleting files as there were IO errors Test: TestSyncWithTrackRenames --- FAIL: TestSyncWithTrackRenames (8.83s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:180: Remote "proton drive root link ID 'rclone-test-viponer6mexo'", Local "Local file system at /tmp/rclone1855972779", Modify Window "1s" sync_test.go:1610: Can track renames: true 2025/04/29 06:52:27 INFO : proton drive root link ID 'rclone-test-viponer6mexo': Making map for --track-renames 2025/04/29 06:52:27 INFO : proton drive root link ID 'rclone-test-viponer6mexo': Finished making map for --track-renames 2025/04/29 06:52:27 DEBUG : proton drive root link ID 'rclone-test-viponer6mexo': Waiting for checks to finish 2025/04/29 06:52:27 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/04/29 06:52:27 DEBUG : proton drive root link ID 'rclone-test-viponer6mexo': Waiting for renames to finish 2025/04/29 06:52:27 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/04/29 06:52:27 DEBUG : proton drive root link ID 'rclone-test-viponer6mexo': Waiting for transfers to finish 2025/04/29 06:52:28 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/04/29 06:52:28 INFO : yam: Copied (new) 2025/04/29 06:52:28 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/04/29 06:52:28 INFO : potato: Copied (new) 2025/04/29 06:52:28 DEBUG : Waiting for deletions to finish run.go:180: Remote "proton drive root link ID 'rclone-test-viponer6mexo'", Local "Local file system at /tmp/rclone1855972779", Modify Window "1s" 2025/04/29 06:52:30 DEBUG : potato: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/04/29 06:52:30 INFO : proton drive root link ID 'rclone-test-viponer6mexo': Making map for --track-renames 2025/04/29 06:52:30 INFO : proton drive root link ID 'rclone-test-viponer6mexo': Finished making map for --track-renames 2025/04/29 06:52:30 DEBUG : proton drive root link ID 'rclone-test-viponer6mexo': Waiting for checks to finish 2025/04/29 06:52:30 DEBUG : potato: Unchanged skipping 2025/04/29 06:52:30 DEBUG : proton drive root link ID 'rclone-test-viponer6mexo': Waiting for renames to finish 2025/04/29 06:52:30.727745 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/IK2UoXd_ZC75fePBTjpzc8a-5pcm560I4HED5P_XvphCCZvdOvYnHk8g1Fh40LPUwKszBI7nyilA1ZxYm20SeA==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/04/29 06:52:30.727779 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/IK2UoXd_ZC75fePBTjpzc8a-5pcm560I4HED5P_XvphCCZvdOvYnHk8g1Fh40LPUwKszBI7nyilA1ZxYm20SeA==/move: This value should not be blank. (Code=2000, Status=400) 2025/04/29 06:52:30 ERROR : yam: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/IK2UoXd_ZC75fePBTjpzc8a-5pcm560I4HED5P_XvphCCZvdOvYnHk8g1Fh40LPUwKszBI7nyilA1ZxYm20SeA==/move: This value should not be blank. (Code=2000, Status=400) 2025/04/29 06:52:30 DEBUG : yaml: Failed to rename to "yam": 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/IK2UoXd_ZC75fePBTjpzc8a-5pcm560I4HED5P_XvphCCZvdOvYnHk8g1Fh40LPUwKszBI7nyilA1ZxYm20SeA==/move: This value should not be blank. (Code=2000, Status=400) 2025/04/29 06:52:30 DEBUG : yaml: Need to transfer - No matching file found at Destination 2025/04/29 06:52:30 DEBUG : proton drive root link ID 'rclone-test-viponer6mexo': Waiting for transfers to finish 2025/04/29 06:52:32 DEBUG : yaml: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/04/29 06:52:32 INFO : yaml: Copied (new) 2025/04/29 06:52:32 ERROR : proton drive root link ID 'rclone-test-viponer6mexo': not deleting files as there were IO errors 2025/04/29 06:52:32 ERROR : proton drive root link ID 'rclone-test-viponer6mexo': not deleting directories as there were IO errors sync_test.go:1628: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1628 Error: Received unexpected error: not deleting files as there were IO errors Test: TestSyncWithTrackRenamesStrategyModtime --- FAIL: TestSyncWithTrackRenamesStrategyModtime (8.22s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:180: Remote "proton drive root link ID 'rclone-test-viponer6mexo'", Local "Local file system at /tmp/rclone1855972779", Modify Window "1s" sync_test.go:1649: Can track renames: true 2025/04/29 06:52:35 INFO : proton drive root link ID 'rclone-test-viponer6mexo': Making map for --track-renames 2025/04/29 06:52:35 INFO : proton drive root link ID 'rclone-test-viponer6mexo': Finished making map for --track-renames 2025/04/29 06:52:35 DEBUG : proton drive root link ID 'rclone-test-viponer6mexo': Waiting for checks to finish 2025/04/29 06:52:35 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/04/29 06:52:35 DEBUG : sub/yam: Need to transfer - No matching file found at Destination 2025/04/29 06:52:35 DEBUG : proton drive root link ID 'rclone-test-viponer6mexo': Waiting for renames to finish 2025/04/29 06:52:35 DEBUG : proton drive root link ID 'rclone-test-viponer6mexo': Waiting for transfers to finish 2025/04/29 06:52:37 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/04/29 06:52:37 INFO : potato: Copied (new) 2025/04/29 06:52:38 DEBUG : sub/yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/04/29 06:52:38 INFO : sub/yam: Copied (new) 2025/04/29 06:52:38 DEBUG : Waiting for deletions to finish run.go:180: Remote "proton drive root link ID 'rclone-test-viponer6mexo'", Local "Local file system at /tmp/rclone1855972779", Modify Window "1s" 2025/04/29 06:52:39 DEBUG : potato: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/04/29 06:52:39 DEBUG : potato: Unchanged skipping 2025/04/29 06:52:40 INFO : proton drive root link ID 'rclone-test-viponer6mexo': Making map for --track-renames 2025/04/29 06:52:40 INFO : proton drive root link ID 'rclone-test-viponer6mexo': Finished making map for --track-renames 2025/04/29 06:52:40 DEBUG : proton drive root link ID 'rclone-test-viponer6mexo': Waiting for checks to finish 2025/04/29 06:52:40 DEBUG : proton drive root link ID 'rclone-test-viponer6mexo': Waiting for renames to finish 2025/04/29 06:52:40.639815 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/FBDkbLVILAqIDtP4Z1IkUprqda9oiVHn4pAekQ7NoF7fDTaCGxRJgrrvLjl-n7HIIAO6Q6O05xftirJtmUmL1A==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/04/29 06:52:40.639854 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/FBDkbLVILAqIDtP4Z1IkUprqda9oiVHn4pAekQ7NoF7fDTaCGxRJgrrvLjl-n7HIIAO6Q6O05xftirJtmUmL1A==/move: This value should not be blank. (Code=2000, Status=400) 2025/04/29 06:52:40 ERROR : sub/yam: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/FBDkbLVILAqIDtP4Z1IkUprqda9oiVHn4pAekQ7NoF7fDTaCGxRJgrrvLjl-n7HIIAO6Q6O05xftirJtmUmL1A==/move: This value should not be blank. (Code=2000, Status=400) 2025/04/29 06:52:40 DEBUG : yam: Failed to rename to "sub/yam": 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/FBDkbLVILAqIDtP4Z1IkUprqda9oiVHn4pAekQ7NoF7fDTaCGxRJgrrvLjl-n7HIIAO6Q6O05xftirJtmUmL1A==/move: This value should not be blank. (Code=2000, Status=400) 2025/04/29 06:52:40 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/04/29 06:52:40 DEBUG : proton drive root link ID 'rclone-test-viponer6mexo': Waiting for transfers to finish 2025/04/29 06:52:42 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/04/29 06:52:42 INFO : yam: Copied (new) 2025/04/29 06:52:42 ERROR : proton drive root link ID 'rclone-test-viponer6mexo': not deleting files as there were IO errors 2025/04/29 06:52:42 ERROR : proton drive root link ID 'rclone-test-viponer6mexo': not deleting directories as there were IO errors sync_test.go:1667: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1667 Error: Received unexpected error: not deleting files as there were IO errors Test: TestSyncWithTrackRenamesStrategyLeaf --- FAIL: TestSyncWithTrackRenamesStrategyLeaf (10.76s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:180: Remote "proton drive root link ID 'rclone-test-viponer6mexo'", Local "Local file system at /tmp/rclone1855972779", Modify Window "1s" 2025/04/29 06:52:46 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/04/29 06:52:46 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2025/04/29 06:52:46 DEBUG : proton drive root link ID 'rclone-test-viponer6mexo': Waiting for checks to finish 2025/04/29 06:52:46 DEBUG : proton drive root link ID 'rclone-test-viponer6mexo': Waiting for transfers to finish 2025/04/29 06:52:50 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2025/04/29 06:52:50 INFO : sub dir/hello world: Copied (new) 2025/04/29 06:52:50 INFO : sub dir/hello world: Deleted 2025/04/29 06:52:50 DEBUG : nested/sub dir/file: sha1 = b4b3e0a278988bc15f2913af3f4153ccef74e465 OK 2025/04/29 06:52:50 INFO : nested/sub dir/file: Copied (new) 2025/04/29 06:52:50 INFO : nested/sub dir/file: Deleted 2025/04/29 06:52:50 ERROR : Local file system at /tmp/rclone1855972779: not deleting directories as there were IO errors sync_test.go:1788: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1788 Error: Received unexpected error: not deleting directories as there were IO errors Test: TestMoveWithDeleteEmptySrcDirs --- FAIL: TestMoveWithDeleteEmptySrcDirs (8.63s) === RUN TestServerSideMove run.go:180: Remote "proton drive root link ID 'rclone-test-viponer6mexo'", Local "Local file system at /tmp/rclone1855972779", Modify Window "1s" 2025/04/29 06:52:54 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-kuyijuh6yeli" 2025/04/29 06:52:54 DEBUG : proton drive root link ID 'rclone-test-kuyijuh6yeli': Has cached credentials 2025/04/29 06:52:55 DEBUG : proton drive root link ID 'rclone-test-kuyijuh6yeli': Used cached credential to initialize the ProtonDrive API sync_test.go:1705: Server side move (if possible) proton drive root link ID 'rclone-test-viponer6mexo' -> proton drive root link ID 'rclone-test-kuyijuh6yeli' 2025/04/29 06:53:06 DEBUG : proton drive root link ID 'rclone-test-kuyijuh6yeli': Using server-side directory move 2025/04/29 06:53:06 INFO : proton drive root link ID 'rclone-test-kuyijuh6yeli': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/04/29 06:53:07 DEBUG : potato2: Need to transfer - File not found at Destination 2025/04/29 06:53:07 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1s) 2025/04/29 06:53:07 DEBUG : empty space: Unchanged skipping 2025/04/29 06:53:07 DEBUG : proton drive root link ID 'rclone-test-kuyijuh6yeli': Waiting for checks to finish 2025/04/29 06:53:07 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/04/29 06:53:07 INFO : empty space: Deleted 2025/04/29 06:53:07 DEBUG : proton drive root link ID 'rclone-test-kuyijuh6yeli': Waiting for transfers to finish 2025/04/29 06:53:07 INFO : potato3: Deleted 2025/04/29 06:53:07.898219 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/g4al-jXg7KqhXwK3ktAjnjCMWT40vYSDdLy7on_kecNDqYsQi-E191j61U3BwS4l2mWUw-1-UbxkpikBtjboVQ==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/04/29 06:53:07.898247 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/g4al-jXg7KqhXwK3ktAjnjCMWT40vYSDdLy7on_kecNDqYsQi-E191j61U3BwS4l2mWUw-1-UbxkpikBtjboVQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/04/29 06:53:07 ERROR : potato2: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/g4al-jXg7KqhXwK3ktAjnjCMWT40vYSDdLy7on_kecNDqYsQi-E191j61U3BwS4l2mWUw-1-UbxkpikBtjboVQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/04/29 06:53:08.364068 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/JDmhGl4eKo32JgZNmRbhy2BMLYY2R8RVhv3UqFmtQ3ISRCsZCrhycH4OhH13IrUcueqCRqWWNQEDPBI9WwMQ8w==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/04/29 06:53:08.364101 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/JDmhGl4eKo32JgZNmRbhy2BMLYY2R8RVhv3UqFmtQ3ISRCsZCrhycH4OhH13IrUcueqCRqWWNQEDPBI9WwMQ8w==/move: This value should not be blank. (Code=2000, Status=400) 2025/04/29 06:53:08 ERROR : potato3: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/JDmhGl4eKo32JgZNmRbhy2BMLYY2R8RVhv3UqFmtQ3ISRCsZCrhycH4OhH13IrUcueqCRqWWNQEDPBI9WwMQ8w==/move: This value should not be blank. (Code=2000, Status=400) sync_test.go:1716: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1716 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1881 Error: Received unexpected error: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/JDmhGl4eKo32JgZNmRbhy2BMLYY2R8RVhv3UqFmtQ3ISRCsZCrhycH4OhH13IrUcueqCRqWWNQEDPBI9WwMQ8w==/move: This value should not be blank. (Code=2000, Status=400) Test: TestServerSideMove 2025/04/29 06:53:08 DEBUG : proton drive root link ID 'rclone-test-kuyijuh6yeli': Purge remote --- FAIL: TestServerSideMove (16.26s) === RUN TestServerSideMoveWithFilter run.go:180: Remote "proton drive root link ID 'rclone-test-viponer6mexo'", Local "Local file system at /tmp/rclone1855972779", Modify Window "1s" 2025/04/29 06:53:10 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-hayadix1wola" 2025/04/29 06:53:10 DEBUG : proton drive root link ID 'rclone-test-hayadix1wola': Has cached credentials 2025/04/29 06:53:11 DEBUG : proton drive root link ID 'rclone-test-hayadix1wola': Used cached credential to initialize the ProtonDrive API sync_test.go:1705: Server side move (if possible) proton drive root link ID 'rclone-test-viponer6mexo' -> proton drive root link ID 'rclone-test-hayadix1wola' 2025/04/29 06:53:23 DEBUG : empty space: Excluded (Size Filter) 2025/04/29 06:53:23 DEBUG : empty space: Excluded 2025/04/29 06:53:23 DEBUG : empty space: Excluded (Size Filter) 2025/04/29 06:53:23 DEBUG : empty space: Excluded 2025/04/29 06:53:23 DEBUG : potato2: Need to transfer - File not found at Destination 2025/04/29 06:53:23 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/04/29 06:53:23 DEBUG : proton drive root link ID 'rclone-test-hayadix1wola': Waiting for checks to finish 2025/04/29 06:53:23 DEBUG : proton drive root link ID 'rclone-test-hayadix1wola': Waiting for transfers to finish 2025/04/29 06:53:23 INFO : potato3: Deleted 2025/04/29 06:53:24.131272 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/ZmfL-TVEWwUDrjCNuIcKBRaXMgqFr0KVC2tsHpMgpmJDIcJ4IzHQSDFt_tNmDwE9xL0VinPvJOV71ERBmoQEQQ==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/04/29 06:53:24.131308 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/ZmfL-TVEWwUDrjCNuIcKBRaXMgqFr0KVC2tsHpMgpmJDIcJ4IzHQSDFt_tNmDwE9xL0VinPvJOV71ERBmoQEQQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/04/29 06:53:24 ERROR : potato2: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/ZmfL-TVEWwUDrjCNuIcKBRaXMgqFr0KVC2tsHpMgpmJDIcJ4IzHQSDFt_tNmDwE9xL0VinPvJOV71ERBmoQEQQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/04/29 06:53:24.435936 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/8JgJqoOWRwsoQfgx60AIc7HNr6CS84EDXqwiCFfC-kNojXuSSsu31XAOexRoTQ3x76ogLQJqQzyewoCXz3i9AA==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/04/29 06:53:24.435972 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/8JgJqoOWRwsoQfgx60AIc7HNr6CS84EDXqwiCFfC-kNojXuSSsu31XAOexRoTQ3x76ogLQJqQzyewoCXz3i9AA==/move: This value should not be blank. (Code=2000, Status=400) 2025/04/29 06:53:24 ERROR : potato3: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/8JgJqoOWRwsoQfgx60AIc7HNr6CS84EDXqwiCFfC-kNojXuSSsu31XAOexRoTQ3x76ogLQJqQzyewoCXz3i9AA==/move: This value should not be blank. (Code=2000, Status=400) sync_test.go:1716: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1716 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1894 Error: Received unexpected error: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/8JgJqoOWRwsoQfgx60AIc7HNr6CS84EDXqwiCFfC-kNojXuSSsu31XAOexRoTQ3x76ogLQJqQzyewoCXz3i9AA==/move: This value should not be blank. (Code=2000, Status=400) Test: TestServerSideMoveWithFilter 2025/04/29 06:53:24 DEBUG : proton drive root link ID 'rclone-test-hayadix1wola': Purge remote --- FAIL: TestServerSideMoveWithFilter (16.69s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:180: Remote "proton drive root link ID 'rclone-test-viponer6mexo'", Local "Local file system at /tmp/rclone1855972779", Modify Window "1s" 2025/04/29 06:53:27 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-gesixec1zejo" 2025/04/29 06:53:27 DEBUG : proton drive root link ID 'rclone-test-gesixec1zejo': Has cached credentials 2025/04/29 06:53:28 DEBUG : proton drive root link ID 'rclone-test-gesixec1zejo': Used cached credential to initialize the ProtonDrive API 2025/04/29 06:53:34 INFO : tomatoDir: Making directory sync_test.go:1705: Server side move (if possible) proton drive root link ID 'rclone-test-viponer6mexo' -> proton drive root link ID 'rclone-test-gesixec1zejo' 2025/04/29 06:53:40 DEBUG : proton drive root link ID 'rclone-test-gesixec1zejo': Using server-side directory move 2025/04/29 06:53:40 INFO : proton drive root link ID 'rclone-test-gesixec1zejo': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/04/29 06:53:41 DEBUG : potato2: Need to transfer - File not found at Destination 2025/04/29 06:53:41 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1s) 2025/04/29 06:53:41 DEBUG : empty space: Unchanged skipping 2025/04/29 06:53:41 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/04/29 06:53:41 DEBUG : proton drive root link ID 'rclone-test-gesixec1zejo': Waiting for checks to finish 2025/04/29 06:53:41 INFO : potato3: Deleted 2025/04/29 06:53:41 INFO : empty space: Deleted 2025/04/29 06:53:41 DEBUG : proton drive root link ID 'rclone-test-gesixec1zejo': Waiting for transfers to finish 2025/04/29 06:53:42.107976 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/7UelQdjavt2U5SPLN8HbcU1GHZndOxuFzWeXqJ86wKgGRUTDHkN4oIf_oFP7ey_-nlVNO8izdTFxoTRWwXRUiQ==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/04/29 06:53:42.108015 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/7UelQdjavt2U5SPLN8HbcU1GHZndOxuFzWeXqJ86wKgGRUTDHkN4oIf_oFP7ey_-nlVNO8izdTFxoTRWwXRUiQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/04/29 06:53:42 ERROR : potato2: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/7UelQdjavt2U5SPLN8HbcU1GHZndOxuFzWeXqJ86wKgGRUTDHkN4oIf_oFP7ey_-nlVNO8izdTFxoTRWwXRUiQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/04/29 06:53:42.316002 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/3kCcZCWEeF_z5EHf3Qbqp_GyNPxokoPdeAT_e9c7p1f__MThTmIWLSsHhyh8GpMzFIRcJ9znpGfozNhYQm0ejQ==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/04/29 06:53:42.316037 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/3kCcZCWEeF_z5EHf3Qbqp_GyNPxokoPdeAT_e9c7p1f__MThTmIWLSsHhyh8GpMzFIRcJ9znpGfozNhYQm0ejQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/04/29 06:53:42 ERROR : potato3: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/3kCcZCWEeF_z5EHf3Qbqp_GyNPxokoPdeAT_e9c7p1f__MThTmIWLSsHhyh8GpMzFIRcJ9znpGfozNhYQm0ejQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/04/29 06:53:42 ERROR : proton drive root link ID 'rclone-test-viponer6mexo': not deleting directories as there were IO errors sync_test.go:1716: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1716 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1901 Error: Received unexpected error: not deleting directories as there were IO errors Test: TestServerSideMoveDeleteEmptySourceDirs 2025/04/29 06:53:42 DEBUG : proton drive root link ID 'rclone-test-gesixec1zejo': Purge remote --- FAIL: TestServerSideMoveDeleteEmptySourceDirs (17.98s) === RUN TestSyncBackupDir run.go:180: Remote "proton drive root link ID 'rclone-test-viponer6mexo'", Local "Local file system at /tmp/rclone1855972779", Modify Window "1s" 2025/04/29 06:53:52 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-viponer6mexo/dst" 2025/04/29 06:53:52 DEBUG : proton drive root link ID 'rclone-test-viponer6mexo/dst': Has cached credentials 2025/04/29 06:53:53 DEBUG : proton drive root link ID 'rclone-test-viponer6mexo/dst': Used cached credential to initialize the ProtonDrive API 2025/04/29 06:54:54 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-viponer6mexo/backup" 2025/04/29 06:54:54 DEBUG : proton drive root link ID 'rclone-test-viponer6mexo/backup': Has cached credentials 2025/04/29 06:54:55 DEBUG : proton drive root link ID 'rclone-test-viponer6mexo/backup': Used cached credential to initialize the ProtonDrive API 2025/04/29 06:57:03 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/04/29 06:57:03 DEBUG : proton drive root link ID 'rclone-test-viponer6mexo/dst': Waiting for checks to finish 2025/04/29 06:57:03 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/04/29 06:57:03 DEBUG : two: Unchanged skipping 2025/04/29 06:57:05.088441 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/EvfCvH7V5gjuEkkJKRflIhefr5VNvwTkgDTUbIySNNFIRp3PEgtkmwFy7nceA4mkJGDxbm-5w9YNQ0sdeEMvfQ==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/04/29 06:57:05.088465 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/EvfCvH7V5gjuEkkJKRflIhefr5VNvwTkgDTUbIySNNFIRp3PEgtkmwFy7nceA4mkJGDxbm-5w9YNQ0sdeEMvfQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/04/29 06:57:05 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/EvfCvH7V5gjuEkkJKRflIhefr5VNvwTkgDTUbIySNNFIRp3PEgtkmwFy7nceA4mkJGDxbm-5w9YNQ0sdeEMvfQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/04/29 06:57:05 DEBUG : proton drive root link ID 'rclone-test-viponer6mexo/dst': Waiting for transfers to finish 2025/04/29 06:57:05 ERROR : proton drive root link ID 'rclone-test-viponer6mexo/dst': not deleting files as there were IO errors 2025/04/29 06:57:05 ERROR : proton drive root link ID 'rclone-test-viponer6mexo/dst': not deleting directories as there were IO errors sync_test.go:2385: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2385 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2427 Error: Received unexpected error: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/EvfCvH7V5gjuEkkJKRflIhefr5VNvwTkgDTUbIySNNFIRp3PEgtkmwFy7nceA4mkJGDxbm-5w9YNQ0sdeEMvfQ==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncBackupDir --- FAIL: TestSyncBackupDir (203.48s) === RUN TestSyncBackupDirWithSuffix run.go:180: Remote "proton drive root link ID 'rclone-test-viponer6mexo'", Local "Local file system at /tmp/rclone1855972779", Modify Window "1s" 2025/04/29 06:57:16 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-viponer6mexo/dst" 2025/04/29 06:57:16 DEBUG : Config file has changed externally - reloading 2025/04/29 06:57:16 DEBUG : proton drive root link ID 'rclone-test-viponer6mexo/dst': Has cached credentials 2025/04/29 06:57:17 DEBUG : proton drive root link ID 'rclone-test-viponer6mexo/dst': Used cached credential to initialize the ProtonDrive API 2025/04/29 06:58:18 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-viponer6mexo/backup" 2025/04/29 06:58:18 DEBUG : proton drive root link ID 'rclone-test-viponer6mexo/backup': Has cached credentials 2025/04/29 06:58:20 DEBUG : proton drive root link ID 'rclone-test-viponer6mexo/backup': Used cached credential to initialize the ProtonDrive API 2025/04/29 07:00:26 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/04/29 07:00:26 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/04/29 07:00:26 DEBUG : two: Unchanged skipping 2025/04/29 07:00:26 DEBUG : proton drive root link ID 'rclone-test-viponer6mexo/dst': Waiting for checks to finish 2025/04/29 07:00:28.467075 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/akWicaqfeq-CwjBj14hLWqQ4sIuxayZTpuaASmZVxHC_c3P7j42Zeq7eFw3OOJDA4FQCV0jEBL4cAqtcL94rgw==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/04/29 07:00:28.467100 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/akWicaqfeq-CwjBj14hLWqQ4sIuxayZTpuaASmZVxHC_c3P7j42Zeq7eFw3OOJDA4FQCV0jEBL4cAqtcL94rgw==/move: This value should not be blank. (Code=2000, Status=400) 2025/04/29 07:00:28 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/akWicaqfeq-CwjBj14hLWqQ4sIuxayZTpuaASmZVxHC_c3P7j42Zeq7eFw3OOJDA4FQCV0jEBL4cAqtcL94rgw==/move: This value should not be blank. (Code=2000, Status=400) 2025/04/29 07:00:28 DEBUG : proton drive root link ID 'rclone-test-viponer6mexo/dst': Waiting for transfers to finish 2025/04/29 07:00:28 ERROR : proton drive root link ID 'rclone-test-viponer6mexo/dst': not deleting files as there were IO errors 2025/04/29 07:00:28 ERROR : proton drive root link ID 'rclone-test-viponer6mexo/dst': not deleting directories as there were IO errors sync_test.go:2385: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2385 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2431 Error: Received unexpected error: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/akWicaqfeq-CwjBj14hLWqQ4sIuxayZTpuaASmZVxHC_c3P7j42Zeq7eFw3OOJDA4FQCV0jEBL4cAqtcL94rgw==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncBackupDirWithSuffix 2025/04/29 07:00:31.083196 WARN RESTY 401 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/folders/9QqAqwxnXw1u2waPtHziDgNXlt2Yg0vxKBMfiwXEr9XKplxkgtltp5W4JtDqhDgfBc__MzByTbPdM7NtnbTI1g==/trash_multiple: Invalid access token (Code=401, Status=401), Attempt 1 2025/04/29 07:00:31.083236 ERROR RESTY 401 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/folders/9QqAqwxnXw1u2waPtHziDgNXlt2Yg0vxKBMfiwXEr9XKplxkgtltp5W4JtDqhDgfBc__MzByTbPdM7NtnbTI1g==/trash_multiple: Invalid access token (Code=401, Status=401) 2025/04/29 07:00:31 DEBUG : Saving config "client_uid" in section "TestProtonDrive" of the config file 2025/04/29 07:00:31 DEBUG : Config file has changed externally - reloading 2025/04/29 07:00:31 DEBUG : Saving config "client_access_token" in section "TestProtonDrive" of the config file 2025/04/29 07:00:31 DEBUG : Saving config "client_refresh_token" in section "TestProtonDrive" of the config file 2025/04/29 07:00:31 DEBUG : Saving config "client_salted_key_pass" in section "TestProtonDrive" of the config file --- FAIL: TestSyncBackupDirWithSuffix (203.70s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:180: Remote "proton drive root link ID 'rclone-test-viponer6mexo'", Local "Local file system at /tmp/rclone1855972779", Modify Window "1s" 2025/04/29 07:00:39 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-viponer6mexo/dst" 2025/04/29 07:00:39 DEBUG : Config file has changed externally - reloading 2025/04/29 07:00:39 DEBUG : proton drive root link ID 'rclone-test-viponer6mexo/dst': Has cached credentials 2025/04/29 07:00:40 DEBUG : proton drive root link ID 'rclone-test-viponer6mexo/dst': Used cached credential to initialize the ProtonDrive API 2025/04/29 07:01:41 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-viponer6mexo/backup" 2025/04/29 07:01:41 DEBUG : proton drive root link ID 'rclone-test-viponer6mexo/backup': Has cached credentials 2025/04/29 07:01:42 DEBUG : proton drive root link ID 'rclone-test-viponer6mexo/backup': Used cached credential to initialize the ProtonDrive API 2025/04/29 07:03:39 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/04/29 07:03:39 DEBUG : proton drive root link ID 'rclone-test-viponer6mexo/dst': Waiting for checks to finish 2025/04/29 07:03:39 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/04/29 07:03:39 DEBUG : two: Unchanged skipping 2025/04/29 07:03:40.908687 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/gM55T1HbP22zPxW443HI5k_onFtmV6y7m6l6w6UbjoCLoNvdDPNISMxZfBrX6cTqA9Pa813IQOWPJwPcUSa3Eg==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/04/29 07:03:40.908722 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/gM55T1HbP22zPxW443HI5k_onFtmV6y7m6l6w6UbjoCLoNvdDPNISMxZfBrX6cTqA9Pa813IQOWPJwPcUSa3Eg==/move: This value should not be blank. (Code=2000, Status=400) 2025/04/29 07:03:40 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/gM55T1HbP22zPxW443HI5k_onFtmV6y7m6l6w6UbjoCLoNvdDPNISMxZfBrX6cTqA9Pa813IQOWPJwPcUSa3Eg==/move: This value should not be blank. (Code=2000, Status=400) 2025/04/29 07:03:40 DEBUG : proton drive root link ID 'rclone-test-viponer6mexo/dst': Waiting for transfers to finish 2025/04/29 07:03:40 ERROR : proton drive root link ID 'rclone-test-viponer6mexo/dst': not deleting files as there were IO errors 2025/04/29 07:03:40 ERROR : proton drive root link ID 'rclone-test-viponer6mexo/dst': not deleting directories as there were IO errors sync_test.go:2385: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2385 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2435 Error: Received unexpected error: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/gM55T1HbP22zPxW443HI5k_onFtmV6y7m6l6w6UbjoCLoNvdDPNISMxZfBrX6cTqA9Pa813IQOWPJwPcUSa3Eg==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncBackupDirWithSuffixKeepExtension --- FAIL: TestSyncBackupDirWithSuffixKeepExtension (191.93s) === RUN TestSyncBackupDirSuffixOnly run.go:180: Remote "proton drive root link ID 'rclone-test-viponer6mexo'", Local "Local file system at /tmp/rclone1855972779", Modify Window "1s" 2025/04/29 07:03:52 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-viponer6mexo/dst" 2025/04/29 07:03:52 DEBUG : proton drive root link ID 'rclone-test-viponer6mexo/dst': Has cached credentials 2025/04/29 07:03:53 DEBUG : proton drive root link ID 'rclone-test-viponer6mexo/dst': Used cached credential to initialize the ProtonDrive API 2025/04/29 07:04:57 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/04/29 07:04:57 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/04/29 07:04:57 DEBUG : two: Unchanged skipping 2025/04/29 07:04:57 DEBUG : proton drive root link ID 'rclone-test-viponer6mexo/dst': Waiting for checks to finish 2025/04/29 07:04:58.137193 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/UrZtpNNk2nNkqpa0OTnnFF2Qjc2lYHMT4Wc_JqZPhd0PwqyHUkpypmVs5RJHdQ44jvsZnrD6Xq0pHOuVG3IofQ==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/04/29 07:04:58.137229 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/UrZtpNNk2nNkqpa0OTnnFF2Qjc2lYHMT4Wc_JqZPhd0PwqyHUkpypmVs5RJHdQ44jvsZnrD6Xq0pHOuVG3IofQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/04/29 07:04:58 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/UrZtpNNk2nNkqpa0OTnnFF2Qjc2lYHMT4Wc_JqZPhd0PwqyHUkpypmVs5RJHdQ44jvsZnrD6Xq0pHOuVG3IofQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/04/29 07:04:58 DEBUG : proton drive root link ID 'rclone-test-viponer6mexo/dst': Waiting for transfers to finish 2025/04/29 07:04:58 ERROR : proton drive root link ID 'rclone-test-viponer6mexo/dst': not deleting files as there were IO errors 2025/04/29 07:04:58 ERROR : proton drive root link ID 'rclone-test-viponer6mexo/dst': not deleting directories as there were IO errors sync_test.go:2385: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2385 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2439 Error: Received unexpected error: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/UrZtpNNk2nNkqpa0OTnnFF2Qjc2lYHMT4Wc_JqZPhd0PwqyHUkpypmVs5RJHdQ44jvsZnrD6Xq0pHOuVG3IofQ==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncBackupDirSuffixOnly --- FAIL: TestSyncBackupDirSuffixOnly (76.69s) === RUN TestSyncSuffix run.go:180: Remote "proton drive root link ID 'rclone-test-viponer6mexo'", Local "Local file system at /tmp/rclone1855972779", Modify Window "1s" 2025/04/29 07:05:08 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-viponer6mexo/dst" 2025/04/29 07:05:08 DEBUG : proton drive root link ID 'rclone-test-viponer6mexo/dst': Has cached credentials 2025/04/29 07:05:09 DEBUG : proton drive root link ID 'rclone-test-viponer6mexo/dst': Used cached credential to initialize the ProtonDrive API 2025/04/29 07:06:16 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/04/29 07:06:17.345901 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/EyJ1_ZkmrW8ZJqgBzQCMqRy75cDcjxO6gGYTm3o1vk72elqIYfUtaD-pMpm5Jr3qCrIne04u15ugyO9C8irmHQ==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/04/29 07:06:17.345927 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/EyJ1_ZkmrW8ZJqgBzQCMqRy75cDcjxO6gGYTm3o1vk72elqIYfUtaD-pMpm5Jr3qCrIne04u15ugyO9C8irmHQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/04/29 07:06:17 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/EyJ1_ZkmrW8ZJqgBzQCMqRy75cDcjxO6gGYTm3o1vk72elqIYfUtaD-pMpm5Jr3qCrIne04u15ugyO9C8irmHQ==/move: This value should not be blank. (Code=2000, Status=400) sync_test.go:2473: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2473 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2523 Error: Received unexpected error: moving to --backup-dir failed: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/EyJ1_ZkmrW8ZJqgBzQCMqRy75cDcjxO6gGYTm3o1vk72elqIYfUtaD-pMpm5Jr3qCrIne04u15ugyO9C8irmHQ==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncSuffix --- FAIL: TestSyncSuffix (79.31s) === RUN TestSyncSuffixKeepExtension run.go:180: Remote "proton drive root link ID 'rclone-test-viponer6mexo'", Local "Local file system at /tmp/rclone1855972779", Modify Window "1s" 2025/04/29 07:06:27 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-viponer6mexo/dst" 2025/04/29 07:06:27 DEBUG : proton drive root link ID 'rclone-test-viponer6mexo/dst': Has cached credentials 2025/04/29 07:06:28 DEBUG : proton drive root link ID 'rclone-test-viponer6mexo/dst': Used cached credential to initialize the ProtonDrive API 2025/04/29 07:07:34 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/04/29 07:07:35.592522 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/34G7kN4AEeX9AOOFXiT35cInP0JnQ5PAcJzWSROpkRSsUKeFBvQytFgD04j2UeyQU8HbKSLkduTv8UTs8PIGng==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/04/29 07:07:35.592562 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/34G7kN4AEeX9AOOFXiT35cInP0JnQ5PAcJzWSROpkRSsUKeFBvQytFgD04j2UeyQU8HbKSLkduTv8UTs8PIGng==/move: This value should not be blank. (Code=2000, Status=400) 2025/04/29 07:07:35 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/34G7kN4AEeX9AOOFXiT35cInP0JnQ5PAcJzWSROpkRSsUKeFBvQytFgD04j2UeyQU8HbKSLkduTv8UTs8PIGng==/move: This value should not be blank. (Code=2000, Status=400) sync_test.go:2473: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2473 /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/34G7kN4AEeX9AOOFXiT35cInP0JnQ5PAcJzWSROpkRSsUKeFBvQytFgD04j2UeyQU8HbKSLkduTv8UTs8PIGng==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncSuffixKeepExtension --- FAIL: TestSyncSuffixKeepExtension (78.34s) FAIL 2025/04/29 07:07:38 DEBUG : proton drive root link ID 'rclone-test-viponer6mexo': 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 15m42.292904753s (try 2/5): exit status 1: Failed [TestServerSideMoveOverSelf TestSyncWithTrackRenames TestSyncWithTrackRenamesStrategyModtime TestSyncWithTrackRenamesStrategyLeaf TestMoveWithDeleteEmptySrcDirs TestServerSideMove TestServerSideMoveWithFilter TestServerSideMoveDeleteEmptySourceDirs TestSyncBackupDir TestSyncBackupDirWithSuffix TestSyncBackupDirWithSuffixKeepExtension TestSyncBackupDirSuffixOnly TestSyncSuffix TestSyncSuffixKeepExtension]