"./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 2/5) 2025/09/18 02:58:20 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-lujasaj7pofa" 2025/09/18 02:58:20 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/09/18 02:58:20 DEBUG : proton drive root link ID 'rclone-test-lujasaj7pofa': Has cached credentials 2025/09/18 02:58:22 DEBUG : proton drive root link ID 'rclone-test-lujasaj7pofa': Used cached credential to initialize the ProtonDrive API 2025/09/18 02:58:22 DEBUG : Creating backend with remote "/tmp/rclone4143240625" === RUN TestServerSideMoveOverSelf run.go:180: Remote "proton drive root link ID 'rclone-test-lujasaj7pofa'", Local "Local file system at /tmp/rclone4143240625", Modify Window "1s" 2025/09/18 02:58:26 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-tewiqaw7muho" 2025/09/18 02:58:26 DEBUG : proton drive root link ID 'rclone-test-tewiqaw7muho': Has cached credentials 2025/09/18 02:58:28 DEBUG : proton drive root link ID 'rclone-test-tewiqaw7muho': Used cached credential to initialize the ProtonDrive API sync_test.go:701: Server side copy (if possible) proton drive root link ID 'rclone-test-lujasaj7pofa' -> proton drive root link ID 'rclone-test-tewiqaw7muho' 2025/09/18 02:58:29 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/09/18 02:58:29 DEBUG : proton drive root link ID 'rclone-test-tewiqaw7muho': Waiting for checks to finish 2025/09/18 02:58:29 DEBUG : proton drive root link ID 'rclone-test-tewiqaw7muho': Waiting for transfers to finish 2025/09/18 02:58:34 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2025/09/18 02:58:34 INFO : sub dir/hello world: Copied (new) 2025/09/18 02:58:35.911161 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/18 02:58:35.911193 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/18 02:58:38 DEBUG : proton drive root link ID 'rclone-test-tewiqaw7muho': Using server-side directory move 2025/09/18 02:58:38 INFO : proton drive root link ID 'rclone-test-tewiqaw7muho': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/09/18 02:58:39 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/09/18 02:58:39 DEBUG : proton drive root link ID 'rclone-test-tewiqaw7muho': Waiting for checks to finish 2025/09/18 02:58:39 DEBUG : proton drive root link ID 'rclone-test-tewiqaw7muho': Waiting for transfers to finish 2025/09/18 02:58:39 INFO : sub dir/hello world: Deleted 2025/09/18 02:58:40.899720 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/5cxWUyOQGdV5OA7DlB4tKrCfHsnfbDmOsF1RoIvNDompOZoLFv21SdDEEOkSX6BHdUFk4I6HaKBZ0V3tm9NczA==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/18 02:58:40.899824 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/5cxWUyOQGdV5OA7DlB4tKrCfHsnfbDmOsF1RoIvNDompOZoLFv21SdDEEOkSX6BHdUFk4I6HaKBZ0V3tm9NczA==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/18 02:58:40 ERROR : sub dir/hello world: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/5cxWUyOQGdV5OA7DlB4tKrCfHsnfbDmOsF1RoIvNDompOZoLFv21SdDEEOkSX6BHdUFk4I6HaKBZ0V3tm9NczA==/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/5cxWUyOQGdV5OA7DlB4tKrCfHsnfbDmOsF1RoIvNDompOZoLFv21SdDEEOkSX6BHdUFk4I6HaKBZ0V3tm9NczA==/move: This value should not be blank. (Code=2000, Status=400) Test: TestServerSideMoveOverSelf 2025/09/18 02:58:40 DEBUG : proton drive root link ID 'rclone-test-tewiqaw7muho': Purge remote --- FAIL: TestServerSideMoveOverSelf (21.05s) === RUN TestSyncWithTrackRenames run.go:180: Remote "proton drive root link ID 'rclone-test-lujasaj7pofa'", Local "Local file system at /tmp/rclone4143240625", Modify Window "1s" sync_test.go:1568: Can track renames: true 2025/09/18 02:58:44 INFO : proton drive root link ID 'rclone-test-lujasaj7pofa': Making map for --track-renames 2025/09/18 02:58:44 INFO : proton drive root link ID 'rclone-test-lujasaj7pofa': Finished making map for --track-renames 2025/09/18 02:58:44 DEBUG : proton drive root link ID 'rclone-test-lujasaj7pofa': Waiting for checks to finish 2025/09/18 02:58:44 DEBUG : proton drive root link ID 'rclone-test-lujasaj7pofa': Waiting for renames to finish 2025/09/18 02:58:44 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/09/18 02:58:44 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/09/18 02:58:44 DEBUG : proton drive root link ID 'rclone-test-lujasaj7pofa': Waiting for transfers to finish 2025/09/18 02:58:45 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/09/18 02:58:45 INFO : yam: Copied (new) 2025/09/18 02:58:45 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/09/18 02:58:45 INFO : potato: Copied (new) 2025/09/18 02:58:45 DEBUG : Waiting for deletions to finish 2025/09/18 02:58:47 DEBUG : potato: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/18 02:58:47 INFO : proton drive root link ID 'rclone-test-lujasaj7pofa': Making map for --track-renames 2025/09/18 02:58:47 INFO : proton drive root link ID 'rclone-test-lujasaj7pofa': Finished making map for --track-renames 2025/09/18 02:58:47 DEBUG : proton drive root link ID 'rclone-test-lujasaj7pofa': Waiting for checks to finish 2025/09/18 02:58:47 DEBUG : potato: Unchanged skipping 2025/09/18 02:58:47 DEBUG : proton drive root link ID 'rclone-test-lujasaj7pofa': Waiting for renames to finish 2025/09/18 02:58:47.929762 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/9OkLioSOJnpvpbWHfnYBcSNkEi2l9tVPjgo9bZLX9qpzYApcmLe7ZjF5y3y-UFnc3BdTaewyEH3deyShhgpyGw==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/18 02:58:47.929788 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/9OkLioSOJnpvpbWHfnYBcSNkEi2l9tVPjgo9bZLX9qpzYApcmLe7ZjF5y3y-UFnc3BdTaewyEH3deyShhgpyGw==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/18 02:58:47 ERROR : yam: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/9OkLioSOJnpvpbWHfnYBcSNkEi2l9tVPjgo9bZLX9qpzYApcmLe7ZjF5y3y-UFnc3BdTaewyEH3deyShhgpyGw==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/18 02:58:47 DEBUG : yaml: Failed to rename to "yam": 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/9OkLioSOJnpvpbWHfnYBcSNkEi2l9tVPjgo9bZLX9qpzYApcmLe7ZjF5y3y-UFnc3BdTaewyEH3deyShhgpyGw==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/18 02:58:47 DEBUG : yaml: Need to transfer - No matching file found at Destination 2025/09/18 02:58:47 DEBUG : proton drive root link ID 'rclone-test-lujasaj7pofa': Waiting for transfers to finish 2025/09/18 02:58:49 DEBUG : yaml: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/09/18 02:58:49 INFO : yaml: Copied (new) 2025/09/18 02:58:49 ERROR : proton drive root link ID 'rclone-test-lujasaj7pofa': not deleting files as there were IO errors 2025/09/18 02:58:49 ERROR : proton drive root link ID 'rclone-test-lujasaj7pofa': 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 (8.13s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:180: Remote "proton drive root link ID 'rclone-test-lujasaj7pofa'", Local "Local file system at /tmp/rclone4143240625", Modify Window "1s" sync_test.go:1640: Can track renames: true 2025/09/18 02:58:52 INFO : proton drive root link ID 'rclone-test-lujasaj7pofa': Making map for --track-renames 2025/09/18 02:58:52 INFO : proton drive root link ID 'rclone-test-lujasaj7pofa': Finished making map for --track-renames 2025/09/18 02:58:52 DEBUG : proton drive root link ID 'rclone-test-lujasaj7pofa': Waiting for checks to finish 2025/09/18 02:58:52 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/09/18 02:58:52 DEBUG : proton drive root link ID 'rclone-test-lujasaj7pofa': Waiting for renames to finish 2025/09/18 02:58:52 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/09/18 02:58:52 DEBUG : proton drive root link ID 'rclone-test-lujasaj7pofa': Waiting for transfers to finish 2025/09/18 02:58:53 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/09/18 02:58:53 INFO : potato: Copied (new) 2025/09/18 02:58:53 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/09/18 02:58:53 INFO : yam: Copied (new) 2025/09/18 02:58:53 DEBUG : Waiting for deletions to finish 2025/09/18 02:58:55 DEBUG : potato: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/18 02:58:55 INFO : proton drive root link ID 'rclone-test-lujasaj7pofa': Making map for --track-renames 2025/09/18 02:58:55 INFO : proton drive root link ID 'rclone-test-lujasaj7pofa': Finished making map for --track-renames 2025/09/18 02:58:55 DEBUG : proton drive root link ID 'rclone-test-lujasaj7pofa': Waiting for checks to finish 2025/09/18 02:58:55 DEBUG : potato: Unchanged skipping 2025/09/18 02:58:55 DEBUG : proton drive root link ID 'rclone-test-lujasaj7pofa': Waiting for renames to finish 2025/09/18 02:58:56.028575 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/FLRRoigGKLuNxveqJpcJDubX9ceWJe7dptNw0MRaQ5PCshK-6lIFa3kjVV2-fpIsz2J9b20OVy3EAcDVsuZLZQ==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/18 02:58:56.028615 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/FLRRoigGKLuNxveqJpcJDubX9ceWJe7dptNw0MRaQ5PCshK-6lIFa3kjVV2-fpIsz2J9b20OVy3EAcDVsuZLZQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/18 02:58:56 ERROR : yam: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/FLRRoigGKLuNxveqJpcJDubX9ceWJe7dptNw0MRaQ5PCshK-6lIFa3kjVV2-fpIsz2J9b20OVy3EAcDVsuZLZQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/18 02:58:56 DEBUG : yaml: Failed to rename to "yam": 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/FLRRoigGKLuNxveqJpcJDubX9ceWJe7dptNw0MRaQ5PCshK-6lIFa3kjVV2-fpIsz2J9b20OVy3EAcDVsuZLZQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/18 02:58:56 DEBUG : yaml: Need to transfer - No matching file found at Destination 2025/09/18 02:58:56 DEBUG : proton drive root link ID 'rclone-test-lujasaj7pofa': Waiting for transfers to finish 2025/09/18 02:58:57 DEBUG : yaml: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/09/18 02:58:57 INFO : yaml: Copied (new) 2025/09/18 02:58:57 ERROR : proton drive root link ID 'rclone-test-lujasaj7pofa': not deleting files as there were IO errors 2025/09/18 02:58:57 ERROR : proton drive root link ID 'rclone-test-lujasaj7pofa': 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 (8.01s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:180: Remote "proton drive root link ID 'rclone-test-lujasaj7pofa'", Local "Local file system at /tmp/rclone4143240625", Modify Window "1s" sync_test.go:1679: Can track renames: true 2025/09/18 02:59:00 INFO : proton drive root link ID 'rclone-test-lujasaj7pofa': Making map for --track-renames 2025/09/18 02:59:00 INFO : proton drive root link ID 'rclone-test-lujasaj7pofa': Finished making map for --track-renames 2025/09/18 02:59:00 DEBUG : proton drive root link ID 'rclone-test-lujasaj7pofa': Waiting for checks to finish 2025/09/18 02:59:00 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/09/18 02:59:00 DEBUG : sub/yam: Need to transfer - No matching file found at Destination 2025/09/18 02:59:00 DEBUG : proton drive root link ID 'rclone-test-lujasaj7pofa': Waiting for renames to finish 2025/09/18 02:59:00 DEBUG : proton drive root link ID 'rclone-test-lujasaj7pofa': Waiting for transfers to finish 2025/09/18 02:59:02 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/09/18 02:59:02 INFO : potato: Copied (new) 2025/09/18 02:59:02 DEBUG : sub/yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/09/18 02:59:02 INFO : sub/yam: Copied (new) 2025/09/18 02:59:02 DEBUG : Waiting for deletions to finish 2025/09/18 02:59:04 DEBUG : potato: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/18 02:59:04 DEBUG : potato: Unchanged skipping 2025/09/18 02:59:04 INFO : proton drive root link ID 'rclone-test-lujasaj7pofa': Making map for --track-renames 2025/09/18 02:59:04 INFO : proton drive root link ID 'rclone-test-lujasaj7pofa': Finished making map for --track-renames 2025/09/18 02:59:04 DEBUG : proton drive root link ID 'rclone-test-lujasaj7pofa': Waiting for checks to finish 2025/09/18 02:59:04 DEBUG : proton drive root link ID 'rclone-test-lujasaj7pofa': Waiting for renames to finish 2025/09/18 02:59:05.328490 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/-dUMac0f1Rn7hKEzGt_BfKhnfA4qEDtqV3OpTdhcyQi8XB4dE2ISz71_2gpPMa8MntHbGJjSK67dlsyKIROPIw==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/18 02:59:05.328525 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/-dUMac0f1Rn7hKEzGt_BfKhnfA4qEDtqV3OpTdhcyQi8XB4dE2ISz71_2gpPMa8MntHbGJjSK67dlsyKIROPIw==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/18 02:59:05 ERROR : sub/yam: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/-dUMac0f1Rn7hKEzGt_BfKhnfA4qEDtqV3OpTdhcyQi8XB4dE2ISz71_2gpPMa8MntHbGJjSK67dlsyKIROPIw==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/18 02:59:05 DEBUG : yam: Failed to rename to "sub/yam": 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/-dUMac0f1Rn7hKEzGt_BfKhnfA4qEDtqV3OpTdhcyQi8XB4dE2ISz71_2gpPMa8MntHbGJjSK67dlsyKIROPIw==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/18 02:59:05 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/09/18 02:59:05 DEBUG : proton drive root link ID 'rclone-test-lujasaj7pofa': Waiting for transfers to finish 2025/09/18 02:59:06 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/09/18 02:59:06 INFO : yam: Copied (new) 2025/09/18 02:59:06 ERROR : proton drive root link ID 'rclone-test-lujasaj7pofa': not deleting files as there were IO errors 2025/09/18 02:59:06 ERROR : proton drive root link ID 'rclone-test-lujasaj7pofa': 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 (10.36s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:180: Remote "proton drive root link ID 'rclone-test-lujasaj7pofa'", Local "Local file system at /tmp/rclone4143240625", Modify Window "1s" 2025/09/18 02:59:10 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/09/18 02:59:10 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2025/09/18 02:59:10 DEBUG : proton drive root link ID 'rclone-test-lujasaj7pofa': Waiting for checks to finish 2025/09/18 02:59:10 DEBUG : proton drive root link ID 'rclone-test-lujasaj7pofa': Waiting for transfers to finish 2025/09/18 02:59:14 DEBUG : nested/sub dir/file: sha1 = b4b3e0a278988bc15f2913af3f4153ccef74e465 OK 2025/09/18 02:59:14 INFO : nested/sub dir/file: Copied (new) 2025/09/18 02:59:14 INFO : nested/sub dir/file: Deleted 2025/09/18 02:59:14 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2025/09/18 02:59:14 INFO : sub dir/hello world: Copied (new) 2025/09/18 02:59:14 INFO : sub dir/hello world: Deleted 2025/09/18 02:59:14 ERROR : Local file system at /tmp/rclone4143240625: 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 (8.57s) === RUN TestServerSideMove run.go:180: Remote "proton drive root link ID 'rclone-test-lujasaj7pofa'", Local "Local file system at /tmp/rclone4143240625", Modify Window "1s" 2025/09/18 02:59:18 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-wukuxuv4gali" 2025/09/18 02:59:18 DEBUG : Config file has changed externally - reloading 2025/09/18 02:59:18 DEBUG : proton drive root link ID 'rclone-test-wukuxuv4gali': Has cached credentials 2025/09/18 02:59:20 DEBUG : proton drive root link ID 'rclone-test-wukuxuv4gali': Used cached credential to initialize the ProtonDrive API sync_test.go:1735: Server side move (if possible) proton drive root link ID 'rclone-test-lujasaj7pofa' -> proton drive root link ID 'rclone-test-wukuxuv4gali' 2025/09/18 02:59:30 DEBUG : proton drive root link ID 'rclone-test-wukuxuv4gali': Using server-side directory move 2025/09/18 02:59:30 INFO : proton drive root link ID 'rclone-test-wukuxuv4gali': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/09/18 02:59:31 DEBUG : potato2: Need to transfer - File not found at Destination 2025/09/18 02:59:31 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1s) 2025/09/18 02:59:31 DEBUG : empty space: Unchanged skipping 2025/09/18 02:59:31 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/09/18 02:59:31 DEBUG : proton drive root link ID 'rclone-test-wukuxuv4gali': Waiting for checks to finish 2025/09/18 02:59:31 INFO : empty space: Deleted 2025/09/18 02:59:31 DEBUG : proton drive root link ID 'rclone-test-wukuxuv4gali': Waiting for transfers to finish 2025/09/18 02:59:31 INFO : potato3: Deleted 2025/09/18 02:59:31.822993 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/jfDQCPv9IH9um3klvQPeQiYB_jPDLkkXFrQVy38J2kNLl6MQ7cVPympn0petRG3F7gOwHXxMCy7iTHZUA3TDCw==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/18 02:59:31.823039 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/jfDQCPv9IH9um3klvQPeQiYB_jPDLkkXFrQVy38J2kNLl6MQ7cVPympn0petRG3F7gOwHXxMCy7iTHZUA3TDCw==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/18 02:59:31 ERROR : potato2: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/jfDQCPv9IH9um3klvQPeQiYB_jPDLkkXFrQVy38J2kNLl6MQ7cVPympn0petRG3F7gOwHXxMCy7iTHZUA3TDCw==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/18 02:59:32.184494 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/1-12L2_48VSudNqequoIXtxvJPP1GGJhf7mRxw8C2oYrL1GFvBfAttvvnfEUaCkCavCx0JZvdwsfw3211YqVhg==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/18 02:59:32.184527 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/1-12L2_48VSudNqequoIXtxvJPP1GGJhf7mRxw8C2oYrL1GFvBfAttvvnfEUaCkCavCx0JZvdwsfw3211YqVhg==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/18 02:59:32 ERROR : potato3: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/1-12L2_48VSudNqequoIXtxvJPP1GGJhf7mRxw8C2oYrL1GFvBfAttvvnfEUaCkCavCx0JZvdwsfw3211YqVhg==/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/1-12L2_48VSudNqequoIXtxvJPP1GGJhf7mRxw8C2oYrL1GFvBfAttvvnfEUaCkCavCx0JZvdwsfw3211YqVhg==/move: This value should not be blank. (Code=2000, Status=400) Test: TestServerSideMove 2025/09/18 02:59:32 DEBUG : proton drive root link ID 'rclone-test-wukuxuv4gali': Purge remote --- FAIL: TestServerSideMove (16.06s) === RUN TestServerSideMoveWithFilter run.go:180: Remote "proton drive root link ID 'rclone-test-lujasaj7pofa'", Local "Local file system at /tmp/rclone4143240625", Modify Window "1s" 2025/09/18 02:59:35 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-keyevux4diza" 2025/09/18 02:59:35 DEBUG : proton drive root link ID 'rclone-test-keyevux4diza': Has cached credentials 2025/09/18 02:59:36 DEBUG : proton drive root link ID 'rclone-test-keyevux4diza': Used cached credential to initialize the ProtonDrive API sync_test.go:1735: Server side move (if possible) proton drive root link ID 'rclone-test-lujasaj7pofa' -> proton drive root link ID 'rclone-test-keyevux4diza' 2025/09/18 02:59:47 DEBUG : empty space: Excluded (Size Filter) 2025/09/18 02:59:47 DEBUG : empty space: Excluded 2025/09/18 02:59:47 DEBUG : empty space: Excluded (Size Filter) 2025/09/18 02:59:47 DEBUG : empty space: Excluded 2025/09/18 02:59:47 DEBUG : potato2: Need to transfer - File not found at Destination 2025/09/18 02:59:47 DEBUG : proton drive root link ID 'rclone-test-keyevux4diza': Waiting for checks to finish 2025/09/18 02:59:47 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/09/18 02:59:47 DEBUG : proton drive root link ID 'rclone-test-keyevux4diza': Waiting for transfers to finish 2025/09/18 02:59:47 INFO : potato3: Deleted 2025/09/18 02:59:48.171544 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/mgP7fGSCENzbBQg-Gjsg3P-erKGPIn7YER5jCt4-E6PjDjYiow6BBKor1XJRFHBMAHQoGpEACFFhBpNm5MvCRg==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/18 02:59:48.171591 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/mgP7fGSCENzbBQg-Gjsg3P-erKGPIn7YER5jCt4-E6PjDjYiow6BBKor1XJRFHBMAHQoGpEACFFhBpNm5MvCRg==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/18 02:59:48 ERROR : potato2: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/mgP7fGSCENzbBQg-Gjsg3P-erKGPIn7YER5jCt4-E6PjDjYiow6BBKor1XJRFHBMAHQoGpEACFFhBpNm5MvCRg==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/18 02:59:48.562202 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/cgA9K2vOpuETVqguYJRPlPULloE_l_c_GTnBZhbveXTruB38KbqQZ0stn_wTqTsTSPX-teUPcADDlMkn2vMilg==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/18 02:59:48.562233 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/cgA9K2vOpuETVqguYJRPlPULloE_l_c_GTnBZhbveXTruB38KbqQZ0stn_wTqTsTSPX-teUPcADDlMkn2vMilg==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/18 02:59:48 ERROR : potato3: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/cgA9K2vOpuETVqguYJRPlPULloE_l_c_GTnBZhbveXTruB38KbqQZ0stn_wTqTsTSPX-teUPcADDlMkn2vMilg==/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/cgA9K2vOpuETVqguYJRPlPULloE_l_c_GTnBZhbveXTruB38KbqQZ0stn_wTqTsTSPX-teUPcADDlMkn2vMilg==/move: This value should not be blank. (Code=2000, Status=400) Test: TestServerSideMoveWithFilter 2025/09/18 02:59:48 DEBUG : proton drive root link ID 'rclone-test-keyevux4diza': Purge remote --- FAIL: TestServerSideMoveWithFilter (16.91s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:180: Remote "proton drive root link ID 'rclone-test-lujasaj7pofa'", Local "Local file system at /tmp/rclone4143240625", Modify Window "1s" 2025/09/18 02:59:51 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-xopucam7miqa" 2025/09/18 02:59:51 DEBUG : proton drive root link ID 'rclone-test-xopucam7miqa': Has cached credentials 2025/09/18 02:59:53 DEBUG : proton drive root link ID 'rclone-test-xopucam7miqa': Used cached credential to initialize the ProtonDrive API 2025/09/18 02:59:58 INFO : tomatoDir: Making directory sync_test.go:1735: Server side move (if possible) proton drive root link ID 'rclone-test-lujasaj7pofa' -> proton drive root link ID 'rclone-test-xopucam7miqa' 2025/09/18 03:00:04 DEBUG : proton drive root link ID 'rclone-test-xopucam7miqa': Using server-side directory move 2025/09/18 03:00:04 INFO : proton drive root link ID 'rclone-test-xopucam7miqa': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/09/18 03:00:05 DEBUG : potato2: Need to transfer - File not found at Destination 2025/09/18 03:00:05 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1s) 2025/09/18 03:00:05 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/09/18 03:00:05 DEBUG : empty space: Unchanged skipping 2025/09/18 03:00:05 DEBUG : proton drive root link ID 'rclone-test-xopucam7miqa': Waiting for checks to finish 2025/09/18 03:00:05 INFO : potato3: Deleted 2025/09/18 03:00:05 INFO : empty space: Deleted 2025/09/18 03:00:05 DEBUG : proton drive root link ID 'rclone-test-xopucam7miqa': Waiting for transfers to finish 2025/09/18 03:00:06.121859 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/r2J-JinsAcqympUwMVpl0V93HQGc5k9kWjt__DOqdYS90pgK_f-WLD7vj4OC3nIs5h2iZo-wgLDD5Ik3rJo7yA==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/18 03:00:06.121889 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/r2J-JinsAcqympUwMVpl0V93HQGc5k9kWjt__DOqdYS90pgK_f-WLD7vj4OC3nIs5h2iZo-wgLDD5Ik3rJo7yA==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/18 03:00:06 ERROR : potato2: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/r2J-JinsAcqympUwMVpl0V93HQGc5k9kWjt__DOqdYS90pgK_f-WLD7vj4OC3nIs5h2iZo-wgLDD5Ik3rJo7yA==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/18 03:00:06.447137 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/SejDoFJ4lM-VotjE1QaTTG8ms7CjX881lT8o1rd6-7FpGeFXs6bz9et2yB6f5O2HGBERRdslsK8V0pLbCnup6Q==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/18 03:00:06.447171 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/SejDoFJ4lM-VotjE1QaTTG8ms7CjX881lT8o1rd6-7FpGeFXs6bz9et2yB6f5O2HGBERRdslsK8V0pLbCnup6Q==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/18 03:00:06 ERROR : potato3: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/SejDoFJ4lM-VotjE1QaTTG8ms7CjX881lT8o1rd6-7FpGeFXs6bz9et2yB6f5O2HGBERRdslsK8V0pLbCnup6Q==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/18 03:00:06 ERROR : proton drive root link ID 'rclone-test-lujasaj7pofa': 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/18 03:00:06 DEBUG : proton drive root link ID 'rclone-test-xopucam7miqa': Purge remote --- FAIL: TestServerSideMoveDeleteEmptySourceDirs (18.17s) === RUN TestSyncBackupDir run.go:180: Remote "proton drive root link ID 'rclone-test-lujasaj7pofa'", Local "Local file system at /tmp/rclone4143240625", Modify Window "1s" 2025/09/18 03:00:16 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-lujasaj7pofa/dst" 2025/09/18 03:00:16 DEBUG : proton drive root link ID 'rclone-test-lujasaj7pofa/dst': Has cached credentials 2025/09/18 03:00:17 DEBUG : proton drive root link ID 'rclone-test-lujasaj7pofa/dst': Used cached credential to initialize the ProtonDrive API 2025/09/18 03:02:20 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-lujasaj7pofa/backup" 2025/09/18 03:02:20 DEBUG : proton drive root link ID 'rclone-test-lujasaj7pofa/backup': Has cached credentials 2025/09/18 03:02:21 DEBUG : proton drive root link ID 'rclone-test-lujasaj7pofa/backup': Used cached credential to initialize the ProtonDrive API 2025/09/18 03:06:27 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/09/18 03:06:27 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/18 03:06:27 DEBUG : two: Unchanged skipping 2025/09/18 03:06:27 DEBUG : proton drive root link ID 'rclone-test-lujasaj7pofa/dst': Waiting for checks to finish 2025/09/18 03:06:29.215986 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/jTR6rtDGHSRuz_8I9ZWyio3sntiOuAUNNO_-Di0VqwiG_t3EZmBt9PYvA-Ias_GH9vXN4AodbbiTIMs5vIXvTA==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/18 03:06:29.216009 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/jTR6rtDGHSRuz_8I9ZWyio3sntiOuAUNNO_-Di0VqwiG_t3EZmBt9PYvA-Ias_GH9vXN4AodbbiTIMs5vIXvTA==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/18 03:06:29 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/jTR6rtDGHSRuz_8I9ZWyio3sntiOuAUNNO_-Di0VqwiG_t3EZmBt9PYvA-Ias_GH9vXN4AodbbiTIMs5vIXvTA==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/18 03:06:29 DEBUG : proton drive root link ID 'rclone-test-lujasaj7pofa/dst': Waiting for transfers to finish 2025/09/18 03:06:29 ERROR : proton drive root link ID 'rclone-test-lujasaj7pofa/dst': not deleting files as there were IO errors 2025/09/18 03:06:29 ERROR : proton drive root link ID 'rclone-test-lujasaj7pofa/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/jTR6rtDGHSRuz_8I9ZWyio3sntiOuAUNNO_-Di0VqwiG_t3EZmBt9PYvA-Ias_GH9vXN4AodbbiTIMs5vIXvTA==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncBackupDir --- FAIL: TestSyncBackupDir (383.32s) === RUN TestSyncBackupDirWithSuffix run.go:180: Remote "proton drive root link ID 'rclone-test-lujasaj7pofa'", Local "Local file system at /tmp/rclone4143240625", Modify Window "1s" 2025/09/18 03:06:39 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-lujasaj7pofa/dst" 2025/09/18 03:06:39 DEBUG : proton drive root link ID 'rclone-test-lujasaj7pofa/dst': Has cached credentials 2025/09/18 03:06:41 DEBUG : proton drive root link ID 'rclone-test-lujasaj7pofa/dst': Used cached credential to initialize the ProtonDrive API 2025/09/18 03:08:36 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-lujasaj7pofa/backup" 2025/09/18 03:08:36 DEBUG : proton drive root link ID 'rclone-test-lujasaj7pofa/backup': Has cached credentials 2025/09/18 03:08:38 DEBUG : proton drive root link ID 'rclone-test-lujasaj7pofa/backup': Used cached credential to initialize the ProtonDrive API 2025/09/18 03:12:26 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/09/18 03:12:26 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/18 03:12:26 DEBUG : proton drive root link ID 'rclone-test-lujasaj7pofa/dst': Waiting for checks to finish 2025/09/18 03:12:26 DEBUG : two: Unchanged skipping 2025/09/18 03:12:28.038171 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/8fV0uUtRezHuC0zhEud6qdBjud0KdwDWTRicHusD4Uoz-9g1yPXR4nwqzgNs6_-h2eeVUWx2GwMVeSGWwIag-g==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/18 03:12:28.038231 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/8fV0uUtRezHuC0zhEud6qdBjud0KdwDWTRicHusD4Uoz-9g1yPXR4nwqzgNs6_-h2eeVUWx2GwMVeSGWwIag-g==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/18 03:12:28 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/8fV0uUtRezHuC0zhEud6qdBjud0KdwDWTRicHusD4Uoz-9g1yPXR4nwqzgNs6_-h2eeVUWx2GwMVeSGWwIag-g==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/18 03:12:28 DEBUG : proton drive root link ID 'rclone-test-lujasaj7pofa/dst': Waiting for transfers to finish 2025/09/18 03:12:28 ERROR : proton drive root link ID 'rclone-test-lujasaj7pofa/dst': not deleting files as there were IO errors 2025/09/18 03:12:28 ERROR : proton drive root link ID 'rclone-test-lujasaj7pofa/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/8fV0uUtRezHuC0zhEud6qdBjud0KdwDWTRicHusD4Uoz-9g1yPXR4nwqzgNs6_-h2eeVUWx2GwMVeSGWwIag-g==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncBackupDirWithSuffix --- FAIL: TestSyncBackupDirWithSuffix (358.91s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:180: Remote "proton drive root link ID 'rclone-test-lujasaj7pofa'", Local "Local file system at /tmp/rclone4143240625", Modify Window "1s" 2025/09/18 03:12:38 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-lujasaj7pofa/dst" 2025/09/18 03:12:38 DEBUG : proton drive root link ID 'rclone-test-lujasaj7pofa/dst': Has cached credentials 2025/09/18 03:12:40 DEBUG : proton drive root link ID 'rclone-test-lujasaj7pofa/dst': Used cached credential to initialize the ProtonDrive API 2025/09/18 03:14:33 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-lujasaj7pofa/backup" 2025/09/18 03:14:33 DEBUG : proton drive root link ID 'rclone-test-lujasaj7pofa/backup': Has cached credentials 2025/09/18 03:14:35 DEBUG : proton drive root link ID 'rclone-test-lujasaj7pofa/backup': Used cached credential to initialize the ProtonDrive API 2025/09/18 03:18:38 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/09/18 03:18:38 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/18 03:18:38 DEBUG : two: Unchanged skipping 2025/09/18 03:18:38 DEBUG : proton drive root link ID 'rclone-test-lujasaj7pofa/dst': Waiting for checks to finish 2025/09/18 03:18:40.695428 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/GdOYQGjTTSfI3cqn6ZrlSkkPweEESx_Cq0zv91GXAaudG-6760_X1zrGJvZMth-G1WtfFtraiWR9-HVA2O9J5g==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/18 03:18:40.695458 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/GdOYQGjTTSfI3cqn6ZrlSkkPweEESx_Cq0zv91GXAaudG-6760_X1zrGJvZMth-G1WtfFtraiWR9-HVA2O9J5g==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/18 03:18:40 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/GdOYQGjTTSfI3cqn6ZrlSkkPweEESx_Cq0zv91GXAaudG-6760_X1zrGJvZMth-G1WtfFtraiWR9-HVA2O9J5g==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/18 03:18:40 DEBUG : proton drive root link ID 'rclone-test-lujasaj7pofa/dst': Waiting for transfers to finish 2025/09/18 03:18:40 ERROR : proton drive root link ID 'rclone-test-lujasaj7pofa/dst': not deleting files as there were IO errors 2025/09/18 03:18:40 ERROR : proton drive root link ID 'rclone-test-lujasaj7pofa/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/GdOYQGjTTSfI3cqn6ZrlSkkPweEESx_Cq0zv91GXAaudG-6760_X1zrGJvZMth-G1WtfFtraiWR9-HVA2O9J5g==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncBackupDirWithSuffixKeepExtension --- FAIL: TestSyncBackupDirWithSuffixKeepExtension (372.61s) === RUN TestSyncBackupDirSuffixOnly run.go:180: Remote "proton drive root link ID 'rclone-test-lujasaj7pofa'", Local "Local file system at /tmp/rclone4143240625", Modify Window "1s" 2025/09/18 03:18:51 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-lujasaj7pofa/dst" 2025/09/18 03:18:51 DEBUG : proton drive root link ID 'rclone-test-lujasaj7pofa/dst': Has cached credentials 2025/09/18 03:18:52 DEBUG : proton drive root link ID 'rclone-test-lujasaj7pofa/dst': Used cached credential to initialize the ProtonDrive API 2025/09/18 03:20:57 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/09/18 03:20:57 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/18 03:20:57 DEBUG : two: Unchanged skipping 2025/09/18 03:20:57 DEBUG : proton drive root link ID 'rclone-test-lujasaj7pofa/dst': Waiting for checks to finish 2025/09/18 03:20:58.020813 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/5c6oMH7LCVotydMTfsb-T-Mf3d1LR3o9zZlsF_zcX4E_U_nEVc6R8dewvHAsoV2tQFJGkdcv7xQJURY9k1mqbg==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/18 03:20:58.020837 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/5c6oMH7LCVotydMTfsb-T-Mf3d1LR3o9zZlsF_zcX4E_U_nEVc6R8dewvHAsoV2tQFJGkdcv7xQJURY9k1mqbg==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/18 03:20:58 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/5c6oMH7LCVotydMTfsb-T-Mf3d1LR3o9zZlsF_zcX4E_U_nEVc6R8dewvHAsoV2tQFJGkdcv7xQJURY9k1mqbg==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/18 03:20:58 DEBUG : proton drive root link ID 'rclone-test-lujasaj7pofa/dst': Waiting for transfers to finish 2025/09/18 03:20:58 ERROR : proton drive root link ID 'rclone-test-lujasaj7pofa/dst': not deleting files as there were IO errors 2025/09/18 03:20:58 ERROR : proton drive root link ID 'rclone-test-lujasaj7pofa/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/5c6oMH7LCVotydMTfsb-T-Mf3d1LR3o9zZlsF_zcX4E_U_nEVc6R8dewvHAsoV2tQFJGkdcv7xQJURY9k1mqbg==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncBackupDirSuffixOnly --- FAIL: TestSyncBackupDirSuffixOnly (136.62s) === RUN TestSyncSuffix run.go:180: Remote "proton drive root link ID 'rclone-test-lujasaj7pofa'", Local "Local file system at /tmp/rclone4143240625", Modify Window "1s" 2025/09/18 03:21:08 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-lujasaj7pofa/dst" 2025/09/18 03:21:08 DEBUG : proton drive root link ID 'rclone-test-lujasaj7pofa/dst': Has cached credentials 2025/09/18 03:21:09 DEBUG : proton drive root link ID 'rclone-test-lujasaj7pofa/dst': Used cached credential to initialize the ProtonDrive API 2025/09/18 03:23:10 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/09/18 03:23:11.361283 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/Vc211iMvy6EanoYLrLTgH7SBNhwztb8OjkNL4QYwWv9DoNnXzh29Tg4pQO2JUIXa0lSAki6jjdBmG4sJKHLxcw==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/18 03:23:11.361319 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/Vc211iMvy6EanoYLrLTgH7SBNhwztb8OjkNL4QYwWv9DoNnXzh29Tg4pQO2JUIXa0lSAki6jjdBmG4sJKHLxcw==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/18 03:23:11 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/Vc211iMvy6EanoYLrLTgH7SBNhwztb8OjkNL4QYwWv9DoNnXzh29Tg4pQO2JUIXa0lSAki6jjdBmG4sJKHLxcw==/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/Vc211iMvy6EanoYLrLTgH7SBNhwztb8OjkNL4QYwWv9DoNnXzh29Tg4pQO2JUIXa0lSAki6jjdBmG4sJKHLxcw==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncSuffix --- FAIL: TestSyncSuffix (133.57s) === RUN TestSyncSuffixKeepExtension run.go:180: Remote "proton drive root link ID 'rclone-test-lujasaj7pofa'", Local "Local file system at /tmp/rclone4143240625", Modify Window "1s" 2025/09/18 03:23:21 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-lujasaj7pofa/dst" 2025/09/18 03:23:21 DEBUG : proton drive root link ID 'rclone-test-lujasaj7pofa/dst': Has cached credentials 2025/09/18 03:23:22 DEBUG : proton drive root link ID 'rclone-test-lujasaj7pofa/dst': Used cached credential to initialize the ProtonDrive API 2025/09/18 03:25:17 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/09/18 03:25:18.581533 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/f8Ev2vArTFCuEaG1kwNJGNjiwOZmaoVhQoIUORWOweP0cA6_0-tjy3VoEXm8EPi23DJmY3I3uTXPjST3vT2O6g==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/18 03:25:18.581770 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/f8Ev2vArTFCuEaG1kwNJGNjiwOZmaoVhQoIUORWOweP0cA6_0-tjy3VoEXm8EPi23DJmY3I3uTXPjST3vT2O6g==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/18 03:25:18 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/f8Ev2vArTFCuEaG1kwNJGNjiwOZmaoVhQoIUORWOweP0cA6_0-tjy3VoEXm8EPi23DJmY3I3uTXPjST3vT2O6g==/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/f8Ev2vArTFCuEaG1kwNJGNjiwOZmaoVhQoIUORWOweP0cA6_0-tjy3VoEXm8EPi23DJmY3I3uTXPjST3vT2O6g==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncSuffixKeepExtension --- FAIL: TestSyncSuffixKeepExtension (127.15s) FAIL 2025/09/18 03:25:22 DEBUG : proton drive root link ID 'rclone-test-lujasaj7pofa': 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 27m2.142969234s (try 2/5): exit status 1: Failed [TestServerSideMoveOverSelf TestSyncWithTrackRenames TestSyncWithTrackRenamesStrategyModtime TestSyncWithTrackRenamesStrategyLeaf TestMoveWithDeleteEmptySrcDirs TestServerSideMove TestServerSideMoveWithFilter TestServerSideMoveDeleteEmptySourceDirs TestSyncBackupDir TestSyncBackupDirWithSuffix TestSyncBackupDirWithSuffixKeepExtension TestSyncBackupDirSuffixOnly TestSyncSuffix TestSyncSuffixKeepExtension]