"./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/09/20 05:43:29 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-hasesam4wepi" 2025/09/20 05:43:29 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/09/20 05:43:29 DEBUG : proton drive root link ID 'rclone-test-hasesam4wepi': Has cached credentials 2025/09/20 05:43:29 DEBUG : proton drive root link ID 'rclone-test-hasesam4wepi': Used cached credential to initialize the ProtonDrive API 2025/09/20 05:43:30 DEBUG : Creating backend with remote "/tmp/rclone2715488113" === RUN TestServerSideMoveOverSelf run.go:180: Remote "proton drive root link ID 'rclone-test-hasesam4wepi'", Local "Local file system at /tmp/rclone2715488113", Modify Window "1s" 2025/09/20 05:43:32 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-wefiwol0jeho" 2025/09/20 05:43:32 DEBUG : proton drive root link ID 'rclone-test-wefiwol0jeho': Has cached credentials 2025/09/20 05:43:33 DEBUG : proton drive root link ID 'rclone-test-wefiwol0jeho': Used cached credential to initialize the ProtonDrive API sync_test.go:701: Server side copy (if possible) proton drive root link ID 'rclone-test-hasesam4wepi' -> proton drive root link ID 'rclone-test-wefiwol0jeho' 2025/09/20 05:43:34 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/09/20 05:43:34 DEBUG : proton drive root link ID 'rclone-test-wefiwol0jeho': Waiting for checks to finish 2025/09/20 05:43:34 DEBUG : proton drive root link ID 'rclone-test-wefiwol0jeho': Waiting for transfers to finish 2025/09/20 05:43:37 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2025/09/20 05:43:37 INFO : sub dir/hello world: Copied (new) 2025/09/20 05:43:38.153294 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/20 05:43:38.153332 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/20 05:43:40 DEBUG : proton drive root link ID 'rclone-test-wefiwol0jeho': Using server-side directory move 2025/09/20 05:43:40 INFO : proton drive root link ID 'rclone-test-wefiwol0jeho': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/09/20 05:43:40 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/09/20 05:43:40 DEBUG : proton drive root link ID 'rclone-test-wefiwol0jeho': Waiting for checks to finish 2025/09/20 05:43:40 DEBUG : proton drive root link ID 'rclone-test-wefiwol0jeho': Waiting for transfers to finish 2025/09/20 05:43:40 INFO : sub dir/hello world: Deleted 2025/09/20 05:43:41.319478 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/0XHhZ9-IJL98jvSrFY_oCSSw-b4altSzgWvvvzVL3zBeWVxRjxuXDAGFkB-OTVKuk9YVnH6QvSmaIiccFdoX7w==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/20 05:43:41.319516 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/0XHhZ9-IJL98jvSrFY_oCSSw-b4altSzgWvvvzVL3zBeWVxRjxuXDAGFkB-OTVKuk9YVnH6QvSmaIiccFdoX7w==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/20 05:43:41 ERROR : sub dir/hello world: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/0XHhZ9-IJL98jvSrFY_oCSSw-b4altSzgWvvvzVL3zBeWVxRjxuXDAGFkB-OTVKuk9YVnH6QvSmaIiccFdoX7w==/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/0XHhZ9-IJL98jvSrFY_oCSSw-b4altSzgWvvvzVL3zBeWVxRjxuXDAGFkB-OTVKuk9YVnH6QvSmaIiccFdoX7w==/move: This value should not be blank. (Code=2000, Status=400) Test: TestServerSideMoveOverSelf 2025/09/20 05:43:41 DEBUG : proton drive root link ID 'rclone-test-wefiwol0jeho': Purge remote --- FAIL: TestServerSideMoveOverSelf (12.69s) === RUN TestSyncWithTrackRenames run.go:180: Remote "proton drive root link ID 'rclone-test-hasesam4wepi'", Local "Local file system at /tmp/rclone2715488113", Modify Window "1s" sync_test.go:1568: Can track renames: true 2025/09/20 05:43:43 INFO : proton drive root link ID 'rclone-test-hasesam4wepi': Making map for --track-renames 2025/09/20 05:43:43 INFO : proton drive root link ID 'rclone-test-hasesam4wepi': Finished making map for --track-renames 2025/09/20 05:43:43 DEBUG : proton drive root link ID 'rclone-test-hasesam4wepi': Waiting for checks to finish 2025/09/20 05:43:43 DEBUG : proton drive root link ID 'rclone-test-hasesam4wepi': Waiting for renames to finish 2025/09/20 05:43:43 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/09/20 05:43:43 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/09/20 05:43:43 DEBUG : proton drive root link ID 'rclone-test-hasesam4wepi': Waiting for transfers to finish 2025/09/20 05:43:44 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/09/20 05:43:44 INFO : yam: Copied (new) 2025/09/20 05:43:44 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/09/20 05:43:44 INFO : potato: Copied (new) 2025/09/20 05:43:44 DEBUG : Waiting for deletions to finish 2025/09/20 05:43:45 DEBUG : potato: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/20 05:43:45 INFO : proton drive root link ID 'rclone-test-hasesam4wepi': Making map for --track-renames 2025/09/20 05:43:45 INFO : proton drive root link ID 'rclone-test-hasesam4wepi': Finished making map for --track-renames 2025/09/20 05:43:45 DEBUG : proton drive root link ID 'rclone-test-hasesam4wepi': Waiting for checks to finish 2025/09/20 05:43:45 DEBUG : potato: Unchanged skipping 2025/09/20 05:43:45 DEBUG : proton drive root link ID 'rclone-test-hasesam4wepi': Waiting for renames to finish 2025/09/20 05:43:45.637545 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/UBYPFBwORZj5ylD0LZgdnswRJc1_qzYUYfsqtxS-i2Dbf-I7e-WSci1vUVoQNHRVtde5Zrd_2iI468oNTxZ2lQ==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/20 05:43:45.637586 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/UBYPFBwORZj5ylD0LZgdnswRJc1_qzYUYfsqtxS-i2Dbf-I7e-WSci1vUVoQNHRVtde5Zrd_2iI468oNTxZ2lQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/20 05:43:45 ERROR : yam: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/UBYPFBwORZj5ylD0LZgdnswRJc1_qzYUYfsqtxS-i2Dbf-I7e-WSci1vUVoQNHRVtde5Zrd_2iI468oNTxZ2lQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/20 05:43:45 DEBUG : yaml: Failed to rename to "yam": 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/UBYPFBwORZj5ylD0LZgdnswRJc1_qzYUYfsqtxS-i2Dbf-I7e-WSci1vUVoQNHRVtde5Zrd_2iI468oNTxZ2lQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/20 05:43:45 DEBUG : yaml: Need to transfer - No matching file found at Destination 2025/09/20 05:43:45 DEBUG : proton drive root link ID 'rclone-test-hasesam4wepi': Waiting for transfers to finish 2025/09/20 05:43:46 DEBUG : yaml: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/09/20 05:43:46 INFO : yaml: Copied (new) 2025/09/20 05:43:46 ERROR : proton drive root link ID 'rclone-test-hasesam4wepi': not deleting files as there were IO errors 2025/09/20 05:43:46 ERROR : proton drive root link ID 'rclone-test-hasesam4wepi': 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 (5.32s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:180: Remote "proton drive root link ID 'rclone-test-hasesam4wepi'", Local "Local file system at /tmp/rclone2715488113", Modify Window "1s" sync_test.go:1640: Can track renames: true 2025/09/20 05:43:48 INFO : proton drive root link ID 'rclone-test-hasesam4wepi': Making map for --track-renames 2025/09/20 05:43:48 INFO : proton drive root link ID 'rclone-test-hasesam4wepi': Finished making map for --track-renames 2025/09/20 05:43:48 DEBUG : proton drive root link ID 'rclone-test-hasesam4wepi': Waiting for checks to finish 2025/09/20 05:43:48 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/09/20 05:43:48 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/09/20 05:43:48 DEBUG : proton drive root link ID 'rclone-test-hasesam4wepi': Waiting for renames to finish 2025/09/20 05:43:48 DEBUG : proton drive root link ID 'rclone-test-hasesam4wepi': Waiting for transfers to finish 2025/09/20 05:43:49 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/09/20 05:43:49 INFO : potato: Copied (new) 2025/09/20 05:43:49 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/09/20 05:43:49 INFO : yam: Copied (new) 2025/09/20 05:43:49 DEBUG : Waiting for deletions to finish 2025/09/20 05:43:50 DEBUG : potato: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/20 05:43:50 INFO : proton drive root link ID 'rclone-test-hasesam4wepi': Making map for --track-renames 2025/09/20 05:43:50 INFO : proton drive root link ID 'rclone-test-hasesam4wepi': Finished making map for --track-renames 2025/09/20 05:43:50 DEBUG : proton drive root link ID 'rclone-test-hasesam4wepi': Waiting for checks to finish 2025/09/20 05:43:50 DEBUG : potato: Unchanged skipping 2025/09/20 05:43:50 DEBUG : proton drive root link ID 'rclone-test-hasesam4wepi': Waiting for renames to finish 2025/09/20 05:43:50.811539 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/WDJAN2t8UDiNY12nxbLaKX9_4LIRuq9K1YnpMLen6pYf12LWzulJMbeJM5r3UdiS9fblmTBW8HsUYLpNw4c--Q==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/20 05:43:50.811577 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/WDJAN2t8UDiNY12nxbLaKX9_4LIRuq9K1YnpMLen6pYf12LWzulJMbeJM5r3UdiS9fblmTBW8HsUYLpNw4c--Q==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/20 05:43:50 ERROR : yam: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/WDJAN2t8UDiNY12nxbLaKX9_4LIRuq9K1YnpMLen6pYf12LWzulJMbeJM5r3UdiS9fblmTBW8HsUYLpNw4c--Q==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/20 05:43:50 DEBUG : yaml: Failed to rename to "yam": 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/WDJAN2t8UDiNY12nxbLaKX9_4LIRuq9K1YnpMLen6pYf12LWzulJMbeJM5r3UdiS9fblmTBW8HsUYLpNw4c--Q==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/20 05:43:50 DEBUG : yaml: Need to transfer - No matching file found at Destination 2025/09/20 05:43:50 DEBUG : proton drive root link ID 'rclone-test-hasesam4wepi': Waiting for transfers to finish 2025/09/20 05:43:52 DEBUG : yaml: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/09/20 05:43:52 INFO : yaml: Copied (new) 2025/09/20 05:43:52 ERROR : proton drive root link ID 'rclone-test-hasesam4wepi': not deleting files as there were IO errors 2025/09/20 05:43:52 ERROR : proton drive root link ID 'rclone-test-hasesam4wepi': 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 (5.14s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:180: Remote "proton drive root link ID 'rclone-test-hasesam4wepi'", Local "Local file system at /tmp/rclone2715488113", Modify Window "1s" sync_test.go:1679: Can track renames: true 2025/09/20 05:43:53 INFO : proton drive root link ID 'rclone-test-hasesam4wepi': Making map for --track-renames 2025/09/20 05:43:53 INFO : proton drive root link ID 'rclone-test-hasesam4wepi': Finished making map for --track-renames 2025/09/20 05:43:53 DEBUG : proton drive root link ID 'rclone-test-hasesam4wepi': Waiting for checks to finish 2025/09/20 05:43:53 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/09/20 05:43:53 DEBUG : sub/yam: Need to transfer - No matching file found at Destination 2025/09/20 05:43:53 DEBUG : proton drive root link ID 'rclone-test-hasesam4wepi': Waiting for renames to finish 2025/09/20 05:43:53 DEBUG : proton drive root link ID 'rclone-test-hasesam4wepi': Waiting for transfers to finish 2025/09/20 05:43:55 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/09/20 05:43:55 INFO : potato: Copied (new) 2025/09/20 05:43:55 DEBUG : sub/yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/09/20 05:43:55 INFO : sub/yam: Copied (new) 2025/09/20 05:43:55 DEBUG : Waiting for deletions to finish 2025/09/20 05:43:56 DEBUG : potato: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/20 05:43:56 DEBUG : potato: Unchanged skipping 2025/09/20 05:43:56 INFO : proton drive root link ID 'rclone-test-hasesam4wepi': Making map for --track-renames 2025/09/20 05:43:56 INFO : proton drive root link ID 'rclone-test-hasesam4wepi': Finished making map for --track-renames 2025/09/20 05:43:56 DEBUG : proton drive root link ID 'rclone-test-hasesam4wepi': Waiting for checks to finish 2025/09/20 05:43:56 DEBUG : proton drive root link ID 'rclone-test-hasesam4wepi': Waiting for renames to finish 2025/09/20 05:43:56.762660 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/sbJe_MH16AKGbkUNMKzdPqf6v9na5AB51brrdKVw54KUzxLmzqbDKFOUZmkXaKDJ1dOqFmDhxjA6e7KI4UjKJQ==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/20 05:43:56.762702 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/sbJe_MH16AKGbkUNMKzdPqf6v9na5AB51brrdKVw54KUzxLmzqbDKFOUZmkXaKDJ1dOqFmDhxjA6e7KI4UjKJQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/20 05:43:56 ERROR : sub/yam: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/sbJe_MH16AKGbkUNMKzdPqf6v9na5AB51brrdKVw54KUzxLmzqbDKFOUZmkXaKDJ1dOqFmDhxjA6e7KI4UjKJQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/20 05:43:56 DEBUG : yam: Failed to rename to "sub/yam": 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/sbJe_MH16AKGbkUNMKzdPqf6v9na5AB51brrdKVw54KUzxLmzqbDKFOUZmkXaKDJ1dOqFmDhxjA6e7KI4UjKJQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/20 05:43:56 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/09/20 05:43:56 DEBUG : proton drive root link ID 'rclone-test-hasesam4wepi': Waiting for transfers to finish 2025/09/20 05:43:57 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/09/20 05:43:57 INFO : yam: Copied (new) 2025/09/20 05:43:57 ERROR : proton drive root link ID 'rclone-test-hasesam4wepi': not deleting files as there were IO errors 2025/09/20 05:43:57 ERROR : proton drive root link ID 'rclone-test-hasesam4wepi': 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 (6.32s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:180: Remote "proton drive root link ID 'rclone-test-hasesam4wepi'", Local "Local file system at /tmp/rclone2715488113", Modify Window "1s" 2025/09/20 05:43:59 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/09/20 05:43:59 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2025/09/20 05:43:59 DEBUG : proton drive root link ID 'rclone-test-hasesam4wepi': Waiting for checks to finish 2025/09/20 05:43:59 DEBUG : proton drive root link ID 'rclone-test-hasesam4wepi': Waiting for transfers to finish 2025/09/20 05:44:02 DEBUG : nested/sub dir/file: sha1 = b4b3e0a278988bc15f2913af3f4153ccef74e465 OK 2025/09/20 05:44:02 INFO : nested/sub dir/file: Copied (new) 2025/09/20 05:44:02 INFO : nested/sub dir/file: Deleted 2025/09/20 05:44:02 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2025/09/20 05:44:02 INFO : sub dir/hello world: Copied (new) 2025/09/20 05:44:02 INFO : sub dir/hello world: Deleted 2025/09/20 05:44:02 ERROR : Local file system at /tmp/rclone2715488113: 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 (4.53s) === RUN TestServerSideMove run.go:180: Remote "proton drive root link ID 'rclone-test-hasesam4wepi'", Local "Local file system at /tmp/rclone2715488113", Modify Window "1s" 2025/09/20 05:44:04 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-lodohav1tuvo" 2025/09/20 05:44:04 DEBUG : proton drive root link ID 'rclone-test-lodohav1tuvo': Has cached credentials 2025/09/20 05:44:04 DEBUG : proton drive root link ID 'rclone-test-lodohav1tuvo': Used cached credential to initialize the ProtonDrive API sync_test.go:1735: Server side move (if possible) proton drive root link ID 'rclone-test-hasesam4wepi' -> proton drive root link ID 'rclone-test-lodohav1tuvo' 2025/09/20 05:44:12 DEBUG : proton drive root link ID 'rclone-test-lodohav1tuvo': Using server-side directory move 2025/09/20 05:44:12 INFO : proton drive root link ID 'rclone-test-lodohav1tuvo': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/09/20 05:44:13 DEBUG : potato2: Need to transfer - File not found at Destination 2025/09/20 05:44:13 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1s) 2025/09/20 05:44:13 DEBUG : empty space: Unchanged skipping 2025/09/20 05:44:13 DEBUG : proton drive root link ID 'rclone-test-lodohav1tuvo': Waiting for checks to finish 2025/09/20 05:44:13 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/09/20 05:44:13 INFO : potato3: Deleted 2025/09/20 05:44:13 INFO : empty space: Deleted 2025/09/20 05:44:13 DEBUG : proton drive root link ID 'rclone-test-lodohav1tuvo': Waiting for transfers to finish 2025/09/20 05:44:13.499071 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/K91ODPgB4jtPR3p3VdMbWj5g_gTPvZkPJLtxmSWH1y0BFGccii19-3Qzob850SIYHDps4tCVJPSrqD39hJYfVg==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/20 05:44:13.499125 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/K91ODPgB4jtPR3p3VdMbWj5g_gTPvZkPJLtxmSWH1y0BFGccii19-3Qzob850SIYHDps4tCVJPSrqD39hJYfVg==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/20 05:44:13 ERROR : potato2: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/K91ODPgB4jtPR3p3VdMbWj5g_gTPvZkPJLtxmSWH1y0BFGccii19-3Qzob850SIYHDps4tCVJPSrqD39hJYfVg==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/20 05:44:13.602616 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/adoguu_aCCj_MdbnSKJuh1w02Vf084wLUEshrqRREgyRRIrBuS5VfdZmCTzaHq4Eyrp2k8GpdCFGu9yoX10cmA==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/20 05:44:13.602653 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/adoguu_aCCj_MdbnSKJuh1w02Vf084wLUEshrqRREgyRRIrBuS5VfdZmCTzaHq4Eyrp2k8GpdCFGu9yoX10cmA==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/20 05:44:13 ERROR : potato3: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/adoguu_aCCj_MdbnSKJuh1w02Vf084wLUEshrqRREgyRRIrBuS5VfdZmCTzaHq4Eyrp2k8GpdCFGu9yoX10cmA==/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/adoguu_aCCj_MdbnSKJuh1w02Vf084wLUEshrqRREgyRRIrBuS5VfdZmCTzaHq4Eyrp2k8GpdCFGu9yoX10cmA==/move: This value should not be blank. (Code=2000, Status=400) Test: TestServerSideMove 2025/09/20 05:44:13 DEBUG : proton drive root link ID 'rclone-test-lodohav1tuvo': Purge remote --- FAIL: TestServerSideMove (10.73s) === RUN TestServerSideMoveWithFilter run.go:180: Remote "proton drive root link ID 'rclone-test-hasesam4wepi'", Local "Local file system at /tmp/rclone2715488113", Modify Window "1s" 2025/09/20 05:44:14 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-viziweg7xehu" 2025/09/20 05:44:14 DEBUG : proton drive root link ID 'rclone-test-viziweg7xehu': Has cached credentials 2025/09/20 05:44:15 DEBUG : proton drive root link ID 'rclone-test-viziweg7xehu': Used cached credential to initialize the ProtonDrive API sync_test.go:1735: Server side move (if possible) proton drive root link ID 'rclone-test-hasesam4wepi' -> proton drive root link ID 'rclone-test-viziweg7xehu' 2025/09/20 05:44:24 DEBUG : empty space: Excluded (Size Filter) 2025/09/20 05:44:24 DEBUG : empty space: Excluded 2025/09/20 05:44:24 DEBUG : empty space: Excluded (Size Filter) 2025/09/20 05:44:24 DEBUG : empty space: Excluded 2025/09/20 05:44:24 DEBUG : potato2: Need to transfer - File not found at Destination 2025/09/20 05:44:24 DEBUG : proton drive root link ID 'rclone-test-viziweg7xehu': Waiting for checks to finish 2025/09/20 05:44:24 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/09/20 05:44:24 DEBUG : proton drive root link ID 'rclone-test-viziweg7xehu': Waiting for transfers to finish 2025/09/20 05:44:24 INFO : potato3: Deleted 2025/09/20 05:44:24.557090 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/0KLYLFY18Sg7sgNuU-2GhbrCfY1qLH2iWwEdMWo-eZfdA-2iU6-39sIIj9dGp3ZaJ-UYy57cymN_BLYUm7lY3g==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/20 05:44:24.557135 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/0KLYLFY18Sg7sgNuU-2GhbrCfY1qLH2iWwEdMWo-eZfdA-2iU6-39sIIj9dGp3ZaJ-UYy57cymN_BLYUm7lY3g==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/20 05:44:24 ERROR : potato2: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/0KLYLFY18Sg7sgNuU-2GhbrCfY1qLH2iWwEdMWo-eZfdA-2iU6-39sIIj9dGp3ZaJ-UYy57cymN_BLYUm7lY3g==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/20 05:44:24.650119 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/waZP1i_mi74y8RbleWbo7QbJstQydKv_XaMg4RTzhtfqCqabnuIltU3OnNvleFPWsS8obVRzbpGM_TCW4naaHQ==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/20 05:44:24.650147 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/waZP1i_mi74y8RbleWbo7QbJstQydKv_XaMg4RTzhtfqCqabnuIltU3OnNvleFPWsS8obVRzbpGM_TCW4naaHQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/20 05:44:24 ERROR : potato3: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/waZP1i_mi74y8RbleWbo7QbJstQydKv_XaMg4RTzhtfqCqabnuIltU3OnNvleFPWsS8obVRzbpGM_TCW4naaHQ==/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/waZP1i_mi74y8RbleWbo7QbJstQydKv_XaMg4RTzhtfqCqabnuIltU3OnNvleFPWsS8obVRzbpGM_TCW4naaHQ==/move: This value should not be blank. (Code=2000, Status=400) Test: TestServerSideMoveWithFilter 2025/09/20 05:44:24 DEBUG : proton drive root link ID 'rclone-test-viziweg7xehu': Purge remote --- FAIL: TestServerSideMoveWithFilter (11.36s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:180: Remote "proton drive root link ID 'rclone-test-hasesam4wepi'", Local "Local file system at /tmp/rclone2715488113", Modify Window "1s" 2025/09/20 05:44:26 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-sobewiz1legi" 2025/09/20 05:44:26 DEBUG : proton drive root link ID 'rclone-test-sobewiz1legi': Has cached credentials 2025/09/20 05:44:27 DEBUG : proton drive root link ID 'rclone-test-sobewiz1legi': Used cached credential to initialize the ProtonDrive API 2025/09/20 05:44:31 INFO : tomatoDir: Making directory sync_test.go:1735: Server side move (if possible) proton drive root link ID 'rclone-test-hasesam4wepi' -> proton drive root link ID 'rclone-test-sobewiz1legi' 2025/09/20 05:44:35 DEBUG : proton drive root link ID 'rclone-test-sobewiz1legi': Using server-side directory move 2025/09/20 05:44:35 INFO : proton drive root link ID 'rclone-test-sobewiz1legi': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/09/20 05:44:36 DEBUG : potato2: Need to transfer - File not found at Destination 2025/09/20 05:44:36 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1s) 2025/09/20 05:44:36 DEBUG : empty space: Unchanged skipping 2025/09/20 05:44:36 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/09/20 05:44:36 DEBUG : proton drive root link ID 'rclone-test-sobewiz1legi': Waiting for checks to finish 2025/09/20 05:44:36 INFO : potato3: Deleted 2025/09/20 05:44:36 INFO : empty space: Deleted 2025/09/20 05:44:36 DEBUG : proton drive root link ID 'rclone-test-sobewiz1legi': Waiting for transfers to finish 2025/09/20 05:44:36.419905 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/Af1B9m3go-bgnsYQxqBYz2Xp5I9A9g6f0n4hLc6M_Y86DN8pm6s3FhAdhDD7wyzZLO-VsN3i7YLD60g19LtcCg==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/20 05:44:36.419948 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/Af1B9m3go-bgnsYQxqBYz2Xp5I9A9g6f0n4hLc6M_Y86DN8pm6s3FhAdhDD7wyzZLO-VsN3i7YLD60g19LtcCg==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/20 05:44:36 ERROR : potato2: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/Af1B9m3go-bgnsYQxqBYz2Xp5I9A9g6f0n4hLc6M_Y86DN8pm6s3FhAdhDD7wyzZLO-VsN3i7YLD60g19LtcCg==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/20 05:44:36.515161 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/nhxbl9-jpHddOTpEqZfHDk9NWLVfJHShXbDSK4TfYf9GX6rt2MEHsyYhLDUCI_GhnjipKJEeqfvwR7frvEpc2Q==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/20 05:44:36.515192 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/nhxbl9-jpHddOTpEqZfHDk9NWLVfJHShXbDSK4TfYf9GX6rt2MEHsyYhLDUCI_GhnjipKJEeqfvwR7frvEpc2Q==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/20 05:44:36 ERROR : potato3: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/nhxbl9-jpHddOTpEqZfHDk9NWLVfJHShXbDSK4TfYf9GX6rt2MEHsyYhLDUCI_GhnjipKJEeqfvwR7frvEpc2Q==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/20 05:44:36 ERROR : proton drive root link ID 'rclone-test-hasesam4wepi': 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/20 05:44:36 DEBUG : proton drive root link ID 'rclone-test-sobewiz1legi': Purge remote --- FAIL: TestServerSideMoveDeleteEmptySourceDirs (11.89s) === RUN TestSyncBackupDir run.go:180: Remote "proton drive root link ID 'rclone-test-hasesam4wepi'", Local "Local file system at /tmp/rclone2715488113", Modify Window "1s" 2025/09/20 05:44:42 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-hasesam4wepi/dst" 2025/09/20 05:44:42 DEBUG : proton drive root link ID 'rclone-test-hasesam4wepi/dst': Has cached credentials 2025/09/20 05:44:43 DEBUG : proton drive root link ID 'rclone-test-hasesam4wepi/dst': Used cached credential to initialize the ProtonDrive API 2025/09/20 05:46:11 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-hasesam4wepi/backup" 2025/09/20 05:46:11 DEBUG : proton drive root link ID 'rclone-test-hasesam4wepi/backup': Has cached credentials 2025/09/20 05:46:11 DEBUG : proton drive root link ID 'rclone-test-hasesam4wepi/backup': Used cached credential to initialize the ProtonDrive API 2025/09/20 05:49:32 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/09/20 05:49:32 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/20 05:49:32 DEBUG : two: Unchanged skipping 2025/09/20 05:49:32 DEBUG : proton drive root link ID 'rclone-test-hasesam4wepi/dst': Waiting for checks to finish 2025/09/20 05:49:33.462098 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/IpDfcPUQUhpsBA3c0lwNxImZIwuQkqvWmrqJcy8CFgIJA6kOE9rWjoEdrLWuh4me8ZNBrLgHKvDUecT6Lq-OPg==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/20 05:49:33.462128 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/IpDfcPUQUhpsBA3c0lwNxImZIwuQkqvWmrqJcy8CFgIJA6kOE9rWjoEdrLWuh4me8ZNBrLgHKvDUecT6Lq-OPg==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/20 05:49:33 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/IpDfcPUQUhpsBA3c0lwNxImZIwuQkqvWmrqJcy8CFgIJA6kOE9rWjoEdrLWuh4me8ZNBrLgHKvDUecT6Lq-OPg==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/20 05:49:33 DEBUG : proton drive root link ID 'rclone-test-hasesam4wepi/dst': Waiting for transfers to finish 2025/09/20 05:49:33 ERROR : proton drive root link ID 'rclone-test-hasesam4wepi/dst': not deleting files as there were IO errors 2025/09/20 05:49:33 ERROR : proton drive root link ID 'rclone-test-hasesam4wepi/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/IpDfcPUQUhpsBA3c0lwNxImZIwuQkqvWmrqJcy8CFgIJA6kOE9rWjoEdrLWuh4me8ZNBrLgHKvDUecT6Lq-OPg==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncBackupDir --- FAIL: TestSyncBackupDir (297.30s) === RUN TestSyncBackupDirWithSuffix run.go:180: Remote "proton drive root link ID 'rclone-test-hasesam4wepi'", Local "Local file system at /tmp/rclone2715488113", Modify Window "1s" 2025/09/20 05:49:40 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-hasesam4wepi/dst" 2025/09/20 05:49:40 DEBUG : proton drive root link ID 'rclone-test-hasesam4wepi/dst': Has cached credentials 2025/09/20 05:49:41 DEBUG : proton drive root link ID 'rclone-test-hasesam4wepi/dst': Used cached credential to initialize the ProtonDrive API 2025/09/20 05:51:21 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-hasesam4wepi/backup" 2025/09/20 05:51:21 DEBUG : proton drive root link ID 'rclone-test-hasesam4wepi/backup': Has cached credentials 2025/09/20 05:51:22 DEBUG : proton drive root link ID 'rclone-test-hasesam4wepi/backup': Used cached credential to initialize the ProtonDrive API 2025/09/20 05:54:33 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/09/20 05:54:33 DEBUG : proton drive root link ID 'rclone-test-hasesam4wepi/dst': Waiting for checks to finish 2025/09/20 05:54:33 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/20 05:54:33 DEBUG : two: Unchanged skipping 2025/09/20 05:54:34.362949 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/nxByISn4YisUVSHWLJFJUvO1AMplITkyYlEXATs15JASBhkNIFa2VgL_VBfDf3xaATaICE5T2MdmdC8ZP4ialQ==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/20 05:54:34.362989 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/nxByISn4YisUVSHWLJFJUvO1AMplITkyYlEXATs15JASBhkNIFa2VgL_VBfDf3xaATaICE5T2MdmdC8ZP4ialQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/20 05:54:34 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/nxByISn4YisUVSHWLJFJUvO1AMplITkyYlEXATs15JASBhkNIFa2VgL_VBfDf3xaATaICE5T2MdmdC8ZP4ialQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/20 05:54:34 DEBUG : proton drive root link ID 'rclone-test-hasesam4wepi/dst': Waiting for transfers to finish 2025/09/20 05:54:34 ERROR : proton drive root link ID 'rclone-test-hasesam4wepi/dst': not deleting files as there were IO errors 2025/09/20 05:54:34 ERROR : proton drive root link ID 'rclone-test-hasesam4wepi/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/nxByISn4YisUVSHWLJFJUvO1AMplITkyYlEXATs15JASBhkNIFa2VgL_VBfDf3xaATaICE5T2MdmdC8ZP4ialQ==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncBackupDirWithSuffix --- FAIL: TestSyncBackupDirWithSuffix (300.88s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:180: Remote "proton drive root link ID 'rclone-test-hasesam4wepi'", Local "Local file system at /tmp/rclone2715488113", Modify Window "1s" 2025/09/20 05:54:41 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-hasesam4wepi/dst" 2025/09/20 05:54:41 DEBUG : proton drive root link ID 'rclone-test-hasesam4wepi/dst': Has cached credentials 2025/09/20 05:54:42 DEBUG : proton drive root link ID 'rclone-test-hasesam4wepi/dst': Used cached credential to initialize the ProtonDrive API 2025/09/20 05:56:19 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-hasesam4wepi/backup" 2025/09/20 05:56:19 DEBUG : proton drive root link ID 'rclone-test-hasesam4wepi/backup': Has cached credentials 2025/09/20 05:56:20 DEBUG : proton drive root link ID 'rclone-test-hasesam4wepi/backup': Used cached credential to initialize the ProtonDrive API 2025/09/20 05:59:30 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/09/20 05:59:30 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/20 05:59:30 DEBUG : two: Unchanged skipping 2025/09/20 05:59:30 DEBUG : proton drive root link ID 'rclone-test-hasesam4wepi/dst': Waiting for checks to finish 2025/09/20 05:59:31.771761 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/hBC9zIYY9uFox9gRBdNGxqSjZYQ4AZQBzwEbwF14l4bqnBjgz2vReVSywp25hCkIiL8oTCzW1U2aLQ1myc_p1A==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/20 05:59:31.771789 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/hBC9zIYY9uFox9gRBdNGxqSjZYQ4AZQBzwEbwF14l4bqnBjgz2vReVSywp25hCkIiL8oTCzW1U2aLQ1myc_p1A==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/20 05:59:31 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/hBC9zIYY9uFox9gRBdNGxqSjZYQ4AZQBzwEbwF14l4bqnBjgz2vReVSywp25hCkIiL8oTCzW1U2aLQ1myc_p1A==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/20 05:59:31 DEBUG : proton drive root link ID 'rclone-test-hasesam4wepi/dst': Waiting for transfers to finish 2025/09/20 05:59:31 ERROR : proton drive root link ID 'rclone-test-hasesam4wepi/dst': not deleting files as there were IO errors 2025/09/20 05:59:31 ERROR : proton drive root link ID 'rclone-test-hasesam4wepi/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/hBC9zIYY9uFox9gRBdNGxqSjZYQ4AZQBzwEbwF14l4bqnBjgz2vReVSywp25hCkIiL8oTCzW1U2aLQ1myc_p1A==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncBackupDirWithSuffixKeepExtension --- FAIL: TestSyncBackupDirWithSuffixKeepExtension (297.55s) === RUN TestSyncBackupDirSuffixOnly run.go:180: Remote "proton drive root link ID 'rclone-test-hasesam4wepi'", Local "Local file system at /tmp/rclone2715488113", Modify Window "1s" 2025/09/20 05:59:39 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-hasesam4wepi/dst" 2025/09/20 05:59:39 DEBUG : Config file has changed externally - reloading 2025/09/20 05:59:39 DEBUG : proton drive root link ID 'rclone-test-hasesam4wepi/dst': Has cached credentials 2025/09/20 05:59:39 DEBUG : proton drive root link ID 'rclone-test-hasesam4wepi/dst': Used cached credential to initialize the ProtonDrive API 2025/09/20 06:01:22 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/09/20 06:01:22 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/20 06:01:22 DEBUG : two: Unchanged skipping 2025/09/20 06:01:22 DEBUG : proton drive root link ID 'rclone-test-hasesam4wepi/dst': Waiting for checks to finish 2025/09/20 06:01:23.160097 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/ATpUweRVXh9jMdPwSyZuNymkhAtODaGjIVQvIKlovAjzbNeOqh8XqYhqSGExKK9u_QPt2Wc3wmSFO5TXnW6Q5w==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/20 06:01:23.160164 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/ATpUweRVXh9jMdPwSyZuNymkhAtODaGjIVQvIKlovAjzbNeOqh8XqYhqSGExKK9u_QPt2Wc3wmSFO5TXnW6Q5w==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/20 06:01:23 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/ATpUweRVXh9jMdPwSyZuNymkhAtODaGjIVQvIKlovAjzbNeOqh8XqYhqSGExKK9u_QPt2Wc3wmSFO5TXnW6Q5w==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/20 06:01:23 DEBUG : proton drive root link ID 'rclone-test-hasesam4wepi/dst': Waiting for transfers to finish 2025/09/20 06:01:23 ERROR : proton drive root link ID 'rclone-test-hasesam4wepi/dst': not deleting files as there were IO errors 2025/09/20 06:01:23 ERROR : proton drive root link ID 'rclone-test-hasesam4wepi/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/ATpUweRVXh9jMdPwSyZuNymkhAtODaGjIVQvIKlovAjzbNeOqh8XqYhqSGExKK9u_QPt2Wc3wmSFO5TXnW6Q5w==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncBackupDirSuffixOnly --- FAIL: TestSyncBackupDirSuffixOnly (110.94s) === RUN TestSyncSuffix run.go:180: Remote "proton drive root link ID 'rclone-test-hasesam4wepi'", Local "Local file system at /tmp/rclone2715488113", Modify Window "1s" 2025/09/20 06:01:29 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-hasesam4wepi/dst" 2025/09/20 06:01:29 DEBUG : proton drive root link ID 'rclone-test-hasesam4wepi/dst': Has cached credentials 2025/09/20 06:01:30 DEBUG : proton drive root link ID 'rclone-test-hasesam4wepi/dst': Used cached credential to initialize the ProtonDrive API 2025/09/20 06:03:10 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/09/20 06:03:11.160617 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/thePt5Y_YXM75vUqXeielQwdu_XJCwUPseLijr19bH2QEkMW1QkBt4goktEnXl3Hgc7iPnhN6PqN8ToC3CKJzQ==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/20 06:03:11.160665 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/thePt5Y_YXM75vUqXeielQwdu_XJCwUPseLijr19bH2QEkMW1QkBt4goktEnXl3Hgc7iPnhN6PqN8ToC3CKJzQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/20 06:03:11 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/thePt5Y_YXM75vUqXeielQwdu_XJCwUPseLijr19bH2QEkMW1QkBt4goktEnXl3Hgc7iPnhN6PqN8ToC3CKJzQ==/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/thePt5Y_YXM75vUqXeielQwdu_XJCwUPseLijr19bH2QEkMW1QkBt4goktEnXl3Hgc7iPnhN6PqN8ToC3CKJzQ==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncSuffix --- FAIL: TestSyncSuffix (108.02s) === RUN TestSyncSuffixKeepExtension run.go:180: Remote "proton drive root link ID 'rclone-test-hasesam4wepi'", Local "Local file system at /tmp/rclone2715488113", Modify Window "1s" 2025/09/20 06:03:18 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-hasesam4wepi/dst" 2025/09/20 06:03:18 DEBUG : proton drive root link ID 'rclone-test-hasesam4wepi/dst': Has cached credentials 2025/09/20 06:03:18 DEBUG : proton drive root link ID 'rclone-test-hasesam4wepi/dst': Used cached credential to initialize the ProtonDrive API 2025/09/20 06:04:58 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/09/20 06:04:58.710237 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/WTeNZBYmCeFsLEh2YRZJMtdG_lfRt4Rh7GsEblYq-Bvs987tT8XBQoh_Sllnb_zRQ2gWF_IbYkSAV9iIUQUshQ==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/20 06:04:58.710276 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/WTeNZBYmCeFsLEh2YRZJMtdG_lfRt4Rh7GsEblYq-Bvs987tT8XBQoh_Sllnb_zRQ2gWF_IbYkSAV9iIUQUshQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/20 06:04:58 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/WTeNZBYmCeFsLEh2YRZJMtdG_lfRt4Rh7GsEblYq-Bvs987tT8XBQoh_Sllnb_zRQ2gWF_IbYkSAV9iIUQUshQ==/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/WTeNZBYmCeFsLEh2YRZJMtdG_lfRt4Rh7GsEblYq-Bvs987tT8XBQoh_Sllnb_zRQ2gWF_IbYkSAV9iIUQUshQ==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncSuffixKeepExtension --- FAIL: TestSyncSuffixKeepExtension (107.58s) FAIL 2025/09/20 06:05:00 DEBUG : proton drive root link ID 'rclone-test-hasesam4wepi': 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 21m31.714178156s (try 5/5): exit status 1: Failed [TestServerSideMoveOverSelf TestSyncWithTrackRenames TestSyncWithTrackRenamesStrategyModtime TestSyncWithTrackRenamesStrategyLeaf TestMoveWithDeleteEmptySrcDirs TestServerSideMove TestServerSideMoveWithFilter TestServerSideMoveDeleteEmptySourceDirs TestSyncBackupDir TestSyncBackupDirWithSuffix TestSyncBackupDirWithSuffixKeepExtension TestSyncBackupDirSuffixOnly TestSyncSuffix TestSyncSuffixKeepExtension]