"./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/07/26 04:45:39 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-tajiqet0dana" 2025/07/26 04:45:39 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/07/26 04:45:39 DEBUG : proton drive root link ID 'rclone-test-tajiqet0dana': Has cached credentials 2025/07/26 04:45:40 DEBUG : proton drive root link ID 'rclone-test-tajiqet0dana': Used cached credential to initialize the ProtonDrive API 2025/07/26 04:45:41 DEBUG : Creating backend with remote "/tmp/rclone2753854032" === RUN TestServerSideMoveOverSelf run.go:180: Remote "proton drive root link ID 'rclone-test-tajiqet0dana'", Local "Local file system at /tmp/rclone2753854032", Modify Window "1s" 2025/07/26 04:45:45 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-nuzohac1sero" 2025/07/26 04:45:45 DEBUG : proton drive root link ID 'rclone-test-nuzohac1sero': Has cached credentials 2025/07/26 04:45:47 DEBUG : proton drive root link ID 'rclone-test-nuzohac1sero': Used cached credential to initialize the ProtonDrive API sync_test.go:701: Server side copy (if possible) proton drive root link ID 'rclone-test-tajiqet0dana' -> proton drive root link ID 'rclone-test-nuzohac1sero' 2025/07/26 04:45:48 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/07/26 04:45:48 DEBUG : proton drive root link ID 'rclone-test-nuzohac1sero': Waiting for checks to finish 2025/07/26 04:45:48 DEBUG : proton drive root link ID 'rclone-test-nuzohac1sero': Waiting for transfers to finish 2025/07/26 04:45:52 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2025/07/26 04:45:52 INFO : sub dir/hello world: Copied (new) 2025/07/26 04:45:54.778716 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/07/26 04:45:54.778755 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/07/26 04:45:57 DEBUG : proton drive root link ID 'rclone-test-nuzohac1sero': Using server-side directory move 2025/07/26 04:45:57 INFO : proton drive root link ID 'rclone-test-nuzohac1sero': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/07/26 04:45:58 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/07/26 04:45:58 DEBUG : proton drive root link ID 'rclone-test-nuzohac1sero': Waiting for checks to finish 2025/07/26 04:45:58 DEBUG : proton drive root link ID 'rclone-test-nuzohac1sero': Waiting for transfers to finish 2025/07/26 04:45:58 INFO : sub dir/hello world: Deleted 2025/07/26 04:45:59.766647 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/fmoDMa-S3-dN7ILIkJthGyFYkqReO0pMWY7WoJ3TW8vAOueC7Rbi1BpEgRjq6yUiQbYzJwur0duwhFISYrTbSQ==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/07/26 04:45:59.766695 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/fmoDMa-S3-dN7ILIkJthGyFYkqReO0pMWY7WoJ3TW8vAOueC7Rbi1BpEgRjq6yUiQbYzJwur0duwhFISYrTbSQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/07/26 04:45:59 ERROR : sub dir/hello world: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/fmoDMa-S3-dN7ILIkJthGyFYkqReO0pMWY7WoJ3TW8vAOueC7Rbi1BpEgRjq6yUiQbYzJwur0duwhFISYrTbSQ==/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/fmoDMa-S3-dN7ILIkJthGyFYkqReO0pMWY7WoJ3TW8vAOueC7Rbi1BpEgRjq6yUiQbYzJwur0duwhFISYrTbSQ==/move: This value should not be blank. (Code=2000, Status=400) Test: TestServerSideMoveOverSelf 2025/07/26 04:45:59 DEBUG : proton drive root link ID 'rclone-test-nuzohac1sero': Purge remote --- FAIL: TestServerSideMoveOverSelf (21.29s) === RUN TestSyncWithTrackRenames run.go:180: Remote "proton drive root link ID 'rclone-test-tajiqet0dana'", Local "Local file system at /tmp/rclone2753854032", Modify Window "1s" sync_test.go:1568: Can track renames: true 2025/07/26 04:46:03 INFO : proton drive root link ID 'rclone-test-tajiqet0dana': Making map for --track-renames 2025/07/26 04:46:03 INFO : proton drive root link ID 'rclone-test-tajiqet0dana': Finished making map for --track-renames 2025/07/26 04:46:03 DEBUG : proton drive root link ID 'rclone-test-tajiqet0dana': Waiting for checks to finish 2025/07/26 04:46:03 DEBUG : proton drive root link ID 'rclone-test-tajiqet0dana': Waiting for renames to finish 2025/07/26 04:46:03 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/07/26 04:46:03 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/07/26 04:46:03 DEBUG : proton drive root link ID 'rclone-test-tajiqet0dana': Waiting for transfers to finish 2025/07/26 04:46:04 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/07/26 04:46:04 INFO : yam: Copied (new) 2025/07/26 04:46:04 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/07/26 04:46:04 INFO : potato: Copied (new) 2025/07/26 04:46:04 DEBUG : Waiting for deletions to finish 2025/07/26 04:46:06 DEBUG : potato: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/07/26 04:46:06 INFO : proton drive root link ID 'rclone-test-tajiqet0dana': Making map for --track-renames 2025/07/26 04:46:06 INFO : proton drive root link ID 'rclone-test-tajiqet0dana': Finished making map for --track-renames 2025/07/26 04:46:06 DEBUG : proton drive root link ID 'rclone-test-tajiqet0dana': Waiting for checks to finish 2025/07/26 04:46:06 DEBUG : potato: Unchanged skipping 2025/07/26 04:46:06 DEBUG : proton drive root link ID 'rclone-test-tajiqet0dana': Waiting for renames to finish 2025/07/26 04:46:07.056224 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/T_PJDdhnrCwlwEkuXCGOHb8eKNOLE-oDW5zsSIA3eBmR5CeEvgqcNK78ivEMfHDlcB1m1_byDZn9RekvQF8Z8g==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/07/26 04:46:07.056267 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/T_PJDdhnrCwlwEkuXCGOHb8eKNOLE-oDW5zsSIA3eBmR5CeEvgqcNK78ivEMfHDlcB1m1_byDZn9RekvQF8Z8g==/move: This value should not be blank. (Code=2000, Status=400) 2025/07/26 04:46:07 ERROR : yam: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/T_PJDdhnrCwlwEkuXCGOHb8eKNOLE-oDW5zsSIA3eBmR5CeEvgqcNK78ivEMfHDlcB1m1_byDZn9RekvQF8Z8g==/move: This value should not be blank. (Code=2000, Status=400) 2025/07/26 04:46:07 DEBUG : yaml: Failed to rename to "yam": 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/T_PJDdhnrCwlwEkuXCGOHb8eKNOLE-oDW5zsSIA3eBmR5CeEvgqcNK78ivEMfHDlcB1m1_byDZn9RekvQF8Z8g==/move: This value should not be blank. (Code=2000, Status=400) 2025/07/26 04:46:07 DEBUG : yaml: Need to transfer - No matching file found at Destination 2025/07/26 04:46:07 DEBUG : proton drive root link ID 'rclone-test-tajiqet0dana': Waiting for transfers to finish 2025/07/26 04:46:08 DEBUG : yaml: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/07/26 04:46:08 INFO : yaml: Copied (new) 2025/07/26 04:46:08 ERROR : proton drive root link ID 'rclone-test-tajiqet0dana': not deleting files as there were IO errors 2025/07/26 04:46:08 ERROR : proton drive root link ID 'rclone-test-tajiqet0dana': 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.51s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:180: Remote "proton drive root link ID 'rclone-test-tajiqet0dana'", Local "Local file system at /tmp/rclone2753854032", Modify Window "1s" sync_test.go:1640: Can track renames: true 2025/07/26 04:46:11 INFO : proton drive root link ID 'rclone-test-tajiqet0dana': Making map for --track-renames 2025/07/26 04:46:11 INFO : proton drive root link ID 'rclone-test-tajiqet0dana': Finished making map for --track-renames 2025/07/26 04:46:11 DEBUG : proton drive root link ID 'rclone-test-tajiqet0dana': Waiting for checks to finish 2025/07/26 04:46:11 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/07/26 04:46:11 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/07/26 04:46:11 DEBUG : proton drive root link ID 'rclone-test-tajiqet0dana': Waiting for renames to finish 2025/07/26 04:46:11 DEBUG : proton drive root link ID 'rclone-test-tajiqet0dana': Waiting for transfers to finish 2025/07/26 04:46:13 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/07/26 04:46:13 INFO : potato: Copied (new) 2025/07/26 04:46:13 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/07/26 04:46:13 INFO : yam: Copied (new) 2025/07/26 04:46:13 DEBUG : Waiting for deletions to finish 2025/07/26 04:46:14 DEBUG : potato: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/07/26 04:46:14 INFO : proton drive root link ID 'rclone-test-tajiqet0dana': Making map for --track-renames 2025/07/26 04:46:14 INFO : proton drive root link ID 'rclone-test-tajiqet0dana': Finished making map for --track-renames 2025/07/26 04:46:14 DEBUG : proton drive root link ID 'rclone-test-tajiqet0dana': Waiting for checks to finish 2025/07/26 04:46:14 DEBUG : potato: Unchanged skipping 2025/07/26 04:46:14 DEBUG : proton drive root link ID 'rclone-test-tajiqet0dana': Waiting for renames to finish 2025/07/26 04:46:15.381130 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/l_K5jtviGwPpDi8qykiWsXwh7p61zUBErKaAoX03WqEbARZu75sa5ujyZsb9rL2110cZb42X6XKOaqxfxjq7DQ==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/07/26 04:46:15.381174 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/l_K5jtviGwPpDi8qykiWsXwh7p61zUBErKaAoX03WqEbARZu75sa5ujyZsb9rL2110cZb42X6XKOaqxfxjq7DQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/07/26 04:46:15 ERROR : yam: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/l_K5jtviGwPpDi8qykiWsXwh7p61zUBErKaAoX03WqEbARZu75sa5ujyZsb9rL2110cZb42X6XKOaqxfxjq7DQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/07/26 04:46:15 DEBUG : yaml: Failed to rename to "yam": 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/l_K5jtviGwPpDi8qykiWsXwh7p61zUBErKaAoX03WqEbARZu75sa5ujyZsb9rL2110cZb42X6XKOaqxfxjq7DQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/07/26 04:46:15 DEBUG : yaml: Need to transfer - No matching file found at Destination 2025/07/26 04:46:15 DEBUG : proton drive root link ID 'rclone-test-tajiqet0dana': Waiting for transfers to finish 2025/07/26 04:46:16 DEBUG : yaml: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/07/26 04:46:16 INFO : yaml: Copied (new) 2025/07/26 04:46:16 ERROR : proton drive root link ID 'rclone-test-tajiqet0dana': not deleting files as there were IO errors 2025/07/26 04:46:16 ERROR : proton drive root link ID 'rclone-test-tajiqet0dana': 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.44s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:180: Remote "proton drive root link ID 'rclone-test-tajiqet0dana'", Local "Local file system at /tmp/rclone2753854032", Modify Window "1s" sync_test.go:1679: Can track renames: true 2025/07/26 04:46:20 INFO : proton drive root link ID 'rclone-test-tajiqet0dana': Making map for --track-renames 2025/07/26 04:46:20 INFO : proton drive root link ID 'rclone-test-tajiqet0dana': Finished making map for --track-renames 2025/07/26 04:46:20 DEBUG : proton drive root link ID 'rclone-test-tajiqet0dana': Waiting for checks to finish 2025/07/26 04:46:20 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/07/26 04:46:20 DEBUG : sub/yam: Need to transfer - No matching file found at Destination 2025/07/26 04:46:20 DEBUG : proton drive root link ID 'rclone-test-tajiqet0dana': Waiting for renames to finish 2025/07/26 04:46:20 DEBUG : proton drive root link ID 'rclone-test-tajiqet0dana': Waiting for transfers to finish 2025/07/26 04:46:22 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/07/26 04:46:22 INFO : potato: Copied (new) 2025/07/26 04:46:22 DEBUG : sub/yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/07/26 04:46:22 INFO : sub/yam: Copied (new) 2025/07/26 04:46:22 DEBUG : Waiting for deletions to finish 2025/07/26 04:46:24 DEBUG : potato: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/07/26 04:46:24 DEBUG : potato: Unchanged skipping 2025/07/26 04:46:24 INFO : proton drive root link ID 'rclone-test-tajiqet0dana': Making map for --track-renames 2025/07/26 04:46:24 INFO : proton drive root link ID 'rclone-test-tajiqet0dana': Finished making map for --track-renames 2025/07/26 04:46:24 DEBUG : proton drive root link ID 'rclone-test-tajiqet0dana': Waiting for checks to finish 2025/07/26 04:46:24 DEBUG : proton drive root link ID 'rclone-test-tajiqet0dana': Waiting for renames to finish 2025/07/26 04:46:25.507103 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/UMyxypDnWCI_ViEUqunYo9NxXt0K4tKr0gRvev0E-3UASu7PPzCrrL_5sJcrFizz8T6bAD2mD-3tXkNgQr47_Q==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/07/26 04:46:25.507168 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/UMyxypDnWCI_ViEUqunYo9NxXt0K4tKr0gRvev0E-3UASu7PPzCrrL_5sJcrFizz8T6bAD2mD-3tXkNgQr47_Q==/move: This value should not be blank. (Code=2000, Status=400) 2025/07/26 04:46:25 ERROR : sub/yam: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/UMyxypDnWCI_ViEUqunYo9NxXt0K4tKr0gRvev0E-3UASu7PPzCrrL_5sJcrFizz8T6bAD2mD-3tXkNgQr47_Q==/move: This value should not be blank. (Code=2000, Status=400) 2025/07/26 04:46:25 DEBUG : yam: Failed to rename to "sub/yam": 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/UMyxypDnWCI_ViEUqunYo9NxXt0K4tKr0gRvev0E-3UASu7PPzCrrL_5sJcrFizz8T6bAD2mD-3tXkNgQr47_Q==/move: This value should not be blank. (Code=2000, Status=400) 2025/07/26 04:46:25 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/07/26 04:46:25 DEBUG : proton drive root link ID 'rclone-test-tajiqet0dana': Waiting for transfers to finish 2025/07/26 04:46:27 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/07/26 04:46:27 INFO : yam: Copied (new) 2025/07/26 04:46:27 ERROR : proton drive root link ID 'rclone-test-tajiqet0dana': not deleting files as there were IO errors 2025/07/26 04:46:27 ERROR : proton drive root link ID 'rclone-test-tajiqet0dana': 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.99s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:180: Remote "proton drive root link ID 'rclone-test-tajiqet0dana'", Local "Local file system at /tmp/rclone2753854032", Modify Window "1s" 2025/07/26 04:46:31 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/07/26 04:46:31 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2025/07/26 04:46:31 DEBUG : proton drive root link ID 'rclone-test-tajiqet0dana': Waiting for checks to finish 2025/07/26 04:46:31 DEBUG : proton drive root link ID 'rclone-test-tajiqet0dana': Waiting for transfers to finish 2025/07/26 04:46:35 DEBUG : nested/sub dir/file: sha1 = b4b3e0a278988bc15f2913af3f4153ccef74e465 OK 2025/07/26 04:46:35 INFO : nested/sub dir/file: Copied (new) 2025/07/26 04:46:35 INFO : nested/sub dir/file: Deleted 2025/07/26 04:46:35 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2025/07/26 04:46:35 INFO : sub dir/hello world: Copied (new) 2025/07/26 04:46:35 INFO : sub dir/hello world: Deleted 2025/07/26 04:46:35 ERROR : Local file system at /tmp/rclone2753854032: 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.40s) === RUN TestServerSideMove run.go:180: Remote "proton drive root link ID 'rclone-test-tajiqet0dana'", Local "Local file system at /tmp/rclone2753854032", Modify Window "1s" 2025/07/26 04:46:39 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-qemixin7raha" 2025/07/26 04:46:39 DEBUG : proton drive root link ID 'rclone-test-qemixin7raha': Has cached credentials 2025/07/26 04:46:40 DEBUG : proton drive root link ID 'rclone-test-qemixin7raha': Used cached credential to initialize the ProtonDrive API sync_test.go:1735: Server side move (if possible) proton drive root link ID 'rclone-test-tajiqet0dana' -> proton drive root link ID 'rclone-test-qemixin7raha' 2025/07/26 04:46:51 DEBUG : proton drive root link ID 'rclone-test-qemixin7raha': Using server-side directory move 2025/07/26 04:46:51 INFO : proton drive root link ID 'rclone-test-qemixin7raha': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/07/26 04:46:52 DEBUG : potato2: Need to transfer - File not found at Destination 2025/07/26 04:46:52 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1s) 2025/07/26 04:46:52 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/07/26 04:46:52 DEBUG : empty space: Unchanged skipping 2025/07/26 04:46:52 DEBUG : proton drive root link ID 'rclone-test-qemixin7raha': Waiting for checks to finish 2025/07/26 04:46:52 INFO : empty space: Deleted 2025/07/26 04:46:52 DEBUG : proton drive root link ID 'rclone-test-qemixin7raha': Waiting for transfers to finish 2025/07/26 04:46:53 INFO : potato3: Deleted 2025/07/26 04:46:53.332840 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/2q1pYDKuoKRyief5CAuKn8OWrgXaUJwq_hZprRPNGDidTDRJBtL7sJjfaVPCiF1xxDuXoJ9QGa2tmLv4xBrfLg==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/07/26 04:46:53.332881 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/2q1pYDKuoKRyief5CAuKn8OWrgXaUJwq_hZprRPNGDidTDRJBtL7sJjfaVPCiF1xxDuXoJ9QGa2tmLv4xBrfLg==/move: This value should not be blank. (Code=2000, Status=400) 2025/07/26 04:46:53 ERROR : potato2: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/2q1pYDKuoKRyief5CAuKn8OWrgXaUJwq_hZprRPNGDidTDRJBtL7sJjfaVPCiF1xxDuXoJ9QGa2tmLv4xBrfLg==/move: This value should not be blank. (Code=2000, Status=400) 2025/07/26 04:46:53.661212 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/_bjaOYrR5XgpPJ5o3UrbsX9wlmzk3UL8hQSF0EX9nntneVgXxlZa6a3xW1RsXhsBnLaS998n7T5_P7dKdRdhUQ==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/07/26 04:46:53.661252 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/_bjaOYrR5XgpPJ5o3UrbsX9wlmzk3UL8hQSF0EX9nntneVgXxlZa6a3xW1RsXhsBnLaS998n7T5_P7dKdRdhUQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/07/26 04:46:53 ERROR : potato3: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/_bjaOYrR5XgpPJ5o3UrbsX9wlmzk3UL8hQSF0EX9nntneVgXxlZa6a3xW1RsXhsBnLaS998n7T5_P7dKdRdhUQ==/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/_bjaOYrR5XgpPJ5o3UrbsX9wlmzk3UL8hQSF0EX9nntneVgXxlZa6a3xW1RsXhsBnLaS998n7T5_P7dKdRdhUQ==/move: This value should not be blank. (Code=2000, Status=400) Test: TestServerSideMove 2025/07/26 04:46:53 DEBUG : proton drive root link ID 'rclone-test-qemixin7raha': Purge remote --- FAIL: TestServerSideMove (17.32s) === RUN TestServerSideMoveWithFilter run.go:180: Remote "proton drive root link ID 'rclone-test-tajiqet0dana'", Local "Local file system at /tmp/rclone2753854032", Modify Window "1s" 2025/07/26 04:46:56 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-vefopos9refu" 2025/07/26 04:46:56 DEBUG : proton drive root link ID 'rclone-test-vefopos9refu': Has cached credentials 2025/07/26 04:46:57 DEBUG : proton drive root link ID 'rclone-test-vefopos9refu': Used cached credential to initialize the ProtonDrive API sync_test.go:1735: Server side move (if possible) proton drive root link ID 'rclone-test-tajiqet0dana' -> proton drive root link ID 'rclone-test-vefopos9refu' 2025/07/26 04:47:09 DEBUG : empty space: Excluded (Size Filter) 2025/07/26 04:47:09 DEBUG : empty space: Excluded 2025/07/26 04:47:09 DEBUG : empty space: Excluded (Size Filter) 2025/07/26 04:47:09 DEBUG : empty space: Excluded 2025/07/26 04:47:09 DEBUG : potato2: Need to transfer - File not found at Destination 2025/07/26 04:47:09 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/07/26 04:47:09 DEBUG : proton drive root link ID 'rclone-test-vefopos9refu': Waiting for checks to finish 2025/07/26 04:47:09 DEBUG : proton drive root link ID 'rclone-test-vefopos9refu': Waiting for transfers to finish 2025/07/26 04:47:10 INFO : potato3: Deleted 2025/07/26 04:47:10.444117 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/3eZ0qhPdufvfUNgcJKRfAc7vCtv2GvIlw7gIFf1A6tY40wJoGtTn4187101ly3OLoyVO15s2_3PHK1cafTo8OQ==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/07/26 04:47:10.444239 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/3eZ0qhPdufvfUNgcJKRfAc7vCtv2GvIlw7gIFf1A6tY40wJoGtTn4187101ly3OLoyVO15s2_3PHK1cafTo8OQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/07/26 04:47:10 ERROR : potato2: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/3eZ0qhPdufvfUNgcJKRfAc7vCtv2GvIlw7gIFf1A6tY40wJoGtTn4187101ly3OLoyVO15s2_3PHK1cafTo8OQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/07/26 04:47:10.756718 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/7IP56yMqVTufz4ZOiF_bgDAwFpvddOooFXylwGWKU1HHZNWJAd7emCWT2FCTcvsCjJGUqiyByYPY4ycDQzi6iQ==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/07/26 04:47:10.756761 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/7IP56yMqVTufz4ZOiF_bgDAwFpvddOooFXylwGWKU1HHZNWJAd7emCWT2FCTcvsCjJGUqiyByYPY4ycDQzi6iQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/07/26 04:47:10 ERROR : potato3: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/7IP56yMqVTufz4ZOiF_bgDAwFpvddOooFXylwGWKU1HHZNWJAd7emCWT2FCTcvsCjJGUqiyByYPY4ycDQzi6iQ==/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/7IP56yMqVTufz4ZOiF_bgDAwFpvddOooFXylwGWKU1HHZNWJAd7emCWT2FCTcvsCjJGUqiyByYPY4ycDQzi6iQ==/move: This value should not be blank. (Code=2000, Status=400) Test: TestServerSideMoveWithFilter 2025/07/26 04:47:10 DEBUG : proton drive root link ID 'rclone-test-vefopos9refu': Purge remote --- FAIL: TestServerSideMoveWithFilter (17.65s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:180: Remote "proton drive root link ID 'rclone-test-tajiqet0dana'", Local "Local file system at /tmp/rclone2753854032", Modify Window "1s" 2025/07/26 04:47:14 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-gasicej4josi" 2025/07/26 04:47:14 DEBUG : proton drive root link ID 'rclone-test-gasicej4josi': Has cached credentials 2025/07/26 04:47:15 DEBUG : proton drive root link ID 'rclone-test-gasicej4josi': Used cached credential to initialize the ProtonDrive API 2025/07/26 04:47:20 INFO : tomatoDir: Making directory sync_test.go:1735: Server side move (if possible) proton drive root link ID 'rclone-test-tajiqet0dana' -> proton drive root link ID 'rclone-test-gasicej4josi' 2025/07/26 04:47:27 DEBUG : proton drive root link ID 'rclone-test-gasicej4josi': Using server-side directory move 2025/07/26 04:47:27 INFO : proton drive root link ID 'rclone-test-gasicej4josi': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/07/26 04:47:28 DEBUG : potato2: Need to transfer - File not found at Destination 2025/07/26 04:47:28 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/07/26 04:47:28 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1s) 2025/07/26 04:47:28 DEBUG : empty space: Unchanged skipping 2025/07/26 04:47:28 DEBUG : proton drive root link ID 'rclone-test-gasicej4josi': Waiting for checks to finish 2025/07/26 04:47:29 INFO : potato3: Deleted 2025/07/26 04:47:29 INFO : empty space: Deleted 2025/07/26 04:47:29 DEBUG : proton drive root link ID 'rclone-test-gasicej4josi': Waiting for transfers to finish 2025/07/26 04:47:29.546462 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/WrNDzg3U7Iw79hjd3RANjPPUYALkWCkWIuzYKB2Kwnx2tJwnfsRwzKXTSIccNmRWoQ3-qzozQej0imMlAmCIew==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/07/26 04:47:29.546582 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/WrNDzg3U7Iw79hjd3RANjPPUYALkWCkWIuzYKB2Kwnx2tJwnfsRwzKXTSIccNmRWoQ3-qzozQej0imMlAmCIew==/move: This value should not be blank. (Code=2000, Status=400) 2025/07/26 04:47:29 ERROR : potato2: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/WrNDzg3U7Iw79hjd3RANjPPUYALkWCkWIuzYKB2Kwnx2tJwnfsRwzKXTSIccNmRWoQ3-qzozQej0imMlAmCIew==/move: This value should not be blank. (Code=2000, Status=400) 2025/07/26 04:47:29.890259 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/HC_efQQfiX0Z--amITuOvW-q963P--LEdE3jg8-Ok39XQTfbk-ElioVGVDNvph25zpSH8tC46qPaEqKUlkQzaQ==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/07/26 04:47:29.890295 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/HC_efQQfiX0Z--amITuOvW-q963P--LEdE3jg8-Ok39XQTfbk-ElioVGVDNvph25zpSH8tC46qPaEqKUlkQzaQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/07/26 04:47:29 ERROR : potato3: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/HC_efQQfiX0Z--amITuOvW-q963P--LEdE3jg8-Ok39XQTfbk-ElioVGVDNvph25zpSH8tC46qPaEqKUlkQzaQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/07/26 04:47:29 ERROR : proton drive root link ID 'rclone-test-tajiqet0dana': 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/07/26 04:47:29 DEBUG : proton drive root link ID 'rclone-test-gasicej4josi': Purge remote --- FAIL: TestServerSideMoveDeleteEmptySourceDirs (19.43s) === RUN TestSyncBackupDir run.go:180: Remote "proton drive root link ID 'rclone-test-tajiqet0dana'", Local "Local file system at /tmp/rclone2753854032", Modify Window "1s" 2025/07/26 04:47:40 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-tajiqet0dana/dst" 2025/07/26 04:47:40 DEBUG : proton drive root link ID 'rclone-test-tajiqet0dana/dst': Has cached credentials 2025/07/26 04:47:41 DEBUG : proton drive root link ID 'rclone-test-tajiqet0dana/dst': Used cached credential to initialize the ProtonDrive API 2025/07/26 04:49:07 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-tajiqet0dana/backup" 2025/07/26 04:49:07 DEBUG : Config file has changed externally - reloading 2025/07/26 04:49:07 DEBUG : proton drive root link ID 'rclone-test-tajiqet0dana/backup': Has cached credentials 2025/07/26 04:49:08 DEBUG : proton drive root link ID 'rclone-test-tajiqet0dana/backup': Used cached credential to initialize the ProtonDrive API 2025/07/26 04:51:48 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/07/26 04:51:48 DEBUG : two: Unchanged skipping 2025/07/26 04:51:48 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/07/26 04:51:48 DEBUG : proton drive root link ID 'rclone-test-tajiqet0dana/dst': Waiting for checks to finish 2025/07/26 04:51:49.942867 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/3QE9QXWVtNUSwFcOXGE30JPCb3eb2RtSyUcylTLlqENTUpVLDNCHTtYnLNy0VMa3kGWoQR8wnsm4pFP3XqE44g==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/07/26 04:51:49.942911 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/3QE9QXWVtNUSwFcOXGE30JPCb3eb2RtSyUcylTLlqENTUpVLDNCHTtYnLNy0VMa3kGWoQR8wnsm4pFP3XqE44g==/move: This value should not be blank. (Code=2000, Status=400) 2025/07/26 04:51:49 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/3QE9QXWVtNUSwFcOXGE30JPCb3eb2RtSyUcylTLlqENTUpVLDNCHTtYnLNy0VMa3kGWoQR8wnsm4pFP3XqE44g==/move: This value should not be blank. (Code=2000, Status=400) 2025/07/26 04:51:49 DEBUG : proton drive root link ID 'rclone-test-tajiqet0dana/dst': Waiting for transfers to finish 2025/07/26 04:51:49 ERROR : proton drive root link ID 'rclone-test-tajiqet0dana/dst': not deleting files as there were IO errors 2025/07/26 04:51:49 ERROR : proton drive root link ID 'rclone-test-tajiqet0dana/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/3QE9QXWVtNUSwFcOXGE30JPCb3eb2RtSyUcylTLlqENTUpVLDNCHTtYnLNy0VMa3kGWoQR8wnsm4pFP3XqE44g==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncBackupDir --- FAIL: TestSyncBackupDir (260.58s) === RUN TestSyncBackupDirWithSuffix run.go:180: Remote "proton drive root link ID 'rclone-test-tajiqet0dana'", Local "Local file system at /tmp/rclone2753854032", Modify Window "1s" 2025/07/26 04:52:01 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-tajiqet0dana/dst" 2025/07/26 04:52:01 DEBUG : proton drive root link ID 'rclone-test-tajiqet0dana/dst': Has cached credentials 2025/07/26 04:52:02 DEBUG : proton drive root link ID 'rclone-test-tajiqet0dana/dst': Used cached credential to initialize the ProtonDrive API 2025/07/26 04:53:22 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-tajiqet0dana/backup" 2025/07/26 04:53:22 DEBUG : proton drive root link ID 'rclone-test-tajiqet0dana/backup': Has cached credentials 2025/07/26 04:53:23 DEBUG : proton drive root link ID 'rclone-test-tajiqet0dana/backup': Used cached credential to initialize the ProtonDrive API 2025/07/26 04:56:04 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/07/26 04:56:04 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/07/26 04:56:04 DEBUG : two: Unchanged skipping 2025/07/26 04:56:04 DEBUG : proton drive root link ID 'rclone-test-tajiqet0dana/dst': Waiting for checks to finish 2025/07/26 04:56:05.973096 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/qphWWrn8gsfRpUjXrxELD7lpUYuVLp3rRd0CoeihDnipOsVRfnL7IxMoKfTZM1nCaoQbacOWcPW57Pflp9wVew==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/07/26 04:56:05.973148 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/qphWWrn8gsfRpUjXrxELD7lpUYuVLp3rRd0CoeihDnipOsVRfnL7IxMoKfTZM1nCaoQbacOWcPW57Pflp9wVew==/move: This value should not be blank. (Code=2000, Status=400) 2025/07/26 04:56:05 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/qphWWrn8gsfRpUjXrxELD7lpUYuVLp3rRd0CoeihDnipOsVRfnL7IxMoKfTZM1nCaoQbacOWcPW57Pflp9wVew==/move: This value should not be blank. (Code=2000, Status=400) 2025/07/26 04:56:05 DEBUG : proton drive root link ID 'rclone-test-tajiqet0dana/dst': Waiting for transfers to finish 2025/07/26 04:56:05 ERROR : proton drive root link ID 'rclone-test-tajiqet0dana/dst': not deleting files as there were IO errors 2025/07/26 04:56:05 ERROR : proton drive root link ID 'rclone-test-tajiqet0dana/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/qphWWrn8gsfRpUjXrxELD7lpUYuVLp3rRd0CoeihDnipOsVRfnL7IxMoKfTZM1nCaoQbacOWcPW57Pflp9wVew==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncBackupDirWithSuffix --- FAIL: TestSyncBackupDirWithSuffix (255.87s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:180: Remote "proton drive root link ID 'rclone-test-tajiqet0dana'", Local "Local file system at /tmp/rclone2753854032", Modify Window "1s" 2025/07/26 04:56:17 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-tajiqet0dana/dst" 2025/07/26 04:56:17 DEBUG : proton drive root link ID 'rclone-test-tajiqet0dana/dst': Has cached credentials 2025/07/26 04:56:18 DEBUG : proton drive root link ID 'rclone-test-tajiqet0dana/dst': Used cached credential to initialize the ProtonDrive API 2025/07/26 04:57:37 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-tajiqet0dana/backup" 2025/07/26 04:57:37 DEBUG : proton drive root link ID 'rclone-test-tajiqet0dana/backup': Has cached credentials 2025/07/26 04:57:39 DEBUG : proton drive root link ID 'rclone-test-tajiqet0dana/backup': Used cached credential to initialize the ProtonDrive API 2025/07/26 05:00:18 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/07/26 05:00:18 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/07/26 05:00:18 DEBUG : two: Unchanged skipping 2025/07/26 05:00:18 DEBUG : proton drive root link ID 'rclone-test-tajiqet0dana/dst': Waiting for checks to finish 2025/07/26 05:00:19.942816 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/ekEbpppbTmy4op9UOJRM-fCf6p9KIk8ZmELFVwGySRXfcZMRXpxyCJq8vkVG1ZYzg9bxNhjZojDlq3VqagodlQ==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/07/26 05:00:19.942861 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/ekEbpppbTmy4op9UOJRM-fCf6p9KIk8ZmELFVwGySRXfcZMRXpxyCJq8vkVG1ZYzg9bxNhjZojDlq3VqagodlQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/07/26 05:00:19 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/ekEbpppbTmy4op9UOJRM-fCf6p9KIk8ZmELFVwGySRXfcZMRXpxyCJq8vkVG1ZYzg9bxNhjZojDlq3VqagodlQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/07/26 05:00:19 DEBUG : proton drive root link ID 'rclone-test-tajiqet0dana/dst': Waiting for transfers to finish 2025/07/26 05:00:19 ERROR : proton drive root link ID 'rclone-test-tajiqet0dana/dst': not deleting files as there were IO errors 2025/07/26 05:00:19 ERROR : proton drive root link ID 'rclone-test-tajiqet0dana/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/ekEbpppbTmy4op9UOJRM-fCf6p9KIk8ZmELFVwGySRXfcZMRXpxyCJq8vkVG1ZYzg9bxNhjZojDlq3VqagodlQ==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncBackupDirWithSuffixKeepExtension --- FAIL: TestSyncBackupDirWithSuffixKeepExtension (253.96s) === RUN TestSyncBackupDirSuffixOnly run.go:180: Remote "proton drive root link ID 'rclone-test-tajiqet0dana'", Local "Local file system at /tmp/rclone2753854032", Modify Window "1s" 2025/07/26 05:00:31 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-tajiqet0dana/dst" 2025/07/26 05:00:31 DEBUG : proton drive root link ID 'rclone-test-tajiqet0dana/dst': Has cached credentials 2025/07/26 05:00:32 DEBUG : proton drive root link ID 'rclone-test-tajiqet0dana/dst': Used cached credential to initialize the ProtonDrive API 2025/07/26 05:01:54 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/07/26 05:01:54 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/07/26 05:01:54 DEBUG : two: Unchanged skipping 2025/07/26 05:01:54 DEBUG : proton drive root link ID 'rclone-test-tajiqet0dana/dst': Waiting for checks to finish 2025/07/26 05:01:55.312139 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/hCVE-qHC86gpiGzkV_BnJufANDr7O0X0sde1Go8fsl_HjtpLeBYOBoLs4vJ_awJi6wkpIbTQr_g2M8u-L_FzPA==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/07/26 05:01:55.312173 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/hCVE-qHC86gpiGzkV_BnJufANDr7O0X0sde1Go8fsl_HjtpLeBYOBoLs4vJ_awJi6wkpIbTQr_g2M8u-L_FzPA==/move: This value should not be blank. (Code=2000, Status=400) 2025/07/26 05:01:55 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/hCVE-qHC86gpiGzkV_BnJufANDr7O0X0sde1Go8fsl_HjtpLeBYOBoLs4vJ_awJi6wkpIbTQr_g2M8u-L_FzPA==/move: This value should not be blank. (Code=2000, Status=400) 2025/07/26 05:01:55 DEBUG : proton drive root link ID 'rclone-test-tajiqet0dana/dst': Waiting for transfers to finish 2025/07/26 05:01:55 ERROR : proton drive root link ID 'rclone-test-tajiqet0dana/dst': not deleting files as there were IO errors 2025/07/26 05:01:55 ERROR : proton drive root link ID 'rclone-test-tajiqet0dana/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/hCVE-qHC86gpiGzkV_BnJufANDr7O0X0sde1Go8fsl_HjtpLeBYOBoLs4vJ_awJi6wkpIbTQr_g2M8u-L_FzPA==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncBackupDirSuffixOnly --- FAIL: TestSyncBackupDirSuffixOnly (94.89s) === RUN TestSyncSuffix run.go:180: Remote "proton drive root link ID 'rclone-test-tajiqet0dana'", Local "Local file system at /tmp/rclone2753854032", Modify Window "1s" 2025/07/26 05:02:06 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-tajiqet0dana/dst" 2025/07/26 05:02:06 DEBUG : proton drive root link ID 'rclone-test-tajiqet0dana/dst': Has cached credentials 2025/07/26 05:02:07 DEBUG : proton drive root link ID 'rclone-test-tajiqet0dana/dst': Used cached credential to initialize the ProtonDrive API 2025/07/26 05:03:27 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/07/26 05:03:28.782464 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/E1dJeLUMv30o14cBwLNkcC0cyMEOoaScgrhmxc4uyE4qX9ZCHTeL62lkzhdFT-umuki_AkZonS4Oo-Dy-7cn3A==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/07/26 05:03:28.782543 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/E1dJeLUMv30o14cBwLNkcC0cyMEOoaScgrhmxc4uyE4qX9ZCHTeL62lkzhdFT-umuki_AkZonS4Oo-Dy-7cn3A==/move: This value should not be blank. (Code=2000, Status=400) 2025/07/26 05:03:28 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/E1dJeLUMv30o14cBwLNkcC0cyMEOoaScgrhmxc4uyE4qX9ZCHTeL62lkzhdFT-umuki_AkZonS4Oo-Dy-7cn3A==/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/E1dJeLUMv30o14cBwLNkcC0cyMEOoaScgrhmxc4uyE4qX9ZCHTeL62lkzhdFT-umuki_AkZonS4Oo-Dy-7cn3A==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncSuffix --- FAIL: TestSyncSuffix (93.49s) === RUN TestSyncSuffixKeepExtension run.go:180: Remote "proton drive root link ID 'rclone-test-tajiqet0dana'", Local "Local file system at /tmp/rclone2753854032", Modify Window "1s" 2025/07/26 05:03:39 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-tajiqet0dana/dst" 2025/07/26 05:03:39 DEBUG : proton drive root link ID 'rclone-test-tajiqet0dana/dst': Has cached credentials 2025/07/26 05:03:41 DEBUG : proton drive root link ID 'rclone-test-tajiqet0dana/dst': Used cached credential to initialize the ProtonDrive API 2025/07/26 05:05:01 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/07/26 05:05:02.543955 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/IFlL40yEojU0rnBKaKsrpSFoHvEHKh83PnQLRDi-bymYONPtz7wTJNu3DcEIsSt4xM8M4GODe3cghyyMHK2LOA==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/07/26 05:05:02.543997 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/IFlL40yEojU0rnBKaKsrpSFoHvEHKh83PnQLRDi-bymYONPtz7wTJNu3DcEIsSt4xM8M4GODe3cghyyMHK2LOA==/move: This value should not be blank. (Code=2000, Status=400) 2025/07/26 05:05:02 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/IFlL40yEojU0rnBKaKsrpSFoHvEHKh83PnQLRDi-bymYONPtz7wTJNu3DcEIsSt4xM8M4GODe3cghyyMHK2LOA==/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/IFlL40yEojU0rnBKaKsrpSFoHvEHKh83PnQLRDi-bymYONPtz7wTJNu3DcEIsSt4xM8M4GODe3cghyyMHK2LOA==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncSuffixKeepExtension --- FAIL: TestSyncSuffixKeepExtension (93.77s) FAIL 2025/07/26 05:05:06 DEBUG : proton drive root link ID 'rclone-test-tajiqet0dana': 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 19m27.413250629s (try 2/5): exit status 1: Failed [TestServerSideMoveOverSelf TestSyncWithTrackRenames TestSyncWithTrackRenamesStrategyModtime TestSyncWithTrackRenamesStrategyLeaf TestMoveWithDeleteEmptySrcDirs TestServerSideMove TestServerSideMoveWithFilter TestServerSideMoveDeleteEmptySourceDirs TestSyncBackupDir TestSyncBackupDirWithSuffix TestSyncBackupDirWithSuffixKeepExtension TestSyncBackupDirSuffixOnly TestSyncSuffix TestSyncSuffixKeepExtension]