"./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/09 04:09:18 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-rimelot0qaya" 2025/09/09 04:09:18 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/09/09 04:09:18 DEBUG : proton drive root link ID 'rclone-test-rimelot0qaya': Has cached credentials 2025/09/09 04:09:20 DEBUG : proton drive root link ID 'rclone-test-rimelot0qaya': Used cached credential to initialize the ProtonDrive API 2025/09/09 04:09:20 DEBUG : Creating backend with remote "/tmp/rclone176250668" === RUN TestServerSideMoveOverSelf run.go:180: Remote "proton drive root link ID 'rclone-test-rimelot0qaya'", Local "Local file system at /tmp/rclone176250668", Modify Window "1s" 2025/09/09 04:09:26 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-gixonek3kave" 2025/09/09 04:09:26 DEBUG : proton drive root link ID 'rclone-test-gixonek3kave': Has cached credentials 2025/09/09 04:09:28 DEBUG : proton drive root link ID 'rclone-test-gixonek3kave': Used cached credential to initialize the ProtonDrive API sync_test.go:701: Server side copy (if possible) proton drive root link ID 'rclone-test-rimelot0qaya' -> proton drive root link ID 'rclone-test-gixonek3kave' 2025/09/09 04:09:30 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/09/09 04:09:30 DEBUG : proton drive root link ID 'rclone-test-gixonek3kave': Waiting for checks to finish 2025/09/09 04:09:30 DEBUG : proton drive root link ID 'rclone-test-gixonek3kave': Waiting for transfers to finish 2025/09/09 04:09:35 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2025/09/09 04:09:35 INFO : sub dir/hello world: Copied (new) 2025/09/09 04:09:37.861182 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/09 04:09:37.861300 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/09 04:09:41 DEBUG : proton drive root link ID 'rclone-test-gixonek3kave': Using server-side directory move 2025/09/09 04:09:41 INFO : proton drive root link ID 'rclone-test-gixonek3kave': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/09/09 04:09:42 DEBUG : proton drive root link ID 'rclone-test-gixonek3kave': Waiting for checks to finish 2025/09/09 04:09:42 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/09/09 04:09:42 DEBUG : proton drive root link ID 'rclone-test-gixonek3kave': Waiting for transfers to finish 2025/09/09 04:09:43 INFO : sub dir/hello world: Deleted 2025/09/09 04:09:44.481192 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/kkQqqKYkZ_nnImVsYzIISkhdc2mzMBSvbeWh3TftVcEzYrQ9uXU3b1J3mtOSu6zw-VbCxHvzW1nKFiqm9GjVEQ==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/09 04:09:44.481220 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/kkQqqKYkZ_nnImVsYzIISkhdc2mzMBSvbeWh3TftVcEzYrQ9uXU3b1J3mtOSu6zw-VbCxHvzW1nKFiqm9GjVEQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/09 04:09:44 ERROR : sub dir/hello world: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/kkQqqKYkZ_nnImVsYzIISkhdc2mzMBSvbeWh3TftVcEzYrQ9uXU3b1J3mtOSu6zw-VbCxHvzW1nKFiqm9GjVEQ==/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/kkQqqKYkZ_nnImVsYzIISkhdc2mzMBSvbeWh3TftVcEzYrQ9uXU3b1J3mtOSu6zw-VbCxHvzW1nKFiqm9GjVEQ==/move: This value should not be blank. (Code=2000, Status=400) Test: TestServerSideMoveOverSelf 2025/09/09 04:09:44 DEBUG : proton drive root link ID 'rclone-test-gixonek3kave': Purge remote --- FAIL: TestServerSideMoveOverSelf (27.59s) === RUN TestSyncWithTrackRenames run.go:180: Remote "proton drive root link ID 'rclone-test-rimelot0qaya'", Local "Local file system at /tmp/rclone176250668", Modify Window "1s" sync_test.go:1568: Can track renames: true 2025/09/09 04:09:49 INFO : proton drive root link ID 'rclone-test-rimelot0qaya': Making map for --track-renames 2025/09/09 04:09:49 INFO : proton drive root link ID 'rclone-test-rimelot0qaya': Finished making map for --track-renames 2025/09/09 04:09:49 DEBUG : proton drive root link ID 'rclone-test-rimelot0qaya': Waiting for checks to finish 2025/09/09 04:09:49 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/09/09 04:09:49 DEBUG : proton drive root link ID 'rclone-test-rimelot0qaya': Waiting for renames to finish 2025/09/09 04:09:49 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/09/09 04:09:49 DEBUG : proton drive root link ID 'rclone-test-rimelot0qaya': Waiting for transfers to finish 2025/09/09 04:09:50 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/09/09 04:09:50 INFO : potato: Copied (new) 2025/09/09 04:09:51 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/09/09 04:09:51 INFO : yam: Copied (new) 2025/09/09 04:09:51 DEBUG : Waiting for deletions to finish 2025/09/09 04:09:53 DEBUG : potato: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/09 04:09:53 INFO : proton drive root link ID 'rclone-test-rimelot0qaya': Making map for --track-renames 2025/09/09 04:09:53 INFO : proton drive root link ID 'rclone-test-rimelot0qaya': Finished making map for --track-renames 2025/09/09 04:09:53 DEBUG : proton drive root link ID 'rclone-test-rimelot0qaya': Waiting for checks to finish 2025/09/09 04:09:53 DEBUG : potato: Unchanged skipping 2025/09/09 04:09:53 DEBUG : proton drive root link ID 'rclone-test-rimelot0qaya': Waiting for renames to finish 2025/09/09 04:09:54.391012 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/Vn4FU0JNaAxUMGPIrPoBoo7vF59221WK1Uz-HJd2i1HHsIKQAO7ZehUwnbRTFJLE7LNqIpcehYgNo2s9waCQ8g==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/09 04:09:54.391049 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/Vn4FU0JNaAxUMGPIrPoBoo7vF59221WK1Uz-HJd2i1HHsIKQAO7ZehUwnbRTFJLE7LNqIpcehYgNo2s9waCQ8g==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/09 04:09:54 ERROR : yam: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/Vn4FU0JNaAxUMGPIrPoBoo7vF59221WK1Uz-HJd2i1HHsIKQAO7ZehUwnbRTFJLE7LNqIpcehYgNo2s9waCQ8g==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/09 04:09:54 DEBUG : yaml: Failed to rename to "yam": 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/Vn4FU0JNaAxUMGPIrPoBoo7vF59221WK1Uz-HJd2i1HHsIKQAO7ZehUwnbRTFJLE7LNqIpcehYgNo2s9waCQ8g==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/09 04:09:54 DEBUG : yaml: Need to transfer - No matching file found at Destination 2025/09/09 04:09:54 DEBUG : proton drive root link ID 'rclone-test-rimelot0qaya': Waiting for transfers to finish 2025/09/09 04:09:55 DEBUG : yaml: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/09/09 04:09:55 INFO : yaml: Copied (new) 2025/09/09 04:09:55 ERROR : proton drive root link ID 'rclone-test-rimelot0qaya': not deleting files as there were IO errors 2025/09/09 04:09:55 ERROR : proton drive root link ID 'rclone-test-rimelot0qaya': 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.71s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:180: Remote "proton drive root link ID 'rclone-test-rimelot0qaya'", Local "Local file system at /tmp/rclone176250668", Modify Window "1s" sync_test.go:1640: Can track renames: true 2025/09/09 04:09:59 INFO : proton drive root link ID 'rclone-test-rimelot0qaya': Making map for --track-renames 2025/09/09 04:09:59 INFO : proton drive root link ID 'rclone-test-rimelot0qaya': Finished making map for --track-renames 2025/09/09 04:09:59 DEBUG : proton drive root link ID 'rclone-test-rimelot0qaya': Waiting for checks to finish 2025/09/09 04:09:59 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/09/09 04:09:59 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/09/09 04:09:59 DEBUG : proton drive root link ID 'rclone-test-rimelot0qaya': Waiting for renames to finish 2025/09/09 04:09:59 DEBUG : proton drive root link ID 'rclone-test-rimelot0qaya': Waiting for transfers to finish 2025/09/09 04:10:01 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/09/09 04:10:01 INFO : potato: Copied (new) 2025/09/09 04:10:01 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/09/09 04:10:01 INFO : yam: Copied (new) 2025/09/09 04:10:01 DEBUG : Waiting for deletions to finish 2025/09/09 04:10:03 DEBUG : potato: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/09 04:10:03 INFO : proton drive root link ID 'rclone-test-rimelot0qaya': Making map for --track-renames 2025/09/09 04:10:03 INFO : proton drive root link ID 'rclone-test-rimelot0qaya': Finished making map for --track-renames 2025/09/09 04:10:03 DEBUG : proton drive root link ID 'rclone-test-rimelot0qaya': Waiting for checks to finish 2025/09/09 04:10:03 DEBUG : potato: Unchanged skipping 2025/09/09 04:10:03 DEBUG : proton drive root link ID 'rclone-test-rimelot0qaya': Waiting for renames to finish 2025/09/09 04:10:03.987600 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/EfkN49yy1p5DpAwdaQYn9d_T_mYEgdwDKygBl2bs-L9QqeWMexCby-IqfaIo5Ed7Pj3iScLzhSm25vcxPs4PcQ==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/09 04:10:03.987631 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/EfkN49yy1p5DpAwdaQYn9d_T_mYEgdwDKygBl2bs-L9QqeWMexCby-IqfaIo5Ed7Pj3iScLzhSm25vcxPs4PcQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/09 04:10:03 ERROR : yam: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/EfkN49yy1p5DpAwdaQYn9d_T_mYEgdwDKygBl2bs-L9QqeWMexCby-IqfaIo5Ed7Pj3iScLzhSm25vcxPs4PcQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/09 04:10:03 DEBUG : yaml: Failed to rename to "yam": 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/EfkN49yy1p5DpAwdaQYn9d_T_mYEgdwDKygBl2bs-L9QqeWMexCby-IqfaIo5Ed7Pj3iScLzhSm25vcxPs4PcQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/09 04:10:03 DEBUG : yaml: Need to transfer - No matching file found at Destination 2025/09/09 04:10:03 DEBUG : proton drive root link ID 'rclone-test-rimelot0qaya': Waiting for transfers to finish 2025/09/09 04:10:05 DEBUG : yaml: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/09/09 04:10:05 INFO : yaml: Copied (new) 2025/09/09 04:10:05 ERROR : proton drive root link ID 'rclone-test-rimelot0qaya': not deleting files as there were IO errors 2025/09/09 04:10:05 ERROR : proton drive root link ID 'rclone-test-rimelot0qaya': 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.65s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:180: Remote "proton drive root link ID 'rclone-test-rimelot0qaya'", Local "Local file system at /tmp/rclone176250668", Modify Window "1s" sync_test.go:1679: Can track renames: true 2025/09/09 04:10:09 INFO : proton drive root link ID 'rclone-test-rimelot0qaya': Making map for --track-renames 2025/09/09 04:10:09 INFO : proton drive root link ID 'rclone-test-rimelot0qaya': Finished making map for --track-renames 2025/09/09 04:10:09 DEBUG : proton drive root link ID 'rclone-test-rimelot0qaya': Waiting for checks to finish 2025/09/09 04:10:09 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/09/09 04:10:09 DEBUG : sub/yam: Need to transfer - No matching file found at Destination 2025/09/09 04:10:09 DEBUG : proton drive root link ID 'rclone-test-rimelot0qaya': Waiting for renames to finish 2025/09/09 04:10:09 DEBUG : proton drive root link ID 'rclone-test-rimelot0qaya': Waiting for transfers to finish 2025/09/09 04:10:11 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/09/09 04:10:11 INFO : potato: Copied (new) 2025/09/09 04:10:12 DEBUG : sub/yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/09/09 04:10:12 INFO : sub/yam: Copied (new) 2025/09/09 04:10:12 DEBUG : Waiting for deletions to finish 2025/09/09 04:10:14 DEBUG : potato: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/09 04:10:14 DEBUG : potato: Unchanged skipping 2025/09/09 04:10:14 INFO : proton drive root link ID 'rclone-test-rimelot0qaya': Making map for --track-renames 2025/09/09 04:10:14 INFO : proton drive root link ID 'rclone-test-rimelot0qaya': Finished making map for --track-renames 2025/09/09 04:10:14 DEBUG : proton drive root link ID 'rclone-test-rimelot0qaya': Waiting for checks to finish 2025/09/09 04:10:14 DEBUG : proton drive root link ID 'rclone-test-rimelot0qaya': Waiting for renames to finish 2025/09/09 04:10:15.842225 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/LIqjuret-aSEssWwKjIO0ZP6aSwPXKviOk5_ZQ2oDKDHQnmxMIlE4r207v35AgGftR8hS6Ci7QqoibgDrS6txQ==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/09 04:10:15.842250 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/LIqjuret-aSEssWwKjIO0ZP6aSwPXKviOk5_ZQ2oDKDHQnmxMIlE4r207v35AgGftR8hS6Ci7QqoibgDrS6txQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/09 04:10:15 ERROR : sub/yam: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/LIqjuret-aSEssWwKjIO0ZP6aSwPXKviOk5_ZQ2oDKDHQnmxMIlE4r207v35AgGftR8hS6Ci7QqoibgDrS6txQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/09 04:10:15 DEBUG : yam: Failed to rename to "sub/yam": 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/LIqjuret-aSEssWwKjIO0ZP6aSwPXKviOk5_ZQ2oDKDHQnmxMIlE4r207v35AgGftR8hS6Ci7QqoibgDrS6txQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/09 04:10:15 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/09/09 04:10:15 DEBUG : proton drive root link ID 'rclone-test-rimelot0qaya': Waiting for transfers to finish 2025/09/09 04:10:17 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/09/09 04:10:17 INFO : yam: Copied (new) 2025/09/09 04:10:17 ERROR : proton drive root link ID 'rclone-test-rimelot0qaya': not deleting files as there were IO errors 2025/09/09 04:10:17 ERROR : proton drive root link ID 'rclone-test-rimelot0qaya': 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.56s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:180: Remote "proton drive root link ID 'rclone-test-rimelot0qaya'", Local "Local file system at /tmp/rclone176250668", Modify Window "1s" 2025/09/09 04:10:21 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/09/09 04:10:21 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2025/09/09 04:10:21 DEBUG : proton drive root link ID 'rclone-test-rimelot0qaya': Waiting for checks to finish 2025/09/09 04:10:21 DEBUG : proton drive root link ID 'rclone-test-rimelot0qaya': Waiting for transfers to finish 2025/09/09 04:10:25 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2025/09/09 04:10:25 INFO : sub dir/hello world: Copied (new) 2025/09/09 04:10:25 INFO : sub dir/hello world: Deleted 2025/09/09 04:10:26 DEBUG : nested/sub dir/file: sha1 = b4b3e0a278988bc15f2913af3f4153ccef74e465 OK 2025/09/09 04:10:26 INFO : nested/sub dir/file: Copied (new) 2025/09/09 04:10:26 INFO : nested/sub dir/file: Deleted 2025/09/09 04:10:26 ERROR : Local file system at /tmp/rclone176250668: 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.41s) === RUN TestServerSideMove run.go:180: Remote "proton drive root link ID 'rclone-test-rimelot0qaya'", Local "Local file system at /tmp/rclone176250668", Modify Window "1s" 2025/09/09 04:10:30 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-xacijat1wiju" 2025/09/09 04:10:30 DEBUG : proton drive root link ID 'rclone-test-xacijat1wiju': Has cached credentials 2025/09/09 04:10:33 DEBUG : proton drive root link ID 'rclone-test-xacijat1wiju': Used cached credential to initialize the ProtonDrive API sync_test.go:1735: Server side move (if possible) proton drive root link ID 'rclone-test-rimelot0qaya' -> proton drive root link ID 'rclone-test-xacijat1wiju' 2025/09/09 04:10:44 DEBUG : proton drive root link ID 'rclone-test-xacijat1wiju': Using server-side directory move 2025/09/09 04:10:44 INFO : proton drive root link ID 'rclone-test-xacijat1wiju': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/09/09 04:10:45 DEBUG : potato2: Need to transfer - File not found at Destination 2025/09/09 04:10:45 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1s) 2025/09/09 04:10:45 DEBUG : empty space: Unchanged skipping 2025/09/09 04:10:45 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/09/09 04:10:45 DEBUG : proton drive root link ID 'rclone-test-xacijat1wiju': Waiting for checks to finish 2025/09/09 04:10:46 INFO : empty space: Deleted 2025/09/09 04:10:46 DEBUG : proton drive root link ID 'rclone-test-xacijat1wiju': Waiting for transfers to finish 2025/09/09 04:10:46 INFO : potato3: Deleted 2025/09/09 04:10:47.012225 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/8JvsVfk08S5mcO9SbmKLyJIRZcbdhlpBbNQvxe4uuJHTiMAv4u5P6I_Q1VNW-I6mVwCwvFbDF1YjZfQQzgXhug==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/09 04:10:47.012258 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/8JvsVfk08S5mcO9SbmKLyJIRZcbdhlpBbNQvxe4uuJHTiMAv4u5P6I_Q1VNW-I6mVwCwvFbDF1YjZfQQzgXhug==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/09 04:10:47 ERROR : potato2: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/8JvsVfk08S5mcO9SbmKLyJIRZcbdhlpBbNQvxe4uuJHTiMAv4u5P6I_Q1VNW-I6mVwCwvFbDF1YjZfQQzgXhug==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/09 04:10:47.321266 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/b_RbKG1L7FKLkBwwUH6WN02wf5YrM-NayHZXVgEL4e1ZWCyWam_1QirAftJlAdtCnKWfk-B6awaRUzYX-tuBFw==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/09 04:10:47.321298 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/b_RbKG1L7FKLkBwwUH6WN02wf5YrM-NayHZXVgEL4e1ZWCyWam_1QirAftJlAdtCnKWfk-B6awaRUzYX-tuBFw==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/09 04:10:47 ERROR : potato3: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/b_RbKG1L7FKLkBwwUH6WN02wf5YrM-NayHZXVgEL4e1ZWCyWam_1QirAftJlAdtCnKWfk-B6awaRUzYX-tuBFw==/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/b_RbKG1L7FKLkBwwUH6WN02wf5YrM-NayHZXVgEL4e1ZWCyWam_1QirAftJlAdtCnKWfk-B6awaRUzYX-tuBFw==/move: This value should not be blank. (Code=2000, Status=400) Test: TestServerSideMove 2025/09/09 04:10:47 DEBUG : proton drive root link ID 'rclone-test-xacijat1wiju': Purge remote --- FAIL: TestServerSideMove (19.71s) === RUN TestServerSideMoveWithFilter run.go:180: Remote "proton drive root link ID 'rclone-test-rimelot0qaya'", Local "Local file system at /tmp/rclone176250668", Modify Window "1s" 2025/09/09 04:10:50 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-jefodax4coba" 2025/09/09 04:10:50 DEBUG : proton drive root link ID 'rclone-test-jefodax4coba': Has cached credentials 2025/09/09 04:10:52 DEBUG : proton drive root link ID 'rclone-test-jefodax4coba': Used cached credential to initialize the ProtonDrive API sync_test.go:1735: Server side move (if possible) proton drive root link ID 'rclone-test-rimelot0qaya' -> proton drive root link ID 'rclone-test-jefodax4coba' 2025/09/09 04:11:04 DEBUG : empty space: Excluded (Size Filter) 2025/09/09 04:11:04 DEBUG : empty space: Excluded 2025/09/09 04:11:05 DEBUG : empty space: Excluded (Size Filter) 2025/09/09 04:11:05 DEBUG : empty space: Excluded 2025/09/09 04:11:05 DEBUG : potato2: Need to transfer - File not found at Destination 2025/09/09 04:11:05 DEBUG : proton drive root link ID 'rclone-test-jefodax4coba': Waiting for checks to finish 2025/09/09 04:11:05 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/09/09 04:11:05 DEBUG : proton drive root link ID 'rclone-test-jefodax4coba': Waiting for transfers to finish 2025/09/09 04:11:05 INFO : potato3: Deleted 2025/09/09 04:11:06.172853 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/EgKuD4_5mJMWMUPhF0B0qInrosDjQqDd3WiFmInR_d3sBTtPTMZ3anGdzREyOpsng9k_UC4xlXlSQW8eCxpp5A==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/09 04:11:06.172897 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/EgKuD4_5mJMWMUPhF0B0qInrosDjQqDd3WiFmInR_d3sBTtPTMZ3anGdzREyOpsng9k_UC4xlXlSQW8eCxpp5A==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/09 04:11:06 ERROR : potato2: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/EgKuD4_5mJMWMUPhF0B0qInrosDjQqDd3WiFmInR_d3sBTtPTMZ3anGdzREyOpsng9k_UC4xlXlSQW8eCxpp5A==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/09 04:11:06.579007 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/bx8aZI5vBUWuCM8F-4i14_XNxiLSOGcPZ8OKuDL3DJ7a_jrqLcghn2vnFIoKQdsUSFHuSmmS9bQiN6Kz9N24Fg==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/09 04:11:06.579060 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/bx8aZI5vBUWuCM8F-4i14_XNxiLSOGcPZ8OKuDL3DJ7a_jrqLcghn2vnFIoKQdsUSFHuSmmS9bQiN6Kz9N24Fg==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/09 04:11:06 ERROR : potato3: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/bx8aZI5vBUWuCM8F-4i14_XNxiLSOGcPZ8OKuDL3DJ7a_jrqLcghn2vnFIoKQdsUSFHuSmmS9bQiN6Kz9N24Fg==/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/bx8aZI5vBUWuCM8F-4i14_XNxiLSOGcPZ8OKuDL3DJ7a_jrqLcghn2vnFIoKQdsUSFHuSmmS9bQiN6Kz9N24Fg==/move: This value should not be blank. (Code=2000, Status=400) Test: TestServerSideMoveWithFilter 2025/09/09 04:11:06 DEBUG : proton drive root link ID 'rclone-test-jefodax4coba': Purge remote --- FAIL: TestServerSideMoveWithFilter (20.34s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:180: Remote "proton drive root link ID 'rclone-test-rimelot0qaya'", Local "Local file system at /tmp/rclone176250668", Modify Window "1s" 2025/09/09 04:11:10 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-yuqonix2firo" 2025/09/09 04:11:10 DEBUG : proton drive root link ID 'rclone-test-yuqonix2firo': Has cached credentials 2025/09/09 04:11:13 DEBUG : proton drive root link ID 'rclone-test-yuqonix2firo': Used cached credential to initialize the ProtonDrive API 2025/09/09 04:11:18 INFO : tomatoDir: Making directory sync_test.go:1735: Server side move (if possible) proton drive root link ID 'rclone-test-rimelot0qaya' -> proton drive root link ID 'rclone-test-yuqonix2firo' 2025/09/09 04:11:26 DEBUG : proton drive root link ID 'rclone-test-yuqonix2firo': Using server-side directory move 2025/09/09 04:11:26 INFO : proton drive root link ID 'rclone-test-yuqonix2firo': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/09/09 04:11:27 DEBUG : potato2: Need to transfer - File not found at Destination 2025/09/09 04:11:27 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1s) 2025/09/09 04:11:27 DEBUG : empty space: Unchanged skipping 2025/09/09 04:11:27 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/09/09 04:11:28 DEBUG : proton drive root link ID 'rclone-test-yuqonix2firo': Waiting for checks to finish 2025/09/09 04:11:28 INFO : empty space: Deleted 2025/09/09 04:11:28 DEBUG : proton drive root link ID 'rclone-test-yuqonix2firo': Waiting for transfers to finish 2025/09/09 04:11:28 INFO : potato3: Deleted 2025/09/09 04:11:28.929496 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/14bicbfUSS-SdW8u4NfB2LQpmHDem8TlP21A6V9XRcpTPRVtFVitIwb1XdeAzdnhR32FpbS8KQoVWWAQ8-y2Aw==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/09 04:11:28.929534 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/14bicbfUSS-SdW8u4NfB2LQpmHDem8TlP21A6V9XRcpTPRVtFVitIwb1XdeAzdnhR32FpbS8KQoVWWAQ8-y2Aw==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/09 04:11:28 ERROR : potato2: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/14bicbfUSS-SdW8u4NfB2LQpmHDem8TlP21A6V9XRcpTPRVtFVitIwb1XdeAzdnhR32FpbS8KQoVWWAQ8-y2Aw==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/09 04:11:29.136992 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/O8O9PlNKYdd1efIeDxLc0whu9VmBUzqJay3pSu8c-CDExRVv5XG25EB-Bu_jz3zORLBKHCWRyfVUz6HFSpE72w==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/09 04:11:29.137034 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/O8O9PlNKYdd1efIeDxLc0whu9VmBUzqJay3pSu8c-CDExRVv5XG25EB-Bu_jz3zORLBKHCWRyfVUz6HFSpE72w==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/09 04:11:29 ERROR : potato3: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/O8O9PlNKYdd1efIeDxLc0whu9VmBUzqJay3pSu8c-CDExRVv5XG25EB-Bu_jz3zORLBKHCWRyfVUz6HFSpE72w==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/09 04:11:29 ERROR : proton drive root link ID 'rclone-test-rimelot0qaya': 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/09 04:11:29 DEBUG : proton drive root link ID 'rclone-test-yuqonix2firo': Purge remote --- FAIL: TestServerSideMoveDeleteEmptySourceDirs (22.64s) === RUN TestSyncBackupDir run.go:180: Remote "proton drive root link ID 'rclone-test-rimelot0qaya'", Local "Local file system at /tmp/rclone176250668", Modify Window "1s" 2025/09/09 04:11:40 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-rimelot0qaya/dst" 2025/09/09 04:11:40 DEBUG : proton drive root link ID 'rclone-test-rimelot0qaya/dst': Has cached credentials 2025/09/09 04:11:43 DEBUG : proton drive root link ID 'rclone-test-rimelot0qaya/dst': Used cached credential to initialize the ProtonDrive API 2025/09/09 04:13:15 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-rimelot0qaya/backup" 2025/09/09 04:13:15 DEBUG : Config file has changed externally - reloading 2025/09/09 04:13:15 DEBUG : proton drive root link ID 'rclone-test-rimelot0qaya/backup': Has cached credentials 2025/09/09 04:13:17 DEBUG : proton drive root link ID 'rclone-test-rimelot0qaya/backup': Used cached credential to initialize the ProtonDrive API 2025/09/09 04:16:17 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/09/09 04:16:17 DEBUG : proton drive root link ID 'rclone-test-rimelot0qaya/dst': Waiting for checks to finish 2025/09/09 04:16:17 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/09 04:16:17 DEBUG : two: Unchanged skipping 2025/09/09 04:16:19.113906 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/3GNxKLt7ZNcidcckBXlKipJidv_5N-7chZeeFA2RhG01wuiMGACP3G8Bx8aFLGYh28AfLVsJSVeDgys97iNSDA==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/09 04:16:19.113941 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/3GNxKLt7ZNcidcckBXlKipJidv_5N-7chZeeFA2RhG01wuiMGACP3G8Bx8aFLGYh28AfLVsJSVeDgys97iNSDA==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/09 04:16:19 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/3GNxKLt7ZNcidcckBXlKipJidv_5N-7chZeeFA2RhG01wuiMGACP3G8Bx8aFLGYh28AfLVsJSVeDgys97iNSDA==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/09 04:16:19 DEBUG : proton drive root link ID 'rclone-test-rimelot0qaya/dst': Waiting for transfers to finish 2025/09/09 04:16:19 ERROR : proton drive root link ID 'rclone-test-rimelot0qaya/dst': not deleting files as there were IO errors 2025/09/09 04:16:19 ERROR : proton drive root link ID 'rclone-test-rimelot0qaya/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/3GNxKLt7ZNcidcckBXlKipJidv_5N-7chZeeFA2RhG01wuiMGACP3G8Bx8aFLGYh28AfLVsJSVeDgys97iNSDA==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncBackupDir --- FAIL: TestSyncBackupDir (291.22s) === RUN TestSyncBackupDirWithSuffix run.go:180: Remote "proton drive root link ID 'rclone-test-rimelot0qaya'", Local "Local file system at /tmp/rclone176250668", Modify Window "1s" 2025/09/09 04:16:32 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-rimelot0qaya/dst" 2025/09/09 04:16:32 DEBUG : proton drive root link ID 'rclone-test-rimelot0qaya/dst': Has cached credentials 2025/09/09 04:16:34 DEBUG : proton drive root link ID 'rclone-test-rimelot0qaya/dst': Used cached credential to initialize the ProtonDrive API 2025/09/09 04:18:04 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-rimelot0qaya/backup" 2025/09/09 04:18:04 DEBUG : proton drive root link ID 'rclone-test-rimelot0qaya/backup': Has cached credentials 2025/09/09 04:18:06 DEBUG : proton drive root link ID 'rclone-test-rimelot0qaya/backup': Used cached credential to initialize the ProtonDrive API 2025/09/09 04:21:05 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/09 04:21:05 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/09/09 04:21:05 DEBUG : two: Unchanged skipping 2025/09/09 04:21:05 DEBUG : proton drive root link ID 'rclone-test-rimelot0qaya/dst': Waiting for checks to finish 2025/09/09 04:21:07.943997 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/RINEn5-fEPluv9qeSAMN1XdRM5lm1Gtt2tt1Dvj1ZgGkzsKJIdLaumrQlxe4ltw4ddyr-EjxjZisNBNiOCs58w==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/09 04:21:07.944031 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/RINEn5-fEPluv9qeSAMN1XdRM5lm1Gtt2tt1Dvj1ZgGkzsKJIdLaumrQlxe4ltw4ddyr-EjxjZisNBNiOCs58w==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/09 04:21:07 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/RINEn5-fEPluv9qeSAMN1XdRM5lm1Gtt2tt1Dvj1ZgGkzsKJIdLaumrQlxe4ltw4ddyr-EjxjZisNBNiOCs58w==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/09 04:21:07 DEBUG : proton drive root link ID 'rclone-test-rimelot0qaya/dst': Waiting for transfers to finish 2025/09/09 04:21:07 ERROR : proton drive root link ID 'rclone-test-rimelot0qaya/dst': not deleting files as there were IO errors 2025/09/09 04:21:07 ERROR : proton drive root link ID 'rclone-test-rimelot0qaya/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/RINEn5-fEPluv9qeSAMN1XdRM5lm1Gtt2tt1Dvj1ZgGkzsKJIdLaumrQlxe4ltw4ddyr-EjxjZisNBNiOCs58w==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncBackupDirWithSuffix --- FAIL: TestSyncBackupDirWithSuffix (288.34s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:180: Remote "proton drive root link ID 'rclone-test-rimelot0qaya'", Local "Local file system at /tmp/rclone176250668", Modify Window "1s" 2025/09/09 04:21:20 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-rimelot0qaya/dst" 2025/09/09 04:21:20 DEBUG : Config file has changed externally - reloading 2025/09/09 04:21:20 DEBUG : proton drive root link ID 'rclone-test-rimelot0qaya/dst': Has cached credentials 2025/09/09 04:21:23 DEBUG : proton drive root link ID 'rclone-test-rimelot0qaya/dst': Used cached credential to initialize the ProtonDrive API 2025/09/09 04:22:52 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-rimelot0qaya/backup" 2025/09/09 04:22:52 DEBUG : proton drive root link ID 'rclone-test-rimelot0qaya/backup': Has cached credentials 2025/09/09 04:22:54 DEBUG : proton drive root link ID 'rclone-test-rimelot0qaya/backup': Used cached credential to initialize the ProtonDrive API 2025/09/09 04:25:55 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/09/09 04:25:55 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/09 04:25:55 DEBUG : two: Unchanged skipping 2025/09/09 04:25:55 DEBUG : proton drive root link ID 'rclone-test-rimelot0qaya/dst': Waiting for checks to finish 2025/09/09 04:25:57.359794 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/WJjS9CPC-hNT1kyyi-ZQV8SKYe-DCFSfjQ5nemc29NuFdi6n6FgUzEXE3ZKnKu_KXQIcSILqhY9AHRacpDkXNw==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/09 04:25:57.359825 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/WJjS9CPC-hNT1kyyi-ZQV8SKYe-DCFSfjQ5nemc29NuFdi6n6FgUzEXE3ZKnKu_KXQIcSILqhY9AHRacpDkXNw==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/09 04:25:57 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/WJjS9CPC-hNT1kyyi-ZQV8SKYe-DCFSfjQ5nemc29NuFdi6n6FgUzEXE3ZKnKu_KXQIcSILqhY9AHRacpDkXNw==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/09 04:25:57 DEBUG : proton drive root link ID 'rclone-test-rimelot0qaya/dst': Waiting for transfers to finish 2025/09/09 04:25:57 ERROR : proton drive root link ID 'rclone-test-rimelot0qaya/dst': not deleting files as there were IO errors 2025/09/09 04:25:57 ERROR : proton drive root link ID 'rclone-test-rimelot0qaya/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/WJjS9CPC-hNT1kyyi-ZQV8SKYe-DCFSfjQ5nemc29NuFdi6n6FgUzEXE3ZKnKu_KXQIcSILqhY9AHRacpDkXNw==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncBackupDirWithSuffixKeepExtension --- FAIL: TestSyncBackupDirWithSuffixKeepExtension (289.62s) === RUN TestSyncBackupDirSuffixOnly run.go:180: Remote "proton drive root link ID 'rclone-test-rimelot0qaya'", Local "Local file system at /tmp/rclone176250668", Modify Window "1s" 2025/09/09 04:26:09 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-rimelot0qaya/dst" 2025/09/09 04:26:09 DEBUG : Config file has changed externally - reloading 2025/09/09 04:26:09 DEBUG : proton drive root link ID 'rclone-test-rimelot0qaya/dst': Has cached credentials 2025/09/09 04:26:11 DEBUG : proton drive root link ID 'rclone-test-rimelot0qaya/dst': Used cached credential to initialize the ProtonDrive API 2025/09/09 04:27:43 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/09/09 04:27:43 DEBUG : proton drive root link ID 'rclone-test-rimelot0qaya/dst': Waiting for checks to finish 2025/09/09 04:27:43 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/09 04:27:43 DEBUG : two: Unchanged skipping 2025/09/09 04:27:44.793760 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/e0wEKblUJiDVuIYbJoVCBI9xA4PMXmPHlDArhRWC2xpoyFC9_OunJ08ndIhfG0lporj3fAMBRto_Kw13D6gzXg==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/09 04:27:44.793801 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/e0wEKblUJiDVuIYbJoVCBI9xA4PMXmPHlDArhRWC2xpoyFC9_OunJ08ndIhfG0lporj3fAMBRto_Kw13D6gzXg==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/09 04:27:44 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/e0wEKblUJiDVuIYbJoVCBI9xA4PMXmPHlDArhRWC2xpoyFC9_OunJ08ndIhfG0lporj3fAMBRto_Kw13D6gzXg==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/09 04:27:44 DEBUG : proton drive root link ID 'rclone-test-rimelot0qaya/dst': Waiting for transfers to finish 2025/09/09 04:27:44 ERROR : proton drive root link ID 'rclone-test-rimelot0qaya/dst': not deleting files as there were IO errors 2025/09/09 04:27:44 ERROR : proton drive root link ID 'rclone-test-rimelot0qaya/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/e0wEKblUJiDVuIYbJoVCBI9xA4PMXmPHlDArhRWC2xpoyFC9_OunJ08ndIhfG0lporj3fAMBRto_Kw13D6gzXg==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncBackupDirSuffixOnly --- FAIL: TestSyncBackupDirSuffixOnly (106.71s) === RUN TestSyncSuffix run.go:180: Remote "proton drive root link ID 'rclone-test-rimelot0qaya'", Local "Local file system at /tmp/rclone176250668", Modify Window "1s" 2025/09/09 04:27:57 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-rimelot0qaya/dst" 2025/09/09 04:27:57 DEBUG : proton drive root link ID 'rclone-test-rimelot0qaya/dst': Has cached credentials 2025/09/09 04:27:59 DEBUG : proton drive root link ID 'rclone-test-rimelot0qaya/dst': Used cached credential to initialize the ProtonDrive API 2025/09/09 04:29:31 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/09/09 04:29:32.347678 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/EDgNj1ol35LjOJINYZpvrst26TiC0mr0_VJpMWKKvlfzqfIgwvQd4AJTGUhebE-v0187FZdtcGWVVKPuvZSiNA==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/09 04:29:32.347701 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/EDgNj1ol35LjOJINYZpvrst26TiC0mr0_VJpMWKKvlfzqfIgwvQd4AJTGUhebE-v0187FZdtcGWVVKPuvZSiNA==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/09 04:29:32 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/EDgNj1ol35LjOJINYZpvrst26TiC0mr0_VJpMWKKvlfzqfIgwvQd4AJTGUhebE-v0187FZdtcGWVVKPuvZSiNA==/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/EDgNj1ol35LjOJINYZpvrst26TiC0mr0_VJpMWKKvlfzqfIgwvQd4AJTGUhebE-v0187FZdtcGWVVKPuvZSiNA==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncSuffix --- FAIL: TestSyncSuffix (107.40s) === RUN TestSyncSuffixKeepExtension run.go:180: Remote "proton drive root link ID 'rclone-test-rimelot0qaya'", Local "Local file system at /tmp/rclone176250668", Modify Window "1s" 2025/09/09 04:29:51.760903 WARN RESTY Post "https://fra-storage.proton.me/storage/blocks": dial tcp: lookup fra-storage.proton.me on 127.0.0.53:53: read udp 127.0.0.1:44291->127.0.0.53:53: i/o timeout, Attempt 1 2025/09/09 04:29:52.193662 WARN RESTY 400 POST https://fra-storage.proton.me/storage/blocks: Invalid content length (Code=2022, Status=400), Attempt 2 2025/09/09 04:29:52.193695 ERROR RESTY 400 POST https://fra-storage.proton.me/storage/blocks: Invalid content length (Code=2022, Status=400) run.go:285: Failed to put "dst/three.txt" to "proton drive root link ID 'rclone-test-rimelot0qaya'": 400 POST https://fra-storage.proton.me/storage/blocks: Invalid content length (Code=2022, Status=400) --- FAIL: TestSyncSuffixKeepExtension (19.14s) FAIL 2025/09/09 04:29:55 DEBUG : proton drive root link ID 'rclone-test-rimelot0qaya': 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 20m38.499995761s (try 4/5): exit status 1: Failed [TestServerSideMoveOverSelf TestSyncWithTrackRenames TestSyncWithTrackRenamesStrategyModtime TestSyncWithTrackRenamesStrategyLeaf TestMoveWithDeleteEmptySrcDirs TestServerSideMove TestServerSideMoveWithFilter TestServerSideMoveDeleteEmptySourceDirs TestSyncBackupDir TestSyncBackupDirWithSuffix TestSyncBackupDirWithSuffixKeepExtension TestSyncBackupDirSuffixOnly TestSyncSuffix TestSyncSuffixKeepExtension]