"./sync.test -test.v -test.timeout 1h0m0s -remote TestProtonDrive: -verbose -test.run '^(TestMoveWithDeleteEmptySrcDirs|TestServerSideMove|TestServerSideMoveDeleteEmptySourceDirs|TestServerSideMoveOverSelf|TestServerSideMoveWithFilter|TestSyncBackupDir|TestSyncBackupDirSuffixOnly|TestSyncBackupDirWithSuffix|TestSyncBackupDirWithSuffixKeepExtension|TestSyncWithTrackRenames|TestSyncWithTrackRenamesStrategyLeaf|TestSyncWithTrackRenamesStrategyModtime)$'" - Starting (try 2/5) 2025/09/01 05:15:12 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-tevomon9tega" 2025/09/01 05:15:12 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/09/01 05:15:12 DEBUG : proton drive root link ID 'rclone-test-tevomon9tega': Has cached credentials 2025/09/01 05:15:13 DEBUG : proton drive root link ID 'rclone-test-tevomon9tega': Used cached credential to initialize the ProtonDrive API 2025/09/01 05:15:13 DEBUG : Creating backend with remote "/tmp/rclone1885352018" === RUN TestServerSideMoveOverSelf run.go:180: Remote "proton drive root link ID 'rclone-test-tevomon9tega'", Local "Local file system at /tmp/rclone1885352018", Modify Window "1s" 2025/09/01 05:15:16 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-bodanod0kaka" 2025/09/01 05:15:16 DEBUG : proton drive root link ID 'rclone-test-bodanod0kaka': Has cached credentials 2025/09/01 05:15:17 DEBUG : proton drive root link ID 'rclone-test-bodanod0kaka': Used cached credential to initialize the ProtonDrive API sync_test.go:701: Server side copy (if possible) proton drive root link ID 'rclone-test-tevomon9tega' -> proton drive root link ID 'rclone-test-bodanod0kaka' 2025/09/01 05:15:17 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/09/01 05:15:17 DEBUG : proton drive root link ID 'rclone-test-bodanod0kaka': Waiting for checks to finish 2025/09/01 05:15:17 DEBUG : proton drive root link ID 'rclone-test-bodanod0kaka': Waiting for transfers to finish 2025/09/01 05:15:20 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2025/09/01 05:15:20 INFO : sub dir/hello world: Copied (new) 2025/09/01 05:15:21.670260 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/01 05:15:21.670302 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/01 05:15:23 DEBUG : proton drive root link ID 'rclone-test-bodanod0kaka': Using server-side directory move 2025/09/01 05:15:23 INFO : proton drive root link ID 'rclone-test-bodanod0kaka': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/09/01 05:15:24 DEBUG : proton drive root link ID 'rclone-test-bodanod0kaka': Waiting for checks to finish 2025/09/01 05:15:24 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/09/01 05:15:24 DEBUG : proton drive root link ID 'rclone-test-bodanod0kaka': Waiting for transfers to finish 2025/09/01 05:15:24 INFO : sub dir/hello world: Deleted 2025/09/01 05:15:24.706662 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/_9wSmbkCEA1OoQ5E75NvSN4jjWZN0b8BYBOnnmriXMLBLl_cSglwzv-AAbAq39vKXkAyGeGSw4mn_Nk-BBLBzA==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/01 05:15:24.706685 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/_9wSmbkCEA1OoQ5E75NvSN4jjWZN0b8BYBOnnmriXMLBLl_cSglwzv-AAbAq39vKXkAyGeGSw4mn_Nk-BBLBzA==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/01 05:15:24 ERROR : sub dir/hello world: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/_9wSmbkCEA1OoQ5E75NvSN4jjWZN0b8BYBOnnmriXMLBLl_cSglwzv-AAbAq39vKXkAyGeGSw4mn_Nk-BBLBzA==/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/_9wSmbkCEA1OoQ5E75NvSN4jjWZN0b8BYBOnnmriXMLBLl_cSglwzv-AAbAq39vKXkAyGeGSw4mn_Nk-BBLBzA==/move: This value should not be blank. (Code=2000, Status=400) Test: TestServerSideMoveOverSelf 2025/09/01 05:15:24 DEBUG : proton drive root link ID 'rclone-test-bodanod0kaka': Purge remote --- FAIL: TestServerSideMoveOverSelf (12.53s) === RUN TestSyncWithTrackRenames run.go:180: Remote "proton drive root link ID 'rclone-test-tevomon9tega'", Local "Local file system at /tmp/rclone1885352018", Modify Window "1s" sync_test.go:1568: Can track renames: true 2025/09/01 05:15:26 INFO : proton drive root link ID 'rclone-test-tevomon9tega': Making map for --track-renames 2025/09/01 05:15:26 INFO : proton drive root link ID 'rclone-test-tevomon9tega': Finished making map for --track-renames 2025/09/01 05:15:26 DEBUG : proton drive root link ID 'rclone-test-tevomon9tega': Waiting for checks to finish 2025/09/01 05:15:26 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/09/01 05:15:26 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/09/01 05:15:26 DEBUG : proton drive root link ID 'rclone-test-tevomon9tega': Waiting for renames to finish 2025/09/01 05:15:26 DEBUG : proton drive root link ID 'rclone-test-tevomon9tega': Waiting for transfers to finish 2025/09/01 05:15:27 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/09/01 05:15:27 INFO : potato: Copied (new) 2025/09/01 05:15:27 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/09/01 05:15:27 INFO : yam: Copied (new) 2025/09/01 05:15:27 DEBUG : Waiting for deletions to finish 2025/09/01 05:15:28 DEBUG : potato: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/01 05:15:28 INFO : proton drive root link ID 'rclone-test-tevomon9tega': Making map for --track-renames 2025/09/01 05:15:28 INFO : proton drive root link ID 'rclone-test-tevomon9tega': Finished making map for --track-renames 2025/09/01 05:15:28 DEBUG : proton drive root link ID 'rclone-test-tevomon9tega': Waiting for checks to finish 2025/09/01 05:15:28 DEBUG : potato: Unchanged skipping 2025/09/01 05:15:28 DEBUG : proton drive root link ID 'rclone-test-tevomon9tega': Waiting for renames to finish 2025/09/01 05:15:28.916143 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/Z4QDPO8IgWtAI8BuTAK7pgL-VyYyKXE78i9YqhBSeu_0uYAy_XTiMEiUFrIF1gz-s-RSgAYXgUEj4gfNz16K6w==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/01 05:15:28.916189 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/Z4QDPO8IgWtAI8BuTAK7pgL-VyYyKXE78i9YqhBSeu_0uYAy_XTiMEiUFrIF1gz-s-RSgAYXgUEj4gfNz16K6w==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/01 05:15:28 ERROR : yam: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/Z4QDPO8IgWtAI8BuTAK7pgL-VyYyKXE78i9YqhBSeu_0uYAy_XTiMEiUFrIF1gz-s-RSgAYXgUEj4gfNz16K6w==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/01 05:15:28 DEBUG : yaml: Failed to rename to "yam": 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/Z4QDPO8IgWtAI8BuTAK7pgL-VyYyKXE78i9YqhBSeu_0uYAy_XTiMEiUFrIF1gz-s-RSgAYXgUEj4gfNz16K6w==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/01 05:15:28 DEBUG : yaml: Need to transfer - No matching file found at Destination 2025/09/01 05:15:28 DEBUG : proton drive root link ID 'rclone-test-tevomon9tega': Waiting for transfers to finish 2025/09/01 05:15:30 DEBUG : yaml: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/09/01 05:15:30 INFO : yaml: Copied (new) 2025/09/01 05:15:30 ERROR : proton drive root link ID 'rclone-test-tevomon9tega': not deleting files as there were IO errors 2025/09/01 05:15:30 ERROR : proton drive root link ID 'rclone-test-tevomon9tega': not deleting directories as there were IO errors sync_test.go:1586: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1586 Error: Received unexpected error: not deleting files as there were IO errors Test: TestSyncWithTrackRenames --- FAIL: TestSyncWithTrackRenames (5.27s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:180: Remote "proton drive root link ID 'rclone-test-tevomon9tega'", Local "Local file system at /tmp/rclone1885352018", Modify Window "1s" sync_test.go:1640: Can track renames: true 2025/09/01 05:15:31 INFO : proton drive root link ID 'rclone-test-tevomon9tega': Making map for --track-renames 2025/09/01 05:15:31 INFO : proton drive root link ID 'rclone-test-tevomon9tega': Finished making map for --track-renames 2025/09/01 05:15:31 DEBUG : proton drive root link ID 'rclone-test-tevomon9tega': Waiting for checks to finish 2025/09/01 05:15:31 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/09/01 05:15:31 DEBUG : proton drive root link ID 'rclone-test-tevomon9tega': Waiting for renames to finish 2025/09/01 05:15:31 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/09/01 05:15:31 DEBUG : proton drive root link ID 'rclone-test-tevomon9tega': Waiting for transfers to finish 2025/09/01 05:15:32 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/09/01 05:15:32 INFO : potato: Copied (new) 2025/09/01 05:15:32 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/09/01 05:15:32 INFO : yam: Copied (new) 2025/09/01 05:15:32 DEBUG : Waiting for deletions to finish 2025/09/01 05:15:33 DEBUG : potato: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/01 05:15:33 INFO : proton drive root link ID 'rclone-test-tevomon9tega': Making map for --track-renames 2025/09/01 05:15:33 INFO : proton drive root link ID 'rclone-test-tevomon9tega': Finished making map for --track-renames 2025/09/01 05:15:33 DEBUG : proton drive root link ID 'rclone-test-tevomon9tega': Waiting for checks to finish 2025/09/01 05:15:33 DEBUG : potato: Unchanged skipping 2025/09/01 05:15:33 DEBUG : proton drive root link ID 'rclone-test-tevomon9tega': Waiting for renames to finish 2025/09/01 05:15:34.076883 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/f0fxTucp8TwGALS3UrVG22R60YtpmMJNv7SIonM9yQ_9odMu3xjyaA2aeQJ6yVZIls_8iM9cX8EKJZyz1eb01Q==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/01 05:15:34.076910 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/f0fxTucp8TwGALS3UrVG22R60YtpmMJNv7SIonM9yQ_9odMu3xjyaA2aeQJ6yVZIls_8iM9cX8EKJZyz1eb01Q==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/01 05:15:34 ERROR : yam: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/f0fxTucp8TwGALS3UrVG22R60YtpmMJNv7SIonM9yQ_9odMu3xjyaA2aeQJ6yVZIls_8iM9cX8EKJZyz1eb01Q==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/01 05:15:34 DEBUG : yaml: Failed to rename to "yam": 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/f0fxTucp8TwGALS3UrVG22R60YtpmMJNv7SIonM9yQ_9odMu3xjyaA2aeQJ6yVZIls_8iM9cX8EKJZyz1eb01Q==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/01 05:15:34 DEBUG : yaml: Need to transfer - No matching file found at Destination 2025/09/01 05:15:34 DEBUG : proton drive root link ID 'rclone-test-tevomon9tega': Waiting for transfers to finish 2025/09/01 05:15:35 DEBUG : yaml: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/09/01 05:15:35 INFO : yaml: Copied (new) 2025/09/01 05:15:35 ERROR : proton drive root link ID 'rclone-test-tevomon9tega': not deleting files as there were IO errors 2025/09/01 05:15:35 ERROR : proton drive root link ID 'rclone-test-tevomon9tega': not deleting directories as there were IO errors sync_test.go:1658: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1658 Error: Received unexpected error: not deleting files as there were IO errors Test: TestSyncWithTrackRenamesStrategyModtime --- FAIL: TestSyncWithTrackRenamesStrategyModtime (5.14s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:180: Remote "proton drive root link ID 'rclone-test-tevomon9tega'", Local "Local file system at /tmp/rclone1885352018", Modify Window "1s" sync_test.go:1679: Can track renames: true 2025/09/01 05:15:36 INFO : proton drive root link ID 'rclone-test-tevomon9tega': Making map for --track-renames 2025/09/01 05:15:36 INFO : proton drive root link ID 'rclone-test-tevomon9tega': Finished making map for --track-renames 2025/09/01 05:15:36 DEBUG : proton drive root link ID 'rclone-test-tevomon9tega': Waiting for checks to finish 2025/09/01 05:15:36 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/09/01 05:15:36 DEBUG : sub/yam: Need to transfer - No matching file found at Destination 2025/09/01 05:15:36 DEBUG : proton drive root link ID 'rclone-test-tevomon9tega': Waiting for renames to finish 2025/09/01 05:15:36 DEBUG : proton drive root link ID 'rclone-test-tevomon9tega': Waiting for transfers to finish 2025/09/01 05:15:38 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/09/01 05:15:38 INFO : potato: Copied (new) 2025/09/01 05:15:38 DEBUG : sub/yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/09/01 05:15:38 INFO : sub/yam: Copied (new) 2025/09/01 05:15:38 DEBUG : Waiting for deletions to finish 2025/09/01 05:15:39 DEBUG : potato: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/01 05:15:39 DEBUG : potato: Unchanged skipping 2025/09/01 05:15:39 INFO : proton drive root link ID 'rclone-test-tevomon9tega': Making map for --track-renames 2025/09/01 05:15:39 INFO : proton drive root link ID 'rclone-test-tevomon9tega': Finished making map for --track-renames 2025/09/01 05:15:39 DEBUG : proton drive root link ID 'rclone-test-tevomon9tega': Waiting for checks to finish 2025/09/01 05:15:39 DEBUG : proton drive root link ID 'rclone-test-tevomon9tega': Waiting for renames to finish 2025/09/01 05:15:40.044388 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/qfSQ-lwaUkExexglmkzcvRv6smlwoHeqZKSAl4cluwjb-LdJ9OUPUgOhyGlulBRp9DFsq3jASF-ZbmXsJjvjyw==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/01 05:15:40.044433 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/qfSQ-lwaUkExexglmkzcvRv6smlwoHeqZKSAl4cluwjb-LdJ9OUPUgOhyGlulBRp9DFsq3jASF-ZbmXsJjvjyw==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/01 05:15:40 ERROR : sub/yam: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/qfSQ-lwaUkExexglmkzcvRv6smlwoHeqZKSAl4cluwjb-LdJ9OUPUgOhyGlulBRp9DFsq3jASF-ZbmXsJjvjyw==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/01 05:15:40 DEBUG : yam: Failed to rename to "sub/yam": 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/qfSQ-lwaUkExexglmkzcvRv6smlwoHeqZKSAl4cluwjb-LdJ9OUPUgOhyGlulBRp9DFsq3jASF-ZbmXsJjvjyw==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/01 05:15:40 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/09/01 05:15:40 DEBUG : proton drive root link ID 'rclone-test-tevomon9tega': Waiting for transfers to finish 2025/09/01 05:15:41 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/09/01 05:15:41 INFO : yam: Copied (new) 2025/09/01 05:15:41 ERROR : proton drive root link ID 'rclone-test-tevomon9tega': not deleting files as there were IO errors 2025/09/01 05:15:41 ERROR : proton drive root link ID 'rclone-test-tevomon9tega': not deleting directories as there were IO errors sync_test.go:1697: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1697 Error: Received unexpected error: not deleting files as there were IO errors Test: TestSyncWithTrackRenamesStrategyLeaf --- FAIL: TestSyncWithTrackRenamesStrategyLeaf (6.49s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:180: Remote "proton drive root link ID 'rclone-test-tevomon9tega'", Local "Local file system at /tmp/rclone1885352018", Modify Window "1s" 2025/09/01 05:15:43 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/09/01 05:15:43 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2025/09/01 05:15:43 DEBUG : proton drive root link ID 'rclone-test-tevomon9tega': Waiting for checks to finish 2025/09/01 05:15:43 DEBUG : proton drive root link ID 'rclone-test-tevomon9tega': Waiting for transfers to finish 2025/09/01 05:15:45 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2025/09/01 05:15:45 INFO : sub dir/hello world: Copied (new) 2025/09/01 05:15:45 INFO : sub dir/hello world: Deleted 2025/09/01 05:15:45 DEBUG : nested/sub dir/file: sha1 = b4b3e0a278988bc15f2913af3f4153ccef74e465 OK 2025/09/01 05:15:45 INFO : nested/sub dir/file: Copied (new) 2025/09/01 05:15:45 INFO : nested/sub dir/file: Deleted 2025/09/01 05:15:45 ERROR : Local file system at /tmp/rclone1885352018: not deleting directories as there were IO errors sync_test.go:1818: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1818 Error: Received unexpected error: not deleting directories as there were IO errors Test: TestMoveWithDeleteEmptySrcDirs --- FAIL: TestMoveWithDeleteEmptySrcDirs (4.54s) === RUN TestServerSideMove run.go:180: Remote "proton drive root link ID 'rclone-test-tevomon9tega'", Local "Local file system at /tmp/rclone1885352018", Modify Window "1s" 2025/09/01 05:15:47 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-kahuzuc9vutu" 2025/09/01 05:15:47 DEBUG : proton drive root link ID 'rclone-test-kahuzuc9vutu': Has cached credentials 2025/09/01 05:15:48 DEBUG : proton drive root link ID 'rclone-test-kahuzuc9vutu': Used cached credential to initialize the ProtonDrive API sync_test.go:1735: Server side move (if possible) proton drive root link ID 'rclone-test-tevomon9tega' -> proton drive root link ID 'rclone-test-kahuzuc9vutu' 2025/09/01 05:15:56 DEBUG : proton drive root link ID 'rclone-test-kahuzuc9vutu': Using server-side directory move 2025/09/01 05:15:56 INFO : proton drive root link ID 'rclone-test-kahuzuc9vutu': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/09/01 05:15:56 DEBUG : potato2: Need to transfer - File not found at Destination 2025/09/01 05:15:56 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1s) 2025/09/01 05:15:56 DEBUG : empty space: Unchanged skipping 2025/09/01 05:15:56 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/09/01 05:15:56 DEBUG : proton drive root link ID 'rclone-test-kahuzuc9vutu': Waiting for checks to finish 2025/09/01 05:15:57 INFO : empty space: Deleted 2025/09/01 05:15:57 DEBUG : proton drive root link ID 'rclone-test-kahuzuc9vutu': Waiting for transfers to finish 2025/09/01 05:15:57 INFO : potato3: Deleted 2025/09/01 05:15:57.378186 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/vx_dR46zXpfuqPA-0SzqtVSA92wOzfjcB4Cwrd39ktT6YyCtviZILnUt8eP8VUU02EWpHK0Tq8-ueoCf7gBzKw==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/01 05:15:57.378222 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/vx_dR46zXpfuqPA-0SzqtVSA92wOzfjcB4Cwrd39ktT6YyCtviZILnUt8eP8VUU02EWpHK0Tq8-ueoCf7gBzKw==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/01 05:15:57 ERROR : potato2: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/vx_dR46zXpfuqPA-0SzqtVSA92wOzfjcB4Cwrd39ktT6YyCtviZILnUt8eP8VUU02EWpHK0Tq8-ueoCf7gBzKw==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/01 05:15:57.523850 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/EwUbrm4v6InWgG6AYHvCjj7mv-_TXXo4tVvUIsvIfnduUlaxKtRVbMPVZa4ZaVGDQN7ZdqfX8RMDJ8WfR3U4xA==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/01 05:15:57.523883 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/EwUbrm4v6InWgG6AYHvCjj7mv-_TXXo4tVvUIsvIfnduUlaxKtRVbMPVZa4ZaVGDQN7ZdqfX8RMDJ8WfR3U4xA==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/01 05:15:57 ERROR : potato3: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/EwUbrm4v6InWgG6AYHvCjj7mv-_TXXo4tVvUIsvIfnduUlaxKtRVbMPVZa4ZaVGDQN7ZdqfX8RMDJ8WfR3U4xA==/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/EwUbrm4v6InWgG6AYHvCjj7mv-_TXXo4tVvUIsvIfnduUlaxKtRVbMPVZa4ZaVGDQN7ZdqfX8RMDJ8WfR3U4xA==/move: This value should not be blank. (Code=2000, Status=400) Test: TestServerSideMove 2025/09/01 05:15:57 DEBUG : proton drive root link ID 'rclone-test-kahuzuc9vutu': Purge remote --- FAIL: TestServerSideMove (11.24s) === RUN TestServerSideMoveWithFilter run.go:180: Remote "proton drive root link ID 'rclone-test-tevomon9tega'", Local "Local file system at /tmp/rclone1885352018", Modify Window "1s" 2025/09/01 05:15:58 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-tuzonex3rumi" 2025/09/01 05:15:58 DEBUG : proton drive root link ID 'rclone-test-tuzonex3rumi': Has cached credentials 2025/09/01 05:15:59 DEBUG : proton drive root link ID 'rclone-test-tuzonex3rumi': Used cached credential to initialize the ProtonDrive API sync_test.go:1735: Server side move (if possible) proton drive root link ID 'rclone-test-tevomon9tega' -> proton drive root link ID 'rclone-test-tuzonex3rumi' 2025/09/01 05:16:08 DEBUG : empty space: Excluded (Size Filter) 2025/09/01 05:16:08 DEBUG : empty space: Excluded 2025/09/01 05:16:08 DEBUG : empty space: Excluded (Size Filter) 2025/09/01 05:16:08 DEBUG : empty space: Excluded 2025/09/01 05:16:08 DEBUG : potato2: Need to transfer - File not found at Destination 2025/09/01 05:16:08 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/09/01 05:16:08 DEBUG : proton drive root link ID 'rclone-test-tuzonex3rumi': Waiting for checks to finish 2025/09/01 05:16:08 DEBUG : proton drive root link ID 'rclone-test-tuzonex3rumi': Waiting for transfers to finish 2025/09/01 05:16:08 INFO : potato3: Deleted 2025/09/01 05:16:08.833615 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/meC7udoyn1caLmHTpnbu2O5B8jegPvvWBryAoNO-1A0CHknu04VC0kb324A2WhSk-KwujNCP71AMoGXgHoVKjQ==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/01 05:16:08.833659 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/meC7udoyn1caLmHTpnbu2O5B8jegPvvWBryAoNO-1A0CHknu04VC0kb324A2WhSk-KwujNCP71AMoGXgHoVKjQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/01 05:16:08 ERROR : potato2: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/meC7udoyn1caLmHTpnbu2O5B8jegPvvWBryAoNO-1A0CHknu04VC0kb324A2WhSk-KwujNCP71AMoGXgHoVKjQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/01 05:16:08.920535 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/GkWr-QC55AkFuus3XnrXUrxcAsFGCUTaP34L7agyDeqlI4oTj7aH9GylfmXx6u-JSmEyLzjJuMMkZFfKvAh0cg==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/01 05:16:08.920578 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/GkWr-QC55AkFuus3XnrXUrxcAsFGCUTaP34L7agyDeqlI4oTj7aH9GylfmXx6u-JSmEyLzjJuMMkZFfKvAh0cg==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/01 05:16:08 ERROR : potato3: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/GkWr-QC55AkFuus3XnrXUrxcAsFGCUTaP34L7agyDeqlI4oTj7aH9GylfmXx6u-JSmEyLzjJuMMkZFfKvAh0cg==/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/GkWr-QC55AkFuus3XnrXUrxcAsFGCUTaP34L7agyDeqlI4oTj7aH9GylfmXx6u-JSmEyLzjJuMMkZFfKvAh0cg==/move: This value should not be blank. (Code=2000, Status=400) Test: TestServerSideMoveWithFilter 2025/09/01 05:16:08 DEBUG : proton drive root link ID 'rclone-test-tuzonex3rumi': Purge remote --- FAIL: TestServerSideMoveWithFilter (11.63s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:180: Remote "proton drive root link ID 'rclone-test-tevomon9tega'", Local "Local file system at /tmp/rclone1885352018", Modify Window "1s" 2025/09/01 05:16:10 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-yiditet3gote" 2025/09/01 05:16:10 DEBUG : proton drive root link ID 'rclone-test-yiditet3gote': Has cached credentials 2025/09/01 05:16:11 DEBUG : proton drive root link ID 'rclone-test-yiditet3gote': Used cached credential to initialize the ProtonDrive API 2025/09/01 05:16:15 INFO : tomatoDir: Making directory sync_test.go:1735: Server side move (if possible) proton drive root link ID 'rclone-test-tevomon9tega' -> proton drive root link ID 'rclone-test-yiditet3gote' 2025/09/01 05:16:19 DEBUG : proton drive root link ID 'rclone-test-yiditet3gote': Using server-side directory move 2025/09/01 05:16:19 INFO : proton drive root link ID 'rclone-test-yiditet3gote': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/09/01 05:16:20 DEBUG : potato2: Need to transfer - File not found at Destination 2025/09/01 05:16:20 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1s) 2025/09/01 05:16:20 DEBUG : empty space: Unchanged skipping 2025/09/01 05:16:20 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/09/01 05:16:20 DEBUG : proton drive root link ID 'rclone-test-yiditet3gote': Waiting for checks to finish 2025/09/01 05:16:20 INFO : empty space: Deleted 2025/09/01 05:16:20 DEBUG : proton drive root link ID 'rclone-test-yiditet3gote': Waiting for transfers to finish 2025/09/01 05:16:20 INFO : potato3: Deleted 2025/09/01 05:16:20.486887 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/vhOOS_PjGq6_wKNzhCifaV5Y6m81hrxQ0QgglHZcEbXYEbSFOBTktGeAsqPpLFtVaFrNAuNoTLcrb63NFcntkQ==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/01 05:16:20.486925 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/vhOOS_PjGq6_wKNzhCifaV5Y6m81hrxQ0QgglHZcEbXYEbSFOBTktGeAsqPpLFtVaFrNAuNoTLcrb63NFcntkQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/01 05:16:20 ERROR : potato2: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/vhOOS_PjGq6_wKNzhCifaV5Y6m81hrxQ0QgglHZcEbXYEbSFOBTktGeAsqPpLFtVaFrNAuNoTLcrb63NFcntkQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/01 05:16:20.612592 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/vf6rwMSKDI_lyjEeyei8vqa13CDBr5UQ9hlENABLCNIAx2y3a_MefKJQMchujINhk6RH6JevCvd8FGpFT9R5bQ==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/01 05:16:20.612639 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/vf6rwMSKDI_lyjEeyei8vqa13CDBr5UQ9hlENABLCNIAx2y3a_MefKJQMchujINhk6RH6JevCvd8FGpFT9R5bQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/01 05:16:20 ERROR : potato3: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/vf6rwMSKDI_lyjEeyei8vqa13CDBr5UQ9hlENABLCNIAx2y3a_MefKJQMchujINhk6RH6JevCvd8FGpFT9R5bQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/01 05:16:20 ERROR : proton drive root link ID 'rclone-test-tevomon9tega': 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/01 05:16:20 DEBUG : proton drive root link ID 'rclone-test-yiditet3gote': Purge remote --- FAIL: TestServerSideMoveDeleteEmptySourceDirs (11.82s) === RUN TestSyncBackupDir run.go:180: Remote "proton drive root link ID 'rclone-test-tevomon9tega'", Local "Local file system at /tmp/rclone1885352018", Modify Window "1s" 2025/09/01 05:16:27 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-tevomon9tega/dst" 2025/09/01 05:16:27 DEBUG : proton drive root link ID 'rclone-test-tevomon9tega/dst': Has cached credentials 2025/09/01 05:16:28 DEBUG : proton drive root link ID 'rclone-test-tevomon9tega/dst': Used cached credential to initialize the ProtonDrive API 2025/09/01 05:18:11 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-tevomon9tega/backup" 2025/09/01 05:18:11 DEBUG : proton drive root link ID 'rclone-test-tevomon9tega/backup': Has cached credentials 2025/09/01 05:18:12 DEBUG : proton drive root link ID 'rclone-test-tevomon9tega/backup': Used cached credential to initialize the ProtonDrive API 2025/09/01 05:21:28 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/09/01 05:21:28 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/01 05:21:28 DEBUG : two: Unchanged skipping 2025/09/01 05:21:28 DEBUG : proton drive root link ID 'rclone-test-tevomon9tega/dst': Waiting for checks to finish 2025/09/01 05:21:29.576934 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/9IxrTvrwORK45auonjwPpqESgZE63fGjV4mX3wBAsOHfWwTLydeUfXM-gHE_FDeGZ8gGEXgkuJSdmRGD6xOu1w==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/01 05:21:29.576970 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/9IxrTvrwORK45auonjwPpqESgZE63fGjV4mX3wBAsOHfWwTLydeUfXM-gHE_FDeGZ8gGEXgkuJSdmRGD6xOu1w==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/01 05:21:29 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/9IxrTvrwORK45auonjwPpqESgZE63fGjV4mX3wBAsOHfWwTLydeUfXM-gHE_FDeGZ8gGEXgkuJSdmRGD6xOu1w==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/01 05:21:29 DEBUG : proton drive root link ID 'rclone-test-tevomon9tega/dst': Waiting for transfers to finish 2025/09/01 05:21:29 ERROR : proton drive root link ID 'rclone-test-tevomon9tega/dst': not deleting files as there were IO errors 2025/09/01 05:21:29 ERROR : proton drive root link ID 'rclone-test-tevomon9tega/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/9IxrTvrwORK45auonjwPpqESgZE63fGjV4mX3wBAsOHfWwTLydeUfXM-gHE_FDeGZ8gGEXgkuJSdmRGD6xOu1w==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncBackupDir --- FAIL: TestSyncBackupDir (309.19s) === RUN TestSyncBackupDirWithSuffix run.go:180: Remote "proton drive root link ID 'rclone-test-tevomon9tega'", Local "Local file system at /tmp/rclone1885352018", Modify Window "1s" 2025/09/01 05:21:36 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-tevomon9tega/dst" 2025/09/01 05:21:36 DEBUG : proton drive root link ID 'rclone-test-tevomon9tega/dst': Has cached credentials 2025/09/01 05:21:37 DEBUG : proton drive root link ID 'rclone-test-tevomon9tega/dst': Used cached credential to initialize the ProtonDrive API 2025/09/01 05:23:21 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-tevomon9tega/backup" 2025/09/01 05:23:21 DEBUG : proton drive root link ID 'rclone-test-tevomon9tega/backup': Has cached credentials 2025/09/01 05:23:22 DEBUG : proton drive root link ID 'rclone-test-tevomon9tega/backup': Used cached credential to initialize the ProtonDrive API 2025/09/01 05:27:10 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/09/01 05:27:10 DEBUG : proton drive root link ID 'rclone-test-tevomon9tega/dst': Waiting for checks to finish 2025/09/01 05:27:10 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/01 05:27:10 DEBUG : two: Unchanged skipping 2025/09/01 05:27:11.437072 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/hfN_7H__Z8vq0mmZt4mX-vVj1E-kXeiJ1jK2gFc42bBzY69ucoBOW4KsBglRN_eVdy-jciKR8rrs5DrcTeEBnQ==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/01 05:27:11.437108 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/hfN_7H__Z8vq0mmZt4mX-vVj1E-kXeiJ1jK2gFc42bBzY69ucoBOW4KsBglRN_eVdy-jciKR8rrs5DrcTeEBnQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/01 05:27:11 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/hfN_7H__Z8vq0mmZt4mX-vVj1E-kXeiJ1jK2gFc42bBzY69ucoBOW4KsBglRN_eVdy-jciKR8rrs5DrcTeEBnQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/01 05:27:11 DEBUG : proton drive root link ID 'rclone-test-tevomon9tega/dst': Waiting for transfers to finish 2025/09/01 05:27:11 ERROR : proton drive root link ID 'rclone-test-tevomon9tega/dst': not deleting files as there were IO errors 2025/09/01 05:27:11 ERROR : proton drive root link ID 'rclone-test-tevomon9tega/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/hfN_7H__Z8vq0mmZt4mX-vVj1E-kXeiJ1jK2gFc42bBzY69ucoBOW4KsBglRN_eVdy-jciKR8rrs5DrcTeEBnQ==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncBackupDirWithSuffix --- FAIL: TestSyncBackupDirWithSuffix (341.99s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:180: Remote "proton drive root link ID 'rclone-test-tevomon9tega'", Local "Local file system at /tmp/rclone1885352018", Modify Window "1s" 2025/09/01 05:27:18 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-tevomon9tega/dst" 2025/09/01 05:27:18 DEBUG : Config file has changed externally - reloading 2025/09/01 05:27:18 DEBUG : proton drive root link ID 'rclone-test-tevomon9tega/dst': Has cached credentials 2025/09/01 05:27:19 DEBUG : proton drive root link ID 'rclone-test-tevomon9tega/dst': Used cached credential to initialize the ProtonDrive API 2025/09/01 05:29:00 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-tevomon9tega/backup" 2025/09/01 05:29:00 DEBUG : proton drive root link ID 'rclone-test-tevomon9tega/backup': Has cached credentials 2025/09/01 05:29:01 DEBUG : proton drive root link ID 'rclone-test-tevomon9tega/backup': Used cached credential to initialize the ProtonDrive API 2025/09/01 05:32:18 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/09/01 05:32:18 DEBUG : proton drive root link ID 'rclone-test-tevomon9tega/dst': Waiting for checks to finish 2025/09/01 05:32:18 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/01 05:32:18 DEBUG : two: Unchanged skipping 2025/09/01 05:32:19.111827 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/nl0B0e85nZCk67gjl9bLRAyeUcKCpWa0CTpj-FrFgbuNMjO1dBYEcYUABA8Squq_Y48TnrWJA9d2slD6fYUBWw==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/01 05:32:19.111884 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/nl0B0e85nZCk67gjl9bLRAyeUcKCpWa0CTpj-FrFgbuNMjO1dBYEcYUABA8Squq_Y48TnrWJA9d2slD6fYUBWw==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/01 05:32:19 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/nl0B0e85nZCk67gjl9bLRAyeUcKCpWa0CTpj-FrFgbuNMjO1dBYEcYUABA8Squq_Y48TnrWJA9d2slD6fYUBWw==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/01 05:32:19 DEBUG : proton drive root link ID 'rclone-test-tevomon9tega/dst': Waiting for transfers to finish 2025/09/01 05:32:19 ERROR : proton drive root link ID 'rclone-test-tevomon9tega/dst': not deleting files as there were IO errors 2025/09/01 05:32:19 ERROR : proton drive root link ID 'rclone-test-tevomon9tega/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/nl0B0e85nZCk67gjl9bLRAyeUcKCpWa0CTpj-FrFgbuNMjO1dBYEcYUABA8Squq_Y48TnrWJA9d2slD6fYUBWw==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncBackupDirWithSuffixKeepExtension --- FAIL: TestSyncBackupDirWithSuffixKeepExtension (307.57s) === RUN TestSyncBackupDirSuffixOnly run.go:180: Remote "proton drive root link ID 'rclone-test-tevomon9tega'", Local "Local file system at /tmp/rclone1885352018", Modify Window "1s" 2025/09/01 05:32:25 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-tevomon9tega/dst" 2025/09/01 05:32:25 DEBUG : proton drive root link ID 'rclone-test-tevomon9tega/dst': Has cached credentials 2025/09/01 05:32:26 DEBUG : proton drive root link ID 'rclone-test-tevomon9tega/dst': Used cached credential to initialize the ProtonDrive API 2025/09/01 05:34:05 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/09/01 05:34:05 DEBUG : proton drive root link ID 'rclone-test-tevomon9tega/dst': Waiting for checks to finish 2025/09/01 05:34:05 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/01 05:34:05 DEBUG : two: Unchanged skipping 2025/09/01 05:34:05.553696 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/4LywCmv5GJnBmI2bu1XWqBtRTCE8Y7wk9gsMEcQyDT0z0gr81PpXR-o0rHX3HrUIv7Gh3-cWcYlJ0Y5XwsB7rQ==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/01 05:34:05.553759 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/4LywCmv5GJnBmI2bu1XWqBtRTCE8Y7wk9gsMEcQyDT0z0gr81PpXR-o0rHX3HrUIv7Gh3-cWcYlJ0Y5XwsB7rQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/01 05:34:05 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/4LywCmv5GJnBmI2bu1XWqBtRTCE8Y7wk9gsMEcQyDT0z0gr81PpXR-o0rHX3HrUIv7Gh3-cWcYlJ0Y5XwsB7rQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/01 05:34:05 DEBUG : proton drive root link ID 'rclone-test-tevomon9tega/dst': Waiting for transfers to finish 2025/09/01 05:34:05 ERROR : proton drive root link ID 'rclone-test-tevomon9tega/dst': not deleting files as there were IO errors 2025/09/01 05:34:05 ERROR : proton drive root link ID 'rclone-test-tevomon9tega/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/4LywCmv5GJnBmI2bu1XWqBtRTCE8Y7wk9gsMEcQyDT0z0gr81PpXR-o0rHX3HrUIv7Gh3-cWcYlJ0Y5XwsB7rQ==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncBackupDirSuffixOnly --- FAIL: TestSyncBackupDirSuffixOnly (106.18s) FAIL 2025/09/01 05:34:07 DEBUG : proton drive root link ID 'rclone-test-tevomon9tega': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestProtonDrive: -verbose -test.run '^(TestMoveWithDeleteEmptySrcDirs|TestServerSideMove|TestServerSideMoveDeleteEmptySourceDirs|TestServerSideMoveOverSelf|TestServerSideMoveWithFilter|TestSyncBackupDir|TestSyncBackupDirSuffixOnly|TestSyncBackupDirWithSuffix|TestSyncBackupDirWithSuffixKeepExtension|TestSyncWithTrackRenames|TestSyncWithTrackRenamesStrategyLeaf|TestSyncWithTrackRenamesStrategyModtime)$'" - Finished ERROR in 18m55.156921455s (try 2/5): exit status 1: Failed [TestServerSideMoveOverSelf TestSyncWithTrackRenames TestSyncWithTrackRenamesStrategyModtime TestSyncWithTrackRenamesStrategyLeaf TestMoveWithDeleteEmptySrcDirs TestServerSideMove TestServerSideMoveWithFilter TestServerSideMoveDeleteEmptySourceDirs TestSyncBackupDir TestSyncBackupDirWithSuffix TestSyncBackupDirWithSuffixKeepExtension TestSyncBackupDirSuffixOnly]