"./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 4/5) 2025/07/25 04:16:22 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-hiricob8waxa" 2025/07/25 04:16:22 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/07/25 04:16:22 DEBUG : proton drive root link ID 'rclone-test-hiricob8waxa': Has cached credentials 2025/07/25 04:16:24 DEBUG : proton drive root link ID 'rclone-test-hiricob8waxa': Used cached credential to initialize the ProtonDrive API 2025/07/25 04:16:25 DEBUG : Creating backend with remote "/tmp/rclone332910899" === RUN TestServerSideMoveOverSelf run.go:180: Remote "proton drive root link ID 'rclone-test-hiricob8waxa'", Local "Local file system at /tmp/rclone332910899", Modify Window "1s" 2025/07/25 04:16:30 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-jeniwas1seje" 2025/07/25 04:16:30 DEBUG : proton drive root link ID 'rclone-test-jeniwas1seje': Has cached credentials 2025/07/25 04:16:32 DEBUG : proton drive root link ID 'rclone-test-jeniwas1seje': Used cached credential to initialize the ProtonDrive API sync_test.go:701: Server side copy (if possible) proton drive root link ID 'rclone-test-hiricob8waxa' -> proton drive root link ID 'rclone-test-jeniwas1seje' 2025/07/25 04:16:34 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/07/25 04:16:34 DEBUG : proton drive root link ID 'rclone-test-jeniwas1seje': Waiting for checks to finish 2025/07/25 04:16:34 DEBUG : proton drive root link ID 'rclone-test-jeniwas1seje': Waiting for transfers to finish 2025/07/25 04:16:39 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2025/07/25 04:16:39 INFO : sub dir/hello world: Copied (new) 2025/07/25 04:16:42.201666 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/07/25 04:16:42.201713 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/07/25 04:16:45 DEBUG : proton drive root link ID 'rclone-test-jeniwas1seje': Using server-side directory move 2025/07/25 04:16:45 INFO : proton drive root link ID 'rclone-test-jeniwas1seje': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/07/25 04:16:47 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/07/25 04:16:47 DEBUG : proton drive root link ID 'rclone-test-jeniwas1seje': Waiting for checks to finish 2025/07/25 04:16:47 DEBUG : proton drive root link ID 'rclone-test-jeniwas1seje': Waiting for transfers to finish 2025/07/25 04:16:47 INFO : sub dir/hello world: Deleted 2025/07/25 04:16:49.207199 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/YEMgAPZyY3YjMi2rH-Uys1AdB79iIGjUmKkaR2IFa_KSmVJNsabAVb9Lu-Ofk-Ul6tjr37ZpgMlFStPk8wpu-Q==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/07/25 04:16:49.207249 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/YEMgAPZyY3YjMi2rH-Uys1AdB79iIGjUmKkaR2IFa_KSmVJNsabAVb9Lu-Ofk-Ul6tjr37ZpgMlFStPk8wpu-Q==/move: This value should not be blank. (Code=2000, Status=400) 2025/07/25 04:16:49 ERROR : sub dir/hello world: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/YEMgAPZyY3YjMi2rH-Uys1AdB79iIGjUmKkaR2IFa_KSmVJNsabAVb9Lu-Ofk-Ul6tjr37ZpgMlFStPk8wpu-Q==/move: This value should not be blank. (Code=2000, Status=400) sync_test.go:714: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:714 Error: Received unexpected error: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/YEMgAPZyY3YjMi2rH-Uys1AdB79iIGjUmKkaR2IFa_KSmVJNsabAVb9Lu-Ofk-Ul6tjr37ZpgMlFStPk8wpu-Q==/move: This value should not be blank. (Code=2000, Status=400) Test: TestServerSideMoveOverSelf 2025/07/25 04:16:49 DEBUG : proton drive root link ID 'rclone-test-jeniwas1seje': Purge remote --- FAIL: TestServerSideMoveOverSelf (28.39s) === RUN TestSyncWithTrackRenames run.go:180: Remote "proton drive root link ID 'rclone-test-hiricob8waxa'", Local "Local file system at /tmp/rclone332910899", Modify Window "1s" sync_test.go:1568: Can track renames: true 2025/07/25 04:16:53 INFO : proton drive root link ID 'rclone-test-hiricob8waxa': Making map for --track-renames 2025/07/25 04:16:53 INFO : proton drive root link ID 'rclone-test-hiricob8waxa': Finished making map for --track-renames 2025/07/25 04:16:53 DEBUG : proton drive root link ID 'rclone-test-hiricob8waxa': Waiting for checks to finish 2025/07/25 04:16:53 DEBUG : proton drive root link ID 'rclone-test-hiricob8waxa': Waiting for renames to finish 2025/07/25 04:16:53 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/07/25 04:16:53 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/07/25 04:16:53 DEBUG : proton drive root link ID 'rclone-test-hiricob8waxa': Waiting for transfers to finish 2025/07/25 04:16:55 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/07/25 04:16:55 INFO : yam: Copied (new) 2025/07/25 04:16:56 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/07/25 04:16:56 INFO : potato: Copied (new) 2025/07/25 04:16:56 DEBUG : Waiting for deletions to finish 2025/07/25 04:16:57 DEBUG : potato: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/07/25 04:16:57 INFO : proton drive root link ID 'rclone-test-hiricob8waxa': Making map for --track-renames 2025/07/25 04:16:57 INFO : proton drive root link ID 'rclone-test-hiricob8waxa': Finished making map for --track-renames 2025/07/25 04:16:57 DEBUG : proton drive root link ID 'rclone-test-hiricob8waxa': Waiting for checks to finish 2025/07/25 04:16:57 DEBUG : potato: Unchanged skipping 2025/07/25 04:16:57 DEBUG : proton drive root link ID 'rclone-test-hiricob8waxa': Waiting for renames to finish 2025/07/25 04:16:58.965599 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/F1WLjlmpKdx61rfybAd5kan_c4u6KuCNM9uj2Pc5veA_kxCayCp-pQRm-0a_PP2xqCqBcqHoYD3BaPbI1tiboA==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/07/25 04:16:58.965647 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/F1WLjlmpKdx61rfybAd5kan_c4u6KuCNM9uj2Pc5veA_kxCayCp-pQRm-0a_PP2xqCqBcqHoYD3BaPbI1tiboA==/move: This value should not be blank. (Code=2000, Status=400) 2025/07/25 04:16:58 ERROR : yam: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/F1WLjlmpKdx61rfybAd5kan_c4u6KuCNM9uj2Pc5veA_kxCayCp-pQRm-0a_PP2xqCqBcqHoYD3BaPbI1tiboA==/move: This value should not be blank. (Code=2000, Status=400) 2025/07/25 04:16:58 DEBUG : yaml: Failed to rename to "yam": 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/F1WLjlmpKdx61rfybAd5kan_c4u6KuCNM9uj2Pc5veA_kxCayCp-pQRm-0a_PP2xqCqBcqHoYD3BaPbI1tiboA==/move: This value should not be blank. (Code=2000, Status=400) 2025/07/25 04:16:58 DEBUG : yaml: Need to transfer - No matching file found at Destination 2025/07/25 04:16:58 DEBUG : proton drive root link ID 'rclone-test-hiricob8waxa': Waiting for transfers to finish 2025/07/25 04:17:00 DEBUG : yaml: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/07/25 04:17:00 INFO : yaml: Copied (new) 2025/07/25 04:17:00 ERROR : proton drive root link ID 'rclone-test-hiricob8waxa': not deleting files as there were IO errors 2025/07/25 04:17:00 ERROR : proton drive root link ID 'rclone-test-hiricob8waxa': not deleting directories as there were IO errors sync_test.go:1586: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1586 Error: Received unexpected error: not deleting files as there were IO errors Test: TestSyncWithTrackRenames --- FAIL: TestSyncWithTrackRenames (10.26s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:180: Remote "proton drive root link ID 'rclone-test-hiricob8waxa'", Local "Local file system at /tmp/rclone332910899", Modify Window "1s" sync_test.go:1640: Can track renames: true 2025/07/25 04:17:04 INFO : proton drive root link ID 'rclone-test-hiricob8waxa': Making map for --track-renames 2025/07/25 04:17:04 INFO : proton drive root link ID 'rclone-test-hiricob8waxa': Finished making map for --track-renames 2025/07/25 04:17:04 DEBUG : proton drive root link ID 'rclone-test-hiricob8waxa': Waiting for checks to finish 2025/07/25 04:17:04 DEBUG : proton drive root link ID 'rclone-test-hiricob8waxa': Waiting for renames to finish 2025/07/25 04:17:04 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/07/25 04:17:04 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/07/25 04:17:04 DEBUG : proton drive root link ID 'rclone-test-hiricob8waxa': Waiting for transfers to finish 2025/07/25 04:17:05 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/07/25 04:17:05 INFO : yam: Copied (new) 2025/07/25 04:17:06 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/07/25 04:17:06 INFO : potato: Copied (new) 2025/07/25 04:17:06 DEBUG : Waiting for deletions to finish 2025/07/25 04:17:08 DEBUG : potato: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/07/25 04:17:08 INFO : proton drive root link ID 'rclone-test-hiricob8waxa': Making map for --track-renames 2025/07/25 04:17:08 INFO : proton drive root link ID 'rclone-test-hiricob8waxa': Finished making map for --track-renames 2025/07/25 04:17:08 DEBUG : proton drive root link ID 'rclone-test-hiricob8waxa': Waiting for checks to finish 2025/07/25 04:17:08 DEBUG : potato: Unchanged skipping 2025/07/25 04:17:08 DEBUG : proton drive root link ID 'rclone-test-hiricob8waxa': Waiting for renames to finish 2025/07/25 04:17:09.152801 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/DZj0IpVLj0alJXG2wiw30QGF8Z3mXMR7pjpLuS6vRggoF2uh-Phj5JqHrqhAFhjetoIHZFe80vklp9m3H-aRPg==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/07/25 04:17:09.152870 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/DZj0IpVLj0alJXG2wiw30QGF8Z3mXMR7pjpLuS6vRggoF2uh-Phj5JqHrqhAFhjetoIHZFe80vklp9m3H-aRPg==/move: This value should not be blank. (Code=2000, Status=400) 2025/07/25 04:17:09 ERROR : yam: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/DZj0IpVLj0alJXG2wiw30QGF8Z3mXMR7pjpLuS6vRggoF2uh-Phj5JqHrqhAFhjetoIHZFe80vklp9m3H-aRPg==/move: This value should not be blank. (Code=2000, Status=400) 2025/07/25 04:17:09 DEBUG : yaml: Failed to rename to "yam": 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/DZj0IpVLj0alJXG2wiw30QGF8Z3mXMR7pjpLuS6vRggoF2uh-Phj5JqHrqhAFhjetoIHZFe80vklp9m3H-aRPg==/move: This value should not be blank. (Code=2000, Status=400) 2025/07/25 04:17:09 DEBUG : yaml: Need to transfer - No matching file found at Destination 2025/07/25 04:17:09 DEBUG : proton drive root link ID 'rclone-test-hiricob8waxa': Waiting for transfers to finish 2025/07/25 04:17:10 DEBUG : yaml: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/07/25 04:17:10 INFO : yaml: Copied (new) 2025/07/25 04:17:10 ERROR : proton drive root link ID 'rclone-test-hiricob8waxa': not deleting files as there were IO errors 2025/07/25 04:17:10 ERROR : proton drive root link ID 'rclone-test-hiricob8waxa': not deleting directories as there were IO errors sync_test.go:1658: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1658 Error: Received unexpected error: not deleting files as there were IO errors Test: TestSyncWithTrackRenamesStrategyModtime --- FAIL: TestSyncWithTrackRenamesStrategyModtime (10.81s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:180: Remote "proton drive root link ID 'rclone-test-hiricob8waxa'", Local "Local file system at /tmp/rclone332910899", Modify Window "1s" sync_test.go:1679: Can track renames: true 2025/07/25 04:17:15 INFO : proton drive root link ID 'rclone-test-hiricob8waxa': Making map for --track-renames 2025/07/25 04:17:15 INFO : proton drive root link ID 'rclone-test-hiricob8waxa': Finished making map for --track-renames 2025/07/25 04:17:15 DEBUG : proton drive root link ID 'rclone-test-hiricob8waxa': Waiting for checks to finish 2025/07/25 04:17:15 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/07/25 04:17:15 DEBUG : sub/yam: Need to transfer - No matching file found at Destination 2025/07/25 04:17:15 DEBUG : proton drive root link ID 'rclone-test-hiricob8waxa': Waiting for renames to finish 2025/07/25 04:17:15 DEBUG : proton drive root link ID 'rclone-test-hiricob8waxa': Waiting for transfers to finish 2025/07/25 04:17:18 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/07/25 04:17:18 INFO : potato: Copied (new) 2025/07/25 04:17:18 DEBUG : sub/yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/07/25 04:17:18 INFO : sub/yam: Copied (new) 2025/07/25 04:17:18 DEBUG : Waiting for deletions to finish 2025/07/25 04:17:20 DEBUG : potato: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/07/25 04:17:20 DEBUG : potato: Unchanged skipping 2025/07/25 04:17:21 INFO : proton drive root link ID 'rclone-test-hiricob8waxa': Making map for --track-renames 2025/07/25 04:17:21 INFO : proton drive root link ID 'rclone-test-hiricob8waxa': Finished making map for --track-renames 2025/07/25 04:17:21 DEBUG : proton drive root link ID 'rclone-test-hiricob8waxa': Waiting for checks to finish 2025/07/25 04:17:21 DEBUG : proton drive root link ID 'rclone-test-hiricob8waxa': Waiting for renames to finish 2025/07/25 04:17:22.377411 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/ji-dcYeyC1Ywhlc0MLUGjCTMMqBtss-g-icmTYq_z02p9QSAUyJkVfP6dF4y6tch7LOXHxOqnUIlkxmy-I2EIg==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/07/25 04:17:22.377440 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/ji-dcYeyC1Ywhlc0MLUGjCTMMqBtss-g-icmTYq_z02p9QSAUyJkVfP6dF4y6tch7LOXHxOqnUIlkxmy-I2EIg==/move: This value should not be blank. (Code=2000, Status=400) 2025/07/25 04:17:22 ERROR : sub/yam: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/ji-dcYeyC1Ywhlc0MLUGjCTMMqBtss-g-icmTYq_z02p9QSAUyJkVfP6dF4y6tch7LOXHxOqnUIlkxmy-I2EIg==/move: This value should not be blank. (Code=2000, Status=400) 2025/07/25 04:17:22 DEBUG : yam: Failed to rename to "sub/yam": 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/ji-dcYeyC1Ywhlc0MLUGjCTMMqBtss-g-icmTYq_z02p9QSAUyJkVfP6dF4y6tch7LOXHxOqnUIlkxmy-I2EIg==/move: This value should not be blank. (Code=2000, Status=400) 2025/07/25 04:17:22 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/07/25 04:17:22 DEBUG : proton drive root link ID 'rclone-test-hiricob8waxa': Waiting for transfers to finish 2025/07/25 04:17:24 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/07/25 04:17:24 INFO : yam: Copied (new) 2025/07/25 04:17:24 ERROR : proton drive root link ID 'rclone-test-hiricob8waxa': not deleting files as there were IO errors 2025/07/25 04:17:24 ERROR : proton drive root link ID 'rclone-test-hiricob8waxa': not deleting directories as there were IO errors sync_test.go:1697: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1697 Error: Received unexpected error: not deleting files as there were IO errors Test: TestSyncWithTrackRenamesStrategyLeaf --- FAIL: TestSyncWithTrackRenamesStrategyLeaf (15.17s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:180: Remote "proton drive root link ID 'rclone-test-hiricob8waxa'", Local "Local file system at /tmp/rclone332910899", Modify Window "1s" 2025/07/25 04:17:30 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/07/25 04:17:30 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2025/07/25 04:17:30 DEBUG : proton drive root link ID 'rclone-test-hiricob8waxa': Waiting for checks to finish 2025/07/25 04:17:30 DEBUG : proton drive root link ID 'rclone-test-hiricob8waxa': Waiting for transfers to finish 2025/07/25 04:17:34 DEBUG : nested/sub dir/file: sha1 = b4b3e0a278988bc15f2913af3f4153ccef74e465 OK 2025/07/25 04:17:34 INFO : nested/sub dir/file: Copied (new) 2025/07/25 04:17:34 INFO : nested/sub dir/file: Deleted 2025/07/25 04:17:35 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2025/07/25 04:17:35 INFO : sub dir/hello world: Copied (new) 2025/07/25 04:17:35 INFO : sub dir/hello world: Deleted 2025/07/25 04:17:35 ERROR : Local file system at /tmp/rclone332910899: not deleting directories as there were IO errors sync_test.go:1818: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1818 Error: Received unexpected error: not deleting directories as there were IO errors Test: TestMoveWithDeleteEmptySrcDirs --- FAIL: TestMoveWithDeleteEmptySrcDirs (10.68s) === RUN TestServerSideMove run.go:180: Remote "proton drive root link ID 'rclone-test-hiricob8waxa'", Local "Local file system at /tmp/rclone332910899", Modify Window "1s" 2025/07/25 04:17:40 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-saqubot9qigo" 2025/07/25 04:17:40 DEBUG : proton drive root link ID 'rclone-test-saqubot9qigo': Has cached credentials 2025/07/25 04:17:42 DEBUG : proton drive root link ID 'rclone-test-saqubot9qigo': Used cached credential to initialize the ProtonDrive API sync_test.go:1735: Server side move (if possible) proton drive root link ID 'rclone-test-hiricob8waxa' -> proton drive root link ID 'rclone-test-saqubot9qigo' 2025/07/25 04:17:55 DEBUG : proton drive root link ID 'rclone-test-saqubot9qigo': Using server-side directory move 2025/07/25 04:17:55 INFO : proton drive root link ID 'rclone-test-saqubot9qigo': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/07/25 04:17:56 DEBUG : potato2: Need to transfer - File not found at Destination 2025/07/25 04:17:56 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1s) 2025/07/25 04:17:56 DEBUG : empty space: Unchanged skipping 2025/07/25 04:17:56 DEBUG : proton drive root link ID 'rclone-test-saqubot9qigo': Waiting for checks to finish 2025/07/25 04:17:56 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/07/25 04:17:56 INFO : potato3: Deleted 2025/07/25 04:17:56 INFO : empty space: Deleted 2025/07/25 04:17:56 DEBUG : proton drive root link ID 'rclone-test-saqubot9qigo': Waiting for transfers to finish 2025/07/25 04:17:57.383051 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/gad-_ZMl4WX3gp32P7gwdALPme0fpiwSODYJj0-wGMXrKORnuaBTwDRYOxETrlCJB4gAeuh3VxwVMfXPYiw6Qw==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/07/25 04:17:57.383089 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/gad-_ZMl4WX3gp32P7gwdALPme0fpiwSODYJj0-wGMXrKORnuaBTwDRYOxETrlCJB4gAeuh3VxwVMfXPYiw6Qw==/move: This value should not be blank. (Code=2000, Status=400) 2025/07/25 04:17:57 ERROR : potato2: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/gad-_ZMl4WX3gp32P7gwdALPme0fpiwSODYJj0-wGMXrKORnuaBTwDRYOxETrlCJB4gAeuh3VxwVMfXPYiw6Qw==/move: This value should not be blank. (Code=2000, Status=400) 2025/07/25 04:17:57.625682 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/Tmc-xgj4oR56mCDwRSw7kUtM58ZEXQ-Te8sYlBCuTtUbPjOjO8DOjGwkbmZQR0K-5UpuZKuLeu-CnqYlWDqkCg==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/07/25 04:17:57.625717 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/Tmc-xgj4oR56mCDwRSw7kUtM58ZEXQ-Te8sYlBCuTtUbPjOjO8DOjGwkbmZQR0K-5UpuZKuLeu-CnqYlWDqkCg==/move: This value should not be blank. (Code=2000, Status=400) 2025/07/25 04:17:57 ERROR : potato3: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/Tmc-xgj4oR56mCDwRSw7kUtM58ZEXQ-Te8sYlBCuTtUbPjOjO8DOjGwkbmZQR0K-5UpuZKuLeu-CnqYlWDqkCg==/move: This value should not be blank. (Code=2000, Status=400) sync_test.go:1746: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1746 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1911 Error: Received unexpected error: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/Tmc-xgj4oR56mCDwRSw7kUtM58ZEXQ-Te8sYlBCuTtUbPjOjO8DOjGwkbmZQR0K-5UpuZKuLeu-CnqYlWDqkCg==/move: This value should not be blank. (Code=2000, Status=400) Test: TestServerSideMove 2025/07/25 04:17:57 DEBUG : proton drive root link ID 'rclone-test-saqubot9qigo': Purge remote --- FAIL: TestServerSideMove (21.10s) === RUN TestServerSideMoveWithFilter run.go:180: Remote "proton drive root link ID 'rclone-test-hiricob8waxa'", Local "Local file system at /tmp/rclone332910899", Modify Window "1s" 2025/07/25 04:18:01 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-lahalag4yupa" 2025/07/25 04:18:01 DEBUG : proton drive root link ID 'rclone-test-lahalag4yupa': Has cached credentials 2025/07/25 04:18:03 DEBUG : proton drive root link ID 'rclone-test-lahalag4yupa': Used cached credential to initialize the ProtonDrive API sync_test.go:1735: Server side move (if possible) proton drive root link ID 'rclone-test-hiricob8waxa' -> proton drive root link ID 'rclone-test-lahalag4yupa' 2025/07/25 04:18:17 DEBUG : empty space: Excluded (Size Filter) 2025/07/25 04:18:17 DEBUG : empty space: Excluded 2025/07/25 04:18:17 DEBUG : empty space: Excluded (Size Filter) 2025/07/25 04:18:17 DEBUG : empty space: Excluded 2025/07/25 04:18:17 DEBUG : potato2: Need to transfer - File not found at Destination 2025/07/25 04:18:17 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/07/25 04:18:17 DEBUG : proton drive root link ID 'rclone-test-lahalag4yupa': Waiting for checks to finish 2025/07/25 04:18:17 DEBUG : proton drive root link ID 'rclone-test-lahalag4yupa': Waiting for transfers to finish 2025/07/25 04:18:18 INFO : potato3: Deleted 2025/07/25 04:18:18.421984 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/YjIAY_JWImYoTgoaQyLTA28f94CZU39nkpCi7Kuysaidu6WABGsFpgJSiGmoyuZ7ccUsps_exIXuWkEHnWH7jA==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/07/25 04:18:18.422042 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/YjIAY_JWImYoTgoaQyLTA28f94CZU39nkpCi7Kuysaidu6WABGsFpgJSiGmoyuZ7ccUsps_exIXuWkEHnWH7jA==/move: This value should not be blank. (Code=2000, Status=400) 2025/07/25 04:18:18 ERROR : potato2: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/YjIAY_JWImYoTgoaQyLTA28f94CZU39nkpCi7Kuysaidu6WABGsFpgJSiGmoyuZ7ccUsps_exIXuWkEHnWH7jA==/move: This value should not be blank. (Code=2000, Status=400) 2025/07/25 04:18:18.881907 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/SzqMkbsWBEA_0Ul6L6X2iYbwhr0y3MVgr_GYAHEIaftSPleFsMUKGDZE41wTA0mK7J0QV-PSnrjdnddtW4BO4w==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/07/25 04:18:18.881936 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/SzqMkbsWBEA_0Ul6L6X2iYbwhr0y3MVgr_GYAHEIaftSPleFsMUKGDZE41wTA0mK7J0QV-PSnrjdnddtW4BO4w==/move: This value should not be blank. (Code=2000, Status=400) 2025/07/25 04:18:18 ERROR : potato3: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/SzqMkbsWBEA_0Ul6L6X2iYbwhr0y3MVgr_GYAHEIaftSPleFsMUKGDZE41wTA0mK7J0QV-PSnrjdnddtW4BO4w==/move: This value should not be blank. (Code=2000, Status=400) sync_test.go:1746: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1746 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1924 Error: Received unexpected error: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/SzqMkbsWBEA_0Ul6L6X2iYbwhr0y3MVgr_GYAHEIaftSPleFsMUKGDZE41wTA0mK7J0QV-PSnrjdnddtW4BO4w==/move: This value should not be blank. (Code=2000, Status=400) Test: TestServerSideMoveWithFilter 2025/07/25 04:18:18 DEBUG : proton drive root link ID 'rclone-test-lahalag4yupa': Purge remote --- FAIL: TestServerSideMoveWithFilter (21.35s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:180: Remote "proton drive root link ID 'rclone-test-hiricob8waxa'", Local "Local file system at /tmp/rclone332910899", Modify Window "1s" 2025/07/25 04:18:22 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-geyoyig6wela" 2025/07/25 04:18:22 DEBUG : proton drive root link ID 'rclone-test-geyoyig6wela': Has cached credentials 2025/07/25 04:18:25 DEBUG : proton drive root link ID 'rclone-test-geyoyig6wela': Used cached credential to initialize the ProtonDrive API 2025/07/25 04:18:30 INFO : tomatoDir: Making directory sync_test.go:1735: Server side move (if possible) proton drive root link ID 'rclone-test-hiricob8waxa' -> proton drive root link ID 'rclone-test-geyoyig6wela' 2025/07/25 04:18:38 DEBUG : proton drive root link ID 'rclone-test-geyoyig6wela': Using server-side directory move 2025/07/25 04:18:38 INFO : proton drive root link ID 'rclone-test-geyoyig6wela': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/07/25 04:18:39 DEBUG : potato2: Need to transfer - File not found at Destination 2025/07/25 04:18:39 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1s) 2025/07/25 04:18:39 DEBUG : empty space: Unchanged skipping 2025/07/25 04:18:39 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/07/25 04:18:40 DEBUG : proton drive root link ID 'rclone-test-geyoyig6wela': Waiting for checks to finish 2025/07/25 04:18:40 INFO : potato3: Deleted 2025/07/25 04:18:40 INFO : empty space: Deleted 2025/07/25 04:18:40 DEBUG : proton drive root link ID 'rclone-test-geyoyig6wela': Waiting for transfers to finish 2025/07/25 04:18:40.944925 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/irPuuPgMieivHDJAPCHpLYy4Ar2fJfYrvUA6fYTidGfyXXaCHJ5RFrq1UnLfWYteX31_MtWh1GzRpGjykuRWdg==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/07/25 04:18:40.944967 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/irPuuPgMieivHDJAPCHpLYy4Ar2fJfYrvUA6fYTidGfyXXaCHJ5RFrq1UnLfWYteX31_MtWh1GzRpGjykuRWdg==/move: This value should not be blank. (Code=2000, Status=400) 2025/07/25 04:18:40 ERROR : potato2: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/irPuuPgMieivHDJAPCHpLYy4Ar2fJfYrvUA6fYTidGfyXXaCHJ5RFrq1UnLfWYteX31_MtWh1GzRpGjykuRWdg==/move: This value should not be blank. (Code=2000, Status=400) 2025/07/25 04:18:41.320570 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/L7qg3Pfb_SW8L-2zjuh5UcqmuDmWv2svDdVU82BJSHjizbWVDL8xbYL4KF8Zl0CdfkvvnnnZIHSIMIjaVKDvFg==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/07/25 04:18:41.320596 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/L7qg3Pfb_SW8L-2zjuh5UcqmuDmWv2svDdVU82BJSHjizbWVDL8xbYL4KF8Zl0CdfkvvnnnZIHSIMIjaVKDvFg==/move: This value should not be blank. (Code=2000, Status=400) 2025/07/25 04:18:41 ERROR : potato3: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/L7qg3Pfb_SW8L-2zjuh5UcqmuDmWv2svDdVU82BJSHjizbWVDL8xbYL4KF8Zl0CdfkvvnnnZIHSIMIjaVKDvFg==/move: This value should not be blank. (Code=2000, Status=400) 2025/07/25 04:18:41 ERROR : proton drive root link ID 'rclone-test-hiricob8waxa': not deleting directories as there were IO errors sync_test.go:1746: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1746 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1931 Error: Received unexpected error: not deleting directories as there were IO errors Test: TestServerSideMoveDeleteEmptySourceDirs 2025/07/25 04:18:41 DEBUG : proton drive root link ID 'rclone-test-geyoyig6wela': Purge remote --- FAIL: TestServerSideMoveDeleteEmptySourceDirs (22.99s) === RUN TestSyncBackupDir run.go:180: Remote "proton drive root link ID 'rclone-test-hiricob8waxa'", Local "Local file system at /tmp/rclone332910899", Modify Window "1s" 2025/07/25 04:18:53 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-hiricob8waxa/dst" 2025/07/25 04:18:53 DEBUG : proton drive root link ID 'rclone-test-hiricob8waxa/dst': Has cached credentials 2025/07/25 04:18:55 DEBUG : proton drive root link ID 'rclone-test-hiricob8waxa/dst': Used cached credential to initialize the ProtonDrive API 2025/07/25 04:20:11 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-hiricob8waxa/backup" 2025/07/25 04:20:11 DEBUG : proton drive root link ID 'rclone-test-hiricob8waxa/backup': Has cached credentials 2025/07/25 04:20:13 DEBUG : proton drive root link ID 'rclone-test-hiricob8waxa/backup': Used cached credential to initialize the ProtonDrive API 2025/07/25 04:22:43 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/07/25 04:22:43 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/07/25 04:22:43 DEBUG : two: Unchanged skipping 2025/07/25 04:22:43 DEBUG : proton drive root link ID 'rclone-test-hiricob8waxa/dst': Waiting for checks to finish 2025/07/25 04:22:45.691437 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/sJmU9Hp1JVbOO1BUrsmrufpbNAI-JgUNV26cuDurFpYNr9CPPdcqSYVw63ycU91dmVNfyoGwJHtc_QkZ4LTrbA==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/07/25 04:22:45.691527 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/sJmU9Hp1JVbOO1BUrsmrufpbNAI-JgUNV26cuDurFpYNr9CPPdcqSYVw63ycU91dmVNfyoGwJHtc_QkZ4LTrbA==/move: This value should not be blank. (Code=2000, Status=400) 2025/07/25 04:22:45 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/sJmU9Hp1JVbOO1BUrsmrufpbNAI-JgUNV26cuDurFpYNr9CPPdcqSYVw63ycU91dmVNfyoGwJHtc_QkZ4LTrbA==/move: This value should not be blank. (Code=2000, Status=400) 2025/07/25 04:22:45 DEBUG : proton drive root link ID 'rclone-test-hiricob8waxa/dst': Waiting for transfers to finish 2025/07/25 04:22:45 ERROR : proton drive root link ID 'rclone-test-hiricob8waxa/dst': not deleting files as there were IO errors 2025/07/25 04:22:45 ERROR : proton drive root link ID 'rclone-test-hiricob8waxa/dst': not deleting directories as there were IO errors sync_test.go:2415: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2415 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2457 Error: Received unexpected error: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/sJmU9Hp1JVbOO1BUrsmrufpbNAI-JgUNV26cuDurFpYNr9CPPdcqSYVw63ycU91dmVNfyoGwJHtc_QkZ4LTrbA==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncBackupDir --- FAIL: TestSyncBackupDir (245.30s) === RUN TestSyncBackupDirWithSuffix run.go:180: Remote "proton drive root link ID 'rclone-test-hiricob8waxa'", Local "Local file system at /tmp/rclone332910899", Modify Window "1s" 2025/07/25 04:22:59 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-hiricob8waxa/dst" 2025/07/25 04:22:59 DEBUG : Config file has changed externally - reloading 2025/07/25 04:22:59 DEBUG : proton drive root link ID 'rclone-test-hiricob8waxa/dst': Has cached credentials 2025/07/25 04:23:01 DEBUG : proton drive root link ID 'rclone-test-hiricob8waxa/dst': Used cached credential to initialize the ProtonDrive API 2025/07/25 04:24:17 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-hiricob8waxa/backup" 2025/07/25 04:24:17 DEBUG : proton drive root link ID 'rclone-test-hiricob8waxa/backup': Has cached credentials 2025/07/25 04:24:19 DEBUG : proton drive root link ID 'rclone-test-hiricob8waxa/backup': Used cached credential to initialize the ProtonDrive API 2025/07/25 04:26:48 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/07/25 04:26:48 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/07/25 04:26:48 DEBUG : two: Unchanged skipping 2025/07/25 04:26:48 DEBUG : proton drive root link ID 'rclone-test-hiricob8waxa/dst': Waiting for checks to finish 2025/07/25 04:26:51.235208 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/g_I7hJ2WNbTtHnuSXUawTObxXnCZjQPTHWJdz2S9GEylV1h4GWcixod9xqnzfGwkZbLRENB6fhofbKoIDMw3Ig==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/07/25 04:26:51.235256 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/g_I7hJ2WNbTtHnuSXUawTObxXnCZjQPTHWJdz2S9GEylV1h4GWcixod9xqnzfGwkZbLRENB6fhofbKoIDMw3Ig==/move: This value should not be blank. (Code=2000, Status=400) 2025/07/25 04:26:51 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/g_I7hJ2WNbTtHnuSXUawTObxXnCZjQPTHWJdz2S9GEylV1h4GWcixod9xqnzfGwkZbLRENB6fhofbKoIDMw3Ig==/move: This value should not be blank. (Code=2000, Status=400) 2025/07/25 04:26:51 DEBUG : proton drive root link ID 'rclone-test-hiricob8waxa/dst': Waiting for transfers to finish 2025/07/25 04:26:51 ERROR : proton drive root link ID 'rclone-test-hiricob8waxa/dst': not deleting files as there were IO errors 2025/07/25 04:26:51 ERROR : proton drive root link ID 'rclone-test-hiricob8waxa/dst': not deleting directories as there were IO errors sync_test.go:2415: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2415 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2461 Error: Received unexpected error: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/g_I7hJ2WNbTtHnuSXUawTObxXnCZjQPTHWJdz2S9GEylV1h4GWcixod9xqnzfGwkZbLRENB6fhofbKoIDMw3Ig==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncBackupDirWithSuffix --- FAIL: TestSyncBackupDirWithSuffix (245.04s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:180: Remote "proton drive root link ID 'rclone-test-hiricob8waxa'", Local "Local file system at /tmp/rclone332910899", Modify Window "1s" 2025/07/25 04:27:04 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-hiricob8waxa/dst" 2025/07/25 04:27:04 DEBUG : proton drive root link ID 'rclone-test-hiricob8waxa/dst': Has cached credentials 2025/07/25 04:27:06 DEBUG : proton drive root link ID 'rclone-test-hiricob8waxa/dst': Used cached credential to initialize the ProtonDrive API 2025/07/25 04:28:21 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-hiricob8waxa/backup" 2025/07/25 04:28:21 DEBUG : proton drive root link ID 'rclone-test-hiricob8waxa/backup': Has cached credentials 2025/07/25 04:28:23 DEBUG : proton drive root link ID 'rclone-test-hiricob8waxa/backup': Used cached credential to initialize the ProtonDrive API 2025/07/25 04:30:54 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/07/25 04:30:54 DEBUG : proton drive root link ID 'rclone-test-hiricob8waxa/dst': Waiting for checks to finish 2025/07/25 04:30:54 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/07/25 04:30:54 DEBUG : two: Unchanged skipping 2025/07/25 04:30:57.436698 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/YQTlSN_0CbUEMh2iEgeHOrAaZke5SU0NTndfG1lfGrU-N8igRQh5-X6o5nhyHlu5yed6itNLexyKfJzSbtobDg==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/07/25 04:30:57.436735 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/YQTlSN_0CbUEMh2iEgeHOrAaZke5SU0NTndfG1lfGrU-N8igRQh5-X6o5nhyHlu5yed6itNLexyKfJzSbtobDg==/move: This value should not be blank. (Code=2000, Status=400) 2025/07/25 04:30:57 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/YQTlSN_0CbUEMh2iEgeHOrAaZke5SU0NTndfG1lfGrU-N8igRQh5-X6o5nhyHlu5yed6itNLexyKfJzSbtobDg==/move: This value should not be blank. (Code=2000, Status=400) 2025/07/25 04:30:57 DEBUG : proton drive root link ID 'rclone-test-hiricob8waxa/dst': Waiting for transfers to finish 2025/07/25 04:30:57 ERROR : proton drive root link ID 'rclone-test-hiricob8waxa/dst': not deleting files as there were IO errors 2025/07/25 04:30:57 ERROR : proton drive root link ID 'rclone-test-hiricob8waxa/dst': not deleting directories as there were IO errors sync_test.go:2415: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2415 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2465 Error: Received unexpected error: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/YQTlSN_0CbUEMh2iEgeHOrAaZke5SU0NTndfG1lfGrU-N8igRQh5-X6o5nhyHlu5yed6itNLexyKfJzSbtobDg==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncBackupDirWithSuffixKeepExtension --- FAIL: TestSyncBackupDirWithSuffixKeepExtension (246.62s) === RUN TestSyncBackupDirSuffixOnly run.go:180: Remote "proton drive root link ID 'rclone-test-hiricob8waxa'", Local "Local file system at /tmp/rclone332910899", Modify Window "1s" 2025/07/25 04:31:10 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-hiricob8waxa/dst" 2025/07/25 04:31:10 DEBUG : proton drive root link ID 'rclone-test-hiricob8waxa/dst': Has cached credentials 2025/07/25 04:31:13 DEBUG : proton drive root link ID 'rclone-test-hiricob8waxa/dst': Used cached credential to initialize the ProtonDrive API 2025/07/25 04:32:29 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/07/25 04:32:29 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/07/25 04:32:29 DEBUG : two: Unchanged skipping 2025/07/25 04:32:29 DEBUG : proton drive root link ID 'rclone-test-hiricob8waxa/dst': Waiting for checks to finish 2025/07/25 04:32:30.162157 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/0HkgCzKgvumBxDEgCdc7_0CF3sX7_am0KbI9XB3ePx_U-8kwE1eZCZsaM8oGfEqce_tmBMhaXP1jvpEaiH_56A==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/07/25 04:32:30.162206 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/0HkgCzKgvumBxDEgCdc7_0CF3sX7_am0KbI9XB3ePx_U-8kwE1eZCZsaM8oGfEqce_tmBMhaXP1jvpEaiH_56A==/move: This value should not be blank. (Code=2000, Status=400) 2025/07/25 04:32:30 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/0HkgCzKgvumBxDEgCdc7_0CF3sX7_am0KbI9XB3ePx_U-8kwE1eZCZsaM8oGfEqce_tmBMhaXP1jvpEaiH_56A==/move: This value should not be blank. (Code=2000, Status=400) 2025/07/25 04:32:30 DEBUG : proton drive root link ID 'rclone-test-hiricob8waxa/dst': Waiting for transfers to finish 2025/07/25 04:32:30 ERROR : proton drive root link ID 'rclone-test-hiricob8waxa/dst': not deleting files as there were IO errors 2025/07/25 04:32:30 ERROR : proton drive root link ID 'rclone-test-hiricob8waxa/dst': not deleting directories as there were IO errors sync_test.go:2415: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2415 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2469 Error: Received unexpected error: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/0HkgCzKgvumBxDEgCdc7_0CF3sX7_am0KbI9XB3ePx_U-8kwE1eZCZsaM8oGfEqce_tmBMhaXP1jvpEaiH_56A==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncBackupDirSuffixOnly --- FAIL: TestSyncBackupDirSuffixOnly (91.94s) === RUN TestSyncSuffix run.go:180: Remote "proton drive root link ID 'rclone-test-hiricob8waxa'", Local "Local file system at /tmp/rclone332910899", Modify Window "1s" 2025/07/25 04:32:42 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-hiricob8waxa/dst" 2025/07/25 04:32:42 DEBUG : proton drive root link ID 'rclone-test-hiricob8waxa/dst': Has cached credentials 2025/07/25 04:32:44 DEBUG : proton drive root link ID 'rclone-test-hiricob8waxa/dst': Used cached credential to initialize the ProtonDrive API 2025/07/25 04:34:01 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/07/25 04:34:02.652958 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/cY5BPhHNXacTfT0ylb6-bnAgtmB__BA1WHNRjTDJrTUL3tE3EdIzL4AN6caUb8doVxo7iztzOQAz94bAkxTv4w==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/07/25 04:34:02.653000 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/cY5BPhHNXacTfT0ylb6-bnAgtmB__BA1WHNRjTDJrTUL3tE3EdIzL4AN6caUb8doVxo7iztzOQAz94bAkxTv4w==/move: This value should not be blank. (Code=2000, Status=400) 2025/07/25 04:34:02 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/cY5BPhHNXacTfT0ylb6-bnAgtmB__BA1WHNRjTDJrTUL3tE3EdIzL4AN6caUb8doVxo7iztzOQAz94bAkxTv4w==/move: This value should not be blank. (Code=2000, Status=400) sync_test.go:2503: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2503 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2553 Error: Received unexpected error: moving to --backup-dir failed: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/cY5BPhHNXacTfT0ylb6-bnAgtmB__BA1WHNRjTDJrTUL3tE3EdIzL4AN6caUb8doVxo7iztzOQAz94bAkxTv4w==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncSuffix --- FAIL: TestSyncSuffix (93.01s) === RUN TestSyncSuffixKeepExtension run.go:180: Remote "proton drive root link ID 'rclone-test-hiricob8waxa'", Local "Local file system at /tmp/rclone332910899", Modify Window "1s" 2025/07/25 04:34:15 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-hiricob8waxa/dst" 2025/07/25 04:34:15 DEBUG : proton drive root link ID 'rclone-test-hiricob8waxa/dst': Has cached credentials 2025/07/25 04:34:17 DEBUG : proton drive root link ID 'rclone-test-hiricob8waxa/dst': Used cached credential to initialize the ProtonDrive API 2025/07/25 04:35:33 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/07/25 04:35:34.154673 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/NctTsTf0bUpu5eO40wJs-GvPe2LRCwYrWRYo_oiTFunTdGm2eiuZl2Ukg2avhf1QFgmHZqHp_9z13vY9Wgzc7g==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/07/25 04:35:34.154710 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/NctTsTf0bUpu5eO40wJs-GvPe2LRCwYrWRYo_oiTFunTdGm2eiuZl2Ukg2avhf1QFgmHZqHp_9z13vY9Wgzc7g==/move: This value should not be blank. (Code=2000, Status=400) 2025/07/25 04:35:34 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/NctTsTf0bUpu5eO40wJs-GvPe2LRCwYrWRYo_oiTFunTdGm2eiuZl2Ukg2avhf1QFgmHZqHp_9z13vY9Wgzc7g==/move: This value should not be blank. (Code=2000, Status=400) sync_test.go:2503: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2503 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2554 Error: Received unexpected error: moving to --backup-dir failed: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/NctTsTf0bUpu5eO40wJs-GvPe2LRCwYrWRYo_oiTFunTdGm2eiuZl2Ukg2avhf1QFgmHZqHp_9z13vY9Wgzc7g==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncSuffixKeepExtension --- FAIL: TestSyncSuffixKeepExtension (90.78s) FAIL 2025/07/25 04:35:38 DEBUG : proton drive root link ID 'rclone-test-hiricob8waxa': 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 19m17.311279437s (try 4/5): exit status 1: Failed [TestServerSideMoveOverSelf TestSyncWithTrackRenames TestSyncWithTrackRenamesStrategyModtime TestSyncWithTrackRenamesStrategyLeaf TestMoveWithDeleteEmptySrcDirs TestServerSideMove TestServerSideMoveWithFilter TestServerSideMoveDeleteEmptySourceDirs TestSyncBackupDir TestSyncBackupDirWithSuffix TestSyncBackupDirWithSuffixKeepExtension TestSyncBackupDirSuffixOnly TestSyncSuffix TestSyncSuffixKeepExtension]