"./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/09/22 04:34:35 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-qudorey2bazu" 2025/09/22 04:34:35 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/09/22 04:34:35 DEBUG : proton drive root link ID 'rclone-test-qudorey2bazu': Has cached credentials 2025/09/22 04:34:38 DEBUG : proton drive root link ID 'rclone-test-qudorey2bazu': Used cached credential to initialize the ProtonDrive API 2025/09/22 04:34:38 DEBUG : Creating backend with remote "/tmp/rclone215716432" === RUN TestServerSideMoveOverSelf run.go:180: Remote "proton drive root link ID 'rclone-test-qudorey2bazu'", Local "Local file system at /tmp/rclone215716432", Modify Window "1s" 2025/09/22 04:34:46 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-fejitul7cike" 2025/09/22 04:34:46 DEBUG : proton drive root link ID 'rclone-test-fejitul7cike': Has cached credentials 2025/09/22 04:34:48 DEBUG : proton drive root link ID 'rclone-test-fejitul7cike': Used cached credential to initialize the ProtonDrive API sync_test.go:701: Server side copy (if possible) proton drive root link ID 'rclone-test-qudorey2bazu' -> proton drive root link ID 'rclone-test-fejitul7cike' 2025/09/22 04:34:49 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/09/22 04:34:49 DEBUG : proton drive root link ID 'rclone-test-fejitul7cike': Waiting for checks to finish 2025/09/22 04:34:49 DEBUG : proton drive root link ID 'rclone-test-fejitul7cike': Waiting for transfers to finish 2025/09/22 04:34:54 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2025/09/22 04:34:54 INFO : sub dir/hello world: Copied (new) 2025/09/22 04:34:56.764793 WARN RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422), Attempt 1 2025/09/22 04:34:56.764824 ERROR RESTY 422 POST https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/files: A file or folder with that name already exists (Code=2500, Status=422) 2025/09/22 04:35:00 DEBUG : proton drive root link ID 'rclone-test-fejitul7cike': Using server-side directory move 2025/09/22 04:35:00 INFO : proton drive root link ID 'rclone-test-fejitul7cike': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/09/22 04:35:01 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/09/22 04:35:01 DEBUG : proton drive root link ID 'rclone-test-fejitul7cike': Waiting for checks to finish 2025/09/22 04:35:01 DEBUG : proton drive root link ID 'rclone-test-fejitul7cike': Waiting for transfers to finish 2025/09/22 04:35:01 INFO : sub dir/hello world: Deleted 2025/09/22 04:35:03.082913 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/ZTP4rADDnoe9kTdxrKAsojh_nBUCLKGhgUbcaXlyHjAL5Q8HGa9LZPbHowNPaP5QRSJltH1ETvFpnXlArqgY_Q==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/22 04:35:03.082935 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/ZTP4rADDnoe9kTdxrKAsojh_nBUCLKGhgUbcaXlyHjAL5Q8HGa9LZPbHowNPaP5QRSJltH1ETvFpnXlArqgY_Q==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/22 04:35:03 ERROR : sub dir/hello world: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/ZTP4rADDnoe9kTdxrKAsojh_nBUCLKGhgUbcaXlyHjAL5Q8HGa9LZPbHowNPaP5QRSJltH1ETvFpnXlArqgY_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/ZTP4rADDnoe9kTdxrKAsojh_nBUCLKGhgUbcaXlyHjAL5Q8HGa9LZPbHowNPaP5QRSJltH1ETvFpnXlArqgY_Q==/move: This value should not be blank. (Code=2000, Status=400) Test: TestServerSideMoveOverSelf 2025/09/22 04:35:03 DEBUG : proton drive root link ID 'rclone-test-fejitul7cike': Purge remote --- FAIL: TestServerSideMoveOverSelf (27.85s) === RUN TestSyncWithTrackRenames run.go:180: Remote "proton drive root link ID 'rclone-test-qudorey2bazu'", Local "Local file system at /tmp/rclone215716432", Modify Window "1s" sync_test.go:1568: Can track renames: true 2025/09/22 04:35:07 INFO : proton drive root link ID 'rclone-test-qudorey2bazu': Making map for --track-renames 2025/09/22 04:35:07 INFO : proton drive root link ID 'rclone-test-qudorey2bazu': Finished making map for --track-renames 2025/09/22 04:35:07 DEBUG : proton drive root link ID 'rclone-test-qudorey2bazu': Waiting for checks to finish 2025/09/22 04:35:07 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/09/22 04:35:07 DEBUG : proton drive root link ID 'rclone-test-qudorey2bazu': Waiting for renames to finish 2025/09/22 04:35:07 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/09/22 04:35:07 DEBUG : proton drive root link ID 'rclone-test-qudorey2bazu': Waiting for transfers to finish 2025/09/22 04:35:08 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/09/22 04:35:08 INFO : potato: Copied (new) 2025/09/22 04:35:08 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/09/22 04:35:08 INFO : yam: Copied (new) 2025/09/22 04:35:08 DEBUG : Waiting for deletions to finish 2025/09/22 04:35:10 DEBUG : potato: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/22 04:35:10 INFO : proton drive root link ID 'rclone-test-qudorey2bazu': Making map for --track-renames 2025/09/22 04:35:10 INFO : proton drive root link ID 'rclone-test-qudorey2bazu': Finished making map for --track-renames 2025/09/22 04:35:10 DEBUG : proton drive root link ID 'rclone-test-qudorey2bazu': Waiting for checks to finish 2025/09/22 04:35:10 DEBUG : potato: Unchanged skipping 2025/09/22 04:35:10 DEBUG : proton drive root link ID 'rclone-test-qudorey2bazu': Waiting for renames to finish 2025/09/22 04:35:11.803129 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/-k81a9d6tBIKGaobBbgY0T5lQke2oert_HIxHW-w9AgHjEZ5Mp6YwT9k4zOgg1QnWvUqWQYyqIlaA-9dENrE_A==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/22 04:35:11.803159 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/-k81a9d6tBIKGaobBbgY0T5lQke2oert_HIxHW-w9AgHjEZ5Mp6YwT9k4zOgg1QnWvUqWQYyqIlaA-9dENrE_A==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/22 04:35:11 ERROR : yam: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/-k81a9d6tBIKGaobBbgY0T5lQke2oert_HIxHW-w9AgHjEZ5Mp6YwT9k4zOgg1QnWvUqWQYyqIlaA-9dENrE_A==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/22 04:35:11 DEBUG : yaml: Failed to rename to "yam": 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/-k81a9d6tBIKGaobBbgY0T5lQke2oert_HIxHW-w9AgHjEZ5Mp6YwT9k4zOgg1QnWvUqWQYyqIlaA-9dENrE_A==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/22 04:35:11 DEBUG : yaml: Need to transfer - No matching file found at Destination 2025/09/22 04:35:11 DEBUG : proton drive root link ID 'rclone-test-qudorey2bazu': Waiting for transfers to finish 2025/09/22 04:35:13 DEBUG : yaml: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/09/22 04:35:13 INFO : yaml: Copied (new) 2025/09/22 04:35:13 ERROR : proton drive root link ID 'rclone-test-qudorey2bazu': not deleting files as there were IO errors 2025/09/22 04:35:13 ERROR : proton drive root link ID 'rclone-test-qudorey2bazu': 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 (9.90s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:180: Remote "proton drive root link ID 'rclone-test-qudorey2bazu'", Local "Local file system at /tmp/rclone215716432", Modify Window "1s" sync_test.go:1640: Can track renames: true 2025/09/22 04:35:16 INFO : proton drive root link ID 'rclone-test-qudorey2bazu': Making map for --track-renames 2025/09/22 04:35:16 INFO : proton drive root link ID 'rclone-test-qudorey2bazu': Finished making map for --track-renames 2025/09/22 04:35:16 DEBUG : proton drive root link ID 'rclone-test-qudorey2bazu': Waiting for checks to finish 2025/09/22 04:35:16 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/09/22 04:35:16 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/09/22 04:35:16 DEBUG : proton drive root link ID 'rclone-test-qudorey2bazu': Waiting for renames to finish 2025/09/22 04:35:16 DEBUG : proton drive root link ID 'rclone-test-qudorey2bazu': Waiting for transfers to finish 2025/09/22 04:35:18 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/09/22 04:35:18 INFO : yam: Copied (new) 2025/09/22 04:35:18 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/09/22 04:35:18 INFO : potato: Copied (new) 2025/09/22 04:35:18 DEBUG : Waiting for deletions to finish 2025/09/22 04:35:20 DEBUG : potato: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/22 04:35:20 INFO : proton drive root link ID 'rclone-test-qudorey2bazu': Making map for --track-renames 2025/09/22 04:35:20 INFO : proton drive root link ID 'rclone-test-qudorey2bazu': Finished making map for --track-renames 2025/09/22 04:35:20 DEBUG : proton drive root link ID 'rclone-test-qudorey2bazu': Waiting for checks to finish 2025/09/22 04:35:20 DEBUG : potato: Unchanged skipping 2025/09/22 04:35:20 DEBUG : proton drive root link ID 'rclone-test-qudorey2bazu': Waiting for renames to finish 2025/09/22 04:35:21.512465 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/SltElSIY8HPAB_zThwCwnxPS0PIFiDGa9GQVvvCNHTmMfkOt93YxoNqHM9164GXuTYPjZPbr8odS41U5h14Egg==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/22 04:35:21.512526 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/SltElSIY8HPAB_zThwCwnxPS0PIFiDGa9GQVvvCNHTmMfkOt93YxoNqHM9164GXuTYPjZPbr8odS41U5h14Egg==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/22 04:35:21 ERROR : yam: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/SltElSIY8HPAB_zThwCwnxPS0PIFiDGa9GQVvvCNHTmMfkOt93YxoNqHM9164GXuTYPjZPbr8odS41U5h14Egg==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/22 04:35:21 DEBUG : yaml: Failed to rename to "yam": 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/SltElSIY8HPAB_zThwCwnxPS0PIFiDGa9GQVvvCNHTmMfkOt93YxoNqHM9164GXuTYPjZPbr8odS41U5h14Egg==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/22 04:35:21 DEBUG : yaml: Need to transfer - No matching file found at Destination 2025/09/22 04:35:21 DEBUG : proton drive root link ID 'rclone-test-qudorey2bazu': Waiting for transfers to finish 2025/09/22 04:35:23 DEBUG : yaml: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/09/22 04:35:23 INFO : yaml: Copied (new) 2025/09/22 04:35:23 ERROR : proton drive root link ID 'rclone-test-qudorey2bazu': not deleting files as there were IO errors 2025/09/22 04:35:23 ERROR : proton drive root link ID 'rclone-test-qudorey2bazu': 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 (9.92s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:180: Remote "proton drive root link ID 'rclone-test-qudorey2bazu'", Local "Local file system at /tmp/rclone215716432", Modify Window "1s" sync_test.go:1679: Can track renames: true 2025/09/22 04:35:26 INFO : proton drive root link ID 'rclone-test-qudorey2bazu': Making map for --track-renames 2025/09/22 04:35:26 INFO : proton drive root link ID 'rclone-test-qudorey2bazu': Finished making map for --track-renames 2025/09/22 04:35:26 DEBUG : proton drive root link ID 'rclone-test-qudorey2bazu': Waiting for checks to finish 2025/09/22 04:35:26 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/09/22 04:35:26 DEBUG : sub/yam: Need to transfer - No matching file found at Destination 2025/09/22 04:35:26 DEBUG : proton drive root link ID 'rclone-test-qudorey2bazu': Waiting for renames to finish 2025/09/22 04:35:26 DEBUG : proton drive root link ID 'rclone-test-qudorey2bazu': Waiting for transfers to finish 2025/09/22 04:35:28 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/09/22 04:35:28 INFO : potato: Copied (new) 2025/09/22 04:35:29 DEBUG : sub/yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/09/22 04:35:29 INFO : sub/yam: Copied (new) 2025/09/22 04:35:29 DEBUG : Waiting for deletions to finish 2025/09/22 04:35:31 DEBUG : potato: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/22 04:35:31 DEBUG : potato: Unchanged skipping 2025/09/22 04:35:32 INFO : proton drive root link ID 'rclone-test-qudorey2bazu': Making map for --track-renames 2025/09/22 04:35:32 INFO : proton drive root link ID 'rclone-test-qudorey2bazu': Finished making map for --track-renames 2025/09/22 04:35:32 DEBUG : proton drive root link ID 'rclone-test-qudorey2bazu': Waiting for checks to finish 2025/09/22 04:35:32 DEBUG : proton drive root link ID 'rclone-test-qudorey2bazu': Waiting for renames to finish 2025/09/22 04:35:33.347003 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/NWkn2nywsXeirbRcMZrvLRe4rjFc9ASvWUcSLVvhTfuRD1tuwawNTgVGVFhMhRPbaWBHLQm81KgJmBOEf9OqBw==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/22 04:35:33.347042 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/NWkn2nywsXeirbRcMZrvLRe4rjFc9ASvWUcSLVvhTfuRD1tuwawNTgVGVFhMhRPbaWBHLQm81KgJmBOEf9OqBw==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/22 04:35:33 ERROR : sub/yam: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/NWkn2nywsXeirbRcMZrvLRe4rjFc9ASvWUcSLVvhTfuRD1tuwawNTgVGVFhMhRPbaWBHLQm81KgJmBOEf9OqBw==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/22 04:35:33 DEBUG : yam: Failed to rename to "sub/yam": 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/NWkn2nywsXeirbRcMZrvLRe4rjFc9ASvWUcSLVvhTfuRD1tuwawNTgVGVFhMhRPbaWBHLQm81KgJmBOEf9OqBw==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/22 04:35:33 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/09/22 04:35:33 DEBUG : proton drive root link ID 'rclone-test-qudorey2bazu': Waiting for transfers to finish 2025/09/22 04:35:35 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/09/22 04:35:35 INFO : yam: Copied (new) 2025/09/22 04:35:35 ERROR : proton drive root link ID 'rclone-test-qudorey2bazu': not deleting files as there were IO errors 2025/09/22 04:35:35 ERROR : proton drive root link ID 'rclone-test-qudorey2bazu': 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 (12.97s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:180: Remote "proton drive root link ID 'rclone-test-qudorey2bazu'", Local "Local file system at /tmp/rclone215716432", Modify Window "1s" 2025/09/22 04:35:39 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/09/22 04:35:39 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2025/09/22 04:35:39 DEBUG : proton drive root link ID 'rclone-test-qudorey2bazu': Waiting for checks to finish 2025/09/22 04:35:39 DEBUG : proton drive root link ID 'rclone-test-qudorey2bazu': Waiting for transfers to finish 2025/09/22 04:35:44 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2025/09/22 04:35:44 INFO : sub dir/hello world: Copied (new) 2025/09/22 04:35:44 INFO : sub dir/hello world: Deleted 2025/09/22 04:35:45 DEBUG : nested/sub dir/file: sha1 = b4b3e0a278988bc15f2913af3f4153ccef74e465 OK 2025/09/22 04:35:45 INFO : nested/sub dir/file: Copied (new) 2025/09/22 04:35:45 INFO : nested/sub dir/file: Deleted 2025/09/22 04:35:45 ERROR : Local file system at /tmp/rclone215716432: 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.46s) === RUN TestServerSideMove run.go:180: Remote "proton drive root link ID 'rclone-test-qudorey2bazu'", Local "Local file system at /tmp/rclone215716432", Modify Window "1s" 2025/09/22 04:35:49 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-rafavix7xose" 2025/09/22 04:35:49 DEBUG : proton drive root link ID 'rclone-test-rafavix7xose': Has cached credentials 2025/09/22 04:35:52 DEBUG : proton drive root link ID 'rclone-test-rafavix7xose': Used cached credential to initialize the ProtonDrive API sync_test.go:1735: Server side move (if possible) proton drive root link ID 'rclone-test-qudorey2bazu' -> proton drive root link ID 'rclone-test-rafavix7xose' 2025/09/22 04:36:03 DEBUG : proton drive root link ID 'rclone-test-rafavix7xose': Using server-side directory move 2025/09/22 04:36:03 INFO : proton drive root link ID 'rclone-test-rafavix7xose': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/09/22 04:36:04 DEBUG : potato2: Need to transfer - File not found at Destination 2025/09/22 04:36:04 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1s) 2025/09/22 04:36:04 DEBUG : empty space: Unchanged skipping 2025/09/22 04:36:04 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/09/22 04:36:04 DEBUG : proton drive root link ID 'rclone-test-rafavix7xose': Waiting for checks to finish 2025/09/22 04:36:05 INFO : potato3: Deleted 2025/09/22 04:36:05 INFO : empty space: Deleted 2025/09/22 04:36:05 DEBUG : proton drive root link ID 'rclone-test-rafavix7xose': Waiting for transfers to finish 2025/09/22 04:36:05.512476 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/EdG-pmYXuC7ArmNstgLU4BPh8J54LNJaGhYscSEB8XmvbHABN6Dqz3PiDxfA6TeIB3Y57A3Rl9Ke8p2A0NgWRw==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/22 04:36:05.512513 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/EdG-pmYXuC7ArmNstgLU4BPh8J54LNJaGhYscSEB8XmvbHABN6Dqz3PiDxfA6TeIB3Y57A3Rl9Ke8p2A0NgWRw==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/22 04:36:05 ERROR : potato2: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/EdG-pmYXuC7ArmNstgLU4BPh8J54LNJaGhYscSEB8XmvbHABN6Dqz3PiDxfA6TeIB3Y57A3Rl9Ke8p2A0NgWRw==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/22 04:36:05.716595 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/MoVdQ3WfujK-M6O5DYDT8f4_gIgyT_pUaqo3iZAwFS2yYqFuVg5Zy-2Lig2uP8DwJU3i5ZhRTvw68fIOr5WEVw==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/22 04:36:05.716629 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/MoVdQ3WfujK-M6O5DYDT8f4_gIgyT_pUaqo3iZAwFS2yYqFuVg5Zy-2Lig2uP8DwJU3i5ZhRTvw68fIOr5WEVw==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/22 04:36:05 ERROR : potato3: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/MoVdQ3WfujK-M6O5DYDT8f4_gIgyT_pUaqo3iZAwFS2yYqFuVg5Zy-2Lig2uP8DwJU3i5ZhRTvw68fIOr5WEVw==/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/MoVdQ3WfujK-M6O5DYDT8f4_gIgyT_pUaqo3iZAwFS2yYqFuVg5Zy-2Lig2uP8DwJU3i5ZhRTvw68fIOr5WEVw==/move: This value should not be blank. (Code=2000, Status=400) Test: TestServerSideMove 2025/09/22 04:36:05 DEBUG : proton drive root link ID 'rclone-test-rafavix7xose': Purge remote --- FAIL: TestServerSideMove (19.25s) === RUN TestServerSideMoveWithFilter run.go:180: Remote "proton drive root link ID 'rclone-test-qudorey2bazu'", Local "Local file system at /tmp/rclone215716432", Modify Window "1s" 2025/09/22 04:36:09 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-noxitiv5cola" 2025/09/22 04:36:09 DEBUG : proton drive root link ID 'rclone-test-noxitiv5cola': Has cached credentials 2025/09/22 04:36:11 DEBUG : proton drive root link ID 'rclone-test-noxitiv5cola': Used cached credential to initialize the ProtonDrive API sync_test.go:1735: Server side move (if possible) proton drive root link ID 'rclone-test-qudorey2bazu' -> proton drive root link ID 'rclone-test-noxitiv5cola' 2025/09/22 04:36:23 DEBUG : empty space: Excluded (Size Filter) 2025/09/22 04:36:23 DEBUG : empty space: Excluded 2025/09/22 04:36:23 DEBUG : empty space: Excluded (Size Filter) 2025/09/22 04:36:23 DEBUG : empty space: Excluded 2025/09/22 04:36:23 DEBUG : potato2: Need to transfer - File not found at Destination 2025/09/22 04:36:23 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/09/22 04:36:23 DEBUG : proton drive root link ID 'rclone-test-noxitiv5cola': Waiting for checks to finish 2025/09/22 04:36:23 DEBUG : proton drive root link ID 'rclone-test-noxitiv5cola': Waiting for transfers to finish 2025/09/22 04:36:24 INFO : potato3: Deleted 2025/09/22 04:36:24.816042 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/uA0gHZieixejsGvr7XLjCa7pSMAh5BiEgZY0-Y3sx9xFLYi5d_QB6slRbWQCCoKU_sDR0Xf6oLc3ceDkViZ1YQ==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/22 04:36:24.816088 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/uA0gHZieixejsGvr7XLjCa7pSMAh5BiEgZY0-Y3sx9xFLYi5d_QB6slRbWQCCoKU_sDR0Xf6oLc3ceDkViZ1YQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/22 04:36:24 ERROR : potato2: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/uA0gHZieixejsGvr7XLjCa7pSMAh5BiEgZY0-Y3sx9xFLYi5d_QB6slRbWQCCoKU_sDR0Xf6oLc3ceDkViZ1YQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/22 04:36:25.081271 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/RLXPBeMaj04fx9NzBCa9dWfJnbUouIa6d4cJfej-RpA4WStjAlBgKv8JyHEV3FjS9O4Zf-MEMv3gO8J7OiMTRg==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/22 04:36:25.081314 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/RLXPBeMaj04fx9NzBCa9dWfJnbUouIa6d4cJfej-RpA4WStjAlBgKv8JyHEV3FjS9O4Zf-MEMv3gO8J7OiMTRg==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/22 04:36:25 ERROR : potato3: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/RLXPBeMaj04fx9NzBCa9dWfJnbUouIa6d4cJfej-RpA4WStjAlBgKv8JyHEV3FjS9O4Zf-MEMv3gO8J7OiMTRg==/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/RLXPBeMaj04fx9NzBCa9dWfJnbUouIa6d4cJfej-RpA4WStjAlBgKv8JyHEV3FjS9O4Zf-MEMv3gO8J7OiMTRg==/move: This value should not be blank. (Code=2000, Status=400) Test: TestServerSideMoveWithFilter 2025/09/22 04:36:25 DEBUG : proton drive root link ID 'rclone-test-noxitiv5cola': Purge remote --- FAIL: TestServerSideMoveWithFilter (20.34s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:180: Remote "proton drive root link ID 'rclone-test-qudorey2bazu'", Local "Local file system at /tmp/rclone215716432", Modify Window "1s" 2025/09/22 04:36:29 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-xakawap2pexo" 2025/09/22 04:36:29 DEBUG : proton drive root link ID 'rclone-test-xakawap2pexo': Has cached credentials 2025/09/22 04:36:31 DEBUG : proton drive root link ID 'rclone-test-xakawap2pexo': Used cached credential to initialize the ProtonDrive API 2025/09/22 04:36:37 INFO : tomatoDir: Making directory sync_test.go:1735: Server side move (if possible) proton drive root link ID 'rclone-test-qudorey2bazu' -> proton drive root link ID 'rclone-test-xakawap2pexo' 2025/09/22 04:36:44 DEBUG : proton drive root link ID 'rclone-test-xakawap2pexo': Using server-side directory move 2025/09/22 04:36:44 INFO : proton drive root link ID 'rclone-test-xakawap2pexo': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/09/22 04:36:45 DEBUG : potato2: Need to transfer - File not found at Destination 2025/09/22 04:36:45 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1s) 2025/09/22 04:36:45 DEBUG : empty space: Unchanged skipping 2025/09/22 04:36:45 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/09/22 04:36:45 DEBUG : proton drive root link ID 'rclone-test-xakawap2pexo': Waiting for checks to finish 2025/09/22 04:36:46 INFO : empty space: Deleted 2025/09/22 04:36:46 DEBUG : proton drive root link ID 'rclone-test-xakawap2pexo': Waiting for transfers to finish 2025/09/22 04:36:46 INFO : potato3: Deleted 2025/09/22 04:36:46.579025 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/bWbWlcqW6yPTxofFwnP7lIbYTPyCeXbPQAf8mBbdfWmbj8Xw2uEq_ZdY4Lz32qgYAYUxxClqayLWDvi6_u2Oyw==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/22 04:36:46.579051 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/bWbWlcqW6yPTxofFwnP7lIbYTPyCeXbPQAf8mBbdfWmbj8Xw2uEq_ZdY4Lz32qgYAYUxxClqayLWDvi6_u2Oyw==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/22 04:36:46 ERROR : potato2: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/bWbWlcqW6yPTxofFwnP7lIbYTPyCeXbPQAf8mBbdfWmbj8Xw2uEq_ZdY4Lz32qgYAYUxxClqayLWDvi6_u2Oyw==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/22 04:36:47.180697 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/8jHDckb5I9qLV9zYyTV2TC1LjtmQGXE8Srd3XWkJNRwuTRCdQONBBV3Q3dllYkJDdwPmFlBLDX6pUAa7zGggDw==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/22 04:36:47.180767 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/8jHDckb5I9qLV9zYyTV2TC1LjtmQGXE8Srd3XWkJNRwuTRCdQONBBV3Q3dllYkJDdwPmFlBLDX6pUAa7zGggDw==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/22 04:36:47 ERROR : potato3: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/8jHDckb5I9qLV9zYyTV2TC1LjtmQGXE8Srd3XWkJNRwuTRCdQONBBV3Q3dllYkJDdwPmFlBLDX6pUAa7zGggDw==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/22 04:36:47 ERROR : proton drive root link ID 'rclone-test-qudorey2bazu': not deleting directories as there were IO errors sync_test.go:1746: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1746 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1931 Error: Received unexpected error: not deleting directories as there were IO errors Test: TestServerSideMoveDeleteEmptySourceDirs 2025/09/22 04:36:47 DEBUG : proton drive root link ID 'rclone-test-xakawap2pexo': Purge remote --- FAIL: TestServerSideMoveDeleteEmptySourceDirs (22.30s) === RUN TestSyncBackupDir run.go:180: Remote "proton drive root link ID 'rclone-test-qudorey2bazu'", Local "Local file system at /tmp/rclone215716432", Modify Window "1s" 2025/09/22 04:36:59 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-qudorey2bazu/dst" 2025/09/22 04:36:59 DEBUG : proton drive root link ID 'rclone-test-qudorey2bazu/dst': Has cached credentials 2025/09/22 04:37:01 DEBUG : proton drive root link ID 'rclone-test-qudorey2bazu/dst': Used cached credential to initialize the ProtonDrive API 2025/09/22 04:38:44 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-qudorey2bazu/backup" 2025/09/22 04:38:44 DEBUG : Config file has changed externally - reloading 2025/09/22 04:38:44 DEBUG : proton drive root link ID 'rclone-test-qudorey2bazu/backup': Has cached credentials 2025/09/22 04:38:46 DEBUG : proton drive root link ID 'rclone-test-qudorey2bazu/backup': Used cached credential to initialize the ProtonDrive API 2025/09/22 04:42:02 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/09/22 04:42:02 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/22 04:42:02 DEBUG : two: Unchanged skipping 2025/09/22 04:42:02 DEBUG : proton drive root link ID 'rclone-test-qudorey2bazu/dst': Waiting for checks to finish 2025/09/22 04:42:04.494594 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/XPNsrqsY2sJFEiys9xwGMdu3UWQUufNI9nMnU1xQ_Qyd62LR7omLEYDxDXCaWTtSuVz3B0dzyeMSh2xoFNXnoQ==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/22 04:42:04.494618 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/XPNsrqsY2sJFEiys9xwGMdu3UWQUufNI9nMnU1xQ_Qyd62LR7omLEYDxDXCaWTtSuVz3B0dzyeMSh2xoFNXnoQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/22 04:42:04 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/XPNsrqsY2sJFEiys9xwGMdu3UWQUufNI9nMnU1xQ_Qyd62LR7omLEYDxDXCaWTtSuVz3B0dzyeMSh2xoFNXnoQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/22 04:42:04 DEBUG : proton drive root link ID 'rclone-test-qudorey2bazu/dst': Waiting for transfers to finish 2025/09/22 04:42:04 ERROR : proton drive root link ID 'rclone-test-qudorey2bazu/dst': not deleting files as there were IO errors 2025/09/22 04:42:04 ERROR : proton drive root link ID 'rclone-test-qudorey2bazu/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/XPNsrqsY2sJFEiys9xwGMdu3UWQUufNI9nMnU1xQ_Qyd62LR7omLEYDxDXCaWTtSuVz3B0dzyeMSh2xoFNXnoQ==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncBackupDir --- FAIL: TestSyncBackupDir (317.92s) === RUN TestSyncBackupDirWithSuffix run.go:180: Remote "proton drive root link ID 'rclone-test-qudorey2bazu'", Local "Local file system at /tmp/rclone215716432", Modify Window "1s" 2025/09/22 04:42:16 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-qudorey2bazu/dst" 2025/09/22 04:42:16 DEBUG : proton drive root link ID 'rclone-test-qudorey2bazu/dst': Has cached credentials 2025/09/22 04:42:19 DEBUG : proton drive root link ID 'rclone-test-qudorey2bazu/dst': Used cached credential to initialize the ProtonDrive API 2025/09/22 04:43:58 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-qudorey2bazu/backup" 2025/09/22 04:43:58 DEBUG : proton drive root link ID 'rclone-test-qudorey2bazu/backup': Has cached credentials 2025/09/22 04:44:00 DEBUG : proton drive root link ID 'rclone-test-qudorey2bazu/backup': Used cached credential to initialize the ProtonDrive API 2025/09/22 04:47:17 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/09/22 04:47:17 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/22 04:47:17 DEBUG : two: Unchanged skipping 2025/09/22 04:47:17 DEBUG : proton drive root link ID 'rclone-test-qudorey2bazu/dst': Waiting for checks to finish 2025/09/22 04:47:20.092771 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/cxhfctCAnkTSbE1ApUIqGGsemNCpADEBzFJ-iHxvl1xQGIHMDOmMEAZc0lhCqH2WW9rBCd-of7XjLUJpRLeRLA==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/22 04:47:20.092805 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/cxhfctCAnkTSbE1ApUIqGGsemNCpADEBzFJ-iHxvl1xQGIHMDOmMEAZc0lhCqH2WW9rBCd-of7XjLUJpRLeRLA==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/22 04:47:20 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/cxhfctCAnkTSbE1ApUIqGGsemNCpADEBzFJ-iHxvl1xQGIHMDOmMEAZc0lhCqH2WW9rBCd-of7XjLUJpRLeRLA==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/22 04:47:20 DEBUG : proton drive root link ID 'rclone-test-qudorey2bazu/dst': Waiting for transfers to finish 2025/09/22 04:47:20 ERROR : proton drive root link ID 'rclone-test-qudorey2bazu/dst': not deleting files as there were IO errors 2025/09/22 04:47:20 ERROR : proton drive root link ID 'rclone-test-qudorey2bazu/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/cxhfctCAnkTSbE1ApUIqGGsemNCpADEBzFJ-iHxvl1xQGIHMDOmMEAZc0lhCqH2WW9rBCd-of7XjLUJpRLeRLA==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncBackupDirWithSuffix --- FAIL: TestSyncBackupDirWithSuffix (315.54s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:180: Remote "proton drive root link ID 'rclone-test-qudorey2bazu'", Local "Local file system at /tmp/rclone215716432", Modify Window "1s" 2025/09/22 04:47:32 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-qudorey2bazu/dst" 2025/09/22 04:47:32 DEBUG : Config file has changed externally - reloading 2025/09/22 04:47:32 DEBUG : proton drive root link ID 'rclone-test-qudorey2bazu/dst': Has cached credentials 2025/09/22 04:47:34 DEBUG : proton drive root link ID 'rclone-test-qudorey2bazu/dst': Used cached credential to initialize the ProtonDrive API 2025/09/22 04:49:13 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-qudorey2bazu/backup" 2025/09/22 04:49:13 DEBUG : proton drive root link ID 'rclone-test-qudorey2bazu/backup': Has cached credentials 2025/09/22 04:49:15 DEBUG : proton drive root link ID 'rclone-test-qudorey2bazu/backup': Used cached credential to initialize the ProtonDrive API 2025/09/22 04:52:33 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/09/22 04:52:33 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/22 04:52:33 DEBUG : two: Unchanged skipping 2025/09/22 04:52:33 DEBUG : proton drive root link ID 'rclone-test-qudorey2bazu/dst': Waiting for checks to finish 2025/09/22 04:52:35.135535 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/bjc8cFJUgLJIyRntxlY9Lt9g-1vRpVJ0xZa6GCIRBdY6jdV47vErQBUFrJ9IrCBYZYakMviBfveNvZiRh0uHdw==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/22 04:52:35.135587 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/bjc8cFJUgLJIyRntxlY9Lt9g-1vRpVJ0xZa6GCIRBdY6jdV47vErQBUFrJ9IrCBYZYakMviBfveNvZiRh0uHdw==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/22 04:52:35 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/bjc8cFJUgLJIyRntxlY9Lt9g-1vRpVJ0xZa6GCIRBdY6jdV47vErQBUFrJ9IrCBYZYakMviBfveNvZiRh0uHdw==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/22 04:52:35 DEBUG : proton drive root link ID 'rclone-test-qudorey2bazu/dst': Waiting for transfers to finish 2025/09/22 04:52:35 ERROR : proton drive root link ID 'rclone-test-qudorey2bazu/dst': not deleting files as there were IO errors 2025/09/22 04:52:35 ERROR : proton drive root link ID 'rclone-test-qudorey2bazu/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/bjc8cFJUgLJIyRntxlY9Lt9g-1vRpVJ0xZa6GCIRBdY6jdV47vErQBUFrJ9IrCBYZYakMviBfveNvZiRh0uHdw==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncBackupDirWithSuffixKeepExtension --- FAIL: TestSyncBackupDirWithSuffixKeepExtension (314.91s) === RUN TestSyncBackupDirSuffixOnly run.go:180: Remote "proton drive root link ID 'rclone-test-qudorey2bazu'", Local "Local file system at /tmp/rclone215716432", Modify Window "1s" 2025/09/22 04:52:48 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-qudorey2bazu/dst" 2025/09/22 04:52:48 DEBUG : proton drive root link ID 'rclone-test-qudorey2bazu/dst': Has cached credentials 2025/09/22 04:52:50 DEBUG : proton drive root link ID 'rclone-test-qudorey2bazu/dst': Used cached credential to initialize the ProtonDrive API 2025/09/22 04:54:29 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/09/22 04:54:29 DEBUG : proton drive root link ID 'rclone-test-qudorey2bazu/dst': Waiting for checks to finish 2025/09/22 04:54:29 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/22 04:54:29 DEBUG : two: Unchanged skipping 2025/09/22 04:54:30.423470 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/tveqBUROg_ENudh0KByU6lsg9vk8zUe9fpqzXWsmx7qSsXXFJSfFemv_VQRbyCoSGtYQQYbJ1FoUf-oIn3DaGQ==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/22 04:54:30.423509 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/tveqBUROg_ENudh0KByU6lsg9vk8zUe9fpqzXWsmx7qSsXXFJSfFemv_VQRbyCoSGtYQQYbJ1FoUf-oIn3DaGQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/22 04:54:30 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/tveqBUROg_ENudh0KByU6lsg9vk8zUe9fpqzXWsmx7qSsXXFJSfFemv_VQRbyCoSGtYQQYbJ1FoUf-oIn3DaGQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/22 04:54:30 DEBUG : proton drive root link ID 'rclone-test-qudorey2bazu/dst': Waiting for transfers to finish 2025/09/22 04:54:30 ERROR : proton drive root link ID 'rclone-test-qudorey2bazu/dst': not deleting files as there were IO errors 2025/09/22 04:54:30 ERROR : proton drive root link ID 'rclone-test-qudorey2bazu/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/tveqBUROg_ENudh0KByU6lsg9vk8zUe9fpqzXWsmx7qSsXXFJSfFemv_VQRbyCoSGtYQQYbJ1FoUf-oIn3DaGQ==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncBackupDirSuffixOnly --- FAIL: TestSyncBackupDirSuffixOnly (114.90s) === RUN TestSyncSuffix run.go:180: Remote "proton drive root link ID 'rclone-test-qudorey2bazu'", Local "Local file system at /tmp/rclone215716432", Modify Window "1s" 2025/09/22 04:54:42 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-qudorey2bazu/dst" 2025/09/22 04:54:42 DEBUG : proton drive root link ID 'rclone-test-qudorey2bazu/dst': Has cached credentials 2025/09/22 04:54:44 DEBUG : proton drive root link ID 'rclone-test-qudorey2bazu/dst': Used cached credential to initialize the ProtonDrive API 2025/09/22 04:56:23 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/09/22 04:56:24.520005 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/JigGtlNRVsaLppmkJnkE4sJmYxSkLhHaEpnfeATMKOpRYvuya1VsSso5kVtVK3bXGC-ZEwVsmzgAESphl3LLxA==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/22 04:56:24.520050 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/JigGtlNRVsaLppmkJnkE4sJmYxSkLhHaEpnfeATMKOpRYvuya1VsSso5kVtVK3bXGC-ZEwVsmzgAESphl3LLxA==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/22 04:56:24 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/JigGtlNRVsaLppmkJnkE4sJmYxSkLhHaEpnfeATMKOpRYvuya1VsSso5kVtVK3bXGC-ZEwVsmzgAESphl3LLxA==/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/JigGtlNRVsaLppmkJnkE4sJmYxSkLhHaEpnfeATMKOpRYvuya1VsSso5kVtVK3bXGC-ZEwVsmzgAESphl3LLxA==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncSuffix --- FAIL: TestSyncSuffix (113.75s) === RUN TestSyncSuffixKeepExtension run.go:180: Remote "proton drive root link ID 'rclone-test-qudorey2bazu'", Local "Local file system at /tmp/rclone215716432", Modify Window "1s" 2025/09/22 04:56:36 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-qudorey2bazu/dst" 2025/09/22 04:56:36 DEBUG : proton drive root link ID 'rclone-test-qudorey2bazu/dst': Has cached credentials 2025/09/22 04:56:38 DEBUG : proton drive root link ID 'rclone-test-qudorey2bazu/dst': Used cached credential to initialize the ProtonDrive API 2025/09/22 04:58:16 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/09/22 04:58:17.604141 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/P8jXOHMrfuOiOTL_KUcDNCdRHXiqkoS-x-a8VP9rXONAMOoISr2ijTFwSSFviggTkV3t4eGpu0DciplndgUf_A==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/22 04:58:17.604179 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/P8jXOHMrfuOiOTL_KUcDNCdRHXiqkoS-x-a8VP9rXONAMOoISr2ijTFwSSFviggTkV3t4eGpu0DciplndgUf_A==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/22 04:58:17 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/P8jXOHMrfuOiOTL_KUcDNCdRHXiqkoS-x-a8VP9rXONAMOoISr2ijTFwSSFviggTkV3t4eGpu0DciplndgUf_A==/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/P8jXOHMrfuOiOTL_KUcDNCdRHXiqkoS-x-a8VP9rXONAMOoISr2ijTFwSSFviggTkV3t4eGpu0DciplndgUf_A==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncSuffixKeepExtension --- FAIL: TestSyncSuffixKeepExtension (113.06s) FAIL 2025/09/22 04:58:21 DEBUG : proton drive root link ID 'rclone-test-qudorey2bazu': 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 23m46.950141259s (try 4/5): exit status 1: Failed [TestServerSideMoveOverSelf TestSyncWithTrackRenames TestSyncWithTrackRenamesStrategyModtime TestSyncWithTrackRenamesStrategyLeaf TestMoveWithDeleteEmptySrcDirs TestServerSideMove TestServerSideMoveWithFilter TestServerSideMoveDeleteEmptySourceDirs TestSyncBackupDir TestSyncBackupDirWithSuffix TestSyncBackupDirWithSuffixKeepExtension TestSyncBackupDirSuffixOnly TestSyncSuffix TestSyncSuffixKeepExtension]