"./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 5/5) 2025/08/18 04:41:17 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-fideruh0nilu" 2025/08/18 04:41:17 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/08/18 04:41:17 DEBUG : proton drive root link ID 'rclone-test-fideruh0nilu': Has cached credentials 2025/08/18 04:41:20 DEBUG : proton drive root link ID 'rclone-test-fideruh0nilu': Used cached credential to initialize the ProtonDrive API 2025/08/18 04:41:20 DEBUG : Creating backend with remote "/tmp/rclone3728299420" === RUN TestServerSideMoveOverSelf run.go:180: Remote "proton drive root link ID 'rclone-test-fideruh0nilu'", Local "Local file system at /tmp/rclone3728299420", Modify Window "1s" 2025/08/18 04:41:25 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-zonisuh2lewi" 2025/08/18 04:41:25 DEBUG : proton drive root link ID 'rclone-test-zonisuh2lewi': Has cached credentials 2025/08/18 04:41:28 DEBUG : proton drive root link ID 'rclone-test-zonisuh2lewi': Used cached credential to initialize the ProtonDrive API sync_test.go:701: Server side copy (if possible) proton drive root link ID 'rclone-test-fideruh0nilu' -> proton drive root link ID 'rclone-test-zonisuh2lewi' 2025/08/18 04:41:29 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/08/18 04:41:29 DEBUG : proton drive root link ID 'rclone-test-zonisuh2lewi': Waiting for checks to finish 2025/08/18 04:41:29 DEBUG : proton drive root link ID 'rclone-test-zonisuh2lewi': Waiting for transfers to finish 2025/08/18 04:41:35 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2025/08/18 04:41:35 INFO : sub dir/hello world: Copied (new) 2025/08/18 04:41:37.592730 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/08/18 04:41:37.592769 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/08/18 04:41:41 DEBUG : proton drive root link ID 'rclone-test-zonisuh2lewi': Using server-side directory move 2025/08/18 04:41:41 INFO : proton drive root link ID 'rclone-test-zonisuh2lewi': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/08/18 04:41:42 DEBUG : proton drive root link ID 'rclone-test-zonisuh2lewi': Waiting for checks to finish 2025/08/18 04:41:42 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/08/18 04:41:42 DEBUG : proton drive root link ID 'rclone-test-zonisuh2lewi': Waiting for transfers to finish 2025/08/18 04:41:43 INFO : sub dir/hello world: Deleted 2025/08/18 04:41:44.509465 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/s7OMUEs24cXTLqeMNtWsNwAqu-zb1rgVDB1kAkU3Mh6goYnD9B6tYqvETrufjTfYB9UnazgtbmT4ezNXB9qv2g==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/08/18 04:41:44.509509 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/s7OMUEs24cXTLqeMNtWsNwAqu-zb1rgVDB1kAkU3Mh6goYnD9B6tYqvETrufjTfYB9UnazgtbmT4ezNXB9qv2g==/move: This value should not be blank. (Code=2000, Status=400) 2025/08/18 04:41:44 ERROR : sub dir/hello world: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/s7OMUEs24cXTLqeMNtWsNwAqu-zb1rgVDB1kAkU3Mh6goYnD9B6tYqvETrufjTfYB9UnazgtbmT4ezNXB9qv2g==/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/s7OMUEs24cXTLqeMNtWsNwAqu-zb1rgVDB1kAkU3Mh6goYnD9B6tYqvETrufjTfYB9UnazgtbmT4ezNXB9qv2g==/move: This value should not be blank. (Code=2000, Status=400) Test: TestServerSideMoveOverSelf 2025/08/18 04:41:44 DEBUG : proton drive root link ID 'rclone-test-zonisuh2lewi': Purge remote --- FAIL: TestServerSideMoveOverSelf (27.39s) === RUN TestSyncWithTrackRenames run.go:180: Remote "proton drive root link ID 'rclone-test-fideruh0nilu'", Local "Local file system at /tmp/rclone3728299420", Modify Window "1s" sync_test.go:1568: Can track renames: true 2025/08/18 04:41:48 INFO : proton drive root link ID 'rclone-test-fideruh0nilu': Making map for --track-renames 2025/08/18 04:41:48 INFO : proton drive root link ID 'rclone-test-fideruh0nilu': Finished making map for --track-renames 2025/08/18 04:41:48 DEBUG : proton drive root link ID 'rclone-test-fideruh0nilu': Waiting for checks to finish 2025/08/18 04:41:48 DEBUG : proton drive root link ID 'rclone-test-fideruh0nilu': Waiting for renames to finish 2025/08/18 04:41:48 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/08/18 04:41:48 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/08/18 04:41:48 DEBUG : proton drive root link ID 'rclone-test-fideruh0nilu': Waiting for transfers to finish 2025/08/18 04:41:50 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/08/18 04:41:50 INFO : potato: Copied (new) 2025/08/18 04:41:50 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/08/18 04:41:50 INFO : yam: Copied (new) 2025/08/18 04:41:50 DEBUG : Waiting for deletions to finish 2025/08/18 04:41:52 DEBUG : potato: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/08/18 04:41:52 INFO : proton drive root link ID 'rclone-test-fideruh0nilu': Making map for --track-renames 2025/08/18 04:41:52 INFO : proton drive root link ID 'rclone-test-fideruh0nilu': Finished making map for --track-renames 2025/08/18 04:41:52 DEBUG : proton drive root link ID 'rclone-test-fideruh0nilu': Waiting for checks to finish 2025/08/18 04:41:52 DEBUG : potato: Unchanged skipping 2025/08/18 04:41:52 DEBUG : proton drive root link ID 'rclone-test-fideruh0nilu': Waiting for renames to finish 2025/08/18 04:41:52.980291 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/uX0Rf7wBHMtX3HXC_Ul6kS_k1wfLd1rWDhJ_62_jPhhmlFlzZJfKsvwVpkTHeUHzB3gttJqRjdDStDccOkKDfA==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/08/18 04:41:52.980344 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/uX0Rf7wBHMtX3HXC_Ul6kS_k1wfLd1rWDhJ_62_jPhhmlFlzZJfKsvwVpkTHeUHzB3gttJqRjdDStDccOkKDfA==/move: This value should not be blank. (Code=2000, Status=400) 2025/08/18 04:41:52 ERROR : yam: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/uX0Rf7wBHMtX3HXC_Ul6kS_k1wfLd1rWDhJ_62_jPhhmlFlzZJfKsvwVpkTHeUHzB3gttJqRjdDStDccOkKDfA==/move: This value should not be blank. (Code=2000, Status=400) 2025/08/18 04:41:52 DEBUG : yaml: Failed to rename to "yam": 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/uX0Rf7wBHMtX3HXC_Ul6kS_k1wfLd1rWDhJ_62_jPhhmlFlzZJfKsvwVpkTHeUHzB3gttJqRjdDStDccOkKDfA==/move: This value should not be blank. (Code=2000, Status=400) 2025/08/18 04:41:52 DEBUG : yaml: Need to transfer - No matching file found at Destination 2025/08/18 04:41:52 DEBUG : proton drive root link ID 'rclone-test-fideruh0nilu': Waiting for transfers to finish 2025/08/18 04:41:55 DEBUG : yaml: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/08/18 04:41:55 INFO : yaml: Copied (new) 2025/08/18 04:41:55 ERROR : proton drive root link ID 'rclone-test-fideruh0nilu': not deleting files as there were IO errors 2025/08/18 04:41:55 ERROR : proton drive root link ID 'rclone-test-fideruh0nilu': 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.18s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:180: Remote "proton drive root link ID 'rclone-test-fideruh0nilu'", Local "Local file system at /tmp/rclone3728299420", Modify Window "1s" sync_test.go:1640: Can track renames: true 2025/08/18 04:41:58 INFO : proton drive root link ID 'rclone-test-fideruh0nilu': Making map for --track-renames 2025/08/18 04:41:58 INFO : proton drive root link ID 'rclone-test-fideruh0nilu': Finished making map for --track-renames 2025/08/18 04:41:58 DEBUG : proton drive root link ID 'rclone-test-fideruh0nilu': Waiting for checks to finish 2025/08/18 04:41:58 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/08/18 04:41:58 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/08/18 04:41:58 DEBUG : proton drive root link ID 'rclone-test-fideruh0nilu': Waiting for renames to finish 2025/08/18 04:41:58 DEBUG : proton drive root link ID 'rclone-test-fideruh0nilu': Waiting for transfers to finish 2025/08/18 04:42:00 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/08/18 04:42:00 INFO : potato: Copied (new) 2025/08/18 04:42:00 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/08/18 04:42:00 INFO : yam: Copied (new) 2025/08/18 04:42:00 DEBUG : Waiting for deletions to finish 2025/08/18 04:42:02 DEBUG : potato: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/08/18 04:42:02 INFO : proton drive root link ID 'rclone-test-fideruh0nilu': Making map for --track-renames 2025/08/18 04:42:02 INFO : proton drive root link ID 'rclone-test-fideruh0nilu': Finished making map for --track-renames 2025/08/18 04:42:02 DEBUG : proton drive root link ID 'rclone-test-fideruh0nilu': Waiting for checks to finish 2025/08/18 04:42:02 DEBUG : potato: Unchanged skipping 2025/08/18 04:42:02 DEBUG : proton drive root link ID 'rclone-test-fideruh0nilu': Waiting for renames to finish 2025/08/18 04:42:03.760366 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/gVCZ_wPYxdftzzx68z-aBtygxzgY8bclinNIFtA3E19lTdswpN-vfdnhrXOrkuqxOo4oPdZ4mqc0JFNaTVPvWw==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/08/18 04:42:03.760395 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/gVCZ_wPYxdftzzx68z-aBtygxzgY8bclinNIFtA3E19lTdswpN-vfdnhrXOrkuqxOo4oPdZ4mqc0JFNaTVPvWw==/move: This value should not be blank. (Code=2000, Status=400) 2025/08/18 04:42:03 ERROR : yam: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/gVCZ_wPYxdftzzx68z-aBtygxzgY8bclinNIFtA3E19lTdswpN-vfdnhrXOrkuqxOo4oPdZ4mqc0JFNaTVPvWw==/move: This value should not be blank. (Code=2000, Status=400) 2025/08/18 04:42:03 DEBUG : yaml: Failed to rename to "yam": 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/gVCZ_wPYxdftzzx68z-aBtygxzgY8bclinNIFtA3E19lTdswpN-vfdnhrXOrkuqxOo4oPdZ4mqc0JFNaTVPvWw==/move: This value should not be blank. (Code=2000, Status=400) 2025/08/18 04:42:03 DEBUG : yaml: Need to transfer - No matching file found at Destination 2025/08/18 04:42:03 DEBUG : proton drive root link ID 'rclone-test-fideruh0nilu': Waiting for transfers to finish 2025/08/18 04:42:05 DEBUG : yaml: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/08/18 04:42:05 INFO : yaml: Copied (new) 2025/08/18 04:42:05 ERROR : proton drive root link ID 'rclone-test-fideruh0nilu': not deleting files as there were IO errors 2025/08/18 04:42:05 ERROR : proton drive root link ID 'rclone-test-fideruh0nilu': 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.50s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:180: Remote "proton drive root link ID 'rclone-test-fideruh0nilu'", Local "Local file system at /tmp/rclone3728299420", Modify Window "1s" sync_test.go:1679: Can track renames: true 2025/08/18 04:42:09 INFO : proton drive root link ID 'rclone-test-fideruh0nilu': Making map for --track-renames 2025/08/18 04:42:09 INFO : proton drive root link ID 'rclone-test-fideruh0nilu': Finished making map for --track-renames 2025/08/18 04:42:09 DEBUG : proton drive root link ID 'rclone-test-fideruh0nilu': Waiting for checks to finish 2025/08/18 04:42:09 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/08/18 04:42:09 DEBUG : sub/yam: Need to transfer - No matching file found at Destination 2025/08/18 04:42:09 DEBUG : proton drive root link ID 'rclone-test-fideruh0nilu': Waiting for renames to finish 2025/08/18 04:42:09 DEBUG : proton drive root link ID 'rclone-test-fideruh0nilu': Waiting for transfers to finish 2025/08/18 04:42:11 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/08/18 04:42:11 INFO : potato: Copied (new) 2025/08/18 04:42:12 DEBUG : sub/yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/08/18 04:42:12 INFO : sub/yam: Copied (new) 2025/08/18 04:42:12 DEBUG : Waiting for deletions to finish 2025/08/18 04:42:14 DEBUG : potato: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/08/18 04:42:14 DEBUG : potato: Unchanged skipping 2025/08/18 04:42:15 INFO : proton drive root link ID 'rclone-test-fideruh0nilu': Making map for --track-renames 2025/08/18 04:42:15 INFO : proton drive root link ID 'rclone-test-fideruh0nilu': Finished making map for --track-renames 2025/08/18 04:42:15 DEBUG : proton drive root link ID 'rclone-test-fideruh0nilu': Waiting for checks to finish 2025/08/18 04:42:15 DEBUG : proton drive root link ID 'rclone-test-fideruh0nilu': Waiting for renames to finish 2025/08/18 04:42:15.864074 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/wAjnPTevVqiZ1UhmUm3PBdChueBW8hjVcB-VVnCplDK1PcQmRsUD-DRmU6hROS8x13p6f7gmHZVg5mdMr5B5uA==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/08/18 04:42:15.864112 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/wAjnPTevVqiZ1UhmUm3PBdChueBW8hjVcB-VVnCplDK1PcQmRsUD-DRmU6hROS8x13p6f7gmHZVg5mdMr5B5uA==/move: This value should not be blank. (Code=2000, Status=400) 2025/08/18 04:42:15 ERROR : sub/yam: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/wAjnPTevVqiZ1UhmUm3PBdChueBW8hjVcB-VVnCplDK1PcQmRsUD-DRmU6hROS8x13p6f7gmHZVg5mdMr5B5uA==/move: This value should not be blank. (Code=2000, Status=400) 2025/08/18 04:42:15 DEBUG : yam: Failed to rename to "sub/yam": 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/wAjnPTevVqiZ1UhmUm3PBdChueBW8hjVcB-VVnCplDK1PcQmRsUD-DRmU6hROS8x13p6f7gmHZVg5mdMr5B5uA==/move: This value should not be blank. (Code=2000, Status=400) 2025/08/18 04:42:15 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/08/18 04:42:15 DEBUG : proton drive root link ID 'rclone-test-fideruh0nilu': Waiting for transfers to finish 2025/08/18 04:42:17 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/08/18 04:42:17 INFO : yam: Copied (new) 2025/08/18 04:42:17 ERROR : proton drive root link ID 'rclone-test-fideruh0nilu': not deleting files as there were IO errors 2025/08/18 04:42:17 ERROR : proton drive root link ID 'rclone-test-fideruh0nilu': 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 (13.63s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:180: Remote "proton drive root link ID 'rclone-test-fideruh0nilu'", Local "Local file system at /tmp/rclone3728299420", Modify Window "1s" 2025/08/18 04:42:22 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/08/18 04:42:22 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2025/08/18 04:42:22 DEBUG : proton drive root link ID 'rclone-test-fideruh0nilu': Waiting for checks to finish 2025/08/18 04:42:22 DEBUG : proton drive root link ID 'rclone-test-fideruh0nilu': Waiting for transfers to finish 2025/08/18 04:42:27 DEBUG : nested/sub dir/file: sha1 = b4b3e0a278988bc15f2913af3f4153ccef74e465 OK 2025/08/18 04:42:27 INFO : nested/sub dir/file: Copied (new) 2025/08/18 04:42:27 INFO : nested/sub dir/file: Deleted 2025/08/18 04:42:27 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2025/08/18 04:42:27 INFO : sub dir/hello world: Copied (new) 2025/08/18 04:42:27 INFO : sub dir/hello world: Deleted 2025/08/18 04:42:27 ERROR : Local file system at /tmp/rclone3728299420: 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 (9.98s) === RUN TestServerSideMove run.go:180: Remote "proton drive root link ID 'rclone-test-fideruh0nilu'", Local "Local file system at /tmp/rclone3728299420", Modify Window "1s" 2025/08/18 04:42:32 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-vutokon5vuro" 2025/08/18 04:42:32 DEBUG : proton drive root link ID 'rclone-test-vutokon5vuro': Has cached credentials 2025/08/18 04:42:34 DEBUG : proton drive root link ID 'rclone-test-vutokon5vuro': Used cached credential to initialize the ProtonDrive API sync_test.go:1735: Server side move (if possible) proton drive root link ID 'rclone-test-fideruh0nilu' -> proton drive root link ID 'rclone-test-vutokon5vuro' 2025/08/18 04:42:46 DEBUG : proton drive root link ID 'rclone-test-vutokon5vuro': Using server-side directory move 2025/08/18 04:42:46 INFO : proton drive root link ID 'rclone-test-vutokon5vuro': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/08/18 04:42:47 DEBUG : potato2: Need to transfer - File not found at Destination 2025/08/18 04:42:47 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1s) 2025/08/18 04:42:47 DEBUG : empty space: Unchanged skipping 2025/08/18 04:42:47 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/08/18 04:42:47 DEBUG : proton drive root link ID 'rclone-test-vutokon5vuro': Waiting for checks to finish 2025/08/18 04:42:48 INFO : potato3: Deleted 2025/08/18 04:42:48 INFO : empty space: Deleted 2025/08/18 04:42:48 DEBUG : proton drive root link ID 'rclone-test-vutokon5vuro': Waiting for transfers to finish 2025/08/18 04:42:49.011058 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/s0-R56uZEXVoSa1zje4wSW3HOcpiBogMOn0mtHQ9tbmwP8OSM_gx7ZmllSx7VXysNbRjdFLd75CXP2hTURGUtQ==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/08/18 04:42:49.011085 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/s0-R56uZEXVoSa1zje4wSW3HOcpiBogMOn0mtHQ9tbmwP8OSM_gx7ZmllSx7VXysNbRjdFLd75CXP2hTURGUtQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/08/18 04:42:49 ERROR : potato3: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/s0-R56uZEXVoSa1zje4wSW3HOcpiBogMOn0mtHQ9tbmwP8OSM_gx7ZmllSx7VXysNbRjdFLd75CXP2hTURGUtQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/08/18 04:42:49.112880 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/7kscm5EA46fwaslJqhMJ8gT07UTjFJqVAtqCGzndmIN380N9A_Hze7Io1IjGSeO47QlRX1QzTV856QsIB86tag==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/08/18 04:42:49.112903 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/7kscm5EA46fwaslJqhMJ8gT07UTjFJqVAtqCGzndmIN380N9A_Hze7Io1IjGSeO47QlRX1QzTV856QsIB86tag==/move: This value should not be blank. (Code=2000, Status=400) 2025/08/18 04:42:49 ERROR : potato2: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/7kscm5EA46fwaslJqhMJ8gT07UTjFJqVAtqCGzndmIN380N9A_Hze7Io1IjGSeO47QlRX1QzTV856QsIB86tag==/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/7kscm5EA46fwaslJqhMJ8gT07UTjFJqVAtqCGzndmIN380N9A_Hze7Io1IjGSeO47QlRX1QzTV856QsIB86tag==/move: This value should not be blank. (Code=2000, Status=400) Test: TestServerSideMove 2025/08/18 04:42:49 DEBUG : proton drive root link ID 'rclone-test-vutokon5vuro': Purge remote --- FAIL: TestServerSideMove (20.59s) === RUN TestServerSideMoveWithFilter run.go:180: Remote "proton drive root link ID 'rclone-test-fideruh0nilu'", Local "Local file system at /tmp/rclone3728299420", Modify Window "1s" 2025/08/18 04:42:52 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-lufohey0cete" 2025/08/18 04:42:52 DEBUG : proton drive root link ID 'rclone-test-lufohey0cete': Has cached credentials 2025/08/18 04:42:55 DEBUG : proton drive root link ID 'rclone-test-lufohey0cete': Used cached credential to initialize the ProtonDrive API sync_test.go:1735: Server side move (if possible) proton drive root link ID 'rclone-test-fideruh0nilu' -> proton drive root link ID 'rclone-test-lufohey0cete' 2025/08/18 04:43:08 DEBUG : empty space: Excluded (Size Filter) 2025/08/18 04:43:08 DEBUG : empty space: Excluded 2025/08/18 04:43:08 DEBUG : empty space: Excluded (Size Filter) 2025/08/18 04:43:08 DEBUG : empty space: Excluded 2025/08/18 04:43:08 DEBUG : potato2: Need to transfer - File not found at Destination 2025/08/18 04:43:08 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/08/18 04:43:08 DEBUG : proton drive root link ID 'rclone-test-lufohey0cete': Waiting for checks to finish 2025/08/18 04:43:08 DEBUG : proton drive root link ID 'rclone-test-lufohey0cete': Waiting for transfers to finish 2025/08/18 04:43:08 INFO : potato3: Deleted 2025/08/18 04:43:09.392430 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/1Hzh-2aPwYVa8bVid7x6wqael3_s8r4hwKTJhCJ4fDi6x0xt8f4mEoOrV-qOLQ22Fh2CaX8wntUmXOZgnIQ0QQ==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/08/18 04:43:09.392466 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/1Hzh-2aPwYVa8bVid7x6wqael3_s8r4hwKTJhCJ4fDi6x0xt8f4mEoOrV-qOLQ22Fh2CaX8wntUmXOZgnIQ0QQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/08/18 04:43:09 ERROR : potato2: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/1Hzh-2aPwYVa8bVid7x6wqael3_s8r4hwKTJhCJ4fDi6x0xt8f4mEoOrV-qOLQ22Fh2CaX8wntUmXOZgnIQ0QQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/08/18 04:43:09.526845 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/v_8AHPYe5RvJr1vzhPLpcvI_WrKY3w4iM9ll6mzjC7W9RCVAuu4QjDYKIjykzKF4aAvDNSlOgR6KiXuW5jUgLw==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/08/18 04:43:09.526890 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/v_8AHPYe5RvJr1vzhPLpcvI_WrKY3w4iM9ll6mzjC7W9RCVAuu4QjDYKIjykzKF4aAvDNSlOgR6KiXuW5jUgLw==/move: This value should not be blank. (Code=2000, Status=400) 2025/08/18 04:43:09 ERROR : potato3: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/v_8AHPYe5RvJr1vzhPLpcvI_WrKY3w4iM9ll6mzjC7W9RCVAuu4QjDYKIjykzKF4aAvDNSlOgR6KiXuW5jUgLw==/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/v_8AHPYe5RvJr1vzhPLpcvI_WrKY3w4iM9ll6mzjC7W9RCVAuu4QjDYKIjykzKF4aAvDNSlOgR6KiXuW5jUgLw==/move: This value should not be blank. (Code=2000, Status=400) Test: TestServerSideMoveWithFilter 2025/08/18 04:43:09 DEBUG : proton drive root link ID 'rclone-test-lufohey0cete': Purge remote --- FAIL: TestServerSideMoveWithFilter (20.97s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:180: Remote "proton drive root link ID 'rclone-test-fideruh0nilu'", Local "Local file system at /tmp/rclone3728299420", Modify Window "1s" 2025/08/18 04:43:13 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-lepuxil0kete" 2025/08/18 04:43:13 DEBUG : proton drive root link ID 'rclone-test-lepuxil0kete': Has cached credentials 2025/08/18 04:43:16 DEBUG : proton drive root link ID 'rclone-test-lepuxil0kete': Used cached credential to initialize the ProtonDrive API 2025/08/18 04:43:21 INFO : tomatoDir: Making directory sync_test.go:1735: Server side move (if possible) proton drive root link ID 'rclone-test-fideruh0nilu' -> proton drive root link ID 'rclone-test-lepuxil0kete' 2025/08/18 04:43:29 DEBUG : proton drive root link ID 'rclone-test-lepuxil0kete': Using server-side directory move 2025/08/18 04:43:29 INFO : proton drive root link ID 'rclone-test-lepuxil0kete': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/08/18 04:43:31 DEBUG : potato2: Need to transfer - File not found at Destination 2025/08/18 04:43:31 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1s) 2025/08/18 04:43:31 DEBUG : empty space: Unchanged skipping 2025/08/18 04:43:31 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/08/18 04:43:31 DEBUG : proton drive root link ID 'rclone-test-lepuxil0kete': Waiting for checks to finish 2025/08/18 04:43:31 INFO : empty space: Deleted 2025/08/18 04:43:31 DEBUG : proton drive root link ID 'rclone-test-lepuxil0kete': Waiting for transfers to finish 2025/08/18 04:43:31 INFO : potato3: Deleted 2025/08/18 04:43:32.010068 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/qVkkqst49ozMWvqkNuhn1Vn-bFLVtl4iESPyH4QYFHbhbmyKo15mTfawgVDcsg_J3LcogGNDyJINEEe19clNpA==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/08/18 04:43:32.010104 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/qVkkqst49ozMWvqkNuhn1Vn-bFLVtl4iESPyH4QYFHbhbmyKo15mTfawgVDcsg_J3LcogGNDyJINEEe19clNpA==/move: This value should not be blank. (Code=2000, Status=400) 2025/08/18 04:43:32 ERROR : potato2: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/qVkkqst49ozMWvqkNuhn1Vn-bFLVtl4iESPyH4QYFHbhbmyKo15mTfawgVDcsg_J3LcogGNDyJINEEe19clNpA==/move: This value should not be blank. (Code=2000, Status=400) 2025/08/18 04:43:32.352138 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/Q4SJ2vKNavBhl4i2ytpbQT8aqrjrR7sTmpQlAPnZ4Y87yv3d9yQirjW0UUpPKFpU7c5Z4zP2nq3S9rlg71dfCg==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/08/18 04:43:32.352167 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/Q4SJ2vKNavBhl4i2ytpbQT8aqrjrR7sTmpQlAPnZ4Y87yv3d9yQirjW0UUpPKFpU7c5Z4zP2nq3S9rlg71dfCg==/move: This value should not be blank. (Code=2000, Status=400) 2025/08/18 04:43:32 ERROR : potato3: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/Q4SJ2vKNavBhl4i2ytpbQT8aqrjrR7sTmpQlAPnZ4Y87yv3d9yQirjW0UUpPKFpU7c5Z4zP2nq3S9rlg71dfCg==/move: This value should not be blank. (Code=2000, Status=400) 2025/08/18 04:43:32 ERROR : proton drive root link ID 'rclone-test-fideruh0nilu': 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/08/18 04:43:32 DEBUG : proton drive root link ID 'rclone-test-lepuxil0kete': Purge remote --- FAIL: TestServerSideMoveDeleteEmptySourceDirs (23.28s) === RUN TestSyncBackupDir run.go:180: Remote "proton drive root link ID 'rclone-test-fideruh0nilu'", Local "Local file system at /tmp/rclone3728299420", Modify Window "1s" 2025/08/18 04:43:44 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-fideruh0nilu/dst" 2025/08/18 04:43:44 DEBUG : Config file has changed externally - reloading 2025/08/18 04:43:44 DEBUG : proton drive root link ID 'rclone-test-fideruh0nilu/dst': Has cached credentials 2025/08/18 04:43:46 DEBUG : proton drive root link ID 'rclone-test-fideruh0nilu/dst': Used cached credential to initialize the ProtonDrive API 2025/08/18 04:45:14 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-fideruh0nilu/backup" 2025/08/18 04:45:14 DEBUG : proton drive root link ID 'rclone-test-fideruh0nilu/backup': Has cached credentials 2025/08/18 04:45:16 DEBUG : proton drive root link ID 'rclone-test-fideruh0nilu/backup': Used cached credential to initialize the ProtonDrive API 2025/08/18 04:48:10 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/08/18 04:48:10 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/08/18 04:48:10 DEBUG : two: Unchanged skipping 2025/08/18 04:48:10 DEBUG : proton drive root link ID 'rclone-test-fideruh0nilu/dst': Waiting for checks to finish 2025/08/18 04:48:13.294253 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/2cS9ujYnQ3pL0sTQB5mCjOjR882fxV29KV3ukCpI6XqAGyPK2dJStIPm5VmKtxCnfhkIu5YmEpgBm_5iHBFSKA==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/08/18 04:48:13.294283 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/2cS9ujYnQ3pL0sTQB5mCjOjR882fxV29KV3ukCpI6XqAGyPK2dJStIPm5VmKtxCnfhkIu5YmEpgBm_5iHBFSKA==/move: This value should not be blank. (Code=2000, Status=400) 2025/08/18 04:48:13 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/2cS9ujYnQ3pL0sTQB5mCjOjR882fxV29KV3ukCpI6XqAGyPK2dJStIPm5VmKtxCnfhkIu5YmEpgBm_5iHBFSKA==/move: This value should not be blank. (Code=2000, Status=400) 2025/08/18 04:48:13 DEBUG : proton drive root link ID 'rclone-test-fideruh0nilu/dst': Waiting for transfers to finish 2025/08/18 04:48:13 ERROR : proton drive root link ID 'rclone-test-fideruh0nilu/dst': not deleting files as there were IO errors 2025/08/18 04:48:13 ERROR : proton drive root link ID 'rclone-test-fideruh0nilu/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/2cS9ujYnQ3pL0sTQB5mCjOjR882fxV29KV3ukCpI6XqAGyPK2dJStIPm5VmKtxCnfhkIu5YmEpgBm_5iHBFSKA==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncBackupDir --- FAIL: TestSyncBackupDir (281.48s) === RUN TestSyncBackupDirWithSuffix run.go:180: Remote "proton drive root link ID 'rclone-test-fideruh0nilu'", Local "Local file system at /tmp/rclone3728299420", Modify Window "1s" 2025/08/18 04:48:26 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-fideruh0nilu/dst" 2025/08/18 04:48:26 DEBUG : proton drive root link ID 'rclone-test-fideruh0nilu/dst': Has cached credentials 2025/08/18 04:48:28 DEBUG : proton drive root link ID 'rclone-test-fideruh0nilu/dst': Used cached credential to initialize the ProtonDrive API 2025/08/18 04:49:55 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-fideruh0nilu/backup" 2025/08/18 04:49:55 DEBUG : proton drive root link ID 'rclone-test-fideruh0nilu/backup': Has cached credentials 2025/08/18 04:49:57 DEBUG : proton drive root link ID 'rclone-test-fideruh0nilu/backup': Used cached credential to initialize the ProtonDrive API 2025/08/18 04:52:50 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/08/18 04:52:50 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/08/18 04:52:50 DEBUG : two: Unchanged skipping 2025/08/18 04:52:50 DEBUG : proton drive root link ID 'rclone-test-fideruh0nilu/dst': Waiting for checks to finish 2025/08/18 04:52:52.633603 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/mw21e63lzANMcpc5iaAEeiBZjOmorRzH8be6lmsfClpf8K309JlPd6ZqgEwce5XEuEzl-fNnIEF6Pl1Zj-V8yw==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/08/18 04:52:52.633741 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/mw21e63lzANMcpc5iaAEeiBZjOmorRzH8be6lmsfClpf8K309JlPd6ZqgEwce5XEuEzl-fNnIEF6Pl1Zj-V8yw==/move: This value should not be blank. (Code=2000, Status=400) 2025/08/18 04:52:52 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/mw21e63lzANMcpc5iaAEeiBZjOmorRzH8be6lmsfClpf8K309JlPd6ZqgEwce5XEuEzl-fNnIEF6Pl1Zj-V8yw==/move: This value should not be blank. (Code=2000, Status=400) 2025/08/18 04:52:52 DEBUG : proton drive root link ID 'rclone-test-fideruh0nilu/dst': Waiting for transfers to finish 2025/08/18 04:52:52 ERROR : proton drive root link ID 'rclone-test-fideruh0nilu/dst': not deleting files as there were IO errors 2025/08/18 04:52:52 ERROR : proton drive root link ID 'rclone-test-fideruh0nilu/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/mw21e63lzANMcpc5iaAEeiBZjOmorRzH8be6lmsfClpf8K309JlPd6ZqgEwce5XEuEzl-fNnIEF6Pl1Zj-V8yw==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncBackupDirWithSuffix --- FAIL: TestSyncBackupDirWithSuffix (279.05s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:180: Remote "proton drive root link ID 'rclone-test-fideruh0nilu'", Local "Local file system at /tmp/rclone3728299420", Modify Window "1s" 2025/08/18 04:53:05 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-fideruh0nilu/dst" 2025/08/18 04:53:05 DEBUG : proton drive root link ID 'rclone-test-fideruh0nilu/dst': Has cached credentials 2025/08/18 04:53:07 DEBUG : proton drive root link ID 'rclone-test-fideruh0nilu/dst': Used cached credential to initialize the ProtonDrive API 2025/08/18 04:54:34 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-fideruh0nilu/backup" 2025/08/18 04:54:34 DEBUG : proton drive root link ID 'rclone-test-fideruh0nilu/backup': Has cached credentials 2025/08/18 04:54:36 DEBUG : proton drive root link ID 'rclone-test-fideruh0nilu/backup': Used cached credential to initialize the ProtonDrive API 2025/08/18 04:57:29 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/08/18 04:57:29 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/08/18 04:57:29 DEBUG : two: Unchanged skipping 2025/08/18 04:57:29 DEBUG : proton drive root link ID 'rclone-test-fideruh0nilu/dst': Waiting for checks to finish 2025/08/18 04:57:31.597610 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/pZ6acOdUlpbrNKY5vtKgbDIAfhcsDDqfHQhlaCFIDVH8t9CQ7JUuUTPHsv10C6UiMwvpmJSBMMNFkwFQtP64qA==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/08/18 04:57:31.597824 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/pZ6acOdUlpbrNKY5vtKgbDIAfhcsDDqfHQhlaCFIDVH8t9CQ7JUuUTPHsv10C6UiMwvpmJSBMMNFkwFQtP64qA==/move: This value should not be blank. (Code=2000, Status=400) 2025/08/18 04:57:31 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/pZ6acOdUlpbrNKY5vtKgbDIAfhcsDDqfHQhlaCFIDVH8t9CQ7JUuUTPHsv10C6UiMwvpmJSBMMNFkwFQtP64qA==/move: This value should not be blank. (Code=2000, Status=400) 2025/08/18 04:57:31 DEBUG : proton drive root link ID 'rclone-test-fideruh0nilu/dst': Waiting for transfers to finish 2025/08/18 04:57:31 ERROR : proton drive root link ID 'rclone-test-fideruh0nilu/dst': not deleting files as there were IO errors 2025/08/18 04:57:31 ERROR : proton drive root link ID 'rclone-test-fideruh0nilu/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/pZ6acOdUlpbrNKY5vtKgbDIAfhcsDDqfHQhlaCFIDVH8t9CQ7JUuUTPHsv10C6UiMwvpmJSBMMNFkwFQtP64qA==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncBackupDirWithSuffixKeepExtension --- FAIL: TestSyncBackupDirWithSuffixKeepExtension (279.71s) === RUN TestSyncBackupDirSuffixOnly run.go:180: Remote "proton drive root link ID 'rclone-test-fideruh0nilu'", Local "Local file system at /tmp/rclone3728299420", Modify Window "1s" 2025/08/18 04:57:44 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-fideruh0nilu/dst" 2025/08/18 04:57:44 DEBUG : proton drive root link ID 'rclone-test-fideruh0nilu/dst': Has cached credentials 2025/08/18 04:57:46 DEBUG : proton drive root link ID 'rclone-test-fideruh0nilu/dst': Used cached credential to initialize the ProtonDrive API 2025/08/18 04:59:16 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/08/18 04:59:16 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/08/18 04:59:16 DEBUG : two: Unchanged skipping 2025/08/18 04:59:16 DEBUG : proton drive root link ID 'rclone-test-fideruh0nilu/dst': Waiting for checks to finish 2025/08/18 04:59:17.502182 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/F-8QLsY9uTH0MaH7uoTQw3ohafVUz_BUZJH4zyIF2bARw3zAVVC6t0h7weiupjjeMj5k1J5a4dmyTspSiHUwIA==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/08/18 04:59:17.502218 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/F-8QLsY9uTH0MaH7uoTQw3ohafVUz_BUZJH4zyIF2bARw3zAVVC6t0h7weiupjjeMj5k1J5a4dmyTspSiHUwIA==/move: This value should not be blank. (Code=2000, Status=400) 2025/08/18 04:59:17 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/F-8QLsY9uTH0MaH7uoTQw3ohafVUz_BUZJH4zyIF2bARw3zAVVC6t0h7weiupjjeMj5k1J5a4dmyTspSiHUwIA==/move: This value should not be blank. (Code=2000, Status=400) 2025/08/18 04:59:17 DEBUG : proton drive root link ID 'rclone-test-fideruh0nilu/dst': Waiting for transfers to finish 2025/08/18 04:59:17 ERROR : proton drive root link ID 'rclone-test-fideruh0nilu/dst': not deleting files as there were IO errors 2025/08/18 04:59:17 ERROR : proton drive root link ID 'rclone-test-fideruh0nilu/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/F-8QLsY9uTH0MaH7uoTQw3ohafVUz_BUZJH4zyIF2bARw3zAVVC6t0h7weiupjjeMj5k1J5a4dmyTspSiHUwIA==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncBackupDirSuffixOnly --- FAIL: TestSyncBackupDirSuffixOnly (104.72s) === RUN TestSyncSuffix run.go:180: Remote "proton drive root link ID 'rclone-test-fideruh0nilu'", Local "Local file system at /tmp/rclone3728299420", Modify Window "1s" 2025/08/18 04:59:30 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-fideruh0nilu/dst" 2025/08/18 04:59:30 DEBUG : proton drive root link ID 'rclone-test-fideruh0nilu/dst': Has cached credentials 2025/08/18 04:59:32 DEBUG : proton drive root link ID 'rclone-test-fideruh0nilu/dst': Used cached credential to initialize the ProtonDrive API 2025/08/18 05:01:00 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/08/18 05:01:01.546367 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/xfDe6E6KnvFQvc2_BajqMJqirR0PL29RIPjgyU17xpO0EnKSOsx4YGS0b5J-jD_-7E7t8cOe-s-KSYILls-9ng==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/08/18 05:01:01.546408 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/xfDe6E6KnvFQvc2_BajqMJqirR0PL29RIPjgyU17xpO0EnKSOsx4YGS0b5J-jD_-7E7t8cOe-s-KSYILls-9ng==/move: This value should not be blank. (Code=2000, Status=400) 2025/08/18 05:01:01 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/xfDe6E6KnvFQvc2_BajqMJqirR0PL29RIPjgyU17xpO0EnKSOsx4YGS0b5J-jD_-7E7t8cOe-s-KSYILls-9ng==/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/xfDe6E6KnvFQvc2_BajqMJqirR0PL29RIPjgyU17xpO0EnKSOsx4YGS0b5J-jD_-7E7t8cOe-s-KSYILls-9ng==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncSuffix --- FAIL: TestSyncSuffix (104.18s) === RUN TestSyncSuffixKeepExtension run.go:180: Remote "proton drive root link ID 'rclone-test-fideruh0nilu'", Local "Local file system at /tmp/rclone3728299420", Modify Window "1s" 2025/08/18 05:01:14 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-fideruh0nilu/dst" 2025/08/18 05:01:14 DEBUG : proton drive root link ID 'rclone-test-fideruh0nilu/dst': Has cached credentials 2025/08/18 05:01:16 DEBUG : proton drive root link ID 'rclone-test-fideruh0nilu/dst': Used cached credential to initialize the ProtonDrive API 2025/08/18 05:02:44 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/08/18 05:02:45.181721 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/eXQLzP4zGVzUbCTmqu2Bt0UJxnOijU9UXsV2Q9hZvBV2ybKJ15aem1TsJIcf67kZhcehRSE1V2xwl3bQKAo-wQ==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/08/18 05:02:45.181754 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/eXQLzP4zGVzUbCTmqu2Bt0UJxnOijU9UXsV2Q9hZvBV2ybKJ15aem1TsJIcf67kZhcehRSE1V2xwl3bQKAo-wQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/08/18 05:02:45 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/eXQLzP4zGVzUbCTmqu2Bt0UJxnOijU9UXsV2Q9hZvBV2ybKJ15aem1TsJIcf67kZhcehRSE1V2xwl3bQKAo-wQ==/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/eXQLzP4zGVzUbCTmqu2Bt0UJxnOijU9UXsV2Q9hZvBV2ybKJ15aem1TsJIcf67kZhcehRSE1V2xwl3bQKAo-wQ==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncSuffixKeepExtension --- FAIL: TestSyncSuffixKeepExtension (103.46s) FAIL 2025/08/18 05:02:49 DEBUG : proton drive root link ID 'rclone-test-fideruh0nilu': 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 21m32.623734285s (try 5/5): exit status 1: Failed [TestServerSideMoveOverSelf TestSyncWithTrackRenames TestSyncWithTrackRenamesStrategyModtime TestSyncWithTrackRenamesStrategyLeaf TestMoveWithDeleteEmptySrcDirs TestServerSideMove TestServerSideMoveWithFilter TestServerSideMoveDeleteEmptySourceDirs TestSyncBackupDir TestSyncBackupDirWithSuffix TestSyncBackupDirWithSuffixKeepExtension TestSyncBackupDirSuffixOnly TestSyncSuffix TestSyncSuffixKeepExtension]